[14:09:04.631] New invocation is queued and will start shortly
[14:09:05.747] Starting the invocation (attempt 1)
[14:09:05.795] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cf5398e1f7dad8335741e313b727a8a79a993c7d"
[14:09:05.795] Popped gitiles commit info from properties and tags
[14:09:05.795] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:09:05.795] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:09:05.795] Buildbucket request:
{
"requestId": "8951437121384391264",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cf5398e1f7dad8335741e313b727a8a79a993c7d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8951437121384391264"
},
{
"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:09:06.291] Scheduled build:
{
"id": "8786007059413582065",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 5877,
"createdBy": "project:chromium",
"createTime": "2023-03-21T14:09:05.881427543Z",
"updateTime": "2023-03-21T14:09:05.881427543Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cf5398e1f7dad8335741e313b727a8a79a993c7d",
"ref": "refs/heads/main"
}
},
"canary": true
}
[14:09:06.291] Task URL: https://cr-buildbucket.appspot.com/build/8786007059413582065
[14:09:06.291] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:2:0) after 9m35s
[14:10:35.407] Received PubSub notification, asking Buildbucket for the build status
[14:10:35.451] Build status: STARTED
[14:18:41.480] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:2:0)
[14:18:41.506] Build status: STARTED
[14:18:41.506] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:4:0) after 7m40s
[14:26:22.233] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:4:0)
[14:26:22.259] Build status: STARTED
[14:26:22.259] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:5:0) after 2m10s
[14:28:32.398] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:5:0)
[14:28:32.418] Build status: STARTED
[14:28:32.418] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:6:0) after 7m39s
[14:36:11.445] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:6:0)
[14:36:11.469] Build status: STARTED
[14:36:11.469] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:7:0) after 8m15s
[14:44:26.803] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:7:0)
[14:44:26.826] Build status: STARTED
[14:44:26.826] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:8:0) after 6m15s
[14:50:42.048] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:8:0)
[14:50:42.089] Build status: STARTED
[14:50:42.089] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:9:0) after 5m19s
[14:56:01.318] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:9:0)
[14:56:01.342] Build status: STARTED
[14:56:01.342] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:10:0) after 4m19s
[15:00:20.554] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:10:0)
[15:00:20.579] Build status: STARTED
[15:00:20.579] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:11:0) after 1m55s
[15:02:16.118] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:11:0)
[15:02:16.139] Build status: STARTED
[15:02:16.139] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:12:0) after 7m57s
[15:10:13.166] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:12:0)
[15:10:13.251] Build status: STARTED
[15:10:13.251] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:13:0) after 5m33s
[15:15:46.338] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:13:0)
[15:15:46.367] Build status: STARTED
[15:15:46.367] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:14:0) after 7m14s
[15:23:01.079] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:14:0)
[15:23:01.108] Build status: STARTED
[15:23:01.108] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:15:0) after 5m7s
[15:28:08.187] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:15:0)
[15:28:08.215] Build status: STARTED
[15:28:08.215] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:16:0) after 7m41s
[15:35:49.316] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:16:0)
[15:35:49.339] Build status: STARTED
[15:35:49.339] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:17:0) after 1m4s
[15:36:53.716] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:17:0)
[15:36:53.737] Build status: STARTED
[15:36:53.737] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:18:0) after 7m47s
[15:44:40.928] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:18:0)
[15:44:40.959] Build status: STARTED
[15:44:40.960] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:19:0) after 4m31s
[15:49:12.396] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:19:0)
[15:49:12.421] Build status: STARTED
[15:49:12.421] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:20:0) after 4m24s
[15:53:36.431] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:20:0)
[15:53:36.460] Build status: STARTED
[15:53:36.460] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:21:0) after 3m39s
[15:57:15.487] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:21:0)
[15:57:15.505] Build status: STARTED
[15:57:15.505] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:22:0) after 5m23s
[16:02:39.209] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:22:0)
[16:02:39.246] Build status: STARTED
[16:02:39.246] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:23:0) after 9m24s
[16:12:03.284] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:23:0)
[16:12:03.309] Build status: STARTED
[16:12:03.309] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:24:0) after 3m55s
[16:15:58.287] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:24:0)
[16:15:58.334] Build status: STARTED
[16:15:58.334] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:25:0) after 7m17s
[16:23:15.361] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:25:0)
[16:23:15.384] Build status: STARTED
[16:23:15.384] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:26:0) after 3m47s
[16:27:02.441] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:26:0)
[16:27:02.474] Build status: STARTED
[16:27:02.474] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:27:0) after 2m49s
[16:29:51.492] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:27:0)
[16:29:51.514] Build status: STARTED
[16:29:51.514] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:28:0) after 2m43s
[16:32:34.540] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:28:0)
[16:32:34.562] Build status: STARTED
[16:32:34.562] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:29:0) after 9m6s
[16:41:40.588] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:29:0)
[16:41:40.607] Build status: STARTED
[16:41:40.607] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:30:0) after 8m57s
[16:50:37.710] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:30:0)
[16:50:37.736] Build status: STARTED
[16:50:37.736] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:31:0) after 9m14s
[16:59:51.755] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:31:0)
[16:59:51.784] Build status: STARTED
[16:59:51.784] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:32:0) after 1m28s
[17:01:19.816] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:32:0)
[17:01:19.837] Build status: STARTED
[17:01:19.837] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:33:0) after 4m1s
[17:05:20.916] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:33:0)
[17:05:20.971] Build status: STARTED
[17:05:20.971] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:34:0) after 4m27s
[17:09:48.048] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:34:0)
[17:09:48.067] Build status: STARTED
[17:09:48.067] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:35:0) after 2m21s
[17:12:09.556] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:35:0)
[17:12:09.586] Build status: STARTED
[17:12:09.586] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:36:0) after 9m23s
[17:21:32.651] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:36:0)
[17:21:32.674] Build status: STARTED
[17:21:32.674] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:37:0) after 6m56s
[17:28:28.704] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:37:0)
[17:28:28.734] Build status: STARTED
[17:28:28.734] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:38:0) after 6m41s
[17:35:09.812] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:38:0)
[17:35:09.835] Build status: STARTED
[17:35:09.835] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:39:0) after 4m54s
[17:40:03.946] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:39:0)
[17:40:03.967] Build status: STARTED
[17:40:03.967] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:40:0) after 2m58s
[17:43:02.075] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:40:0)
[17:43:02.095] Build status: STARTED
[17:43:02.095] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:41:0) after 7m54s
[17:50:56.205] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:41:0)
[17:50:56.225] Build status: STARTED
[17:50:56.225] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:42:0) after 9m36s
[18:00:32.271] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:42:0)
[18:00:32.305] Build status: STARTED
[18:00:32.305] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:43:0) after 8m31s
[18:09:03.354] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:43:0)
[18:09:03.380] Build status: STARTED
[18:09:03.380] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:44:0) after 5m51s
[18:14:54.403] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:44:0)
[18:14:54.442] Build status: STARTED
[18:14:54.442] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:45:0) after 6m33s
[18:21:27.466] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:45:0)
[18:21:27.485] Build status: STARTED
[18:21:27.486] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:46:0) after 4m48s
[18:26:15.519] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:46:0)
[18:26:15.540] Build status: STARTED
[18:26:15.540] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:47:0) after 8m8s
[18:34:23.692] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:47:0)
[18:34:23.713] Build status: STARTED
[18:34:23.713] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:48:0) after 3m5s
[18:37:28.835] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:48:0)
[18:37:28.910] Build status: STARTED
[18:37:28.910] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:49:0) after 8m46s
[18:46:14.986] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:49:0)
[18:46:15.005] Build status: STARTED
[18:46:15.005] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:50:0) after 8m32s
[18:54:47.077] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:50:0)
[18:54:47.105] Build status: STARTED
[18:54:47.105] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:51:0) after 8m15s
[19:03:02.183] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:51:0)
[19:03:02.221] Build status: STARTED
[19:03:02.221] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:52:0) after 3m9s
[19:06:11.258] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:52:0)
[19:06:11.296] Build status: STARTED
[19:06:11.296] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:53:0) after 6m12s
[19:12:23.342] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:53:0)
[19:12:23.361] Build status: STARTED
[19:12:23.361] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:54:0) after 1m15s
[19:13:38.380] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:54:0)
[19:13:38.408] Build status: STARTED
[19:13:38.408] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:55:0) after 8m11s
[19:21:49.452] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:55:0)
[19:21:49.503] Build status: STARTED
[19:21:49.503] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:56:0) after 3m50s
[19:25:39.553] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:56:0)
[19:25:39.591] Build status: STARTED
[19:25:39.591] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:57:0) after 1m37s
[19:27:16.614] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:57:0)
[19:27:16.649] Build status: STARTED
[19:27:16.649] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:58:0) after 1m20s
[19:28:36.635] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:58:0)
[19:28:36.664] Build status: STARTED
[19:28:36.664] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:59:0) after 6m39s
[19:35:15.690] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:59:0)
[19:35:15.713] Build status: STARTED
[19:35:15.713] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:60:0) after 6m47s
[19:42:02.914] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:60:0)
[19:42:02.978] Build status: STARTED
[19:42:02.978] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:61:0) after 5m33s
[19:47:35.995] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:61:0)
[19:47:36.048] Build status: STARTED
[19:47:36.048] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:62:0) after 9m56s
[19:57:32.565] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:62:0)
[19:57:32.587] Build status: STARTED
[19:57:32.587] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:63:0) after 5m49s
[20:03:21.842] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:63:0)
[20:03:21.869] Build status: STARTED
[20:03:21.869] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:64:0) after 9m2s
[20:12:24.140] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:64:0)
[20:12:24.161] Build status: STARTED
[20:12:24.161] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:65:0) after 4m30s
[20:16:54.194] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:65:0)
[20:16:54.263] Build status: STARTED
[20:16:54.263] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:66:0) after 2m2s
[20:18:56.358] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:66:0)
[20:18:56.389] Build status: STARTED
[20:18:56.389] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:67:0) after 2m11s
[20:21:07.414] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:67:0)
[20:21:07.437] Build status: STARTED
[20:21:07.437] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:68:0) after 3m30s
[20:24:37.462] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:68:0)
[20:24:37.484] Build status: STARTED
[20:24:37.484] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:69:0) after 6m18s
[20:30:55.510] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:69:0)
[20:30:55.536] Build status: STARTED
[20:30:55.536] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:70:0) after 5m55s
[20:36:50.565] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:70:0)
[20:36:50.588] Build status: STARTED
[20:36:50.588] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:71:0) after 4m39s
[20:41:29.785] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:71:0)
[20:41:29.803] Build status: STARTED
[20:41:29.803] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:72:0) after 2m45s
[20:44:15.879] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:72:0)
[20:44:15.904] Build status: STARTED
[20:44:15.904] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:73:0) after 1m33s
[20:45:49.013] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:73:0)
[20:45:49.038] Build status: STARTED
[20:45:49.038] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:74:0) after 8m27s
[20:54:16.133] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:74:0)
[20:54:16.170] Build status: STARTED
[20:54:16.170] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:75:0) after 7m42s
[21:01:58.500] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:75:0)
[21:01:58.519] Build status: STARTED
[21:01:58.520] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:76:0) after 9m45s
[21:11:43.545] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:76:0)
[21:11:43.569] Build status: STARTED
[21:11:43.569] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:77:0) after 8m24s
[21:20:07.859] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:77:0)
[21:20:07.949] Build status: STARTED
[21:20:07.949] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:78:0) after 1m9s
[21:21:17.171] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:78:0)
[21:21:17.295] Build status: STARTED
[21:21:17.295] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:79:0) after 9m20s
[21:30:37.415] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:79:0)
[21:30:37.482] Build status: STARTED
[21:30:37.482] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:80:0) after 4m3s
[21:34:40.703] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:80:0)
[21:34:40.725] Build status: STARTED
[21:34:40.726] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:81:0) after 3m33s
[21:38:13.781] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:81:0)
[21:38:13.801] Build status: STARTED
[21:38:13.801] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:82:0) after 1m30s
[21:39:43.869] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:82:0)
[21:39:43.894] Build status: STARTED
[21:39:43.894] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:83:0) after 6m21s
[21:46:04.935] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:83:0)
[21:46:04.965] Build status: STARTED
[21:46:04.965] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:84:0) after 6m51s
[21:52:56.001] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:84:0)
[21:52:56.050] Build status: STARTED
[21:52:56.050] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:85:0) after 6m14s
[21:59:10.157] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:85:0)
[21:59:10.182] Build status: STARTED
[21:59:10.182] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:86:0) after 7m36s
[22:06:46.520] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:86:0)
[22:06:46.545] Build status: STARTED
[22:06:46.545] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:87:0) after 9m56s
[22:16:42.802] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:87:0)
[22:16:42.825] Build status: STARTED
[22:16:42.825] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:88:0) after 7m52s
[22:24:34.846] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:88:0)
[22:24:34.880] Build status: STARTED
[22:24:34.880] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:89:0) after 8m59s
[22:33:33.999] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:89:0)
[22:33:34.030] Build status: STARTED
[22:33:34.030] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:90:0) after 5m41s
[22:39:15.284] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:90:0)
[22:39:15.307] Build status: STARTED
[22:39:15.307] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:91:0) after 3m36s
[22:42:51.789] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:91:0)
[22:42:51.811] Build status: STARTED
[22:42:51.811] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:92:0) after 6m21s
[22:49:13.643] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:92:0)
[22:49:13.679] Build status: STARTED
[22:49:13.679] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:93:0) after 5m21s
[22:54:34.799] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:93:0)
[22:54:34.821] Build status: STARTED
[22:54:34.821] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:94:0) after 3m12s
[22:57:46.956] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:94:0)
[22:57:46.982] Build status: STARTED
[22:57:46.982] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:95:0) after 3m34s
[23:01:21.005] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:95:0)
[23:01:21.039] Build status: STARTED
[23:01:21.039] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:96:0) after 6m20s
[23:07:41.147] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:96:0)
[23:07:41.196] Build status: STARTED
[23:07:41.196] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:97:0) after 6m4s
[23:13:45.372] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:97:0)
[23:13:45.453] Build status: STARTED
[23:13:45.453] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:98:0) after 7m29s
[23:21:14.861] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:98:0)
[23:21:14.882] Build status: STARTED
[23:21:14.882] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:99:0) after 9m17s
[23:30:31.901] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:99:0)
[23:30:31.931] Build status: STARTED
[23:30:31.931] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:100:0) after 7m4s
[23:37:37.079] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:100:0)
[23:37:37.099] Build status: STARTED
[23:37:37.100] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:101:0) after 5m35s
[23:43:12.131] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:101:0)
[23:43:12.151] Build status: STARTED
[23:43:12.151] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:102:0) after 6m9s
[23:49:21.172] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:102:0)
[23:49:21.200] Build status: STARTED
[23:49:21.200] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:103:0) after 7m5s
[23:56:26.229] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:103:0)
[23:56:26.268] Build status: STARTED
[23:56:26.268] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:104:0) after 7m39s
[00:04:05.487] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:104:0)
[00:04:05.507] Build status: STARTED
[00:04:05.507] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8951437121384391264:105:0) after 7m23s
[00:10:52.208] Received PubSub notification, asking Buildbucket for the build status
[00:10:52.230] Build:
{
"id": "8786007059413582065",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 5877,
"createdBy": "project:chromium",
"createTime": "2023-03-21T14:09:05.881427543Z",
"startTime": "2023-03-21T14:10:33.418553Z",
"endTime": "2023-03-22T00:10:50.632102888Z",
"updateTime": "2023-03-22T00:10:50.632102888Z",
"status": "CANCELED",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cf5398e1f7dad8335741e313b727a8a79a993c7d",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:10:52.230] Invocation finished in 10h1m47.611832659s with status ABORTED