[19:26:34.871] New invocation is queued and will start shortly
[19:26:36.046] Starting the invocation (attempt 1)
[19:26:36.075] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9492662b981fbae31d0b202200afbea17bd59e68"
[19:26:36.075] Popped gitiles commit info from properties and tags
[19:26:36.075] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:26:36.075] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:26:36.075] Buildbucket request:
{
"requestId": "8867343160932649120",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9492662b981fbae31d0b202200afbea17bd59e68",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8867343160932649120"
},
{
"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"
}
}
[19:26:36.839] Scheduled build:
{
"id": "8701913098875859425",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 30559,
"createdBy": "project:chromium",
"createTime": "2025-10-04T19:26:36.210712409Z",
"updateTime": "2025-10-04T19:26:36.210712409Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9492662b981fbae31d0b202200afbea17bd59e68",
"ref": "refs/heads/main"
}
}
}
[19:26:36.839] Task URL: https://cr-buildbucket.appspot.com/build/8701913098875859425
[19:26:36.839] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:2:0) after 8m19s
[19:26:37.789] Received PubSub notification, asking Buildbucket for the build status
[19:26:37.808] Build status: SCHEDULED
[19:27:36.852] Received PubSub notification, asking Buildbucket for the build status
[19:27:36.917] Build status: STARTED
[19:34:55.872] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:2:0)
[19:34:55.886] Build status: STARTED
[19:34:55.886] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:5:0) after 1m46s
[19:36:41.900] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:5:0)
[19:36:41.928] Build status: STARTED
[19:36:41.928] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:6:0) after 4m4s
[19:40:46.098] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:6:0)
[19:40:46.129] Build status: STARTED
[19:40:46.129] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:7:0) after 7m0s
[19:47:46.151] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:7:0)
[19:47:46.170] Build status: STARTED
[19:47:46.170] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:8:0) after 9m34s
[19:57:20.187] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:8:0)
[19:57:20.209] Build status: STARTED
[19:57:20.209] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:9:0) after 4m4s
[20:01:24.281] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:9:0)
[20:01:24.311] Build status: STARTED
[20:01:24.311] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:10:0) after 9m9s
[20:10:33.573] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:10:0)
[20:10:33.653] Build status: STARTED
[20:10:33.653] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:11:0) after 7m55s
[20:18:28.870] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:11:0)
[20:18:28.923] Build status: STARTED
[20:18:28.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:12:0) after 4m39s
[20:23:07.942] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:12:0)
[20:23:07.959] Build status: STARTED
[20:23:07.959] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:13:0) after 9m0s
[20:32:08.158] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:13:0)
[20:32:08.172] Build status: STARTED
[20:32:08.173] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:14:0) after 6m3s
[20:38:11.188] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:14:0)
[20:38:11.222] Build status: STARTED
[20:38:11.222] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:15:0) after 9m20s
[20:47:31.323] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:15:0)
[20:47:31.349] Build status: STARTED
[20:47:31.349] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:16:0) after 6m41s
[20:54:12.418] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:16:0)
[20:54:12.440] Build status: STARTED
[20:54:12.440] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:17:0) after 8m33s
[21:02:45.608] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:17:0)
[21:02:45.623] Build status: STARTED
[21:02:45.623] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:18:0) after 8m51s
[21:11:36.640] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:18:0)
[21:11:36.658] Build status: STARTED
[21:11:36.658] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:19:0) after 7m42s
[21:19:18.784] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:19:0)
[21:19:18.801] Build status: STARTED
[21:19:18.801] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:20:0) after 4m54s
[21:24:12.893] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:20:0)
[21:24:12.910] Build status: STARTED
[21:24:12.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:21:0) after 6m3s
[21:30:16.160] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:21:0)
[21:30:16.204] Build status: STARTED
[21:30:16.204] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:22:0) after 6m7s
[21:36:23.316] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:22:0)
[21:36:23.341] Build status: STARTED
[21:36:23.341] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:23:0) after 6m19s
[21:42:42.353] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:23:0)
[21:42:42.457] Build status: STARTED
[21:42:42.457] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:24:0) after 5m1s
[21:47:43.792] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:24:0)
[21:47:43.809] Build status: STARTED
[21:47:43.809] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:25:0) after 9m22s
[21:57:05.823] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:25:0)
[21:57:05.841] Build status: STARTED
[21:57:05.841] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:26:0) after 4m8s
[22:01:14.055] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:26:0)
[22:01:14.080] Build status: STARTED
[22:01:14.080] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:27:0) after 2m23s
[22:03:37.097] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:27:0)
[22:03:37.120] Build status: STARTED
[22:03:37.120] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:28:0) after 8m30s
[22:12:07.496] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:28:0)
[22:12:07.519] Build status: STARTED
[22:12:07.519] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:29:0) after 2m43s
[22:14:50.559] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:29:0)
[22:14:50.575] Build status: STARTED
[22:14:50.575] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:30:0) after 4m45s
[22:19:35.595] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:30:0)
[22:19:35.609] Build status: STARTED
[22:19:35.609] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:31:0) after 1m58s
[22:21:33.629] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:31:0)
[22:21:33.651] Build status: STARTED
[22:21:33.651] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:32:0) after 3m51s
[22:25:24.666] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:32:0)
[22:25:24.683] Build status: STARTED
[22:25:24.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:33:0) after 5m45s
[22:31:09.805] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:33:0)
[22:31:09.838] Build status: STARTED
[22:31:09.838] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:34:0) after 5m28s
[22:36:37.854] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:34:0)
[22:36:37.871] Build status: STARTED
[22:36:37.871] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:35:0) after 3m23s
[22:40:00.891] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:35:0)
[22:40:00.915] Build status: STARTED
[22:40:00.915] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:36:0) after 8m53s
[22:48:54.100] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:36:0)
[22:48:54.134] Build status: STARTED
[22:48:54.134] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:37:0) after 2m6s
[22:51:00.154] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:37:0)
[22:51:00.175] Build status: STARTED
[22:51:00.176] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:38:0) after 1m14s
[22:52:14.192] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:38:0)
[22:52:14.215] Build status: STARTED
[22:52:14.215] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8867343160932649120:39:0) after 4m4s
[22:52:49.015] Received PubSub notification, asking Buildbucket for the build status
[22:52:49.053] Build:
{
"id": "8701913098875859425",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 30559,
"createdBy": "project:chromium",
"createTime": "2025-10-04T19:26:36.210712409Z",
"startTime": "2025-10-04T19:27:36.511756940Z",
"endTime": "2025-10-04T22:52:48.602202207Z",
"updateTime": "2025-10-04T22:52:48.602202207Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9492662b981fbae31d0b202200afbea17bd59e68",
"ref": "refs/heads/main"
}
}
}
[22:52:49.053] Invocation finished in 3h26m14.188294228s with status FAILED