[20:14:48.344] New invocation is queued and will start shortly
[20:14:49.687] Starting the invocation (attempt 1)
[20:14:49.712] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/dbedc30c1b02f97ba6acb7d3210176caef65d569"
[20:14:49.712] Popped gitiles commit info from properties and tags
[20:14:49.712] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:14:49.712] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:14:49.713] Buildbucket request:
{
"requestId": "8900045631781577600",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-kubsan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8900045631781577600",
"job": "fuchsia/core.x64-kubsan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5a8743251cc37bf6b21c37d73470fa5590cd8192"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5a8743251cc37bf6b21c37d73470fa5590cd8192",
"title": "5a8743251cc37bf6b21c37d73470fa5590cd8192",
"url": "https://fuchsia.googlesource.com/integration/+/5a8743251cc37bf6b21c37d73470fa5590cd8192"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3ef8fe3c41163a6cb90173c09c98c3b38478a286"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3ef8fe3c41163a6cb90173c09c98c3b38478a286",
"title": "3ef8fe3c41163a6cb90173c09c98c3b38478a286",
"url": "https://fuchsia.googlesource.com/integration/+/3ef8fe3c41163a6cb90173c09c98c3b38478a286"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1a5e168f53f6e6c64d1a02a46a08086fa8f9fc62"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1a5e168f53f6e6c64d1a02a46a08086fa8f9fc62",
"title": "1a5e168f53f6e6c64d1a02a46a08086fa8f9fc62",
"url": "https://fuchsia.googlesource.com/integration/+/1a5e168f53f6e6c64d1a02a46a08086fa8f9fc62"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "01ed59ea275106b90a5a904a3bb6eacd3acec4ae"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@01ed59ea275106b90a5a904a3bb6eacd3acec4ae",
"title": "01ed59ea275106b90a5a904a3bb6eacd3acec4ae",
"url": "https://fuchsia.googlesource.com/integration/+/01ed59ea275106b90a5a904a3bb6eacd3acec4ae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "23cc39e03d93d4361dd248a82b8482395c07e5da"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@23cc39e03d93d4361dd248a82b8482395c07e5da",
"title": "23cc39e03d93d4361dd248a82b8482395c07e5da",
"url": "https://fuchsia.googlesource.com/integration/+/23cc39e03d93d4361dd248a82b8482395c07e5da"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "dbedc30c1b02f97ba6acb7d3210176caef65d569"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@dbedc30c1b02f97ba6acb7d3210176caef65d569",
"title": "dbedc30c1b02f97ba6acb7d3210176caef65d569",
"url": "https://fuchsia.googlesource.com/integration/+/dbedc30c1b02f97ba6acb7d3210176caef65d569"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "dbedc30c1b02f97ba6acb7d3210176caef65d569",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8900045631781577600"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-kubsan-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:14:50.142] Scheduled build:
{
"id": "8734615569628539553",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-kubsan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-08T20:14:49.771666983Z",
"updateTime": "2024-10-08T20:14:49.771666983Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "dbedc30c1b02f97ba6acb7d3210176caef65d569",
"ref": "refs/heads/main"
}
}
}
[20:14:50.142] Task URL: https://cr-buildbucket.appspot.com/build/8734615569628539553
[20:14:50.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:2:0) after 5m43s
[20:14:51.064] Received PubSub notification, asking Buildbucket for the build status
[20:14:51.079] Build status: SCHEDULED
[20:14:56.137] Received PubSub notification, asking Buildbucket for the build status
[20:14:56.159] Build status: STARTED
[20:20:33.172] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:2:0)
[20:20:33.194] Build status: STARTED
[20:20:33.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:5:0) after 6m30s
[20:27:03.288] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:5:0)
[20:27:03.307] Build status: STARTED
[20:27:03.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:6:0) after 5m31s
[20:32:34.373] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:6:0)
[20:32:34.415] Build status: STARTED
[20:32:34.415] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:7:0) after 9m16s
[20:41:50.483] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:7:0)
[20:41:50.514] Build status: STARTED
[20:41:50.514] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:8:0) after 3m57s
[20:45:47.499] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:8:0)
[20:45:47.538] Build status: STARTED
[20:45:47.538] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:9:0) after 8m20s
[20:54:07.509] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:9:0)
[20:54:07.529] Build status: STARTED
[20:54:07.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:10:0) after 4m29s
[20:58:36.585] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:10:0)
[20:58:36.604] Build status: STARTED
[20:58:36.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:11:0) after 2m27s
[21:01:03.624] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:11:0)
[21:01:03.642] Build status: STARTED
[21:01:03.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:12:0) after 9m48s
[21:10:51.658] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:12:0)
[21:10:51.678] Build status: STARTED
[21:10:51.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:13:0) after 4m16s
[21:15:07.727] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:13:0)
[21:15:07.752] Build status: STARTED
[21:15:07.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:14:0) after 2m30s
[21:17:37.838] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:14:0)
[21:17:37.856] Build status: STARTED
[21:17:37.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:15:0) after 4m11s
[21:21:48.823] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:15:0)
[21:21:48.840] Build status: STARTED
[21:21:48.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:16:0) after 8m51s
[21:30:39.849] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:16:0)
[21:30:39.867] Build status: STARTED
[21:30:39.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:17:0) after 2m14s
[21:32:53.933] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:17:0)
[21:32:53.962] Build status: STARTED
[21:32:53.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:18:0) after 4m16s
[21:37:09.979] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:18:0)
[21:37:09.997] Build status: STARTED
[21:37:09.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:19:0) after 7m47s
[21:44:57.018] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:19:0)
[21:44:57.046] Build status: STARTED
[21:44:57.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:20:0) after 2m58s
[21:47:55.136] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:20:0)
[21:47:55.152] Build status: STARTED
[21:47:55.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-kubsan-e5a0ec7f:8900045631781577600:21:0) after 8m6s
[21:53:04.475] Received PubSub notification, asking Buildbucket for the build status
[21:53:04.516] Build:
{
"id": "8734615569628539553",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-kubsan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-08T20:14:49.771666983Z",
"startTime": "2024-10-08T20:14:55.953292165Z",
"endTime": "2024-10-08T21:53:04.094558097Z",
"updateTime": "2024-10-08T21:53:04.094558097Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "dbedc30c1b02f97ba6acb7d3210176caef65d569",
"ref": "refs/heads/main"
}
}
}
[21:53:04.516] Invocation finished in 1h38m16.182117856s with status SUCCEEDED