[14:16:42.643] New invocation is queued and will start shortly
[14:16:43.762] Starting the invocation (attempt 1)
[14:16:43.790] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/df3743b10e253842a2001d73a1b59a0f95f9d0f3"
[14:16:43.790] Popped gitiles commit info from properties and tags
[14:16:43.790] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:16:43.790] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:16:43.790] Buildbucket request:
{
"requestId": "8955151116744536736",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "df3743b10e253842a2001d73a1b59a0f95f9d0f3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8955151116744536736"
},
{
"key": "scheduler_job_id",
"value": "chromium/fuchsia-fyi-arm64-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:16:44.487] Scheduled build:
{
"id": "8789721054662486929",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 5706,
"createdBy": "project:chromium",
"createTime": "2023-02-08T14:16:44.001165817Z",
"updateTime": "2023-02-08T14:16:44.001165817Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "df3743b10e253842a2001d73a1b59a0f95f9d0f3",
"ref": "refs/heads/main"
}
}
}
[14:16:44.487] Task URL: https://cr-buildbucket.appspot.com/build/8789721054662486929
[14:16:44.487] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:2:0) after 6m0s
[14:19:04.371] Received PubSub notification, asking Buildbucket for the build status
[14:19:04.395] Build status: STARTED
[14:22:44.755] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:2:0)
[14:22:44.778] Build status: STARTED
[14:22:44.779] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:4:0) after 6m2s
[14:28:47.003] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:4:0)
[14:28:47.043] Build status: STARTED
[14:28:47.043] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:5:0) after 7m50s
[14:36:37.190] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:5:0)
[14:36:37.215] Build status: STARTED
[14:36:37.215] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:6:0) after 4m42s
[14:41:19.481] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:6:0)
[14:41:19.514] Build status: STARTED
[14:41:19.514] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:7:0) after 5m6s
[14:46:25.593] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:7:0)
[14:46:25.614] Build status: STARTED
[14:46:25.614] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:8:0) after 9m43s
[14:56:09.001] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:8:0)
[14:56:09.030] Build status: STARTED
[14:56:09.030] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:9:0) after 7m43s
[15:03:52.029] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:9:0)
[15:03:52.051] Build status: STARTED
[15:03:52.051] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:10:0) after 7m17s
[15:11:09.070] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:10:0)
[15:11:09.092] Build status: STARTED
[15:11:09.092] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:11:0) after 2m57s
[15:14:06.261] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:11:0)
[15:14:06.286] Build status: STARTED
[15:14:06.286] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:12:0) after 4m20s
[15:18:26.388] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:12:0)
[15:18:26.412] Build status: STARTED
[15:18:26.412] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:13:0) after 5m50s
[15:24:16.438] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:13:0)
[15:24:16.459] Build status: STARTED
[15:24:16.459] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:14:0) after 6m58s
[15:31:14.590] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:14:0)
[15:31:14.626] Build status: STARTED
[15:31:14.626] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:15:0) after 1m49s
[15:33:03.811] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:15:0)
[15:33:03.833] Build status: STARTED
[15:33:03.833] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:16:0) after 6m17s
[15:39:20.952] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:16:0)
[15:39:20.974] Build status: STARTED
[15:39:20.974] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:17:0) after 2m25s
[15:41:45.996] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:17:0)
[15:41:46.019] Build status: STARTED
[15:41:46.019] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:18:0) after 1m32s
[15:43:18.051] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:18:0)
[15:43:18.206] Build status: STARTED
[15:43:18.206] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:19:0) after 9m33s
[15:52:51.257] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:19:0)
[15:52:51.279] Build status: STARTED
[15:52:51.279] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:20:0) after 7m35s
[16:00:26.350] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:20:0)
[16:00:26.370] Build status: STARTED
[16:00:26.370] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:21:0) after 8m59s
[16:09:25.429] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:21:0)
[16:09:25.452] Build status: STARTED
[16:09:25.452] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:22:0) after 4m13s
[16:13:38.475] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:22:0)
[16:13:38.502] Build status: STARTED
[16:13:38.502] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:23:0) after 2m25s
[16:16:03.479] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:23:0)
[16:16:03.500] Build status: STARTED
[16:16:03.500] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:24:0) after 5m26s
[16:21:29.560] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:24:0)
[16:21:29.590] Build status: STARTED
[16:21:29.590] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:25:0) after 9m36s
[16:31:05.731] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:25:0)
[16:31:05.769] Build status: STARTED
[16:31:05.769] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:26:0) after 9m57s
[16:41:02.806] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:26:0)
[16:41:02.900] Build status: STARTED
[16:41:02.900] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:27:0) after 3m52s
[16:44:54.967] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:27:0)
[16:44:54.991] Build status: STARTED
[16:44:54.991] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:28:0) after 5m59s
[16:50:54.079] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:28:0)
[16:50:54.106] Build status: STARTED
[16:50:54.106] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:29:0) after 5m26s
[16:56:20.127] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:29:0)
[16:56:20.150] Build status: STARTED
[16:56:20.151] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:30:0) after 8m1s
[17:04:21.193] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:30:0)
[17:04:21.223] Build status: STARTED
[17:04:21.223] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:31:0) after 8m8s
[17:12:29.241] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:31:0)
[17:12:29.265] Build status: STARTED
[17:12:29.265] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:32:0) after 4m22s
[17:16:51.296] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:32:0)
[17:16:51.320] Build status: STARTED
[17:16:51.320] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:33:0) after 5m24s
[17:22:15.369] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:33:0)
[17:22:15.391] Build status: STARTED
[17:22:15.391] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:34:0) after 9m43s
[17:31:58.416] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:34:0)
[17:31:58.439] Build status: STARTED
[17:31:58.439] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:35:0) after 9m33s
[17:41:31.447] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:35:0)
[17:41:31.468] Build status: STARTED
[17:41:31.468] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:36:0) after 4m38s
[17:46:09.514] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:36:0)
[17:46:09.535] Build status: STARTED
[17:46:09.535] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:37:0) after 2m2s
[17:48:11.602] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:37:0)
[17:48:11.632] Build status: STARTED
[17:48:11.633] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:38:0) after 3m16s
[17:51:27.653] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:38:0)
[17:51:27.760] Build status: STARTED
[17:51:27.760] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:39:0) after 1m30s
[17:52:57.743] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:39:0)
[17:52:57.764] Build status: STARTED
[17:52:57.764] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:40:0) after 3m14s
[17:56:11.800] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:40:0)
[17:56:11.843] Build status: STARTED
[17:56:11.843] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:41:0) after 3m44s
[17:59:55.866] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:41:0)
[17:59:55.890] Build status: STARTED
[17:59:55.890] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:42:0) after 6m4s
[18:05:59.891] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:42:0)
[18:05:59.942] Build status: STARTED
[18:05:59.942] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:43:0) after 9m4s
[18:15:03.962] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:43:0)
[18:15:03.983] Build status: STARTED
[18:15:03.983] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:44:0) after 8m23s
[18:23:27.001] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:44:0)
[18:23:27.023] Build status: STARTED
[18:23:27.023] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:45:0) after 7m6s
[18:30:33.079] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:45:0)
[18:30:33.102] Build status: STARTED
[18:30:33.102] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:46:0) after 2m14s
[18:32:47.180] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:46:0)
[18:32:47.202] Build status: STARTED
[18:32:47.202] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:47:0) after 4m32s
[18:37:19.219] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:47:0)
[18:37:19.242] Build status: STARTED
[18:37:19.242] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:48:0) after 5m18s
[18:42:37.297] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:48:0)
[18:42:37.324] Build status: STARTED
[18:42:37.324] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:49:0) after 5m13s
[18:47:50.348] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:49:0)
[18:47:50.369] Build status: STARTED
[18:47:50.369] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:50:0) after 3m28s
[18:51:18.394] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:50:0)
[18:51:18.421] Build status: STARTED
[18:51:18.421] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:51:0) after 1m23s
[18:52:41.725] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:51:0)
[18:52:41.753] Build status: STARTED
[18:52:41.753] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:52:0) after 8m27s
[19:01:08.809] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:52:0)
[19:01:08.828] Build status: STARTED
[19:01:08.828] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:53:0) after 2m13s
[19:03:21.870] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:53:0)
[19:03:21.894] Build status: STARTED
[19:03:21.894] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:54:0) after 1m4s
[19:04:25.922] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:54:0)
[19:04:25.948] Build status: STARTED
[19:04:25.948] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:55:0) after 3m10s
[19:07:36.021] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:55:0)
[19:07:36.044] Build status: STARTED
[19:07:36.044] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:56:0) after 2m11s
[19:09:47.105] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:56:0)
[19:09:47.166] Build status: STARTED
[19:09:47.166] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:57:0) after 8m33s
[19:18:20.194] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:57:0)
[19:18:20.238] Build status: STARTED
[19:18:20.238] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:58:0) after 4m18s
[19:22:38.279] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:58:0)
[19:22:38.321] Build status: STARTED
[19:22:38.321] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:59:0) after 7m53s
[19:30:31.515] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:59:0)
[19:30:31.544] Build status: STARTED
[19:30:31.544] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:60:0) after 9m37s
[19:40:08.576] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:60:0)
[19:40:08.602] Build status: STARTED
[19:40:08.602] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:61:0) after 9m5s
[19:49:13.712] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:61:0)
[19:49:13.734] Build status: STARTED
[19:49:13.734] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:62:0) after 5m1s
[19:54:14.757] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:62:0)
[19:54:14.778] Build status: STARTED
[19:54:14.778] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:63:0) after 1m48s
[19:56:02.810] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:63:0)
[19:56:02.835] Build status: STARTED
[19:56:02.835] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:64:0) after 9m54s
[20:05:56.862] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:64:0)
[20:05:56.885] Build status: STARTED
[20:05:56.885] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:65:0) after 6m11s
[20:12:07.954] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:65:0)
[20:12:07.981] Build status: STARTED
[20:12:07.981] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:66:0) after 9m11s
[20:21:19.301] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:66:0)
[20:21:19.322] Build status: STARTED
[20:21:19.322] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:67:0) after 3m20s
[20:24:39.342] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:67:0)
[20:24:39.372] Build status: STARTED
[20:24:39.372] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:68:0) after 9m54s
[20:34:33.395] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:68:0)
[20:34:33.419] Build status: STARTED
[20:34:33.419] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:69:0) after 3m20s
[20:37:53.488] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:69:0)
[20:37:53.511] Build status: STARTED
[20:37:53.511] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:70:0) after 8m59s
[20:46:52.546] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:70:0)
[20:46:52.568] Build status: STARTED
[20:46:52.568] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:71:0) after 8m38s
[20:55:30.699] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:71:0)
[20:55:30.720] Build status: STARTED
[20:55:30.721] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:72:0) after 8m15s
[21:03:45.882] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:72:0)
[21:03:45.904] Build status: STARTED
[21:03:45.904] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:73:0) after 5m13s
[21:08:59.080] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:73:0)
[21:08:59.279] Build status: STARTED
[21:08:59.279] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:74:0) after 6m51s
[21:15:50.291] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:74:0)
[21:15:50.406] Build status: STARTED
[21:15:50.406] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:75:0) after 1m23s
[21:17:13.829] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:75:0)
[21:17:13.892] Build status: STARTED
[21:17:13.892] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:76:0) after 8m4s
[21:25:17.968] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:76:0)
[21:25:18.033] Build status: STARTED
[21:25:18.033] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:77:0) after 2m38s
[21:27:56.054] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:77:0)
[21:27:56.095] Build status: STARTED
[21:27:56.095] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:78:0) after 8m13s
[21:36:09.158] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:78:0)
[21:36:09.203] Build status: STARTED
[21:36:09.203] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:79:0) after 1m27s
[21:37:36.228] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:79:0)
[21:37:36.259] Build status: STARTED
[21:37:36.259] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:80:0) after 7m33s
[21:45:09.326] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:80:0)
[21:45:09.348] Build status: STARTED
[21:45:09.348] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:81:0) after 6m47s
[21:51:56.399] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:81:0)
[21:51:56.425] Build status: STARTED
[21:51:56.425] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:82:0) after 7m21s
[21:59:17.448] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:82:0)
[21:59:17.536] Build status: STARTED
[21:59:17.536] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:83:0) after 6m58s
[22:06:15.556] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:83:0)
[22:06:15.578] Build status: STARTED
[22:06:15.578] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:84:0) after 9m53s
[22:16:08.616] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:84:0)
[22:16:08.675] Build status: STARTED
[22:16:08.675] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:85:0) after 7m46s
[22:23:55.129] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:85:0)
[22:23:55.154] Build status: STARTED
[22:23:55.154] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:86:0) after 5m45s
[22:29:40.181] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:86:0)
[22:29:40.203] Build status: STARTED
[22:29:40.203] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:87:0) after 1m15s
[22:30:55.356] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:87:0)
[22:30:55.459] Build status: STARTED
[22:30:55.459] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:88:0) after 9m1s
[22:39:56.493] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:88:0)
[22:39:56.518] Build status: STARTED
[22:39:56.518] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:89:0) after 8m41s
[22:48:38.548] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:89:0)
[22:48:38.571] Build status: STARTED
[22:48:38.571] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:90:0) after 3m52s
[22:52:30.595] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:90:0)
[22:52:30.616] Build status: STARTED
[22:52:30.616] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:91:0) after 2m52s
[22:55:22.631] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:91:0)
[22:55:22.661] Build status: STARTED
[22:55:22.662] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:92:0) after 4m34s
[22:59:56.739] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:92:0)
[22:59:56.761] Build status: STARTED
[22:59:56.761] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:93:0) after 8m11s
[23:08:07.881] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:93:0)
[23:08:07.902] Build status: STARTED
[23:08:07.902] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:94:0) after 9m8s
[23:17:15.922] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:94:0)
[23:17:15.978] Build status: STARTED
[23:17:15.978] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:95:0) after 1m10s
[23:18:26.206] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:95:0)
[23:18:26.250] Build status: STARTED
[23:18:26.250] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:96:0) after 8m50s
[23:27:16.323] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:96:0)
[23:27:16.440] Build status: STARTED
[23:27:16.440] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:97:0) after 2m33s
[23:29:49.493] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:97:0)
[23:29:49.514] Build status: STARTED
[23:29:49.514] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:98:0) after 3m44s
[23:33:33.543] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:98:0)
[23:33:33.580] Build status: STARTED
[23:33:33.580] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:99:0) after 8m52s
[23:42:25.619] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:99:0)
[23:42:25.643] Build status: STARTED
[23:42:25.644] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:100:0) after 1m18s
[23:43:43.673] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:100:0)
[23:43:43.699] Build status: STARTED
[23:43:43.699] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:101:0) after 4m57s
[23:48:40.861] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:101:0)
[23:48:40.884] Build status: STARTED
[23:48:40.884] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:102:0) after 1m48s
[23:50:28.910] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:102:0)
[23:50:28.941] Build status: STARTED
[23:50:28.941] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:103:0) after 3m9s
[23:53:38.024] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:103:0)
[23:53:38.045] Build status: STARTED
[23:53:38.045] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:104:0) after 3m34s
[23:57:12.162] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:104:0)
[23:57:12.180] Build status: STARTED
[23:57:12.180] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:105:0) after 7m51s
[00:05:03.200] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:105:0)
[00:05:03.227] Build status: STARTED
[00:05:03.227] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:106:0) after 1m19s
[00:06:22.351] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:106:0)
[00:06:22.456] Build status: STARTED
[00:06:22.456] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:107:0) after 3m59s
[00:10:21.491] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:107:0)
[00:10:21.514] Build status: STARTED
[00:10:21.514] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:108:0) after 6m14s
[00:16:35.549] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:108:0)
[00:16:35.570] Build status: STARTED
[00:16:35.570] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8955151116744536736:109:0) after 4m18s
[00:19:17.339] Received PubSub notification, asking Buildbucket for the build status
[00:19:17.369] Build:
{
"id": "8789721054662486929",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 5706,
"createdBy": "project:chromium",
"createTime": "2023-02-08T14:16:44.001165817Z",
"startTime": "2023-02-08T14:19:03.852383Z",
"endTime": "2023-02-09T00:19:16.999398423Z",
"updateTime": "2023-02-09T00:19:16.999398423Z",
"status": "CANCELED",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "df3743b10e253842a2001d73a1b59a0f95f9d0f3",
"ref": "refs/heads/main"
}
}
}
[00:19:17.369] Invocation finished in 10h2m34.737798116s with status ABORTED