[05:13:42.623] New invocation is queued and will start shortly
[05:13:43.697] Starting the invocation (attempt 1)
[05:13:43.734] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ece2d501d1ccbac13d3bb423688627d3d8da1ca"
[05:13:43.734] Popped gitiles commit info from properties and tags
[05:13:43.734] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:13:43.734] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:13:43.735] Buildbucket request:
{
"requestId": "8927734398550928112",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Windows deterministic"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4ece2d501d1ccbac13d3bb423688627d3d8da1ca",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8927734398550928112"
},
{
"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"
}
}
[05:13:44.670] Scheduled build:
{
"id": "8762304336548786641",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Windows deterministic"
},
"number": 22852,
"createdBy": "project:chromium",
"createTime": "2023-12-08T05:13:43.906457009Z",
"updateTime": "2023-12-08T05:13:43.906457009Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4ece2d501d1ccbac13d3bb423688627d3d8da1ca",
"ref": "refs/heads/main"
}
}
}
[05:13:44.670] Task URL: https://cr-buildbucket.appspot.com/build/8762304336548786641
[05:13:44.670] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:2:0) after 9m27s
[05:13:45.568] Received PubSub notification, asking Buildbucket for the build status
[05:13:45.586] Build status: SCHEDULED
[05:19:00.966] Received PubSub notification, asking Buildbucket for the build status
[05:19:00.988] Build status: STARTED
[05:23:11.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:2:0)
[05:23:11.766] Build status: STARTED
[05:23:11.766] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:5:0) after 4m26s
[05:27:37.860] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:5:0)
[05:27:37.920] Build status: STARTED
[05:27:37.920] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:6:0) after 1m43s
[05:29:20.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:6:0)
[05:29:20.931] Build status: STARTED
[05:29:20.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:7:0) after 2m41s
[05:32:01.971] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:7:0)
[05:32:01.993] Build status: STARTED
[05:32:01.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:8:0) after 7m35s
[05:39:37.017] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:8:0)
[05:39:37.036] Build status: STARTED
[05:39:37.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:9:0) after 1m21s
[05:40:58.038] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:9:0)
[05:40:58.063] Build status: STARTED
[05:40:58.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:10:0) after 3m39s
[05:44:37.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:10:0)
[05:44:37.159] Build status: STARTED
[05:44:37.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:11:0) after 4m16s
[05:48:53.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:11:0)
[05:48:53.205] Build status: STARTED
[05:48:53.205] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:12:0) after 5m59s
[05:54:52.309] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:12:0)
[05:54:52.341] Build status: STARTED
[05:54:52.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:13:0) after 3m25s
[05:58:17.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:13:0)
[05:58:17.554] Build status: STARTED
[05:58:17.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:14:0) after 9m55s
[06:08:12.794] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:14:0)
[06:08:12.813] Build status: STARTED
[06:08:12.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:15:0) after 9m42s
[06:17:55.024] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:15:0)
[06:17:55.065] Build status: STARTED
[06:17:55.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:16:0) after 7m29s
[06:25:24.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:16:0)
[06:25:24.670] Build status: STARTED
[06:25:24.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:17:0) after 5m14s
[06:30:38.865] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:17:0)
[06:30:38.910] Build status: STARTED
[06:30:38.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:18:0) after 4m46s
[06:35:25.026] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:18:0)
[06:35:25.046] Build status: STARTED
[06:35:25.046] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:19:0) after 7m0s
[06:42:25.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:19:0)
[06:42:25.254] Build status: STARTED
[06:42:25.254] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:20:0) after 7m42s
[06:50:07.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:20:0)
[06:50:07.297] Build status: STARTED
[06:50:07.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:21:0) after 2m9s
[06:52:17.103] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:21:0)
[06:52:17.117] Build status: STARTED
[06:52:17.117] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:22:0) after 5m8s
[06:57:25.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:22:0)
[06:57:25.166] Build status: STARTED
[06:57:25.166] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:23:0) after 8m41s
[07:06:06.245] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:23:0)
[07:06:06.268] Build status: STARTED
[07:06:06.269] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:24:0) after 1m58s
[07:08:05.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:24:0)
[07:08:05.146] Build status: STARTED
[07:08:05.146] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:25:0) after 2m50s
[07:10:55.371] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:25:0)
[07:10:55.392] Build status: STARTED
[07:10:55.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:26:0) after 2m32s
[07:13:27.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:26:0)
[07:13:27.428] Build status: STARTED
[07:13:27.428] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:27:0) after 6m13s
[07:19:40.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:27:0)
[07:19:40.565] Build status: STARTED
[07:19:40.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:28:0) after 7m14s
[07:26:54.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:28:0)
[07:26:54.819] Build status: STARTED
[07:26:54.819] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:29:0) after 4m44s
[07:31:38.943] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:29:0)
[07:31:38.962] Build status: STARTED
[07:31:38.962] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:30:0) after 7m43s
[07:39:21.991] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:30:0)
[07:39:22.009] Build status: STARTED
[07:39:22.009] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:31:0) after 7m14s
[07:46:36.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:31:0)
[07:46:36.299] Build status: STARTED
[07:46:36.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:32:0) after 8m46s
[07:55:22.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:32:0)
[07:55:22.554] Build status: STARTED
[07:55:22.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:33:0) after 5m48s
[08:01:10.569] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:33:0)
[08:01:10.660] Build status: STARTED
[08:01:10.661] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:34:0) after 4m56s
[08:06:06.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:34:0)
[08:06:06.864] Build status: STARTED
[08:06:06.864] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:35:0) after 5m22s
[08:11:28.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:35:0)
[08:11:28.971] Build status: STARTED
[08:11:28.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:36:0) after 8m54s
[08:20:23.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:36:0)
[08:20:23.028] Build status: STARTED
[08:20:23.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:37:0) after 2m50s
[08:23:13.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:37:0)
[08:23:13.060] Build status: STARTED
[08:23:13.060] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:38:0) after 6m33s
[08:29:46.296] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:38:0)
[08:29:46.342] Build status: STARTED
[08:29:46.342] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:39:0) after 2m40s
[08:32:26.496] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:39:0)
[08:32:26.516] Build status: STARTED
[08:32:26.516] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:40:0) after 4m54s
[08:37:20.650] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:40:0)
[08:37:20.669] Build status: STARTED
[08:37:20.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:41:0) after 4m53s
[08:42:13.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:41:0)
[08:42:13.801] Build status: STARTED
[08:42:13.801] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:42:0) after 4m41s
[08:46:54.828] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:42:0)
[08:46:54.846] Build status: STARTED
[08:46:54.846] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:43:0) after 2m8s
[08:49:02.864] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:43:0)
[08:49:02.948] Build status: STARTED
[08:49:02.948] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:44:0) after 7m59s
[08:57:01.970] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:44:0)
[08:57:01.987] Build status: STARTED
[08:57:01.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:45:0) after 3m9s
[09:00:11.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:45:0)
[09:00:11.023] Build status: STARTED
[09:00:11.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:46:0) after 9m35s
[09:09:46.131] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:46:0)
[09:09:46.197] Build status: STARTED
[09:09:46.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8927734398550928112:47:0) after 9m50s
[09:12:45.139] Received PubSub notification, asking Buildbucket for the build status
[09:12:45.163] Build:
{
"id": "8762304336548786641",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Windows deterministic"
},
"number": 22852,
"createdBy": "project:chromium",
"createTime": "2023-12-08T05:13:43.906457009Z",
"startTime": "2023-12-08T05:18:59.956660Z",
"endTime": "2023-12-08T09:12:44.401391Z",
"updateTime": "2023-12-08T09:12:44.401391Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4ece2d501d1ccbac13d3bb423688627d3d8da1ca",
"ref": "refs/heads/main"
}
}
}
[09:12:45.163] Invocation finished in 3h59m2.550404703s with status SUCCEEDED