[13:49:01.087] New invocation is queued and will start shortly
[13:49:02.230] Starting the invocation (attempt 1)
[13:49:02.261] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8ceee5a345dd439ab2cf962b671ac8823731b34d"
[13:49:02.262] Popped gitiles commit info from properties and tags
[13:49:02.262] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:49:02.262] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:49:02.262] Buildbucket request:
{
"requestId": "8950532413727408544",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8ceee5a345dd439ab2cf962b671ac8823731b34d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8950532413727408544"
},
{
"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"
}
}
[13:49:02.667] Scheduled build:
{
"id": "8785102351764684961",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 5919,
"createdBy": "project:chromium",
"createTime": "2023-03-31T13:49:02.329918949Z",
"updateTime": "2023-03-31T13:49:02.329918949Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8ceee5a345dd439ab2cf962b671ac8823731b34d",
"ref": "refs/heads/main"
}
}
}
[13:49:02.667] Task URL: https://cr-buildbucket.appspot.com/build/8785102351764684961
[13:49:02.667] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:2:0) after 3m46s
[13:50:06.074] Received PubSub notification, asking Buildbucket for the build status
[13:50:06.102] Build status: STARTED
[13:52:48.689] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:2:0)
[13:52:48.710] Build status: STARTED
[13:52:48.710] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:4:0) after 3m49s
[13:56:37.718] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:4:0)
[13:56:37.747] Build status: STARTED
[13:56:37.747] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:5:0) after 6m9s
[14:02:47.185] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:5:0)
[14:02:47.219] Build status: STARTED
[14:02:47.219] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:6:0) after 9m1s
[14:11:48.384] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:6:0)
[14:11:48.456] Build status: STARTED
[14:11:48.456] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:7:0) after 4m2s
[14:15:50.479] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:7:0)
[14:15:50.522] Build status: STARTED
[14:15:50.522] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:8:0) after 4m4s
[14:19:54.636] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:8:0)
[14:19:54.657] Build status: STARTED
[14:19:54.657] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:9:0) after 9m33s
[14:29:27.711] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:9:0)
[14:29:27.818] Build status: STARTED
[14:29:27.818] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:10:0) after 5m28s
[14:34:56.247] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:10:0)
[14:34:56.285] Build status: STARTED
[14:34:56.285] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:11:0) after 1m15s
[14:36:11.310] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:11:0)
[14:36:11.336] Build status: STARTED
[14:36:11.336] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:12:0) after 8m10s
[14:44:21.374] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:12:0)
[14:44:21.397] Build status: STARTED
[14:44:21.397] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:13:0) after 4m48s
[14:49:09.514] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:13:0)
[14:49:09.535] Build status: STARTED
[14:49:09.535] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:14:0) after 7m38s
[14:56:48.172] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:14:0)
[14:56:48.196] Build status: STARTED
[14:56:48.196] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:15:0) after 8m38s
[15:05:26.267] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:15:0)
[15:05:26.376] Build status: STARTED
[15:05:26.376] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:16:0) after 9m15s
[15:14:41.399] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:16:0)
[15:14:41.420] Build status: STARTED
[15:14:41.420] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:17:0) after 2m8s
[15:16:49.448] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:17:0)
[15:16:49.471] Build status: STARTED
[15:16:49.471] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:18:0) after 7m55s
[15:24:44.491] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:18:0)
[15:24:44.513] Build status: STARTED
[15:24:44.513] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:19:0) after 1m20s
[15:26:04.582] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:19:0)
[15:26:04.654] Build status: STARTED
[15:26:04.654] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:20:0) after 2m20s
[15:28:24.676] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:20:0)
[15:28:24.711] Build status: STARTED
[15:28:24.711] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:21:0) after 6m3s
[15:34:27.700] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:21:0)
[15:34:27.731] Build status: STARTED
[15:34:27.731] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:22:0) after 9m13s
[15:43:40.819] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:22:0)
[15:43:40.839] Build status: STARTED
[15:43:40.839] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:23:0) after 3m35s
[15:47:15.875] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:23:0)
[15:47:15.899] Build status: STARTED
[15:47:15.899] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:24:0) after 6m14s
[15:53:30.209] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:24:0)
[15:53:30.275] Build status: STARTED
[15:53:30.275] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:25:0) after 5m7s
[15:58:37.386] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:25:0)
[15:58:37.404] Build status: STARTED
[15:58:37.404] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:26:0) after 1m3s
[15:59:40.434] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:26:0)
[15:59:40.454] Build status: STARTED
[15:59:40.454] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:27:0) after 8m18s
[16:07:58.463] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:27:0)
[16:07:58.494] Build status: STARTED
[16:07:58.494] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:28:0) after 7m52s
[16:15:50.519] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:28:0)
[16:15:50.545] Build status: STARTED
[16:15:50.545] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:29:0) after 4m36s
[16:20:26.939] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:29:0)
[16:20:26.968] Build status: STARTED
[16:20:26.968] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:30:0) after 9m42s
[16:30:09.043] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:30:0)
[16:30:09.089] Build status: STARTED
[16:30:09.089] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:31:0) after 4m25s
[16:34:34.232] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:31:0)
[16:34:34.255] Build status: STARTED
[16:34:34.255] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:32:0) after 4m0s
[16:38:34.481] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:32:0)
[16:38:34.514] Build status: STARTED
[16:38:34.514] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:33:0) after 5m30s
[16:44:04.730] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:33:0)
[16:44:04.753] Build status: STARTED
[16:44:04.753] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:34:0) after 1m18s
[16:45:22.773] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:34:0)
[16:45:22.829] Build status: STARTED
[16:45:22.829] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:35:0) after 1m45s
[16:47:07.954] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:35:0)
[16:47:07.973] Build status: STARTED
[16:47:07.973] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:36:0) after 3m33s
[16:50:41.229] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:36:0)
[16:50:41.275] Build status: STARTED
[16:50:41.275] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:37:0) after 2m29s
[16:53:10.768] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:37:0)
[16:53:10.793] Build status: STARTED
[16:53:10.793] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:38:0) after 2m8s
[16:55:19.266] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:38:0)
[16:55:19.331] Build status: STARTED
[16:55:19.331] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:39:0) after 2m23s
[16:57:42.524] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:39:0)
[16:57:42.550] Build status: STARTED
[16:57:42.550] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:40:0) after 3m36s
[17:01:18.703] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:40:0)
[17:01:18.734] Build status: STARTED
[17:01:18.734] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:41:0) after 2m34s
[17:03:52.990] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:41:0)
[17:03:53.009] Build status: STARTED
[17:03:53.009] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:42:0) after 9m44s
[17:13:37.187] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:42:0)
[17:13:37.217] Build status: STARTED
[17:13:37.217] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:43:0) after 1m33s
[17:15:10.290] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:43:0)
[17:15:10.317] Build status: STARTED
[17:15:10.317] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:44:0) after 4m10s
[17:19:20.344] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:44:0)
[17:19:20.365] Build status: STARTED
[17:19:20.365] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:45:0) after 7m35s
[17:26:55.579] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:45:0)
[17:26:55.624] Build status: STARTED
[17:26:55.624] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:46:0) after 5m39s
[17:32:34.652] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:46:0)
[17:32:34.686] Build status: STARTED
[17:32:34.686] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:47:0) after 5m58s
[17:38:32.705] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:47:0)
[17:38:32.725] Build status: STARTED
[17:38:32.726] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:48:0) after 1m32s
[17:40:04.747] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:48:0)
[17:40:04.777] Build status: STARTED
[17:40:04.777] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:49:0) after 7m46s
[17:47:50.942] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:49:0)
[17:47:50.993] Build status: STARTED
[17:47:50.993] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:50:0) after 5m32s
[17:53:23.018] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:50:0)
[17:53:23.041] Build status: STARTED
[17:53:23.041] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:51:0) after 1m21s
[17:54:44.124] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:51:0)
[17:54:44.145] Build status: STARTED
[17:54:44.145] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:52:0) after 1m5s
[17:55:49.293] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:52:0)
[17:55:49.323] Build status: STARTED
[17:55:49.323] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:53:0) after 5m44s
[18:01:33.345] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:53:0)
[18:01:33.372] Build status: STARTED
[18:01:33.372] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:54:0) after 4m39s
[18:06:12.459] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:54:0)
[18:06:12.483] Build status: STARTED
[18:06:12.483] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:55:0) after 4m37s
[18:10:49.567] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:55:0)
[18:10:49.598] Build status: STARTED
[18:10:49.599] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:56:0) after 6m14s
[18:17:03.805] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:56:0)
[18:17:03.826] Build status: STARTED
[18:17:03.826] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:57:0) after 3m56s
[18:21:00.365] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:57:0)
[18:21:00.387] Build status: STARTED
[18:21:00.387] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:58:0) after 7m2s
[18:28:03.330] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:58:0)
[18:28:03.355] Build status: STARTED
[18:28:03.355] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:59:0) after 2m41s
[18:30:44.873] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:59:0)
[18:30:44.899] Build status: STARTED
[18:30:44.899] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:60:0) after 6m15s
[18:36:59.929] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:60:0)
[18:36:59.950] Build status: STARTED
[18:36:59.950] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:61:0) after 9m55s
[18:46:54.954] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:61:0)
[18:46:54.978] Build status: STARTED
[18:46:54.978] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:62:0) after 9m35s
[18:56:29.975] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:62:0)
[18:56:30.003] Build status: STARTED
[18:56:30.003] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:63:0) after 6m50s
[19:03:20.300] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:63:0)
[19:03:20.338] Build status: STARTED
[19:03:20.338] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:64:0) after 1m48s
[19:05:08.376] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:64:0)
[19:05:08.406] Build status: STARTED
[19:05:08.406] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:65:0) after 5m54s
[19:11:02.433] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:65:0)
[19:11:02.494] Build status: STARTED
[19:11:02.494] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:66:0) after 9m58s
[19:21:00.560] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:66:0)
[19:21:00.584] Build status: STARTED
[19:21:00.584] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:67:0) after 9m2s
[19:30:02.606] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:67:0)
[19:30:02.652] Build status: STARTED
[19:30:02.652] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:68:0) after 1m50s
[19:31:52.809] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:68:0)
[19:31:52.831] Build status: STARTED
[19:31:52.831] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:69:0) after 2m32s
[19:34:25.225] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:69:0)
[19:34:25.250] Build status: STARTED
[19:34:25.250] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:70:0) after 4m15s
[19:38:40.374] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:70:0)
[19:38:40.402] Build status: STARTED
[19:38:40.402] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:71:0) after 2m34s
[19:41:14.421] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:71:0)
[19:41:14.441] Build status: STARTED
[19:41:14.441] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:72:0) after 8m47s
[19:50:01.583] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:72:0)
[19:50:01.610] Build status: STARTED
[19:50:01.610] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:73:0) after 3m54s
[19:53:55.629] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:73:0)
[19:53:55.652] Build status: STARTED
[19:53:55.652] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:74:0) after 8m29s
[20:02:24.783] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:74:0)
[20:02:24.803] Build status: STARTED
[20:02:24.803] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:75:0) after 7m12s
[20:09:37.028] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:75:0)
[20:09:37.055] Build status: STARTED
[20:09:37.055] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:76:0) after 6m31s
[20:16:08.781] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:76:0)
[20:16:08.803] Build status: STARTED
[20:16:08.803] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:77:0) after 5m29s
[20:21:38.128] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:77:0)
[20:21:38.152] Build status: STARTED
[20:21:38.153] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:78:0) after 9m23s
[20:31:01.184] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:78:0)
[20:31:01.243] Build status: STARTED
[20:31:01.243] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:79:0) after 2m38s
[20:33:39.295] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:79:0)
[20:33:39.327] Build status: STARTED
[20:33:39.327] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:80:0) after 4m4s
[20:37:43.744] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:80:0)
[20:37:43.766] Build status: STARTED
[20:37:43.766] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:81:0) after 2m16s
[20:39:59.842] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:81:0)
[20:39:59.864] Build status: STARTED
[20:39:59.864] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:82:0) after 1m20s
[20:41:19.883] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:82:0)
[20:41:19.905] Build status: STARTED
[20:41:19.905] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:83:0) after 5m59s
[20:47:18.941] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:83:0)
[20:47:18.964] Build status: STARTED
[20:47:18.965] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:84:0) after 5m59s
[20:53:18.020] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:84:0)
[20:53:18.041] Build status: STARTED
[20:53:18.041] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:85:0) after 1m6s
[20:54:24.109] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:85:0)
[20:54:24.129] Build status: STARTED
[20:54:24.129] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:86:0) after 9m9s
[21:03:33.151] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:86:0)
[21:03:33.177] Build status: STARTED
[21:03:33.177] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:87:0) after 6m51s
[21:10:24.205] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:87:0)
[21:10:24.372] Build status: STARTED
[21:10:24.372] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:88:0) after 1m5s
[21:11:29.432] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:88:0)
[21:11:29.462] Build status: STARTED
[21:11:29.462] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:89:0) after 6m28s
[21:17:57.534] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:89:0)
[21:17:57.586] Build status: STARTED
[21:17:57.586] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:90:0) after 3m9s
[21:21:06.610] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:90:0)
[21:21:06.636] Build status: STARTED
[21:21:06.636] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:91:0) after 2m17s
[21:23:23.750] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:91:0)
[21:23:23.768] Build status: STARTED
[21:23:23.768] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:92:0) after 4m38s
[21:28:02.087] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:92:0)
[21:28:02.114] Build status: STARTED
[21:28:02.114] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:93:0) after 2m1s
[21:30:03.255] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:93:0)
[21:30:03.277] Build status: STARTED
[21:30:03.277] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:94:0) after 4m27s
[21:34:30.309] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:94:0)
[21:34:30.378] Build status: STARTED
[21:34:30.378] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:95:0) after 1m19s
[21:35:49.709] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:95:0)
[21:35:49.735] Build status: STARTED
[21:35:49.735] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:96:0) after 9m23s
[21:45:12.792] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:96:0)
[21:45:12.886] Build status: STARTED
[21:45:12.886] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:97:0) after 9m48s
[21:55:01.597] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:97:0)
[21:55:01.618] Build status: STARTED
[21:55:01.618] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:98:0) after 4m24s
[21:59:26.007] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:98:0)
[21:59:26.029] Build status: STARTED
[21:59:26.029] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:99:0) after 6m40s
[22:06:06.136] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:99:0)
[22:06:06.164] Build status: STARTED
[22:06:06.164] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:100:0) after 8m28s
[22:14:34.426] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:100:0)
[22:14:34.448] Build status: STARTED
[22:14:34.448] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:101:0) after 1m46s
[22:16:20.482] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:101:0)
[22:16:20.537] Build status: STARTED
[22:16:20.537] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:102:0) after 1m13s
[22:17:33.596] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:102:0)
[22:17:33.631] Build status: STARTED
[22:17:33.631] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:103:0) after 4m9s
[22:21:42.756] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:103:0)
[22:21:42.779] Build status: STARTED
[22:21:42.779] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:104:0) after 6m6s
[22:27:48.801] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:104:0)
[22:27:48.831] Build status: STARTED
[22:27:48.831] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:105:0) after 3m21s
[22:31:09.874] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:105:0)
[22:31:09.894] Build status: STARTED
[22:31:09.895] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:106:0) after 4m3s
[22:35:12.919] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:106:0)
[22:35:12.944] Build status: STARTED
[22:35:12.944] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:107:0) after 5m15s
[22:40:27.986] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:107:0)
[22:40:28.040] Build status: STARTED
[22:40:28.040] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:108:0) after 7m38s
[22:48:06.147] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:108:0)
[22:48:06.203] Build status: STARTED
[22:48:06.203] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:109:0) after 9m38s
[22:57:44.287] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:109:0)
[22:57:44.311] Build status: STARTED
[22:57:44.311] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:110:0) after 9m35s
[23:07:19.288] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:110:0)
[23:07:19.317] Build status: STARTED
[23:07:19.317] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:111:0) after 6m50s
[23:14:09.340] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:111:0)
[23:14:09.360] Build status: STARTED
[23:14:09.360] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:112:0) after 2m24s
[23:16:33.393] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:112:0)
[23:16:33.419] Build status: STARTED
[23:16:33.419] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:113:0) after 4m9s
[23:20:42.544] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:113:0)
[23:20:42.578] Build status: STARTED
[23:20:42.578] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:114:0) after 6m50s
[23:27:32.834] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:114:0)
[23:27:32.858] Build status: STARTED
[23:27:32.858] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:115:0) after 5m44s
[23:33:17.086] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:115:0)
[23:33:17.109] Build status: STARTED
[23:33:17.109] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:116:0) after 6m4s
[23:39:21.136] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:116:0)
[23:39:21.164] Build status: STARTED
[23:39:21.164] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:117:0) after 9m52s
[23:49:13.207] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:117:0)
[23:49:13.227] Build status: STARTED
[23:49:13.227] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8950532413727408544:118:0) after 1m59s
[23:49:53.628] Received PubSub notification, asking Buildbucket for the build status
[23:49:53.648] Build:
{
"id": "8785102351764684961",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 5919,
"createdBy": "project:chromium",
"createTime": "2023-03-31T13:49:02.329918949Z",
"startTime": "2023-03-31T13:50:05.377527Z",
"endTime": "2023-03-31T23:49:53.207522069Z",
"updateTime": "2023-03-31T23:49:53.207522069Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8ceee5a345dd439ab2cf962b671ac8823731b34d",
"ref": "refs/heads/main"
}
}
}
[23:49:53.648] Invocation finished in 10h0m52.574304597s with status FAILED