[17:59:34.043] New invocation is queued and will start shortly
[17:59:35.112] Starting the invocation (attempt 1)
[17:59:35.139] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c197c9230cc484c703cbf397b0d27faef97aada6"
[17:59:35.139] Popped gitiles commit info from properties and tags
[17:59:35.139] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[17:59:35.139] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[17:59:35.139] Buildbucket request:
{
"requestId": "8835458503194813344",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win11-rel-dev"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c197c9230cc484c703cbf397b0d27faef97aada6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8835458503194813344"
},
{
"key": "scheduler_job_id",
"value": "chromium/win11-rel-dev"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[17:59:38.359] Scheduled build:
{
"id": "8670028439226687057",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win11-rel-dev"
},
"number": 22871,
"createdBy": "project:chromium",
"createTime": "2026-09-21T17:59:37.205438531Z",
"updateTime": "2026-09-21T17:59:37.926369851Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c197c9230cc484c703cbf397b0d27faef97aada6",
"ref": "refs/heads/main"
}
}
}
[17:59:38.359] Task URL: https://cr-buildbucket-dev.appspot.com/build/8670028439226687057
[17:59:38.359] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:2:0) after 4m37s
[17:59:38.805] Received PubSub notification, asking Buildbucket for the build status
[17:59:38.823] Build status: SCHEDULED
[18:02:30.033] Received PubSub notification, asking Buildbucket for the build status
[18:02:30.059] Build status: STARTED
[18:04:15.384] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:2:0)
[18:04:15.442] Build status: STARTED
[18:04:15.442] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:5:0) after 9m45s
[18:14:00.459] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:5:0)
[18:14:00.481] Build status: STARTED
[18:14:00.481] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:6:0) after 5m2s
[18:19:02.515] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:6:0)
[18:19:02.545] Build status: STARTED
[18:19:02.545] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:7:0) after 6m15s
[18:25:17.569] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:7:0)
[18:25:17.586] Build status: STARTED
[18:25:17.586] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:8:0) after 3m22s
[18:28:39.605] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:8:0)
[18:28:39.626] Build status: STARTED
[18:28:39.626] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:9:0) after 8m23s
[18:37:02.644] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:9:0)
[18:37:02.667] Build status: STARTED
[18:37:02.667] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:10:0) after 4m41s
[18:41:43.691] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:10:0)
[18:41:43.708] Build status: STARTED
[18:41:43.708] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:11:0) after 1m30s
[18:43:13.727] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:11:0)
[18:43:13.746] Build status: STARTED
[18:43:13.746] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:12:0) after 5m33s
[18:48:46.772] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:12:0)
[18:48:46.803] Build status: STARTED
[18:48:46.803] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:13:0) after 4m42s
[18:53:28.835] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:13:0)
[18:53:28.857] Build status: STARTED
[18:53:28.857] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:14:0) after 8m47s
[19:02:15.885] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:14:0)
[19:02:15.912] Build status: STARTED
[19:02:15.912] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:15:0) after 7m27s
[19:09:42.931] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:15:0)
[19:09:42.967] Build status: STARTED
[19:09:42.967] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:16:0) after 1m56s
[19:11:38.986] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:16:0)
[19:11:39.008] Build status: STARTED
[19:11:39.008] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:17:0) after 5m47s
[19:17:26.911] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:17:0)
[19:17:26.930] Build status: STARTED
[19:17:26.930] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:18:0) after 4m44s
[19:22:10.949] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:18:0)
[19:22:10.965] Build status: STARTED
[19:22:10.966] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:19:0) after 4m20s
[19:26:30.994] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:19:0)
[19:26:31.017] Build status: STARTED
[19:26:31.017] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:20:0) after 7m0s
[19:33:31.048] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:20:0)
[19:33:31.077] Build status: STARTED
[19:33:31.078] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:21:0) after 3m20s
[19:36:51.096] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:21:0)
[19:36:51.122] Build status: STARTED
[19:36:51.122] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:22:0) after 9m21s
[19:46:12.143] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:22:0)
[19:46:14.151] Build status: STARTED
[19:46:14.151] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:23:0) after 5m37s
[19:51:51.174] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:23:0)
[19:51:51.196] Build status: STARTED
[19:51:51.196] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:24:0) after 3m42s
[19:55:33.217] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:24:0)
[19:55:33.242] Build status: STARTED
[19:55:33.242] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:25:0) after 4m35s
[20:00:08.264] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:25:0)
[20:00:08.282] Build status: STARTED
[20:00:08.282] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:26:0) after 8m18s
[20:08:26.304] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:26:0)
[20:08:26.326] Build status: STARTED
[20:08:26.326] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:27:0) after 2m38s
[20:11:04.350] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:27:0)
[20:11:04.371] Build status: STARTED
[20:11:04.371] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:28:0) after 7m50s
[20:18:54.397] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:28:0)
[20:18:54.427] Build status: STARTED
[20:18:54.428] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:29:0) after 7m28s
[20:26:22.452] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:29:0)
[20:26:22.474] Build status: STARTED
[20:26:22.474] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:30:0) after 7m31s
[20:33:53.494] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:30:0)
[20:33:53.528] Build status: STARTED
[20:33:53.528] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:31:0) after 9m20s
[20:43:13.549] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:31:0)
[20:43:13.568] Build status: STARTED
[20:43:13.568] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:32:0) after 4m43s
[20:47:56.605] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:32:0)
[20:47:56.623] Build status: STARTED
[20:47:56.623] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:33:0) after 6m45s
[20:54:41.648] Handling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:33:0)
[20:54:41.669] Build status: STARTED
[20:54:41.669] Scheduling timer "check-buildbucket-build-status" (chromium/win11-rel-dev:8835458503194813344:34:0) after 8m34s
[21:03:00.047] Received PubSub notification, asking Buildbucket for the build status
[21:03:00.066] Build:
{
"id": "8670028439226687057",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win11-rel-dev"
},
"number": 22871,
"createdBy": "project:chromium",
"createTime": "2026-09-21T17:59:37.205438531Z",
"startTime": "2026-09-21T18:02:07.626459652Z",
"endTime": "2026-09-21T21:02:34.558345223Z",
"updateTime": "2026-09-21T21:02:34.558345223Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c197c9230cc484c703cbf397b0d27faef97aada6",
"ref": "refs/heads/main"
}
}
}
[21:03:00.066] Invocation finished in 3h3m26.028312335s with status SUCCEEDED