[10:15:52.148] New invocation is queued and will start shortly
[10:15:53.251] Starting the invocation (attempt 1)
[10:15:53.277] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/afbc4826da0f625a5bb5f412ec5db3a652ea1922"
[10:15:53.277] Popped gitiles commit info from properties and tags
[10:15:53.278] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:15:53.278] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:15:53.278] Buildbucket request:
{
"requestId": "8875803326627672416",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "afbc4826da0f625a5bb5f412ec5db3a652ea1922",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8875803326627672416"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-arm64-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[10:15:53.843] Scheduled build:
{
"id": "8710373264701530257",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 5062,
"createdBy": "project:chromium",
"createTime": "2025-07-03T10:15:53.350419341Z",
"updateTime": "2025-07-03T10:15:53.350419341Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "afbc4826da0f625a5bb5f412ec5db3a652ea1922",
"ref": "refs/heads/main"
}
}
}
[10:15:53.843] Task URL: https://cr-buildbucket.appspot.com/build/8710373264701530257
[10:15:53.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:2:0) after 5m7s
[10:15:54.878] Received PubSub notification, asking Buildbucket for the build status
[10:15:54.902] Build status: SCHEDULED
[10:15:59.466] Received PubSub notification, asking Buildbucket for the build status
[10:15:59.491] Build status: STARTED
[10:21:00.865] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:2:0)
[10:21:00.964] Build status: STARTED
[10:21:00.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:5:0) after 9m13s
[10:30:14.037] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:5:0)
[10:30:14.059] Build status: STARTED
[10:30:14.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:6:0) after 8m53s
[10:39:07.231] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:6:0)
[10:39:07.279] Build status: STARTED
[10:39:07.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:7:0) after 9m0s
[10:48:07.478] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:7:0)
[10:48:07.509] Build status: STARTED
[10:48:07.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:8:0) after 3m9s
[10:51:17.108] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:8:0)
[10:51:17.126] Build status: STARTED
[10:51:17.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:9:0) after 1m52s
[10:53:09.120] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:9:0)
[10:53:09.144] Build status: STARTED
[10:53:09.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:10:0) after 7m52s
[11:01:01.467] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:10:0)
[11:01:01.485] Build status: STARTED
[11:01:01.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:11:0) after 3m33s
[11:04:34.523] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:11:0)
[11:04:34.549] Build status: STARTED
[11:04:34.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:12:0) after 6m6s
[11:10:40.897] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:12:0)
[11:10:40.921] Build status: STARTED
[11:10:40.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:13:0) after 7m41s
[11:18:22.145] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:13:0)
[11:18:22.175] Build status: STARTED
[11:18:22.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:14:0) after 9m10s
[11:27:32.658] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:14:0)
[11:27:32.678] Build status: STARTED
[11:27:32.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:15:0) after 3m43s
[11:31:16.206] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:15:0)
[11:31:16.258] Build status: STARTED
[11:31:16.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:16:0) after 8m4s
[11:39:20.626] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:16:0)
[11:39:20.655] Build status: STARTED
[11:39:20.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:17:0) after 4m6s
[11:43:26.834] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:17:0)
[11:43:26.853] Build status: STARTED
[11:43:26.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:18:0) after 9m1s
[11:52:28.025] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:18:0)
[11:52:28.045] Build status: STARTED
[11:52:28.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:19:0) after 9m24s
[12:01:52.067] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:19:0)
[12:01:52.103] Build status: STARTED
[12:01:52.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:20:0) after 2m21s
[12:04:13.111] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:20:0)
[12:04:13.128] Build status: STARTED
[12:04:13.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:21:0) after 4m5s
[12:08:18.181] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:21:0)
[12:08:18.198] Build status: STARTED
[12:08:18.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:22:0) after 5m15s
[12:13:33.902] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:22:0)
[12:13:33.926] Build status: STARTED
[12:13:33.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:23:0) after 2m28s
[12:16:01.976] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:23:0)
[12:16:01.999] Build status: STARTED
[12:16:01.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:24:0) after 9m28s
[12:25:30.038] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:24:0)
[12:25:30.056] Build status: STARTED
[12:25:30.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:25:0) after 1m42s
[12:27:12.086] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:25:0)
[12:27:12.163] Build status: STARTED
[12:27:12.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:26:0) after 8m19s
[12:35:31.235] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:26:0)
[12:35:31.254] Build status: STARTED
[12:35:31.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:27:0) after 9m59s
[12:45:30.274] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:27:0)
[12:45:30.299] Build status: STARTED
[12:45:30.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:28:0) after 8m43s
[12:54:13.339] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:28:0)
[12:54:13.355] Build status: STARTED
[12:54:13.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:29:0) after 4m15s
[12:58:28.385] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:29:0)
[12:58:28.412] Build status: STARTED
[12:58:28.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:30:0) after 5m4s
[13:03:32.431] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:30:0)
[13:03:32.447] Build status: STARTED
[13:03:32.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:31:0) after 7m42s
[13:11:14.508] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:31:0)
[13:11:14.527] Build status: STARTED
[13:11:14.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:32:0) after 2m53s
[13:14:07.556] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:32:0)
[13:14:07.584] Build status: STARTED
[13:14:07.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:33:0) after 8m19s
[13:22:26.608] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:33:0)
[13:22:26.624] Build status: STARTED
[13:22:26.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:34:0) after 5m0s
[13:27:26.645] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:34:0)
[13:27:26.670] Build status: STARTED
[13:27:26.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8875803326627672416:35:0) after 6m2s
[13:32:57.578] Received PubSub notification, asking Buildbucket for the build status
[13:32:57.601] Build:
{
"id": "8710373264701530257",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 5062,
"createdBy": "project:chromium",
"createTime": "2025-07-03T10:15:53.350419341Z",
"startTime": "2025-07-03T10:15:59.239315875Z",
"endTime": "2025-07-03T13:32:57.167522015Z",
"updateTime": "2025-07-03T13:32:57.167522015Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "afbc4826da0f625a5bb5f412ec5db3a652ea1922",
"ref": "refs/heads/main"
}
}
}
[13:32:57.601] Invocation finished in 3h17m5.471146916s with status SUCCEEDED