[19:04:12.100] New invocation is queued and will start shortly
[19:04:13.234] Starting the invocation (attempt 1)
[19:04:13.281] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/f3edbb644de52b0fbbd8697f4de65d9f07f55be6"
[19:04:13.281] Popped gitiles commit info from properties and tags
[19:04:13.281] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:04:13.281] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:04:13.281] Buildbucket request:
{
"requestId": "8988835100883548064",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "f3edbb644de52b0fbbd8697f4de65d9f07f55be6"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@f3edbb644de52b0fbbd8697f4de65d9f07f55be6",
"title": "f3edbb644de52b0fbbd8697f4de65d9f07f55be6",
"url": "https://r8.googlesource.com/r8/+/f3edbb644de52b0fbbd8697f4de65d9f07f55be6"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "f3edbb644de52b0fbbd8697f4de65d9f07f55be6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8988835100883548064"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-internal"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:04:13.810] Scheduled build:
{
"id": "8823405038879505009",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"number": 2333,
"createdBy": "project:r8",
"createTime": "2022-02-01T19:04:13.379343243Z",
"updateTime": "2022-02-01T19:04:13.379343243Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "f3edbb644de52b0fbbd8697f4de65d9f07f55be6",
"ref": "refs/heads/main"
}
}
}
[19:04:13.810] Task URL: https://cr-buildbucket.appspot.com/build/8823405038879505009
[19:04:13.811] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:2:0) after 1m10s
[19:05:23.804] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:2:0)
[19:05:23.839] Build status: SCHEDULED
[19:05:23.839] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:3:0) after 2m24s
[19:07:47.857] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:3:0)
[19:07:47.880] Build status: SCHEDULED
[19:07:47.880] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:4:0) after 6m18s
[19:14:05.902] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:4:0)
[19:14:05.937] Build status: SCHEDULED
[19:14:05.937] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:5:0) after 5m28s
[19:19:33.955] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:5:0)
[19:19:33.975] Build status: SCHEDULED
[19:19:33.975] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:6:0) after 1m50s
[19:21:24.008] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:6:0)
[19:21:24.107] Build status: SCHEDULED
[19:21:24.107] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:7:0) after 5m3s
[19:26:27.333] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:7:0)
[19:26:27.364] Build status: SCHEDULED
[19:26:27.364] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:8:0) after 6m44s
[19:33:11.662] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:8:0)
[19:33:11.719] Build status: SCHEDULED
[19:33:11.719] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:9:0) after 9m59s
[19:43:10.744] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:9:0)
[19:43:10.776] Build status: SCHEDULED
[19:43:10.776] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:10:0) after 6m2s
[19:49:12.797] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:10:0)
[19:49:12.872] Build status: SCHEDULED
[19:49:12.872] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:11:0) after 8m5s
[19:57:17.891] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:11:0)
[19:57:17.963] Build status: SCHEDULED
[19:57:17.963] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:12:0) after 7m4s
[20:04:22.099] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:12:0)
[20:04:22.128] Build status: SCHEDULED
[20:04:22.128] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:13:0) after 9m33s
[20:13:55.202] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:13:0)
[20:13:55.232] Build status: SCHEDULED
[20:13:55.232] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:14:0) after 9m52s
[20:23:47.416] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:14:0)
[20:23:47.444] Build status: SCHEDULED
[20:23:47.444] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:15:0) after 6m54s
[20:30:41.551] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:15:0)
[20:30:41.575] Build status: SCHEDULED
[20:30:41.575] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:16:0) after 4m42s
[20:35:23.630] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:16:0)
[20:35:23.673] Build status: SCHEDULED
[20:35:23.673] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:17:0) after 6m56s
[20:42:19.889] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:17:0)
[20:42:19.932] Build status: SCHEDULED
[20:42:19.932] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:18:0) after 9m42s
[20:52:02.125] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:18:0)
[20:52:02.198] Build status: SCHEDULED
[20:52:02.198] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:19:0) after 2m34s
[20:54:36.347] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:19:0)
[20:54:36.381] Build status: SCHEDULED
[20:54:36.381] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:20:0) after 5m55s
[21:00:31.570] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:20:0)
[21:00:31.600] Build status: SCHEDULED
[21:00:31.600] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:21:0) after 4m18s
[21:04:49.571] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:21:0)
[21:04:49.600] Build status: SCHEDULED
[21:04:49.600] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:22:0) after 3m44s
[21:08:33.637] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:22:0)
[21:08:33.672] Build status: SCHEDULED
[21:08:33.672] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:23:0) after 2m47s
[21:11:20.781] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:23:0)
[21:11:20.816] Build status: SCHEDULED
[21:11:20.816] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:24:0) after 1m55s
[21:13:15.864] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:24:0)
[21:13:15.888] Build status: SCHEDULED
[21:13:15.888] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:25:0) after 8m13s
[21:21:28.999] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:25:0)
[21:21:29.032] Build status: SCHEDULED
[21:21:29.032] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:26:0) after 4m9s
[21:25:38.189] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:26:0)
[21:25:38.221] Build status: SCHEDULED
[21:25:38.221] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:27:0) after 1m5s
[21:26:43.749] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:27:0)
[21:26:43.811] Build status: SCHEDULED
[21:26:43.811] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:28:0) after 9m49s
[21:36:33.163] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:28:0)
[21:36:33.198] Build status: SCHEDULED
[21:36:33.198] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:29:0) after 5m0s
[21:41:33.384] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:29:0)
[21:41:33.422] Build status: SCHEDULED
[21:41:33.422] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:30:0) after 7m14s
[21:48:47.484] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:30:0)
[21:48:47.505] Build status: SCHEDULED
[21:48:47.505] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:31:0) after 7m17s
[21:56:04.624] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:31:0)
[21:56:04.682] Build status: SCHEDULED
[21:56:04.682] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:32:0) after 9m11s
[22:05:15.798] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:32:0)
[22:05:15.831] Build status: SCHEDULED
[22:05:15.831] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:33:0) after 5m12s
[22:10:28.403] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:33:0)
[22:10:28.437] Build status: SCHEDULED
[22:10:28.437] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:34:0) after 6m25s
[22:16:53.576] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:34:0)
[22:16:53.610] Build status: SCHEDULED
[22:16:53.610] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:35:0) after 8m26s
[22:25:19.583] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:35:0)
[22:25:19.681] Build status: SCHEDULED
[22:25:19.681] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:36:0) after 2m6s
[22:27:25.865] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:36:0)
[22:27:25.899] Build status: SCHEDULED
[22:27:25.899] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:37:0) after 6m6s
[22:33:32.165] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:37:0)
[22:33:32.194] Build status: SCHEDULED
[22:33:32.194] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:38:0) after 6m53s
[22:40:25.220] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:38:0)
[22:40:25.257] Build status: SCHEDULED
[22:40:25.257] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:39:0) after 8m31s
[22:48:56.279] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:39:0)
[22:48:56.315] Build status: SCHEDULED
[22:48:56.315] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:40:0) after 2m49s
[22:51:45.334] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:40:0)
[22:51:45.365] Build status: SCHEDULED
[22:51:45.365] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:41:0) after 4m21s
[22:56:06.484] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:41:0)
[22:56:06.522] Build status: SCHEDULED
[22:56:06.522] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:42:0) after 4m15s
[23:00:21.542] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:42:0)
[23:00:21.574] Build status: SCHEDULED
[23:00:21.574] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:43:0) after 2m40s
[23:03:01.660] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:43:0)
[23:03:01.698] Build status: SCHEDULED
[23:03:01.698] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:44:0) after 1m7s
[23:04:08.919] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:44:0)
[23:04:08.964] Build status: SCHEDULED
[23:04:08.964] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:45:0) after 1m20s
[23:05:29.013] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:45:0)
[23:05:29.048] Build status: SCHEDULED
[23:05:29.048] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:46:0) after 4m42s
[23:10:11.072] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:46:0)
[23:10:11.106] Build status: SCHEDULED
[23:10:11.106] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:47:0) after 2m52s
[23:13:03.273] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:47:0)
[23:13:03.311] Build status: SCHEDULED
[23:13:03.311] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:48:0) after 4m29s
[23:17:32.299] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:48:0)
[23:17:32.332] Build status: SCHEDULED
[23:17:32.332] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:49:0) after 2m17s
[23:19:49.350] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:49:0)
[23:19:49.378] Build status: SCHEDULED
[23:19:49.378] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:50:0) after 7m8s
[23:26:57.508] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:50:0)
[23:26:57.536] Build status: SCHEDULED
[23:26:57.536] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:51:0) after 1m51s
[23:28:48.753] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:51:0)
[23:28:48.786] Build status: SCHEDULED
[23:28:48.786] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:52:0) after 5m35s
[23:34:23.809] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:52:0)
[23:34:23.838] Build status: SCHEDULED
[23:34:23.838] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:53:0) after 2m3s
[23:36:27.087] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:53:0)
[23:36:27.113] Build status: SCHEDULED
[23:36:27.113] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:54:0) after 3m14s
[23:39:41.259] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:54:0)
[23:39:41.599] Build status: SCHEDULED
[23:39:41.599] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:55:0) after 2m14s
[23:41:55.619] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:55:0)
[23:41:55.651] Build status: SCHEDULED
[23:41:55.651] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:56:0) after 6m35s
[23:48:30.672] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:56:0)
[23:48:30.702] Build status: SCHEDULED
[23:48:30.702] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:57:0) after 1m38s
[23:50:08.791] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:57:0)
[23:50:08.820] Build status: SCHEDULED
[23:50:08.820] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:58:0) after 6m38s
[23:56:46.858] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:58:0)
[23:56:46.893] Build status: SCHEDULED
[23:56:46.893] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:59:0) after 4m22s
[00:01:09.044] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:59:0)
[00:01:09.086] Build status: SCHEDULED
[00:01:09.086] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:60:0) after 4m42s
[00:05:51.099] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:60:0)
[00:05:51.168] Build status: SCHEDULED
[00:05:51.168] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:61:0) after 1m35s
[00:07:26.291] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:61:0)
[00:07:26.470] Build status: SCHEDULED
[00:07:26.470] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:62:0) after 9m12s
[00:16:38.600] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:62:0)
[00:16:38.702] Build status: SCHEDULED
[00:16:38.702] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:63:0) after 3m59s
[00:20:37.904] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:63:0)
[00:20:37.949] Build status: SCHEDULED
[00:20:37.949] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:64:0) after 7m0s
[00:27:38.242] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:64:0)
[00:27:38.366] Build status: SCHEDULED
[00:27:38.366] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:65:0) after 8m36s
[00:36:14.543] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:65:0)
[00:36:14.591] Build status: SCHEDULED
[00:36:14.591] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:66:0) after 4m8s
[00:40:22.574] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:66:0)
[00:40:22.607] Build status: SCHEDULED
[00:40:22.607] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:67:0) after 4m48s
[00:45:10.990] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:67:0)
[00:45:11.027] Build status: SCHEDULED
[00:45:11.027] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:68:0) after 6m39s
[00:51:50.292] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:68:0)
[00:51:50.347] Build status: SCHEDULED
[00:51:50.347] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:69:0) after 9m2s
[01:00:52.369] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:69:0)
[01:00:52.412] Build status: SCHEDULED
[01:00:52.412] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:70:0) after 4m51s
[01:05:43.722] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:70:0)
[01:05:43.758] Build status: SCHEDULED
[01:05:43.758] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:71:0) after 5m23s
[01:11:06.776] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:71:0)
[01:11:06.810] Build status: SCHEDULED
[01:11:06.810] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:72:0) after 2m12s
[01:13:18.831] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:72:0)
[01:13:18.893] Build status: SCHEDULED
[01:13:18.893] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:73:0) after 1m33s
[01:14:52.051] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:73:0)
[01:14:52.073] Build status: SCHEDULED
[01:14:52.073] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:74:0) after 3m52s
[01:18:23.567] Received PubSub notification, asking Buildbucket for the build status
[01:18:23.603] Build status: STARTED
[01:18:44.318] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:74:0)
[01:18:44.371] Build status: STARTED
[01:18:44.371] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:76:0) after 7m29s
[01:26:13.393] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:76:0)
[01:26:13.426] Build status: STARTED
[01:26:13.426] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:77:0) after 2m56s
[01:29:09.538] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:77:0)
[01:29:09.576] Build status: STARTED
[01:29:09.576] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:78:0) after 2m19s
[01:31:28.580] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:78:0)
[01:31:28.609] Build status: STARTED
[01:31:28.609] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:79:0) after 3m50s
[01:35:18.589] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:79:0)
[01:35:18.621] Build status: STARTED
[01:35:18.621] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:80:0) after 7m20s
[01:42:38.669] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:80:0)
[01:42:38.700] Build status: STARTED
[01:42:38.701] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:81:0) after 8m45s
[01:51:23.757] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:81:0)
[01:51:23.824] Build status: STARTED
[01:51:23.824] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:82:0) after 5m58s
[01:57:22.020] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:82:0)
[01:57:22.041] Build status: STARTED
[01:57:22.041] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:83:0) after 2m26s
[01:59:48.048] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:83:0)
[01:59:48.099] Build status: STARTED
[01:59:48.099] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:84:0) after 7m36s
[02:07:24.270] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:84:0)
[02:07:24.340] Build status: STARTED
[02:07:24.340] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:85:0) after 1m44s
[02:09:08.364] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:85:0)
[02:09:08.396] Build status: STARTED
[02:09:08.396] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:86:0) after 3m23s
[02:12:31.491] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:86:0)
[02:12:31.528] Build status: STARTED
[02:12:31.528] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:87:0) after 4m52s
[02:17:23.549] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:87:0)
[02:17:23.585] Build status: STARTED
[02:17:23.585] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:88:0) after 2m35s
[02:19:58.606] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:88:0)
[02:19:58.644] Build status: STARTED
[02:19:58.644] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:89:0) after 3m45s
[02:23:43.704] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:89:0)
[02:23:43.734] Build status: STARTED
[02:23:43.734] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:90:0) after 9m57s
[02:33:40.871] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:90:0)
[02:33:40.964] Build status: STARTED
[02:33:40.964] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:91:0) after 4m6s
[02:37:47.320] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:91:0)
[02:37:47.353] Build status: STARTED
[02:37:47.353] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:92:0) after 9m14s
[02:47:01.455] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:92:0)
[02:47:01.494] Build status: STARTED
[02:47:01.494] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:93:0) after 8m0s
[02:55:01.516] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:93:0)
[02:55:01.551] Build status: STARTED
[02:55:01.551] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:94:0) after 8m38s
[03:03:39.766] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:94:0)
[03:03:39.790] Build status: STARTED
[03:03:39.790] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:95:0) after 3m29s
[03:07:08.809] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:95:0)
[03:07:08.830] Build status: STARTED
[03:07:08.830] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:96:0) after 3m6s
[03:10:14.962] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:96:0)
[03:10:14.993] Build status: STARTED
[03:10:14.993] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:97:0) after 4m54s
[03:15:09.025] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:97:0)
[03:15:09.054] Build status: STARTED
[03:15:09.054] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:98:0) after 5m1s
[03:20:10.169] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:98:0)
[03:20:10.209] Build status: STARTED
[03:20:10.209] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:99:0) after 4m53s
[03:25:03.229] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:99:0)
[03:25:03.253] Build status: STARTED
[03:25:03.253] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:100:0) after 9m12s
[03:34:15.405] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:100:0)
[03:34:15.485] Build status: STARTED
[03:34:15.485] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:101:0) after 8m7s
[03:42:22.661] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:101:0)
[03:42:22.719] Build status: STARTED
[03:42:22.719] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:102:0) after 2m52s
[03:45:14.736] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:102:0)
[03:45:14.764] Build status: STARTED
[03:45:14.764] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:103:0) after 2m11s
[03:47:25.936] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:103:0)
[03:47:25.971] Build status: STARTED
[03:47:25.971] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8988835100883548064:104:0) after 9m4s
[03:54:33.047] Received PubSub notification, asking Buildbucket for the build status
[03:54:33.082] Build:
{
"id": "8823405038879505009",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal"
},
"number": 2333,
"createdBy": "project:r8",
"createTime": "2022-02-01T19:04:13.379343243Z",
"startTime": "2022-02-02T01:18:22.908578Z",
"endTime": "2022-02-02T03:54:32.503893083Z",
"updateTime": "2022-02-02T03:54:32.503893083Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "f3edbb644de52b0fbbd8697f4de65d9f07f55be6",
"ref": "refs/heads/main"
}
}
}
[03:54:33.082] Invocation finished in 8h50m20.997438907s with status FAILED