[11:25:59.312] New invocation is queued and will start shortly
[11:26:00.524] Starting the invocation (attempt 1)
[11:26:00.554] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/07542951acb2cc5867596913b1a8b52799741e59"
[11:26:00.554] Popped gitiles commit info from properties and tags
[11:26:00.554] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:26:00.554] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:26:00.554] Buildbucket request:
{
"requestId": "8835664458968458624",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux Viz"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "07542951acb2cc5867596913b1a8b52799741e59",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8835664458968458624"
},
{
"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"
}
}
[11:26:01.237] Scheduled build:
{
"id": "8670234396900775041",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux Viz"
},
"number": 49385,
"createdBy": "project:chromium",
"createTime": "2026-09-19T11:26:00.661420481Z",
"updateTime": "2026-09-19T11:26:00.661420481Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "07542951acb2cc5867596913b1a8b52799741e59",
"ref": "refs/heads/main"
}
}
}
[11:26:01.237] Task URL: https://cr-buildbucket.appspot.com/build/8670234396900775041
[11:26:01.237] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:2:0) after 6m23s
[11:26:02.105] Received PubSub notification, asking Buildbucket for the build status
[11:26:02.125] Build status: SCHEDULED
[11:32:24.398] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:2:0)
[11:32:24.420] Build status: SCHEDULED
[11:32:24.420] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:4:0) after 9m2s
[11:39:18.892] Received PubSub notification, asking Buildbucket for the build status
[11:39:18.924] Build status: STARTED
[11:41:26.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:4:0)
[11:41:26.422] Build status: STARTED
[11:41:26.423] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:6:0) after 9m57s
[11:51:23.407] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:6:0)
[11:51:23.426] Build status: STARTED
[11:51:23.426] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:7:0) after 9m33s
[12:00:56.447] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:7:0)
[12:00:56.469] Build status: STARTED
[12:00:56.469] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:8:0) after 2m7s
[12:03:03.615] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:8:0)
[12:03:03.637] Build status: STARTED
[12:03:03.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:9:0) after 6m56s
[12:09:59.660] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:9:0)
[12:09:59.683] Build status: STARTED
[12:09:59.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:10:0) after 6m20s
[12:16:20.128] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:10:0)
[12:16:20.149] Build status: STARTED
[12:16:20.149] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:11:0) after 6m40s
[12:23:00.236] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:11:0)
[12:23:00.285] Build status: STARTED
[12:23:00.285] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:12:0) after 2m26s
[12:25:26.308] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:12:0)
[12:25:26.329] Build status: STARTED
[12:25:26.329] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:13:0) after 8m23s
[12:33:49.311] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:13:0)
[12:33:49.388] Build status: STARTED
[12:33:49.388] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:14:0) after 7m37s
[12:41:26.664] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:14:0)
[12:41:26.683] Build status: STARTED
[12:41:26.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:15:0) after 1m9s
[12:42:35.709] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:15:0)
[12:42:35.731] Build status: STARTED
[12:42:35.731] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:16:0) after 2m13s
[12:44:48.754] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:16:0)
[12:44:48.774] Build status: STARTED
[12:44:48.774] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:17:0) after 5m21s
[12:50:10.347] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:17:0)
[12:50:10.386] Build status: STARTED
[12:50:10.386] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:18:0) after 9m59s
[13:00:09.404] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:18:0)
[13:00:09.423] Build status: STARTED
[13:00:09.423] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:19:0) after 8m37s
[13:08:46.648] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:19:0)
[13:08:46.744] Build status: STARTED
[13:08:46.744] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:20:0) after 3m15s
[13:12:01.763] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:20:0)
[13:12:01.784] Build status: STARTED
[13:12:01.784] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:21:0) after 4m55s
[13:16:56.903] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:21:0)
[13:16:56.922] Build status: STARTED
[13:16:56.922] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:22:0) after 6m29s
[13:23:26.044] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:22:0)
[13:23:26.062] Build status: STARTED
[13:23:26.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:23:0) after 2m10s
[13:25:36.139] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:23:0)
[13:25:36.168] Build status: STARTED
[13:25:36.168] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:24:0) after 1m44s
[13:27:20.188] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:24:0)
[13:27:20.270] Build status: STARTED
[13:27:20.270] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:25:0) after 7m46s
[13:35:06.367] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:25:0)
[13:35:06.447] Build status: STARTED
[13:35:06.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:26:0) after 2m3s
[13:37:09.493] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:26:0)
[13:37:09.525] Build status: STARTED
[13:37:09.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:27:0) after 6m47s
[13:43:56.543] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:27:0)
[13:43:56.570] Build status: STARTED
[13:43:56.570] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:28:0) after 6m1s
[13:49:57.673] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:28:0)
[13:49:57.694] Build status: STARTED
[13:49:57.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:29:0) after 2m14s
[13:52:11.858] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:29:0)
[13:52:11.905] Build status: STARTED
[13:52:11.905] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:30:0) after 3m41s
[13:55:52.943] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:30:0)
[13:55:52.974] Build status: STARTED
[13:55:52.974] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:31:0) after 2m16s
[13:58:09.084] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:31:0)
[13:58:09.171] Build status: STARTED
[13:58:09.171] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:32:0) after 4m35s
[14:02:44.238] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:32:0)
[14:02:44.258] Build status: STARTED
[14:02:44.258] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:33:0) after 5m3s
[14:07:47.285] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:33:0)
[14:07:47.315] Build status: STARTED
[14:07:47.315] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:34:0) after 8m57s
[14:16:44.478] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:34:0)
[14:16:44.497] Build status: STARTED
[14:16:44.497] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:35:0) after 4m11s
[14:20:55.567] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:35:0)
[14:20:55.589] Build status: STARTED
[14:20:55.589] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:36:0) after 3m23s
[14:24:18.605] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:36:0)
[14:24:18.627] Build status: STARTED
[14:24:18.628] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:37:0) after 2m45s
[14:27:03.645] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:37:0)
[14:27:03.664] Build status: STARTED
[14:27:03.664] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:38:0) after 1m45s
[14:28:48.727] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:38:0)
[14:28:48.771] Build status: STARTED
[14:28:48.771] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:39:0) after 5m24s
[14:34:12.913] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:39:0)
[14:34:12.935] Build status: STARTED
[14:34:12.935] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:40:0) after 7m17s
[14:41:30.006] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:40:0)
[14:41:30.046] Build status: STARTED
[14:41:30.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:41:0) after 5m28s
[14:46:58.426] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:41:0)
[14:46:58.447] Build status: STARTED
[14:46:58.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:42:0) after 4m40s
[14:51:38.510] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:42:0)
[14:51:38.528] Build status: STARTED
[14:51:38.528] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:43:0) after 1m20s
[14:52:58.502] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:43:0)
[14:52:58.522] Build status: STARTED
[14:52:58.522] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:44:0) after 9m48s
[15:02:46.542] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:44:0)
[15:02:46.563] Build status: STARTED
[15:02:46.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:45:0) after 1m48s
[15:04:34.583] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:45:0)
[15:04:34.602] Build status: STARTED
[15:04:34.602] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:46:0) after 8m42s
[15:13:16.822] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:46:0)
[15:13:16.839] Build status: STARTED
[15:13:16.839] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:47:0) after 7m13s
[15:20:29.968] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:47:0)
[15:20:29.986] Build status: STARTED
[15:20:29.986] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8835664458968458624:48:0) after 1m44s
[15:20:52.415] Received PubSub notification, asking Buildbucket for the build status
[15:20:52.436] Build:
{
"id": "8670234396900775041",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux Viz"
},
"number": 49385,
"createdBy": "project:chromium",
"createTime": "2026-09-19T11:26:00.661420481Z",
"startTime": "2026-09-19T11:39:18.527422815Z",
"endTime": "2026-09-19T15:20:52.163041957Z",
"updateTime": "2026-09-19T15:20:52.163041957Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "07542951acb2cc5867596913b1a8b52799741e59",
"ref": "refs/heads/main"
}
}
}
[15:20:52.436] Invocation finished in 3h54m53.129886818s with status FAILED