[07:57:09.645] New invocation is queued and will start shortly
[07:57:10.885] Starting the invocation (attempt 1)
[07:57:10.908] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/97f213fb19951e1bf4463d528f68efec87e11a27"
[07:57:10.908] Popped gitiles commit info from properties and tags
[07:57:10.908] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:57:10.908] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:57:10.909] Buildbucket request:
{
"requestId": "8850897887632279552",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "97f213fb19951e1bf4463d528f68efec87e11a27",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8850897887632279552"
},
{
"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"
}
}
[07:57:11.851] Scheduled build:
{
"id": "8685467825594072001",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"number": 14503,
"createdBy": "project:chromium",
"createTime": "2026-04-04T07:57:10.966852016Z",
"updateTime": "2026-04-04T07:57:10.966852016Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "97f213fb19951e1bf4463d528f68efec87e11a27",
"ref": "refs/heads/main"
}
}
}
[07:57:11.851] Task URL: https://cr-buildbucket.appspot.com/build/8685467825594072001
[07:57:11.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:2:0) after 1m14s
[07:57:12.888] Received PubSub notification, asking Buildbucket for the build status
[07:57:12.911] Build status: SCHEDULED
[07:57:18.013] Received PubSub notification, asking Buildbucket for the build status
[07:57:18.040] Build status: STARTED
[07:58:26.032] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:2:0)
[07:58:26.050] Build status: STARTED
[07:58:26.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:5:0) after 2m30s
[08:00:56.062] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:5:0)
[08:00:56.135] Build status: STARTED
[08:00:56.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:6:0) after 9m35s
[08:10:31.701] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:6:0)
[08:10:31.730] Build status: STARTED
[08:10:31.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:7:0) after 3m29s
[08:14:00.692] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:7:0)
[08:14:00.712] Build status: STARTED
[08:14:00.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:8:0) after 6m25s
[08:20:25.804] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:8:0)
[08:20:25.833] Build status: STARTED
[08:20:25.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:9:0) after 2m44s
[08:23:09.850] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:9:0)
[08:23:09.874] Build status: STARTED
[08:23:09.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:10:0) after 5m40s
[08:28:49.921] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:10:0)
[08:28:49.938] Build status: STARTED
[08:28:49.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:11:0) after 4m31s
[08:33:20.952] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:11:0)
[08:33:21.031] Build status: STARTED
[08:33:21.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:12:0) after 8m20s
[08:41:41.056] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:12:0)
[08:41:41.079] Build status: STARTED
[08:41:41.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:13:0) after 9m48s
[08:51:29.161] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:13:0)
[08:51:29.271] Build status: STARTED
[08:51:29.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:14:0) after 8m56s
[09:00:25.398] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:14:0)
[09:00:25.432] Build status: STARTED
[09:00:25.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:15:0) after 3m26s
[09:03:51.548] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:15:0)
[09:03:51.566] Build status: STARTED
[09:03:51.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:16:0) after 9m46s
[09:13:37.655] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:16:0)
[09:13:37.687] Build status: STARTED
[09:13:37.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:17:0) after 4m18s
[09:17:55.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:17:0)
[09:17:55.721] Build status: STARTED
[09:17:55.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:18:0) after 2m36s
[09:20:31.791] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:18:0)
[09:20:31.806] Build status: STARTED
[09:20:31.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:19:0) after 2m45s
[09:23:16.983] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:19:0)
[09:23:17.007] Build status: STARTED
[09:23:17.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:20:0) after 1m53s
[09:25:10.020] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:20:0)
[09:25:10.042] Build status: STARTED
[09:25:10.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:21:0) after 8m17s
[09:33:27.017] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:21:0)
[09:33:27.092] Build status: STARTED
[09:33:27.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:22:0) after 7m44s
[09:41:11.105] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:22:0)
[09:41:11.127] Build status: STARTED
[09:41:11.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:23:0) after 9m56s
[09:51:07.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:23:0)
[09:51:07.362] Build status: STARTED
[09:51:07.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:24:0) after 7m45s
[09:58:52.498] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:24:0)
[09:58:52.513] Build status: STARTED
[09:58:52.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:25:0) after 4m22s
[10:03:14.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:25:0)
[10:03:14.617] Build status: STARTED
[10:03:14.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:26:0) after 4m41s
[10:07:55.982] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:26:0)
[10:07:56.000] Build status: STARTED
[10:07:56.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:27:0) after 9m59s
[10:17:55.021] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:27:0)
[10:17:55.043] Build status: STARTED
[10:17:55.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:28:0) after 9m29s
[10:27:24.205] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:28:0)
[10:27:24.295] Build status: STARTED
[10:27:24.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:29:0) after 4m58s
[10:32:22.313] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:29:0)
[10:32:22.347] Build status: STARTED
[10:32:22.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:30:0) after 4m54s
[10:37:16.365] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:30:0)
[10:37:16.450] Build status: STARTED
[10:37:16.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:31:0) after 9m20s
[10:46:36.465] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:31:0)
[10:46:36.481] Build status: STARTED
[10:46:36.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:32:0) after 8m58s
[10:55:34.498] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:32:0)
[10:55:34.591] Build status: STARTED
[10:55:34.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:33:0) after 4m27s
[11:00:01.606] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:33:0)
[11:00:01.673] Build status: STARTED
[11:00:01.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:34:0) after 6m9s
[11:06:10.825] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:34:0)
[11:06:10.845] Build status: STARTED
[11:06:10.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:35:0) after 5m8s
[11:11:19.002] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:35:0)
[11:11:19.024] Build status: STARTED
[11:11:19.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:36:0) after 6m56s
[11:18:15.055] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:36:0)
[11:18:15.091] Build status: STARTED
[11:18:15.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:37:0) after 4m0s
[11:22:15.109] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:37:0)
[11:22:15.129] Build status: STARTED
[11:22:15.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:38:0) after 1m37s
[11:23:52.507] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:38:0)
[11:23:52.526] Build status: STARTED
[11:23:52.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:39:0) after 6m28s
[11:30:20.540] Handling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:39:0)
[11:30:20.559] Build status: STARTED
[11:30:20.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8850897887632279552:40:0) after 9m10s
[11:31:52.606] Received PubSub notification, asking Buildbucket for the build status
[11:31:52.636] Build:
{
"id": "8685467825594072001",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"number": 14503,
"createdBy": "project:chromium",
"createTime": "2026-04-04T07:57:10.966852016Z",
"startTime": "2026-04-04T07:57:17.787987620Z",
"endTime": "2026-04-04T11:31:36.936240068Z",
"updateTime": "2026-04-04T11:31:36.936240068Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "97f213fb19951e1bf4463d528f68efec87e11a27",
"ref": "refs/heads/main"
}
}
}
[11:31:52.636] Invocation finished in 3h34m42.997621292s with status SUCCEEDED