[17:14:24.039] New invocation is queued and will start shortly
[17:14:26.095] Starting the invocation (attempt 1)
[17:14:26.118] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/85cacd39c111acba3d2b1fd1c6d829d8c40eaf2b"
[17:14:26.118] Popped gitiles commit info from properties and tags
[17:14:26.118] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:14:26.118] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:14:26.118] Buildbucket request:
{
"requestId": "8847963726201558608",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "85cacd39c111acba3d2b1fd1c6d829d8c40eaf2b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8847963726201558608"
},
{
"key": "scheduler_job_id",
"value": "chromium/fuchsia-x64-cast-receiver-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:14:26.669] Scheduled build:
{
"id": "8682533663306540529",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 8021,
"createdBy": "project:chromium",
"createTime": "2026-05-06T17:14:26.179087193Z",
"updateTime": "2026-05-06T17:14:26.179087193Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "85cacd39c111acba3d2b1fd1c6d829d8c40eaf2b",
"ref": "refs/heads/main"
}
},
"canary": true
}
[17:14:26.669] Task URL: https://cr-buildbucket.appspot.com/build/8682533663306540529
[17:14:26.669] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:2:0) after 2m41s
[17:14:29.707] Received PubSub notification, asking Buildbucket for the build status
[17:14:29.775] Build status: SCHEDULED
[17:17:07.786] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:2:0)
[17:17:07.815] Build status: SCHEDULED
[17:17:07.815] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:4:0) after 4m7s
[17:21:14.894] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:4:0)
[17:21:14.912] Build status: SCHEDULED
[17:21:14.912] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:5:0) after 1m49s
[17:23:03.945] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:5:0)
[17:23:03.963] Build status: SCHEDULED
[17:23:03.963] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:6:0) after 5m50s
[17:28:53.984] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:6:0)
[17:28:54.000] Build status: SCHEDULED
[17:28:54.000] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:7:0) after 1m43s
[17:30:37.053] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:7:0)
[17:30:37.070] Build status: SCHEDULED
[17:30:37.070] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:8:0) after 3m31s
[17:34:08.349] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:8:0)
[17:34:08.367] Build status: SCHEDULED
[17:34:08.367] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:9:0) after 1m42s
[17:35:50.444] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:9:0)
[17:35:50.523] Build status: SCHEDULED
[17:35:50.523] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:10:0) after 1m1s
[17:36:51.586] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:10:0)
[17:36:51.624] Build status: SCHEDULED
[17:36:51.624] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:11:0) after 5m6s
[17:38:13.717] Received PubSub notification, asking Buildbucket for the build status
[17:38:13.825] Build status: STARTED
[17:41:57.777] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:11:0)
[17:41:57.800] Build status: STARTED
[17:41:57.800] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:13:0) after 7m33s
[17:49:30.902] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:13:0)
[17:49:30.949] Build status: STARTED
[17:49:30.949] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:14:0) after 3m35s
[17:53:06.073] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:14:0)
[17:53:06.125] Build status: STARTED
[17:53:06.125] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:15:0) after 5m46s
[17:58:52.143] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:15:0)
[17:58:52.441] Build status: STARTED
[17:58:52.441] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:16:0) after 6m53s
[18:05:45.485] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:16:0)
[18:05:45.509] Build status: STARTED
[18:05:45.509] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:17:0) after 5m11s
[18:10:56.560] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:17:0)
[18:10:56.577] Build status: STARTED
[18:10:56.577] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:18:0) after 9m5s
[18:20:01.598] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:18:0)
[18:20:01.614] Build status: STARTED
[18:20:01.614] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:19:0) after 4m53s
[18:24:54.691] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:19:0)
[18:24:54.704] Build status: STARTED
[18:24:54.704] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:20:0) after 2m23s
[18:27:17.730] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:20:0)
[18:27:17.745] Build status: STARTED
[18:27:17.745] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:21:0) after 5m28s
[18:32:45.893] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:21:0)
[18:32:45.911] Build status: STARTED
[18:32:45.911] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:22:0) after 1m39s
[18:34:25.075] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:22:0)
[18:34:25.175] Build status: STARTED
[18:34:25.175] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:23:0) after 7m55s
[18:42:20.193] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:23:0)
[18:42:20.248] Build status: STARTED
[18:42:20.248] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:24:0) after 2m6s
[18:44:26.485] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:24:0)
[18:44:26.550] Build status: STARTED
[18:44:26.571] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:25:0) after 2m54s
[18:47:20.751] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:25:0)
[18:47:20.782] Build status: STARTED
[18:47:20.782] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:26:0) after 9m29s
[18:56:49.857] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:26:0)
[18:56:49.875] Build status: STARTED
[18:56:49.875] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:27:0) after 2m53s
[18:59:42.893] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:27:0)
[18:59:42.914] Build status: STARTED
[18:59:42.914] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:28:0) after 8m26s
[19:08:09.079] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:28:0)
[19:08:09.095] Build status: STARTED
[19:08:09.096] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:29:0) after 7m35s
[19:15:44.297] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:29:0)
[19:15:44.335] Build status: STARTED
[19:15:44.335] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:30:0) after 4m0s
[19:19:44.407] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:30:0)
[19:19:44.422] Build status: STARTED
[19:19:44.422] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:31:0) after 9m31s
[19:29:15.440] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:31:0)
[19:29:15.471] Build status: STARTED
[19:29:15.471] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:32:0) after 7m49s
[19:37:04.520] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:32:0)
[19:37:04.540] Build status: STARTED
[19:37:04.540] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:33:0) after 6m3s
[19:43:07.563] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:33:0)
[19:43:07.591] Build status: STARTED
[19:43:07.591] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:34:0) after 6m54s
[19:50:01.672] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:34:0)
[19:50:01.692] Build status: STARTED
[19:50:01.692] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:35:0) after 7m9s
[19:57:10.753] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:35:0)
[19:57:10.776] Build status: STARTED
[19:57:10.776] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:36:0) after 4m41s
[20:01:51.860] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:36:0)
[20:01:51.876] Build status: STARTED
[20:01:51.876] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:37:0) after 7m54s
[20:09:46.091] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:37:0)
[20:09:46.106] Build status: STARTED
[20:09:46.106] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:38:0) after 9m53s
[20:19:39.199] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:38:0)
[20:19:39.215] Build status: STARTED
[20:19:39.215] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:39:0) after 7m36s
[20:27:15.231] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:39:0)
[20:27:15.246] Build status: STARTED
[20:27:15.246] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:40:0) after 1m31s
[20:28:46.305] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:40:0)
[20:28:46.319] Build status: STARTED
[20:28:46.319] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:41:0) after 1m48s
[20:30:34.343] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:41:0)
[20:30:34.362] Build status: STARTED
[20:30:34.362] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:42:0) after 1m28s
[20:32:02.511] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:42:0)
[20:32:02.534] Build status: STARTED
[20:32:02.534] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:43:0) after 7m41s
[20:39:43.597] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:43:0)
[20:39:43.613] Build status: STARTED
[20:39:43.613] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:44:0) after 8m3s
[20:47:47.008] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:44:0)
[20:47:47.021] Build status: STARTED
[20:47:47.021] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:45:0) after 7m3s
[20:54:50.164] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:45:0)
[20:54:50.211] Build status: STARTED
[20:54:50.211] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:46:0) after 4m5s
[20:58:55.271] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:46:0)
[20:58:55.289] Build status: STARTED
[20:58:55.290] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8847963726201558608:47:0) after 8m13s
[21:02:56.337] Received PubSub notification, asking Buildbucket for the build status
[21:02:56.507] Build:
{
"id": "8682533663306540529",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 8021,
"createdBy": "project:chromium",
"createTime": "2026-05-06T17:14:26.179087193Z",
"startTime": "2026-05-06T17:38:13.566907689Z",
"endTime": "2026-05-06T21:02:55.757840047Z",
"updateTime": "2026-05-06T21:02:55.757840047Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "85cacd39c111acba3d2b1fd1c6d829d8c40eaf2b",
"ref": "refs/heads/main"
}
},
"canary": true
}
[21:02:56.507] Invocation finished in 3h48m32.472066276s with status SUCCEEDED