[21:05:48.853] New invocation is queued and will start shortly
[21:05:49.996] Starting the invocation (attempt 1)
[21:05:50.024] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a88888fa7b4edaf0ed432df0977f6c5465f52c7b"
[21:05:50.024] Popped gitiles commit info from properties and tags
[21:05:50.024] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:05:50.025] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:05:50.025] Buildbucket request:
{
"requestId": "8835174994657728752",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a88888fa7b4edaf0ed432df0977f6c5465f52c7b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8835174994657728752"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:05:50.591] Scheduled build:
{
"id": "8669744932706552321",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"number": 15578,
"createdBy": "project:chromium",
"createTime": "2026-09-24T21:05:50.092975921Z",
"updateTime": "2026-09-24T21:05:50.092975921Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a88888fa7b4edaf0ed432df0977f6c5465f52c7b",
"ref": "refs/heads/main"
}
}
}
[21:05:50.592] Task URL: https://cr-buildbucket.appspot.com/build/8669744932706552321
[21:05:50.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:2:0) after 7m2s
[21:05:51.532] Received PubSub notification, asking Buildbucket for the build status
[21:05:51.615] Build status: SCHEDULED
[21:09:35.987] Received PubSub notification, asking Buildbucket for the build status
[21:09:36.008] Build status: STARTED
[21:12:52.613] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:2:0)
[21:12:52.675] Build status: STARTED
[21:12:52.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:5:0) after 3m11s
[21:16:03.695] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:5:0)
[21:16:03.718] Build status: STARTED
[21:16:03.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:6:0) after 4m6s
[21:20:09.702] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:6:0)
[21:20:09.731] Build status: STARTED
[21:20:09.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:7:0) after 9m51s
[21:30:00.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:7:0)
[21:30:00.843] Build status: STARTED
[21:30:00.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:8:0) after 9m16s
[21:39:16.886] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:8:0)
[21:39:16.906] Build status: STARTED
[21:39:16.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:9:0) after 9m26s
[21:48:42.925] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:9:0)
[21:48:42.944] Build status: STARTED
[21:48:42.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:10:0) after 1m49s
[21:50:31.967] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:10:0)
[21:50:31.993] Build status: STARTED
[21:50:31.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:11:0) after 9m24s
[21:59:56.015] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:11:0)
[21:59:56.032] Build status: STARTED
[21:59:56.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:12:0) after 1m21s
[22:01:17.062] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:12:0)
[22:01:17.078] Build status: STARTED
[22:01:17.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:13:0) after 2m44s
[22:04:01.169] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:13:0)
[22:04:01.190] Build status: STARTED
[22:04:01.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:14:0) after 8m43s
[22:12:44.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:14:0)
[22:12:44.259] Build status: STARTED
[22:12:44.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:15:0) after 4m2s
[22:16:46.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:15:0)
[22:16:46.360] Build status: STARTED
[22:16:46.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:16:0) after 8m26s
[22:25:12.397] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:16:0)
[22:25:12.474] Build status: STARTED
[22:25:12.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:17:0) after 7m52s
[22:33:04.594] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:17:0)
[22:33:04.615] Build status: STARTED
[22:33:04.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:18:0) after 1m45s
[22:34:49.688] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:18:0)
[22:34:49.705] Build status: STARTED
[22:34:49.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:19:0) after 9m22s
[22:44:11.742] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:19:0)
[22:44:11.763] Build status: STARTED
[22:44:11.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:20:0) after 6m39s
[22:50:50.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:20:0)
[22:50:50.799] Build status: STARTED
[22:50:50.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:21:0) after 7m54s
[22:58:44.833] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:21:0)
[22:58:44.859] Build status: STARTED
[22:58:44.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:22:0) after 9m14s
[23:07:58.931] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:22:0)
[23:07:58.950] Build status: STARTED
[23:07:58.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:23:0) after 3m1s
[23:10:59.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:23:0)
[23:10:59.999] Build status: STARTED
[23:10:59.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:24:0) after 1m42s
[23:12:42.075] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:24:0)
[23:12:42.092] Build status: STARTED
[23:12:42.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:25:0) after 2m25s
[23:15:07.107] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:25:0)
[23:15:07.128] Build status: STARTED
[23:15:07.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:26:0) after 8m32s
[23:23:39.239] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:26:0)
[23:23:39.363] Build status: STARTED
[23:23:39.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:27:0) after 1m7s
[23:24:46.397] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:27:0)
[23:24:46.427] Build status: STARTED
[23:24:46.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:28:0) after 7m56s
[23:32:42.446] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:28:0)
[23:32:42.465] Build status: STARTED
[23:32:42.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:29:0) after 5m9s
[23:37:51.518] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:29:0)
[23:37:51.542] Build status: STARTED
[23:37:51.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:30:0) after 3m12s
[23:41:03.561] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:30:0)
[23:41:03.581] Build status: STARTED
[23:41:03.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:31:0) after 2m16s
[23:43:19.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:31:0)
[23:43:19.621] Build status: STARTED
[23:43:19.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:32:0) after 6m9s
[23:49:28.642] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:32:0)
[23:49:28.671] Build status: STARTED
[23:49:28.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:33:0) after 5m27s
[23:54:55.743] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:33:0)
[23:54:55.762] Build status: STARTED
[23:54:55.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:34:0) after 6m4s
[00:00:59.782] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:34:0)
[00:00:59.805] Build status: STARTED
[00:00:59.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:35:0) after 7m12s
[00:08:11.806] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:35:0)
[00:08:11.847] Build status: STARTED
[00:08:11.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:36:0) after 3m10s
[00:11:21.919] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:36:0)
[00:11:22.006] Build status: STARTED
[00:11:22.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:37:0) after 9m52s
[00:21:14.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:37:0)
[00:21:14.230] Build status: STARTED
[00:21:14.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:38:0) after 6m21s
[00:27:35.471] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:38:0)
[00:27:35.493] Build status: STARTED
[00:27:35.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:39:0) after 1m8s
[00:28:43.504] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:39:0)
[00:28:43.522] Build status: STARTED
[00:28:43.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:40:0) after 9m46s
[00:38:29.545] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:40:0)
[00:38:29.570] Build status: STARTED
[00:38:29.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:41:0) after 3m39s
[00:42:08.708] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:41:0)
[00:42:08.727] Build status: STARTED
[00:42:08.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:42:0) after 9m28s
[00:51:36.713] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:42:0)
[00:51:36.752] Build status: STARTED
[00:51:36.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:43:0) after 8m47s
[01:00:23.820] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:43:0)
[01:00:23.863] Build status: STARTED
[01:00:23.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:44:0) after 7m10s
[01:07:33.889] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:44:0)
[01:07:33.908] Build status: STARTED
[01:07:33.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:45:0) after 5m59s
[01:13:33.009] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:45:0)
[01:13:33.027] Build status: STARTED
[01:13:33.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:46:0) after 1m54s
[01:15:27.180] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:46:0)
[01:15:27.200] Build status: STARTED
[01:15:27.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:47:0) after 6m21s
[01:21:48.222] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:47:0)
[01:21:48.244] Build status: STARTED
[01:21:48.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:48:0) after 4m45s
[01:26:33.267] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:48:0)
[01:26:33.286] Build status: STARTED
[01:26:33.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:49:0) after 9m46s
[01:36:19.327] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:49:0)
[01:36:19.359] Build status: STARTED
[01:36:19.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:50:0) after 5m0s
[01:41:19.381] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:50:0)
[01:41:19.425] Build status: STARTED
[01:41:19.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:51:0) after 9m30s
[01:50:49.498] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:51:0)
[01:50:49.516] Build status: STARTED
[01:50:49.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:52:0) after 1m0s
[01:51:49.587] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:52:0)
[01:51:49.609] Build status: STARTED
[01:51:49.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:53:0) after 3m35s
[01:55:24.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:53:0)
[01:55:24.690] Build status: STARTED
[01:55:24.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8835174994657728752:54:0) after 5m51s
[01:57:25.595] Received PubSub notification, asking Buildbucket for the build status
[01:57:25.627] Build:
{
"id": "8669744932706552321",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"number": 15578,
"createdBy": "project:chromium",
"createTime": "2026-09-24T21:05:50.092975921Z",
"startTime": "2026-09-24T21:09:35.360783977Z",
"endTime": "2026-09-25T01:57:19.797353367Z",
"updateTime": "2026-09-25T01:57:19.797353367Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a88888fa7b4edaf0ed432df0977f6c5465f52c7b",
"ref": "refs/heads/main"
}
}
}
[01:57:25.627] Invocation finished in 4h51m36.779752037s with status SUCCEEDED