[13:29:10.115] New invocation is queued and will start shortly
[13:29:11.189] Starting the invocation (attempt 1)
[13:29:11.219] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c99933d3524c226b3bf4e8824b3da37a98bc1155"
[13:29:11.220] Popped gitiles commit info from properties and tags
[13:29:11.220] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:29:11.220] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:29:11.220] Buildbucket request:
{
"requestId": "8952617392772554656",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c99933d3524c226b3bf4e8824b3da37a98bc1155",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8952617392772554656"
},
{
"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:29:11.824] Scheduled build:
{
"id": "8787187330890794833",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 5820,
"createdBy": "project:chromium",
"createTime": "2023-03-08T13:29:11.287812964Z",
"updateTime": "2023-03-08T13:29:11.287812964Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c99933d3524c226b3bf4e8824b3da37a98bc1155",
"ref": "refs/heads/main"
}
}
}
[13:29:11.824] Task URL: https://cr-buildbucket.appspot.com/build/8787187330890794833
[13:29:11.825] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:2:0) after 5m36s
[13:29:42.306] Received PubSub notification, asking Buildbucket for the build status
[13:29:42.335] Build status: STARTED
[13:34:47.909] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:2:0)
[13:34:47.930] Build status: STARTED
[13:34:47.930] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:4:0) after 3m25s
[13:38:13.212] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:4:0)
[13:38:13.241] Build status: STARTED
[13:38:13.241] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:5:0) after 9m54s
[13:48:07.266] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:5:0)
[13:48:07.357] Build status: STARTED
[13:48:07.357] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:6:0) after 5m11s
[13:53:18.384] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:6:0)
[13:53:18.441] Build status: STARTED
[13:53:18.441] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:7:0) after 3m0s
[13:56:18.807] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:7:0)
[13:56:18.873] Build status: STARTED
[13:56:18.873] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:8:0) after 7m35s
[14:03:54.010] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:8:0)
[14:03:54.037] Build status: STARTED
[14:03:54.037] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:9:0) after 9m17s
[14:13:11.054] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:9:0)
[14:13:11.074] Build status: STARTED
[14:13:11.074] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:10:0) after 6m43s
[14:19:54.468] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:10:0)
[14:19:54.530] Build status: STARTED
[14:19:54.530] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:11:0) after 6m18s
[14:26:12.601] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:11:0)
[14:26:12.685] Build status: STARTED
[14:26:12.686] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:12:0) after 8m0s
[14:34:12.683] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:12:0)
[14:34:12.707] Build status: STARTED
[14:34:12.707] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:13:0) after 2m46s
[14:36:58.731] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:13:0)
[14:36:58.771] Build status: STARTED
[14:36:58.771] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:14:0) after 3m1s
[14:39:59.913] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:14:0)
[14:39:59.938] Build status: STARTED
[14:39:59.938] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:15:0) after 6m47s
[14:46:47.101] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:15:0)
[14:46:47.127] Build status: STARTED
[14:46:47.127] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:16:0) after 2m47s
[14:49:34.439] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:16:0)
[14:49:34.466] Build status: STARTED
[14:49:34.466] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:17:0) after 4m17s
[14:53:51.510] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:17:0)
[14:53:51.540] Build status: STARTED
[14:53:51.540] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:18:0) after 5m2s
[14:58:53.796] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:18:0)
[14:58:53.868] Build status: STARTED
[14:58:53.868] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:19:0) after 3m52s
[15:02:46.330] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:19:0)
[15:02:46.423] Build status: STARTED
[15:02:46.423] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:20:0) after 8m28s
[15:11:14.441] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:20:0)
[15:11:14.467] Build status: STARTED
[15:11:14.467] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:21:0) after 4m9s
[15:15:23.588] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:21:0)
[15:15:23.610] Build status: STARTED
[15:15:23.610] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:22:0) after 5m37s
[15:21:00.813] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:22:0)
[15:21:00.847] Build status: STARTED
[15:21:00.847] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:23:0) after 7m18s
[15:28:18.928] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:23:0)
[15:28:18.946] Build status: STARTED
[15:28:18.946] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:24:0) after 1m0s
[15:29:19.254] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:24:0)
[15:29:19.279] Build status: STARTED
[15:29:19.279] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:25:0) after 3m36s
[15:32:55.378] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:25:0)
[15:32:55.399] Build status: STARTED
[15:32:55.399] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:26:0) after 5m10s
[15:38:05.421] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:26:0)
[15:38:05.447] Build status: STARTED
[15:38:05.447] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:27:0) after 6m51s
[15:44:56.732] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:27:0)
[15:44:56.777] Build status: STARTED
[15:44:56.777] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:28:0) after 1m6s
[15:46:03.093] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:28:0)
[15:46:03.133] Build status: STARTED
[15:46:03.133] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:29:0) after 4m1s
[15:50:04.636] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:29:0)
[15:50:04.660] Build status: STARTED
[15:50:04.660] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:30:0) after 7m43s
[15:57:47.789] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:30:0)
[15:57:47.820] Build status: STARTED
[15:57:47.820] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:31:0) after 1m16s
[15:59:03.891] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:31:0)
[15:59:03.916] Build status: STARTED
[15:59:03.916] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:32:0) after 2m12s
[16:01:16.074] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:32:0)
[16:01:16.093] Build status: STARTED
[16:01:16.093] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:33:0) after 2m46s
[16:04:02.316] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:33:0)
[16:04:02.358] Build status: STARTED
[16:04:02.358] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:34:0) after 2m25s
[16:06:27.531] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:34:0)
[16:06:27.554] Build status: STARTED
[16:06:27.554] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:35:0) after 1m9s
[16:07:36.683] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:35:0)
[16:07:36.721] Build status: STARTED
[16:07:36.721] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:36:0) after 7m45s
[16:15:21.924] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:36:0)
[16:15:21.965] Build status: STARTED
[16:15:21.965] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:37:0) after 6m10s
[16:21:32.047] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:37:0)
[16:21:32.071] Build status: STARTED
[16:21:32.072] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:38:0) after 5m59s
[16:27:31.713] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:38:0)
[16:27:31.737] Build status: STARTED
[16:27:31.737] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:39:0) after 8m54s
[16:36:25.906] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:39:0)
[16:36:25.925] Build status: STARTED
[16:36:25.925] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:40:0) after 6m56s
[16:43:22.259] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:40:0)
[16:43:22.309] Build status: STARTED
[16:43:22.309] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:41:0) after 1m7s
[16:44:29.361] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:41:0)
[16:44:29.379] Build status: STARTED
[16:44:29.379] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:42:0) after 6m42s
[16:51:11.444] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:42:0)
[16:51:11.479] Build status: STARTED
[16:51:11.479] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:43:0) after 5m0s
[16:56:11.536] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:43:0)
[16:56:11.569] Build status: STARTED
[16:56:11.569] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:44:0) after 1m26s
[16:57:37.688] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:44:0)
[16:57:37.706] Build status: STARTED
[16:57:37.706] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:45:0) after 8m10s
[17:05:47.786] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:45:0)
[17:05:47.815] Build status: STARTED
[17:05:47.815] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:46:0) after 9m5s
[17:14:52.909] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:46:0)
[17:14:52.957] Build status: STARTED
[17:14:52.957] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:47:0) after 6m54s
[17:21:47.209] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:47:0)
[17:21:47.227] Build status: STARTED
[17:21:47.227] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:48:0) after 7m36s
[17:29:23.683] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:48:0)
[17:29:23.703] Build status: STARTED
[17:29:23.703] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:49:0) after 5m57s
[17:35:20.782] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:49:0)
[17:35:20.806] Build status: STARTED
[17:35:20.806] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:50:0) after 8m11s
[17:43:31.884] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:50:0)
[17:43:31.915] Build status: STARTED
[17:43:31.915] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:51:0) after 2m57s
[17:46:28.988] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:51:0)
[17:46:29.012] Build status: STARTED
[17:46:29.012] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:52:0) after 6m7s
[17:52:36.386] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:52:0)
[17:52:36.407] Build status: STARTED
[17:52:36.408] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:53:0) after 8m18s
[18:00:54.661] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:53:0)
[18:00:54.690] Build status: STARTED
[18:00:54.690] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:54:0) after 2m18s
[18:03:12.823] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:54:0)
[18:03:12.912] Build status: STARTED
[18:03:12.913] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:55:0) after 5m34s
[18:08:47.012] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:55:0)
[18:08:47.033] Build status: STARTED
[18:08:47.033] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:56:0) after 3m58s
[18:12:45.153] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:56:0)
[18:12:45.173] Build status: STARTED
[18:12:45.173] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:57:0) after 5m16s
[18:18:01.700] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:57:0)
[18:18:01.742] Build status: STARTED
[18:18:01.742] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:58:0) after 2m17s
[18:20:18.807] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:58:0)
[18:20:18.832] Build status: STARTED
[18:20:18.832] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:59:0) after 8m17s
[18:28:36.030] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:59:0)
[18:28:36.092] Build status: STARTED
[18:28:36.092] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:60:0) after 1m40s
[18:30:16.456] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:60:0)
[18:30:16.502] Build status: STARTED
[18:30:16.502] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:61:0) after 1m2s
[18:31:18.520] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:61:0)
[18:31:18.544] Build status: STARTED
[18:31:18.544] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:62:0) after 7m17s
[18:38:35.785] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:62:0)
[18:38:35.808] Build status: STARTED
[18:38:35.808] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:63:0) after 4m10s
[18:42:45.866] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:63:0)
[18:42:45.887] Build status: STARTED
[18:42:45.887] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:64:0) after 8m26s
[18:51:11.908] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:64:0)
[18:51:11.932] Build status: STARTED
[18:51:11.932] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:65:0) after 4m42s
[18:55:53.953] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:65:0)
[18:55:53.984] Build status: STARTED
[18:55:53.984] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:66:0) after 5m51s
[19:01:45.458] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:66:0)
[19:01:45.539] Build status: STARTED
[19:01:45.539] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:67:0) after 9m39s
[19:11:24.519] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:67:0)
[19:11:24.542] Build status: STARTED
[19:11:24.542] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:68:0) after 4m23s
[19:15:47.616] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:68:0)
[19:15:47.641] Build status: STARTED
[19:15:47.641] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:69:0) after 8m8s
[19:23:55.759] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:69:0)
[19:23:55.782] Build status: STARTED
[19:23:55.782] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:70:0) after 7m37s
[19:31:32.803] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:70:0)
[19:31:33.005] Build status: STARTED
[19:31:33.005] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:71:0) after 5m17s
[19:36:50.142] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:71:0)
[19:36:50.165] Build status: STARTED
[19:36:50.165] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:72:0) after 1m9s
[19:37:59.312] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:72:0)
[19:37:59.542] Build status: STARTED
[19:37:59.542] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:73:0) after 8m10s
[19:46:09.677] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:73:0)
[19:46:09.715] Build status: STARTED
[19:46:09.715] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:74:0) after 5m32s
[19:51:41.745] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:74:0)
[19:51:41.775] Build status: STARTED
[19:51:41.775] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:75:0) after 3m18s
[19:54:59.996] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:75:0)
[19:55:00.099] Build status: STARTED
[19:55:00.100] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:76:0) after 7m12s
[20:02:12.142] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:76:0)
[20:02:12.194] Build status: STARTED
[20:02:12.194] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:77:0) after 9m8s
[20:11:20.262] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:77:0)
[20:11:20.282] Build status: STARTED
[20:11:20.282] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:78:0) after 3m40s
[20:15:00.340] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:78:0)
[20:15:00.375] Build status: STARTED
[20:15:00.375] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:79:0) after 5m26s
[20:20:26.514] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:79:0)
[20:20:26.535] Build status: STARTED
[20:20:26.536] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:80:0) after 1m45s
[20:22:11.637] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:80:0)
[20:22:11.658] Build status: STARTED
[20:22:11.658] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:81:0) after 6m14s
[20:28:25.731] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:81:0)
[20:28:25.921] Build status: STARTED
[20:28:25.921] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:82:0) after 7m16s
[20:35:41.941] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:82:0)
[20:35:42.003] Build status: STARTED
[20:35:42.003] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:83:0) after 4m7s
[20:39:49.121] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:83:0)
[20:39:49.142] Build status: STARTED
[20:39:49.142] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:84:0) after 2m35s
[20:42:24.169] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:84:0)
[20:42:24.197] Build status: STARTED
[20:42:24.197] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:85:0) after 9m3s
[20:51:27.331] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:85:0)
[20:51:27.351] Build status: STARTED
[20:51:27.351] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:86:0) after 7m13s
[20:58:40.380] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:86:0)
[20:58:40.402] Build status: STARTED
[20:58:40.402] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:87:0) after 1m56s
[21:00:36.454] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:87:0)
[21:00:36.484] Build status: STARTED
[21:00:36.484] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:88:0) after 6m39s
[21:07:15.660] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:88:0)
[21:07:15.688] Build status: STARTED
[21:07:15.688] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:89:0) after 8m4s
[21:15:19.718] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:89:0)
[21:15:19.743] Build status: STARTED
[21:15:19.743] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:90:0) after 7m0s
[21:22:19.768] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:90:0)
[21:22:19.787] Build status: STARTED
[21:22:19.787] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:91:0) after 7m17s
[21:29:36.809] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:91:0)
[21:29:36.829] Build status: STARTED
[21:29:36.829] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:92:0) after 6m20s
[21:35:56.952] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:92:0)
[21:35:56.971] Build status: STARTED
[21:35:56.971] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:93:0) after 1m41s
[21:37:38.291] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:93:0)
[21:37:38.309] Build status: STARTED
[21:37:38.309] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:94:0) after 7m51s
[21:45:29.412] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:94:0)
[21:45:29.432] Build status: STARTED
[21:45:29.432] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:95:0) after 6m57s
[21:52:26.467] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:95:0)
[21:52:26.487] Build status: STARTED
[21:52:26.487] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:96:0) after 8m42s
[22:01:08.546] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:96:0)
[22:01:08.568] Build status: STARTED
[22:01:08.568] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:97:0) after 8m56s
[22:10:04.615] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:97:0)
[22:10:04.642] Build status: STARTED
[22:10:04.642] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:98:0) after 7m52s
[22:17:56.684] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:98:0)
[22:17:56.710] Build status: STARTED
[22:17:56.710] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:99:0) after 3m29s
[22:21:25.828] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:99:0)
[22:21:25.850] Build status: STARTED
[22:21:25.850] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:100:0) after 1m12s
[22:22:37.876] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:100:0)
[22:22:37.895] Build status: STARTED
[22:22:37.895] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:101:0) after 1m53s
[22:24:30.892] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:101:0)
[22:24:30.935] Build status: STARTED
[22:24:30.935] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:102:0) after 3m17s
[22:27:48.218] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:102:0)
[22:27:48.250] Build status: STARTED
[22:27:48.250] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:103:0) after 8m0s
[22:35:48.270] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:103:0)
[22:35:48.293] Build status: STARTED
[22:35:48.293] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:104:0) after 2m11s
[22:37:59.316] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:104:0)
[22:37:59.340] Build status: STARTED
[22:37:59.340] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:105:0) after 6m22s
[22:44:21.371] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:105:0)
[22:44:21.413] Build status: STARTED
[22:44:21.413] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:106:0) after 1m21s
[22:45:42.531] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:106:0)
[22:45:42.571] Build status: STARTED
[22:45:42.571] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:107:0) after 6m57s
[22:52:39.646] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:107:0)
[22:52:39.666] Build status: STARTED
[22:52:39.666] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:108:0) after 6m19s
[22:58:59.197] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:108:0)
[22:58:59.223] Build status: STARTED
[22:58:59.223] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:109:0) after 5m2s
[23:04:01.242] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:109:0)
[23:04:01.266] Build status: STARTED
[23:04:01.266] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:110:0) after 7m49s
[23:11:50.245] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:110:0)
[23:11:50.286] Build status: STARTED
[23:11:50.286] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:111:0) after 4m10s
[23:16:00.519] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:111:0)
[23:16:00.541] Build status: STARTED
[23:16:00.541] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:112:0) after 5m34s
[23:21:34.561] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:112:0)
[23:21:34.596] Build status: STARTED
[23:21:34.596] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:113:0) after 7m17s
[23:28:52.023] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:113:0)
[23:28:52.049] Build status: STARTED
[23:28:52.049] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8952617392772554656:114:0) after 7m54s
[23:29:53.664] Received PubSub notification, asking Buildbucket for the build status
[23:29:53.685] Build:
{
"id": "8787187330890794833",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 5820,
"createdBy": "project:chromium",
"createTime": "2023-03-08T13:29:11.287812964Z",
"startTime": "2023-03-08T13:29:41.784015Z",
"endTime": "2023-03-08T23:29:53.375814004Z",
"updateTime": "2023-03-08T23:29:53.375814004Z",
"status": "CANCELED",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c99933d3524c226b3bf4e8824b3da37a98bc1155",
"ref": "refs/heads/main"
}
}
}
[23:29:53.685] Invocation finished in 10h0m43.577892273s with status ABORTED