[00:41:17.199] New invocation is queued and will start shortly
[00:41:18.391] Starting the invocation (attempt 1)
[00:41:18.417] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/10b413e21424acb3273d788a0ea1807a38192153"
[00:41:18.417] Popped gitiles commit info from properties and tags
[00:41:18.417] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:41:18.417] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:41:18.417] Buildbucket request:
{
"requestId": "8841956210785639760",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux Viz"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "10b413e21424acb3273d788a0ea1807a38192153",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8841956210785639760"
},
{
"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"
}
}
[00:41:18.997] Scheduled build:
{
"id": "8676526148791584801",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux Viz"
},
"number": 48925,
"createdBy": "project:chromium",
"createTime": "2026-07-12T00:41:18.478475938Z",
"updateTime": "2026-07-12T00:41:18.478475938Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "10b413e21424acb3273d788a0ea1807a38192153",
"ref": "refs/heads/main"
}
}
}
[00:41:18.997] Task URL: https://cr-buildbucket.appspot.com/build/8676526148791584801
[00:41:18.997] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:2:0) after 2m0s
[00:41:20.374] Received PubSub notification, asking Buildbucket for the build status
[00:41:20.438] Build status: SCHEDULED
[00:43:19.139] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:2:0)
[00:43:19.178] Build status: SCHEDULED
[00:43:19.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:4:0) after 9m54s
[00:44:54.518] Received PubSub notification, asking Buildbucket for the build status
[00:44:54.553] Build status: STARTED
[00:53:13.195] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:4:0)
[00:53:13.215] Build status: STARTED
[00:53:13.215] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:6:0) after 4m42s
[00:57:55.315] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:6:0)
[00:57:55.340] Build status: STARTED
[00:57:55.340] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:7:0) after 1m50s
[00:59:45.648] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:7:0)
[00:59:45.680] Build status: STARTED
[00:59:45.680] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:8:0) after 8m53s
[01:08:38.662] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:8:0)
[01:08:38.685] Build status: STARTED
[01:08:38.685] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:9:0) after 2m55s
[01:11:33.655] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:9:0)
[01:11:33.713] Build status: STARTED
[01:11:33.713] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:10:0) after 9m15s
[01:20:48.814] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:10:0)
[01:20:48.871] Build status: STARTED
[01:20:48.871] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:11:0) after 6m23s
[01:27:11.938] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:11:0)
[01:27:11.965] Build status: STARTED
[01:27:11.965] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:12:0) after 5m19s
[01:32:30.955] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:12:0)
[01:32:31.429] Build status: STARTED
[01:32:31.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:13:0) after 3m55s
[01:36:26.442] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:13:0)
[01:36:26.467] Build status: STARTED
[01:36:26.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:14:0) after 5m30s
[01:41:56.720] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:14:0)
[01:41:56.736] Build status: STARTED
[01:41:56.736] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:15:0) after 8m22s
[01:50:18.726] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:15:0)
[01:50:18.746] Build status: STARTED
[01:50:18.746] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:16:0) after 1m41s
[01:51:59.806] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:16:0)
[01:51:59.942] Build status: STARTED
[01:51:59.942] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:17:0) after 6m24s
[01:58:23.956] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:17:0)
[01:58:24.170] Build status: STARTED
[01:58:24.170] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:18:0) after 3m57s
[02:02:21.425] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:18:0)
[02:02:21.446] Build status: STARTED
[02:02:21.446] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:19:0) after 5m23s
[02:07:44.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:19:0)
[02:07:44.721] Build status: STARTED
[02:07:44.721] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:20:0) after 9m41s
[02:17:25.846] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:20:0)
[02:17:25.861] Build status: STARTED
[02:17:25.861] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:21:0) after 1m20s
[02:18:46.144] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:21:0)
[02:18:46.181] Build status: STARTED
[02:18:46.182] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:22:0) after 6m25s
[02:25:11.198] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:22:0)
[02:25:11.220] Build status: STARTED
[02:25:11.220] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:23:0) after 7m24s
[02:32:35.213] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:23:0)
[02:32:35.240] Build status: STARTED
[02:32:35.240] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:24:0) after 6m25s
[02:39:00.536] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:24:0)
[02:39:00.690] Build status: STARTED
[02:39:00.690] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:25:0) after 7m58s
[02:46:58.891] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:25:0)
[02:46:58.925] Build status: STARTED
[02:46:58.925] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:26:0) after 1m19s
[02:48:18.028] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:26:0)
[02:48:18.047] Build status: STARTED
[02:48:18.047] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:27:0) after 7m8s
[02:55:26.224] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:27:0)
[02:55:26.242] Build status: STARTED
[02:55:26.243] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:28:0) after 6m57s
[03:02:23.481] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:28:0)
[03:02:23.498] Build status: STARTED
[03:02:23.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:29:0) after 1m47s
[03:04:10.982] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:29:0)
[03:04:10.998] Build status: STARTED
[03:04:10.998] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:30:0) after 1m46s
[03:05:57.134] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:30:0)
[03:05:57.151] Build status: STARTED
[03:05:57.151] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:31:0) after 4m29s
[03:10:26.169] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:31:0)
[03:10:26.188] Build status: STARTED
[03:10:26.189] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:32:0) after 7m1s
[03:17:27.202] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:32:0)
[03:17:27.299] Build status: STARTED
[03:17:27.299] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:33:0) after 8m54s
[03:26:21.392] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:33:0)
[03:26:21.414] Build status: STARTED
[03:26:21.414] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:34:0) after 6m45s
[03:33:06.779] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:34:0)
[03:33:06.796] Build status: STARTED
[03:33:06.796] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:35:0) after 3m4s
[03:36:10.795] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:35:0)
[03:36:10.849] Build status: STARTED
[03:36:10.849] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:36:0) after 7m6s
[03:43:16.862] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:36:0)
[03:43:16.884] Build status: STARTED
[03:43:16.884] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:37:0) after 2m13s
[03:45:30.041] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:37:0)
[03:45:30.059] Build status: STARTED
[03:45:30.059] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:38:0) after 5m32s
[03:51:02.440] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:38:0)
[03:51:02.455] Build status: STARTED
[03:51:02.455] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:39:0) after 4m1s
[03:55:03.698] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:39:0)
[03:55:03.714] Build status: STARTED
[03:55:03.714] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:40:0) after 9m15s
[04:04:18.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:40:0)
[04:04:19.012] Build status: STARTED
[04:04:19.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:41:0) after 2m59s
[04:07:18.031] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:41:0)
[04:07:18.048] Build status: STARTED
[04:07:18.048] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:42:0) after 8m0s
[04:15:19.136] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:42:0)
[04:15:19.304] Build status: STARTED
[04:15:19.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:43:0) after 7m10s
[04:22:29.558] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:43:0)
[04:22:29.582] Build status: STARTED
[04:22:29.582] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:44:0) after 5m19s
[04:27:48.730] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:44:0)
[04:27:48.747] Build status: STARTED
[04:27:48.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:45:0) after 2m37s
[04:30:25.761] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:45:0)
[04:30:25.781] Build status: STARTED
[04:30:25.781] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8841956210785639760:46:0) after 7m29s
[04:33:27.957] Received PubSub notification, asking Buildbucket for the build status
[04:33:27.976] Build:
{
"id": "8676526148791584801",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux Viz"
},
"number": 48925,
"createdBy": "project:chromium",
"createTime": "2026-07-12T00:41:18.478475938Z",
"startTime": "2026-07-12T00:44:30.992832826Z",
"endTime": "2026-07-12T04:32:58.977375005Z",
"updateTime": "2026-07-12T04:32:58.977375005Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "10b413e21424acb3273d788a0ea1807a38192153",
"ref": "refs/heads/main"
}
}
}
[04:33:27.976] Invocation finished in 3h52m10.783118126s with status FAILED