[08:56:02.017] New invocation is queued and will start shortly
[08:56:03.167] Starting the invocation (attempt 1)
[08:56:03.187] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/7627cbbeb60773baf10d691576a62f4257add330"
[08:56:03.187] Popped gitiles commit info from properties and tags
[08:56:03.187] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:56:03.187] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:56:03.187] Buildbucket request:
{
"requestId": "8861131640873903312",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal_release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8861131640873903312",
"job": "r8/linux-internal_release",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/9.1",
"repo": "https://r8.googlesource.com/r8",
"revision": "7627cbbeb60773baf10d691576a62f4257add330"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/9.1@7627cbbeb60773baf10d691576a62f4257add330",
"title": "7627cbbeb60773baf10d691576a62f4257add330",
"url": "https://r8.googlesource.com/r8/+/7627cbbeb60773baf10d691576a62f4257add330"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "7627cbbeb60773baf10d691576a62f4257add330",
"ref": "refs/heads/9.1"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8861131640873903312"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-internal_release"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[08:56:03.666] Scheduled build:
{
"id": "8695701578940452033",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal_release"
},
"number": 1547,
"createdBy": "project:r8",
"createTime": "2025-12-12T08:56:03.239781876Z",
"updateTime": "2025-12-12T08:56:03.239781876Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "7627cbbeb60773baf10d691576a62f4257add330",
"ref": "refs/heads/9.1"
}
}
}
[08:56:03.666] Task URL: https://cr-buildbucket.appspot.com/build/8695701578940452033
[08:56:03.666] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:2:0) after 6m32s
[08:56:04.578] Received PubSub notification, asking Buildbucket for the build status
[08:56:04.625] Build status: SCHEDULED
[09:02:35.667] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:2:0)
[09:02:35.830] Build status: SCHEDULED
[09:02:35.830] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:4:0) after 5m18s
[09:07:53.844] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:4:0)
[09:07:53.873] Build status: SCHEDULED
[09:07:53.873] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:5:0) after 4m20s
[09:12:13.882] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:5:0)
[09:12:13.914] Build status: SCHEDULED
[09:12:13.914] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:6:0) after 2m21s
[09:14:34.931] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:6:0)
[09:14:34.955] Build status: SCHEDULED
[09:14:34.955] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:7:0) after 6m24s
[09:20:58.984] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:7:0)
[09:20:59.010] Build status: SCHEDULED
[09:20:59.010] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:8:0) after 9m45s
[09:30:44.144] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:8:0)
[09:30:44.184] Build status: SCHEDULED
[09:30:44.184] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:9:0) after 8m58s
[09:39:42.297] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:9:0)
[09:39:42.321] Build status: SCHEDULED
[09:39:42.321] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:10:0) after 2m9s
[09:41:51.363] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:10:0)
[09:41:51.396] Build status: SCHEDULED
[09:41:51.396] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:11:0) after 6m25s
[09:48:16.405] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:11:0)
[09:48:16.419] Build status: SCHEDULED
[09:48:16.419] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:12:0) after 8m42s
[09:50:11.145] Received PubSub notification, asking Buildbucket for the build status
[09:50:11.166] Build status: STARTED
[09:56:58.728] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:12:0)
[09:56:58.752] Build status: STARTED
[09:56:58.753] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:14:0) after 6m53s
[10:03:51.765] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:14:0)
[10:03:51.783] Build status: STARTED
[10:03:51.783] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:15:0) after 5m11s
[10:09:02.796] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:15:0)
[10:09:02.812] Build status: STARTED
[10:09:02.812] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:16:0) after 5m34s
[10:14:36.824] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:16:0)
[10:14:36.841] Build status: STARTED
[10:14:36.841] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:17:0) after 2m54s
[10:17:30.901] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:17:0)
[10:17:30.916] Build status: STARTED
[10:17:30.916] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:18:0) after 3m35s
[10:21:05.971] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:18:0)
[10:21:05.987] Build status: STARTED
[10:21:05.987] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:19:0) after 3m13s
[10:24:19.027] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:19:0)
[10:24:19.078] Build status: STARTED
[10:24:19.078] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:20:0) after 6m18s
[10:30:37.088] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:20:0)
[10:30:37.113] Build status: STARTED
[10:30:37.113] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:21:0) after 9m16s
[10:39:53.114] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:21:0)
[10:39:53.142] Build status: STARTED
[10:39:53.142] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:22:0) after 3m51s
[10:43:44.155] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:22:0)
[10:43:44.169] Build status: STARTED
[10:43:44.169] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:23:0) after 9m49s
[10:53:33.179] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:23:0)
[10:53:33.202] Build status: STARTED
[10:53:33.202] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:24:0) after 7m8s
[11:00:41.215] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:24:0)
[11:00:41.242] Build status: STARTED
[11:00:41.242] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:25:0) after 8m10s
[11:08:51.305] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:25:0)
[11:08:51.352] Build status: STARTED
[11:08:51.352] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:26:0) after 9m11s
[11:18:02.393] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:26:0)
[11:18:02.537] Build status: STARTED
[11:18:02.537] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:27:0) after 3m41s
[11:21:43.549] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:27:0)
[11:21:43.565] Build status: STARTED
[11:21:43.565] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:28:0) after 2m15s
[11:23:58.631] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:28:0)
[11:23:58.711] Build status: STARTED
[11:23:58.711] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:29:0) after 3m31s
[11:27:29.824] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:29:0)
[11:27:29.863] Build status: STARTED
[11:27:29.863] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:30:0) after 6m14s
[11:33:43.880] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:30:0)
[11:33:43.900] Build status: STARTED
[11:33:43.900] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:31:0) after 9m37s
[11:43:20.917] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:31:0)
[11:43:20.991] Build status: STARTED
[11:43:20.991] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:32:0) after 3m20s
[11:46:41.055] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:32:0)
[11:46:41.081] Build status: STARTED
[11:46:41.081] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:33:0) after 8m4s
[11:54:45.092] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:33:0)
[11:54:45.133] Build status: STARTED
[11:54:45.134] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:34:0) after 6m17s
[12:01:02.108] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:34:0)
[12:01:02.130] Build status: STARTED
[12:01:02.130] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:35:0) after 7m20s
[12:08:22.145] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:35:0)
[12:08:22.167] Build status: STARTED
[12:08:22.168] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:36:0) after 5m36s
[12:13:58.215] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:36:0)
[12:13:58.232] Build status: STARTED
[12:13:58.232] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:37:0) after 9m53s
[12:23:51.275] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:37:0)
[12:23:51.296] Build status: STARTED
[12:23:51.296] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:38:0) after 9m1s
[12:32:52.356] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:38:0)
[12:32:52.372] Build status: STARTED
[12:32:52.372] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:39:0) after 5m16s
[12:38:08.569] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:39:0)
[12:38:08.590] Build status: STARTED
[12:38:08.590] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:40:0) after 8m10s
[12:46:18.561] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:40:0)
[12:46:18.577] Build status: STARTED
[12:46:18.577] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:41:0) after 8m18s
[12:54:36.620] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:41:0)
[12:54:36.662] Build status: STARTED
[12:54:36.663] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:42:0) after 4m44s
[12:59:20.754] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:42:0)
[12:59:20.771] Build status: STARTED
[12:59:20.771] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:43:0) after 3m0s
[13:02:20.836] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:43:0)
[13:02:20.857] Build status: STARTED
[13:02:20.857] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:44:0) after 7m26s
[13:09:46.925] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:44:0)
[13:09:46.945] Build status: STARTED
[13:09:46.945] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:45:0) after 1m23s
[13:11:09.959] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:45:0)
[13:11:09.975] Build status: STARTED
[13:11:09.975] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:46:0) after 2m27s
[13:13:37.196] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:46:0)
[13:13:37.266] Build status: STARTED
[13:13:37.266] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:47:0) after 7m44s
[13:21:21.331] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:47:0)
[13:21:21.354] Build status: STARTED
[13:21:21.354] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:48:0) after 6m49s
[13:28:10.384] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:48:0)
[13:28:10.400] Build status: STARTED
[13:28:10.400] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:49:0) after 8m32s
[13:36:42.487] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:49:0)
[13:36:42.503] Build status: STARTED
[13:36:42.503] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:50:0) after 9m31s
[13:46:13.512] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:50:0)
[13:46:13.532] Build status: STARTED
[13:46:13.532] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:51:0) after 8m44s
[13:54:57.551] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:51:0)
[13:54:57.578] Build status: STARTED
[13:54:57.578] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:52:0) after 5m50s
[14:00:47.615] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:52:0)
[14:00:47.641] Build status: STARTED
[14:00:47.641] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:53:0) after 4m29s
[14:05:16.657] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:53:0)
[14:05:16.811] Build status: STARTED
[14:05:16.811] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:54:0) after 9m32s
[14:14:48.873] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:54:0)
[14:14:48.893] Build status: STARTED
[14:14:48.893] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:55:0) after 3m10s
[14:17:58.955] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:55:0)
[14:17:58.972] Build status: STARTED
[14:17:58.972] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:56:0) after 3m45s
[14:21:44.002] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:56:0)
[14:21:44.034] Build status: STARTED
[14:21:44.034] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:57:0) after 9m9s
[14:30:53.195] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:57:0)
[14:30:53.222] Build status: STARTED
[14:30:53.222] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:58:0) after 9m31s
[14:40:24.304] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:58:0)
[14:40:24.375] Build status: STARTED
[14:40:24.375] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:59:0) after 9m3s
[14:49:27.442] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:59:0)
[14:49:27.459] Build status: STARTED
[14:49:27.459] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:60:0) after 6m15s
[14:55:42.540] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:60:0)
[14:55:42.564] Build status: STARTED
[14:55:42.564] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:61:0) after 5m34s
[15:01:16.634] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:61:0)
[15:01:16.843] Build status: STARTED
[15:01:16.843] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:62:0) after 6m59s
[15:08:15.928] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:62:0)
[15:08:15.944] Build status: STARTED
[15:08:15.944] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:63:0) after 5m18s
[15:13:33.955] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:63:0)
[15:13:33.975] Build status: STARTED
[15:13:33.975] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:64:0) after 1m19s
[15:14:52.986] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:64:0)
[15:14:53.011] Build status: STARTED
[15:14:53.011] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:65:0) after 5m5s
[15:19:58.073] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:65:0)
[15:19:58.097] Build status: STARTED
[15:19:58.097] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:66:0) after 2m35s
[15:22:33.244] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:66:0)
[15:22:33.258] Build status: STARTED
[15:22:33.258] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:67:0) after 5m11s
[15:27:44.386] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:67:0)
[15:27:44.398] Build status: STARTED
[15:27:44.398] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:68:0) after 5m29s
[15:33:13.415] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:68:0)
[15:33:13.462] Build status: STARTED
[15:33:13.462] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:69:0) after 8m29s
[15:41:42.526] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:69:0)
[15:41:42.542] Build status: STARTED
[15:41:42.542] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:70:0) after 1m36s
[15:43:18.600] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:70:0)
[15:43:18.630] Build status: STARTED
[15:43:18.630] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:71:0) after 1m24s
[15:44:42.698] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:71:0)
[15:44:42.747] Build status: STARTED
[15:44:42.747] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:72:0) after 7m8s
[15:51:50.965] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:72:0)
[15:51:50.980] Build status: STARTED
[15:51:50.980] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:73:0) after 7m38s
[15:59:28.988] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:73:0)
[15:59:29.008] Build status: STARTED
[15:59:29.008] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:74:0) after 9m8s
[16:08:37.132] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:74:0)
[16:08:37.147] Build status: STARTED
[16:08:37.147] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:75:0) after 1m20s
[16:09:57.189] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:75:0)
[16:09:57.207] Build status: STARTED
[16:09:57.207] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:76:0) after 4m53s
[16:14:50.274] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:76:0)
[16:14:50.291] Build status: STARTED
[16:14:50.291] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:77:0) after 2m19s
[16:17:09.303] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:77:0)
[16:17:09.326] Build status: STARTED
[16:17:09.326] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:78:0) after 7m52s
[16:25:01.398] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:78:0)
[16:25:01.419] Build status: STARTED
[16:25:01.419] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:79:0) after 3m20s
[16:28:21.434] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:79:0)
[16:28:21.455] Build status: STARTED
[16:28:21.455] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:80:0) after 3m14s
[16:31:35.488] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:80:0)
[16:31:35.504] Build status: STARTED
[16:31:35.505] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:81:0) after 8m18s
[16:39:53.654] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:81:0)
[16:39:53.719] Build status: STARTED
[16:39:53.719] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:82:0) after 3m45s
[16:43:38.732] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:82:0)
[16:43:38.755] Build status: STARTED
[16:43:38.755] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:83:0) after 1m43s
[16:45:21.813] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:83:0)
[16:45:21.830] Build status: STARTED
[16:45:21.830] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:84:0) after 2m34s
[16:47:55.901] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:84:0)
[16:47:55.916] Build status: STARTED
[16:47:55.916] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:85:0) after 5m28s
[16:53:23.996] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:85:0)
[16:53:24.051] Build status: STARTED
[16:53:24.052] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:86:0) after 4m57s
[16:58:21.063] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:86:0)
[16:58:21.114] Build status: STARTED
[16:58:21.115] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:87:0) after 4m16s
[17:02:37.213] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:87:0)
[17:02:37.251] Build status: STARTED
[17:02:37.251] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:88:0) after 7m42s
[17:10:19.373] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:88:0)
[17:10:19.392] Build status: STARTED
[17:10:19.393] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:89:0) after 5m22s
[17:15:41.542] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:89:0)
[17:15:41.557] Build status: STARTED
[17:15:41.557] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:90:0) after 1m17s
[17:16:58.574] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:90:0)
[17:16:58.597] Build status: STARTED
[17:16:58.597] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:91:0) after 4m27s
[17:21:25.609] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:91:0)
[17:21:25.627] Build status: STARTED
[17:21:25.627] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:92:0) after 5m44s
[17:27:09.643] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:92:0)
[17:27:09.659] Build status: STARTED
[17:27:09.659] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:93:0) after 3m33s
[17:30:42.676] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:93:0)
[17:30:42.692] Build status: STARTED
[17:30:42.692] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:94:0) after 1m16s
[17:31:58.710] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:94:0)
[17:31:58.759] Build status: STARTED
[17:31:58.759] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:95:0) after 3m10s
[17:35:08.780] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:95:0)
[17:35:08.833] Build status: STARTED
[17:35:08.833] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:96:0) after 8m18s
[17:43:26.900] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:96:0)
[17:43:26.916] Build status: STARTED
[17:43:26.917] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:97:0) after 7m27s
[17:50:53.980] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:97:0)
[17:50:54.002] Build status: STARTED
[17:50:54.002] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:98:0) after 4m57s
[17:55:51.017] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:98:0)
[17:55:51.033] Build status: STARTED
[17:55:51.033] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:99:0) after 5m27s
[18:01:18.007] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:99:0)
[18:01:18.027] Build status: STARTED
[18:01:18.027] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:100:0) after 7m56s
[18:09:14.092] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:100:0)
[18:09:14.116] Build status: STARTED
[18:09:14.116] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:101:0) after 7m38s
[18:16:52.130] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:101:0)
[18:16:52.158] Build status: STARTED
[18:16:52.158] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:102:0) after 4m30s
[18:21:22.281] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:102:0)
[18:21:22.301] Build status: STARTED
[18:21:22.301] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:103:0) after 2m13s
[18:23:35.311] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:103:0)
[18:23:35.327] Build status: STARTED
[18:23:35.327] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:104:0) after 2m51s
[18:26:26.361] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:104:0)
[18:26:26.378] Build status: STARTED
[18:26:26.378] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:105:0) after 2m20s
[18:28:46.451] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:105:0)
[18:28:46.476] Build status: STARTED
[18:28:46.476] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:106:0) after 1m50s
[18:30:36.491] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:106:0)
[18:30:36.504] Build status: STARTED
[18:30:36.504] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:107:0) after 1m31s
[18:32:07.518] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:107:0)
[18:32:07.590] Build status: STARTED
[18:32:07.590] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:108:0) after 2m28s
[18:34:35.613] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:108:0)
[18:34:35.630] Build status: STARTED
[18:34:35.630] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:109:0) after 6m28s
[18:41:03.647] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:109:0)
[18:41:03.667] Build status: STARTED
[18:41:03.667] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:110:0) after 3m44s
[18:44:47.732] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:110:0)
[18:44:47.749] Build status: STARTED
[18:44:47.749] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:111:0) after 7m24s
[18:52:11.826] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:111:0)
[18:52:11.849] Build status: STARTED
[18:52:11.850] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:112:0) after 6m30s
[18:58:41.867] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:112:0)
[18:58:41.885] Build status: STARTED
[18:58:41.885] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:113:0) after 2m11s
[19:00:52.898] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:113:0)
[19:00:52.914] Build status: STARTED
[19:00:52.914] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:114:0) after 1m40s
[19:02:32.979] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:114:0)
[19:02:33.001] Build status: STARTED
[19:02:33.001] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:115:0) after 8m46s
[19:11:19.079] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:115:0)
[19:11:19.102] Build status: STARTED
[19:11:19.102] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:116:0) after 1m0s
[19:12:19.154] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:116:0)
[19:12:19.189] Build status: STARTED
[19:12:19.189] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:117:0) after 7m58s
[19:20:17.251] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:117:0)
[19:20:17.306] Build status: STARTED
[19:20:17.306] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:118:0) after 1m44s
[19:22:01.377] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:118:0)
[19:22:01.393] Build status: STARTED
[19:22:01.393] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:119:0) after 3m53s
[19:25:54.452] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:119:0)
[19:25:54.470] Build status: STARTED
[19:25:54.470] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:120:0) after 4m28s
[19:30:22.494] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:120:0)
[19:30:22.579] Build status: STARTED
[19:30:22.579] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:121:0) after 9m4s
[19:39:26.644] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:121:0)
[19:39:26.664] Build status: STARTED
[19:39:26.664] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:122:0) after 7m8s
[19:46:34.676] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:122:0)
[19:46:34.751] Build status: STARTED
[19:46:34.751] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:123:0) after 5m59s
[19:52:33.846] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:123:0)
[19:52:33.873] Build status: STARTED
[19:52:33.873] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:124:0) after 4m47s
[19:57:20.888] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:124:0)
[19:57:20.906] Build status: STARTED
[19:57:20.906] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:125:0) after 5m5s
[20:02:25.962] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:125:0)
[20:02:25.998] Build status: STARTED
[20:02:25.998] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:126:0) after 5m18s
[20:07:44.013] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:126:0)
[20:07:44.078] Build status: STARTED
[20:07:44.078] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:127:0) after 5m21s
[20:13:05.092] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:127:0)
[20:13:05.182] Build status: STARTED
[20:13:05.182] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:128:0) after 8m32s
[20:21:37.265] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:128:0)
[20:21:37.291] Build status: STARTED
[20:21:37.291] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:129:0) after 8m32s
[20:30:09.396] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:129:0)
[20:30:09.672] Build status: STARTED
[20:30:09.672] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:130:0) after 8m19s
[20:38:28.828] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:130:0)
[20:38:28.843] Build status: STARTED
[20:38:28.843] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:131:0) after 7m19s
[20:45:47.925] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:131:0)
[20:45:47.939] Build status: STARTED
[20:45:47.940] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:132:0) after 8m8s
[20:53:55.996] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:132:0)
[20:53:56.059] Build status: STARTED
[20:53:56.059] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:133:0) after 7m53s
[21:01:49.078] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:133:0)
[21:01:49.105] Build status: STARTED
[21:01:49.105] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:134:0) after 2m45s
[21:04:34.215] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:134:0)
[21:04:34.361] Build status: STARTED
[21:04:34.361] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:135:0) after 4m45s
[21:09:19.517] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:135:0)
[21:09:19.534] Build status: STARTED
[21:09:19.534] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:136:0) after 6m16s
[21:15:35.713] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:136:0)
[21:15:35.737] Build status: STARTED
[21:15:35.737] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:137:0) after 7m43s
[21:23:18.758] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:137:0)
[21:23:19.137] Build status: STARTED
[21:23:19.137] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:138:0) after 4m12s
[21:27:31.151] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:138:0)
[21:27:31.166] Build status: STARTED
[21:27:31.166] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:139:0) after 6m23s
[21:33:54.209] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:139:0)
[21:33:54.225] Build status: STARTED
[21:33:54.225] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:140:0) after 9m16s
[21:43:10.251] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:140:0)
[21:43:10.269] Build status: STARTED
[21:43:10.269] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:141:0) after 6m41s
[21:49:51.479] Handling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:141:0)
[21:49:51.552] Build status: STARTED
[21:49:51.552] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal_release:8861131640873903312:142:0) after 7m29s
[21:50:20.835] Received PubSub notification, asking Buildbucket for the build status
[21:50:20.905] Build:
{
"id": "8695701578940452033",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-internal_release"
},
"number": 1547,
"createdBy": "project:r8",
"createTime": "2025-12-12T08:56:03.239781876Z",
"startTime": "2025-12-12T09:50:10.705504313Z",
"endTime": "2025-12-12T21:50:20.378781111Z",
"updateTime": "2025-12-12T21:50:20.378781111Z",
"status": "INFRA_FAILURE",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "7627cbbeb60773baf10d691576a62f4257add330",
"ref": "refs/heads/9.1"
}
}
}
[21:50:20.905] Invocation finished in 12h54m18.894619893s with status FAILED