[01:11:24.816] New invocation is queued and will start shortly
[01:11:25.894] Starting the invocation (attempt 1)
[01:11:25.924] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ccb4bc121c3b02ea0899fa14b79c5e19c11793cf"
[01:11:25.924] Popped gitiles commit info from properties and tags
[01:11:25.924] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:11:25.924] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:11:25.924] Buildbucket request:
{
"requestId": "8847843118177829152",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux Viz"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ccb4bc121c3b02ea0899fa14b79c5e19c11793cf",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8847843118177829152"
},
{
"key": "scheduler_job_id",
"value": "chromium/Linux Viz"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:11:26.464] Scheduled build:
{
"id": "8682413056302244497",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux Viz"
},
"number": 48518,
"createdBy": "project:chromium",
"createTime": "2026-05-08T01:11:25.982898703Z",
"updateTime": "2026-05-08T01:11:25.982898703Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ccb4bc121c3b02ea0899fa14b79c5e19c11793cf",
"ref": "refs/heads/main"
}
}
}
[01:11:26.464] Task URL: https://cr-buildbucket.appspot.com/build/8682413056302244497
[01:11:26.464] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:2:0) after 9m9s
[01:11:27.389] Received PubSub notification, asking Buildbucket for the build status
[01:11:27.409] Build status: SCHEDULED
[01:15:24.918] Received PubSub notification, asking Buildbucket for the build status
[01:15:24.945] Build status: STARTED
[01:20:35.599] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:2:0)
[01:20:35.615] Build status: STARTED
[01:20:35.616] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:5:0) after 3m51s
[01:24:26.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:5:0)
[01:24:26.754] Build status: STARTED
[01:24:26.754] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:6:0) after 4m25s
[01:28:51.887] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:6:0)
[01:28:51.905] Build status: STARTED
[01:28:51.905] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:7:0) after 1m34s
[01:30:25.964] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:7:0)
[01:30:25.985] Build status: STARTED
[01:30:25.985] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:8:0) after 5m34s
[01:36:00.001] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:8:0)
[01:36:00.020] Build status: STARTED
[01:36:00.020] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:9:0) after 8m9s
[01:44:09.197] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:9:0)
[01:44:09.220] Build status: STARTED
[01:44:09.220] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:10:0) after 9m30s
[01:53:39.199] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:10:0)
[01:53:39.218] Build status: STARTED
[01:53:39.218] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:11:0) after 2m42s
[01:56:21.234] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:11:0)
[01:56:21.252] Build status: STARTED
[01:56:21.252] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:12:0) after 7m19s
[02:03:40.308] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:12:0)
[02:03:40.326] Build status: STARTED
[02:03:40.326] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:13:0) after 5m8s
[02:08:48.370] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:13:0)
[02:08:48.388] Build status: STARTED
[02:08:48.388] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:14:0) after 2m52s
[02:11:40.401] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:14:0)
[02:11:40.426] Build status: STARTED
[02:11:40.426] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:15:0) after 5m22s
[02:17:02.572] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:15:0)
[02:17:02.590] Build status: STARTED
[02:17:02.590] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:16:0) after 4m24s
[02:21:26.607] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:16:0)
[02:21:26.643] Build status: STARTED
[02:21:26.644] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:17:0) after 6m8s
[02:27:34.879] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:17:0)
[02:27:34.917] Build status: STARTED
[02:27:34.917] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:18:0) after 5m40s
[02:33:15.020] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:18:0)
[02:33:15.047] Build status: STARTED
[02:33:15.047] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:19:0) after 7m17s
[02:40:32.265] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:19:0)
[02:40:32.285] Build status: STARTED
[02:40:32.285] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:20:0) after 9m1s
[02:49:33.299] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:20:0)
[02:49:33.397] Build status: STARTED
[02:49:33.397] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:21:0) after 1m54s
[02:51:27.532] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:21:0)
[02:51:27.683] Build status: STARTED
[02:51:27.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:22:0) after 4m43s
[02:56:10.688] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:22:0)
[02:56:10.703] Build status: STARTED
[02:56:10.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:23:0) after 1m18s
[02:57:28.877] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:23:0)
[02:57:28.897] Build status: STARTED
[02:57:28.897] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:24:0) after 1m9s
[02:58:38.064] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:24:0)
[02:58:38.087] Build status: STARTED
[02:58:38.087] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:25:0) after 3m2s
[03:01:40.169] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:25:0)
[03:01:40.186] Build status: STARTED
[03:01:40.186] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:26:0) after 5m46s
[03:07:26.372] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:26:0)
[03:07:26.468] Build status: STARTED
[03:07:26.468] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:27:0) after 7m11s
[03:14:37.476] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:27:0)
[03:14:37.497] Build status: STARTED
[03:14:37.497] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:28:0) after 5m36s
[03:20:13.552] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:28:0)
[03:20:13.635] Build status: STARTED
[03:20:13.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:29:0) after 6m51s
[03:27:04.618] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:29:0)
[03:27:04.656] Build status: STARTED
[03:27:04.656] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:30:0) after 6m31s
[03:33:35.687] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:30:0)
[03:33:35.707] Build status: STARTED
[03:33:35.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:31:0) after 5m1s
[03:38:36.919] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:31:0)
[03:38:36.939] Build status: STARTED
[03:38:36.939] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:32:0) after 4m56s
[03:43:32.979] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:32:0)
[03:43:32.999] Build status: STARTED
[03:43:32.999] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:33:0) after 5m16s
[03:48:49.066] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:33:0)
[03:48:49.091] Build status: STARTED
[03:48:49.091] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:34:0) after 8m33s
[03:57:22.186] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:34:0)
[03:57:22.202] Build status: STARTED
[03:57:22.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:35:0) after 8m24s
[04:05:46.311] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:35:0)
[04:05:46.332] Build status: STARTED
[04:05:46.332] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:36:0) after 5m37s
[04:11:23.413] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:36:0)
[04:11:23.455] Build status: STARTED
[04:11:23.456] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:37:0) after 1m35s
[04:12:58.558] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:37:0)
[04:12:58.637] Build status: STARTED
[04:12:58.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:38:0) after 9m27s
[04:22:25.680] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:38:0)
[04:22:25.698] Build status: STARTED
[04:22:25.698] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:39:0) after 8m58s
[04:31:23.769] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:39:0)
[04:31:23.798] Build status: STARTED
[04:31:23.798] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:40:0) after 3m50s
[04:35:13.846] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:40:0)
[04:35:13.867] Build status: STARTED
[04:35:13.868] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:41:0) after 8m39s
[04:43:52.880] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:41:0)
[04:43:52.896] Build status: STARTED
[04:43:52.896] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:42:0) after 2m33s
[04:46:26.125] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:42:0)
[04:46:26.142] Build status: STARTED
[04:46:26.142] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:43:0) after 3m35s
[04:50:01.143] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:43:0)
[04:50:01.165] Build status: STARTED
[04:50:01.165] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:44:0) after 7m58s
[04:57:59.179] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:44:0)
[04:57:59.210] Build status: STARTED
[04:57:59.210] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8847843118177829152:45:0) after 9m41s
[05:03:53.652] Received PubSub notification, asking Buildbucket for the build status
[05:03:53.673] Build:
{
"id": "8682413056302244497",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux Viz"
},
"number": 48518,
"createdBy": "project:chromium",
"createTime": "2026-05-08T01:11:25.982898703Z",
"startTime": "2026-05-08T01:15:24.303931251Z",
"endTime": "2026-05-08T05:03:53.301219881Z",
"updateTime": "2026-05-08T05:03:53.301219881Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ccb4bc121c3b02ea0899fa14b79c5e19c11793cf",
"ref": "refs/heads/main"
}
}
}
[05:03:53.673] Invocation finished in 3h52m28.863240532s with status FAILED