[02:22:46.596] New invocation is queued and will start shortly
[02:22:47.766] Starting the invocation (attempt 1)
[02:22:47.797] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9bdd8c777b6ad6ed34f7bf19c5c6c1a58fa0811a"
[02:22:47.797] Popped gitiles commit info from properties and tags
[02:22:47.797] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:22:47.798] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:22:47.798] Buildbucket request:
{
"requestId": "8845664301209970720",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9bdd8c777b6ad6ed34f7bf19c5c6c1a58fa0811a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8845664301209970720"
},
{
"key": "scheduler_job_id",
"value": "chromium/Linux CFI"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:22:48.336] Scheduled build:
{
"id": "8680234239246535249",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 32097,
"createdBy": "project:chromium",
"createTime": "2026-06-01T02:22:47.848770992Z",
"updateTime": "2026-06-01T02:22:47.848770992Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9bdd8c777b6ad6ed34f7bf19c5c6c1a58fa0811a",
"ref": "refs/heads/main"
}
}
}
[02:22:48.336] Task URL: https://cr-buildbucket.appspot.com/build/8680234239246535249
[02:22:48.336] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:2:0) after 3m29s
[02:22:49.315] Received PubSub notification, asking Buildbucket for the build status
[02:22:49.331] Build status: SCHEDULED
[02:23:54.145] Received PubSub notification, asking Buildbucket for the build status
[02:23:54.163] Build status: STARTED
[02:26:17.476] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:2:0)
[02:26:17.495] Build status: STARTED
[02:26:17.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:5:0) after 1m20s
[02:27:37.562] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:5:0)
[02:27:37.578] Build status: STARTED
[02:27:37.578] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:6:0) after 1m39s
[02:29:16.591] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:6:0)
[02:29:16.623] Build status: STARTED
[02:29:16.623] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:7:0) after 7m28s
[02:36:45.197] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:7:0)
[02:36:45.211] Build status: STARTED
[02:36:45.212] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:8:0) after 3m16s
[02:40:01.485] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:8:0)
[02:40:01.526] Build status: STARTED
[02:40:01.526] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:9:0) after 2m53s
[02:42:54.589] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:9:0)
[02:42:54.634] Build status: STARTED
[02:42:54.634] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:10:0) after 1m6s
[02:44:00.675] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:10:0)
[02:44:00.851] Build status: STARTED
[02:44:00.851] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:11:0) after 9m47s
[02:53:47.962] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:11:0)
[02:53:47.979] Build status: STARTED
[02:53:47.979] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:12:0) after 4m50s
[02:58:38.049] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:12:0)
[02:58:38.065] Build status: STARTED
[02:58:38.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:13:0) after 3m33s
[03:02:11.079] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:13:0)
[03:02:11.097] Build status: STARTED
[03:02:11.097] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:14:0) after 2m59s
[03:05:10.113] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:14:0)
[03:05:10.130] Build status: STARTED
[03:05:10.130] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:15:0) after 2m18s
[03:07:28.370] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:15:0)
[03:07:28.410] Build status: STARTED
[03:07:28.410] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:16:0) after 8m33s
[03:16:01.554] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:16:0)
[03:16:01.609] Build status: STARTED
[03:16:01.609] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:17:0) after 6m2s
[03:22:03.580] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:17:0)
[03:22:03.599] Build status: STARTED
[03:22:03.599] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:18:0) after 5m52s
[03:27:55.821] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:18:0)
[03:27:55.845] Build status: STARTED
[03:27:55.845] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:19:0) after 8m34s
[03:36:29.962] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:19:0)
[03:36:29.973] Build status: STARTED
[03:36:29.973] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:20:0) after 7m16s
[03:43:46.686] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:20:0)
[03:43:46.711] Build status: STARTED
[03:43:46.711] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:21:0) after 7m43s
[03:51:29.746] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:21:0)
[03:51:29.764] Build status: STARTED
[03:51:29.764] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:22:0) after 1m9s
[03:52:38.802] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:22:0)
[03:52:38.819] Build status: STARTED
[03:52:38.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:23:0) after 9m50s
[04:02:28.836] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:23:0)
[04:02:28.871] Build status: STARTED
[04:02:28.871] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:24:0) after 2m46s
[04:05:14.912] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:24:0)
[04:05:14.927] Build status: STARTED
[04:05:14.927] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:25:0) after 7m58s
[04:13:12.993] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:25:0)
[04:13:13.008] Build status: STARTED
[04:13:13.008] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:26:0) after 1m16s
[04:14:29.001] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:26:0)
[04:14:29.052] Build status: STARTED
[04:14:29.052] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:27:0) after 6m15s
[04:20:44.087] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:27:0)
[04:20:44.117] Build status: STARTED
[04:20:44.117] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:28:0) after 3m29s
[04:24:13.185] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:28:0)
[04:24:13.254] Build status: STARTED
[04:24:13.254] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:29:0) after 2m48s
[04:27:01.298] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:29:0)
[04:27:01.319] Build status: STARTED
[04:27:01.319] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:30:0) after 5m43s
[04:32:44.341] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:30:0)
[04:32:44.357] Build status: STARTED
[04:32:44.358] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:31:0) after 6m17s
[04:39:02.188] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:31:0)
[04:39:02.209] Build status: STARTED
[04:39:02.209] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:32:0) after 1m57s
[04:40:59.227] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:32:0)
[04:40:59.249] Build status: STARTED
[04:40:59.249] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:33:0) after 7m3s
[04:48:02.314] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:33:0)
[04:48:02.333] Build status: STARTED
[04:48:02.333] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:34:0) after 3m8s
[04:51:10.396] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:34:0)
[04:51:10.707] Build status: STARTED
[04:51:10.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:35:0) after 5m3s
[04:56:13.724] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:35:0)
[04:56:13.745] Build status: STARTED
[04:56:13.745] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:36:0) after 4m46s
[05:00:59.850] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:36:0)
[05:00:59.869] Build status: STARTED
[05:00:59.869] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:37:0) after 7m36s
[05:08:36.212] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:37:0)
[05:08:36.230] Build status: STARTED
[05:08:36.230] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:38:0) after 9m9s
[05:17:45.243] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:38:0)
[05:17:45.259] Build status: STARTED
[05:17:45.259] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:39:0) after 8m2s
[05:25:47.431] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:39:0)
[05:25:47.447] Build status: STARTED
[05:25:47.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:40:0) after 2m0s
[05:27:47.464] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:40:0)
[05:27:47.482] Build status: STARTED
[05:27:47.482] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:41:0) after 1m1s
[05:28:48.639] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:41:0)
[05:28:48.657] Build status: STARTED
[05:28:48.657] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:42:0) after 6m17s
[05:35:05.737] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:42:0)
[05:35:05.755] Build status: STARTED
[05:35:05.755] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:43:0) after 9m43s
[05:44:48.770] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:43:0)
[05:44:48.790] Build status: STARTED
[05:44:48.790] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:44:0) after 2m47s
[05:47:35.805] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:44:0)
[05:47:35.819] Build status: STARTED
[05:47:35.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:45:0) after 6m33s
[05:54:09.134] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:45:0)
[05:54:09.153] Build status: STARTED
[05:54:09.153] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:46:0) after 1m6s
[05:55:15.316] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:46:0)
[05:55:15.342] Build status: STARTED
[05:55:15.342] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:47:0) after 1m59s
[05:57:14.603] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:47:0)
[05:57:14.621] Build status: STARTED
[05:57:14.621] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:48:0) after 9m39s
[06:06:53.667] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:48:0)
[06:06:53.686] Build status: STARTED
[06:06:53.686] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:49:0) after 2m19s
[06:09:12.745] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:49:0)
[06:09:12.761] Build status: STARTED
[06:09:12.761] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:50:0) after 4m59s
[06:14:11.841] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:50:0)
[06:14:11.908] Build status: STARTED
[06:14:11.908] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:51:0) after 1m24s
[06:15:36.835] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:51:0)
[06:15:36.866] Build status: STARTED
[06:15:36.866] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8845664301209970720:52:0) after 8m22s
[06:19:32.825] Received PubSub notification, asking Buildbucket for the build status
[06:19:32.853] Build:
{
"id": "8680234239246535249",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 32097,
"createdBy": "project:chromium",
"createTime": "2026-06-01T02:22:47.848770992Z",
"startTime": "2026-06-01T02:23:53.905812952Z",
"endTime": "2026-06-01T06:19:31.393216608Z",
"updateTime": "2026-06-01T06:19:31.393216608Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9bdd8c777b6ad6ed34f7bf19c5c6c1a58fa0811a",
"ref": "refs/heads/main"
}
}
}
[06:19:32.853] Invocation finished in 3h56m46.261150531s with status FAILED