[19:39:41.419] New invocation is queued and will start shortly
[19:39:42.826] Starting the invocation (attempt 1)
[19:39:42.858] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0d34ef0bb80cb8107e666923653aa7a29dd6705a"
[19:39:42.858] Popped gitiles commit info from properties and tags
[19:39:42.858] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:39:42.858] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:39:42.858] Buildbucket request:
{
"requestId": "8892256501939741520",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Windows deterministic"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0d34ef0bb80cb8107e666923653aa7a29dd6705a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8892256501939741520"
},
{
"key": "scheduler_job_id",
"value": "chromium/Windows deterministic"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:39:43.369] Scheduled build:
{
"id": "8726826439692622545",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Windows deterministic"
},
"number": 27877,
"createdBy": "project:chromium",
"createTime": "2025-01-02T19:39:42.938902375Z",
"updateTime": "2025-01-02T19:39:42.938902375Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0d34ef0bb80cb8107e666923653aa7a29dd6705a",
"ref": "refs/heads/main"
}
}
}
[19:39:43.369] Task URL: https://cr-buildbucket.appspot.com/build/8726826439692622545
[19:39:43.369] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:2:0) after 8m42s
[19:40:03.211] Received PubSub notification, asking Buildbucket for the build status
[19:40:03.245] Build status: STARTED
[19:40:08.812] Received PubSub notification, asking Buildbucket for the build status
[19:40:08.839] Build status: STARTED
[19:48:25.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:2:0)
[19:48:25.545] Build status: STARTED
[19:48:25.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:5:0) after 7m26s
[19:55:51.571] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:5:0)
[19:55:51.612] Build status: STARTED
[19:55:51.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:6:0) after 3m27s
[19:59:18.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:6:0)
[19:59:18.707] Build status: STARTED
[19:59:18.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:7:0) after 5m35s
[20:04:53.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:7:0)
[20:04:53.820] Build status: STARTED
[20:04:53.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:8:0) after 6m1s
[20:10:54.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:8:0)
[20:10:54.902] Build status: STARTED
[20:10:54.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:9:0) after 2m7s
[20:13:01.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:9:0)
[20:13:01.965] Build status: STARTED
[20:13:01.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:10:0) after 8m17s
[20:21:18.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:10:0)
[20:21:19.012] Build status: STARTED
[20:21:19.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:11:0) after 1m59s
[20:23:18.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:11:0)
[20:23:18.220] Build status: STARTED
[20:23:18.220] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:12:0) after 4m4s
[20:27:22.237] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:12:0)
[20:27:22.260] Build status: STARTED
[20:27:22.260] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:13:0) after 1m21s
[20:28:43.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:13:0)
[20:28:43.267] Build status: STARTED
[20:28:43.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:14:0) after 6m14s
[20:34:57.466] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:14:0)
[20:34:57.594] Build status: STARTED
[20:34:57.594] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:15:0) after 2m31s
[20:37:28.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:15:0)
[20:37:28.772] Build status: STARTED
[20:37:28.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:16:0) after 9m13s
[20:46:41.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:16:0)
[20:46:41.871] Build status: STARTED
[20:46:41.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:17:0) after 5m47s
[20:52:28.986] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:17:0)
[20:52:29.046] Build status: STARTED
[20:52:29.046] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:18:0) after 4m40s
[20:57:09.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:18:0)
[20:57:09.057] Build status: STARTED
[20:57:09.057] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:19:0) after 3m8s
[21:00:17.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:19:0)
[21:00:17.122] Build status: STARTED
[21:00:17.122] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:20:0) after 8m58s
[21:09:15.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:20:0)
[21:09:15.214] Build status: STARTED
[21:09:15.214] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:21:0) after 9m35s
[21:18:50.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:21:0)
[21:18:50.274] Build status: STARTED
[21:18:50.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:22:0) after 9m36s
[21:28:26.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:22:0)
[21:28:26.449] Build status: STARTED
[21:28:26.449] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:23:0) after 2m16s
[21:30:42.547] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:23:0)
[21:30:42.564] Build status: STARTED
[21:30:42.564] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:24:0) after 6m42s
[21:37:24.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:24:0)
[21:37:24.745] Build status: STARTED
[21:37:24.745] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:25:0) after 5m45s
[21:43:09.765] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:25:0)
[21:43:09.781] Build status: STARTED
[21:43:09.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:26:0) after 8m5s
[21:51:14.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:26:0)
[21:51:14.822] Build status: STARTED
[21:51:14.822] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:27:0) after 3m36s
[21:54:50.842] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:27:0)
[21:54:50.857] Build status: STARTED
[21:54:50.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:28:0) after 2m33s
[21:57:23.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:28:0)
[21:57:23.902] Build status: STARTED
[21:57:23.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:29:0) after 6m1s
[22:03:25.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:29:0)
[22:03:25.158] Build status: STARTED
[22:03:25.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:30:0) after 4m12s
[22:07:37.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:30:0)
[22:07:37.257] Build status: STARTED
[22:07:37.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:31:0) after 6m25s
[22:14:02.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:31:0)
[22:14:02.344] Build status: STARTED
[22:14:02.344] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:32:0) after 3m50s
[22:17:52.390] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:32:0)
[22:17:52.414] Build status: STARTED
[22:17:52.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:33:0) after 4m12s
[22:22:04.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:33:0)
[22:22:04.463] Build status: STARTED
[22:22:04.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:34:0) after 7m52s
[22:29:56.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:34:0)
[22:29:56.508] Build status: STARTED
[22:29:56.508] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:35:0) after 2m6s
[22:32:02.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:35:0)
[22:32:02.559] Build status: STARTED
[22:32:02.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:36:0) after 7m23s
[22:39:25.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:36:0)
[22:39:25.639] Build status: STARTED
[22:39:25.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:37:0) after 1m46s
[22:41:11.657] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:37:0)
[22:41:11.680] Build status: STARTED
[22:41:11.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:38:0) after 8m24s
[22:49:35.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:38:0)
[22:49:35.720] Build status: STARTED
[22:49:35.720] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:39:0) after 4m11s
[22:53:46.825] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:39:0)
[22:53:46.842] Build status: STARTED
[22:53:46.842] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8892256501939741520:40:0) after 6m5s
[22:57:19.740] Received PubSub notification, asking Buildbucket for the build status
[22:57:19.768] Build:
{
"id": "8726826439692622545",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Windows deterministic"
},
"number": 27877,
"createdBy": "project:chromium",
"createTime": "2025-01-02T19:39:42.938902375Z",
"startTime": "2025-01-02T19:39:48.319867924Z",
"endTime": "2025-01-02T22:57:19.118871252Z",
"updateTime": "2025-01-02T22:57:19.118871252Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0d34ef0bb80cb8107e666923653aa7a29dd6705a",
"ref": "refs/heads/main"
}
}
}
[22:57:19.768] Invocation finished in 3h17m38.356994405s with status SUCCEEDED