[06:22:03.096] New invocation is queued and will start shortly
[06:22:04.301] Starting the invocation (attempt 1)
[06:22:04.332] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd05b9906d67632aa847c41fb67d5c0b23f6cf3b"
[06:22:04.332] Popped gitiles commit info from properties and tags
[06:22:04.332] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:22:04.332] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:22:04.333] Buildbucket request:
{
"requestId": "8933165916880091408",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-rel-cft"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "dd05b9906d67632aa847c41fb67d5c0b23f6cf3b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8933165916880091408"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-rel-cft"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:22:04.960] Scheduled build:
{
"id": "8767735854862091569",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-rel-cft"
},
"number": 3508,
"createdBy": "project:chromium",
"createTime": "2023-10-09T06:22:04.391131107Z",
"updateTime": "2023-10-09T06:22:04.391131107Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "dd05b9906d67632aa847c41fb67d5c0b23f6cf3b",
"ref": "refs/heads/main"
}
}
}
[06:22:04.960] Task URL: https://cr-buildbucket.appspot.com/build/8767735854862091569
[06:22:04.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:2:0) after 9m51s
[06:22:05.903] Received PubSub notification, asking Buildbucket for the build status
[06:22:05.923] Build status: SCHEDULED
[06:24:08.372] Received PubSub notification, asking Buildbucket for the build status
[06:24:08.395] Build status: STARTED
[06:31:56.078] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:2:0)
[06:31:56.108] Build status: STARTED
[06:31:56.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:5:0) after 3m42s
[06:35:38.280] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:5:0)
[06:35:38.402] Build status: STARTED
[06:35:38.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:6:0) after 9m8s
[06:44:46.614] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:6:0)
[06:44:46.763] Build status: STARTED
[06:44:46.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:7:0) after 8m0s
[06:52:46.852] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:7:0)
[06:52:46.873] Build status: STARTED
[06:52:46.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:8:0) after 3m23s
[06:56:09.898] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:8:0)
[06:56:09.927] Build status: STARTED
[06:56:09.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:9:0) after 7m58s
[07:04:07.948] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:9:0)
[07:04:07.972] Build status: STARTED
[07:04:07.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:10:0) after 4m7s
[07:08:15.016] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:10:0)
[07:08:15.034] Build status: STARTED
[07:08:15.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:11:0) after 4m5s
[07:12:20.054] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:11:0)
[07:12:20.075] Build status: STARTED
[07:12:20.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:12:0) after 2m28s
[07:14:48.108] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:12:0)
[07:14:48.148] Build status: STARTED
[07:14:48.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:13:0) after 3m57s
[07:18:45.166] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:13:0)
[07:18:45.198] Build status: STARTED
[07:18:45.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:14:0) after 9m50s
[07:28:35.226] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:14:0)
[07:28:35.248] Build status: STARTED
[07:28:35.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:15:0) after 9m0s
[07:37:35.550] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:15:0)
[07:37:35.574] Build status: STARTED
[07:37:35.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:16:0) after 5m36s
[07:43:11.603] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:16:0)
[07:43:11.743] Build status: STARTED
[07:43:11.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:17:0) after 1m17s
[07:44:28.798] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:17:0)
[07:44:28.819] Build status: STARTED
[07:44:28.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:18:0) after 3m42s
[07:48:10.837] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:18:0)
[07:48:10.857] Build status: STARTED
[07:48:10.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:19:0) after 8m3s
[07:56:13.941] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:19:0)
[07:56:13.960] Build status: STARTED
[07:56:13.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:20:0) after 7m59s
[08:04:13.023] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:20:0)
[08:04:13.046] Build status: STARTED
[08:04:13.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:21:0) after 7m24s
[08:11:37.089] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:21:0)
[08:11:37.110] Build status: STARTED
[08:11:37.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:22:0) after 2m17s
[08:13:54.198] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:22:0)
[08:13:54.216] Build status: STARTED
[08:13:54.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:23:0) after 1m32s
[08:15:26.212] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:23:0)
[08:15:26.230] Build status: STARTED
[08:15:26.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:24:0) after 4m45s
[08:20:11.346] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:24:0)
[08:20:11.398] Build status: STARTED
[08:20:11.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:25:0) after 4m23s
[08:24:34.576] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:25:0)
[08:24:34.645] Build status: STARTED
[08:24:34.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:26:0) after 4m45s
[08:29:19.858] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:26:0)
[08:29:19.879] Build status: STARTED
[08:29:19.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:27:0) after 8m30s
[08:37:49.944] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:27:0)
[08:37:50.179] Build status: STARTED
[08:37:50.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:28:0) after 2m11s
[08:40:01.303] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:28:0)
[08:40:01.335] Build status: STARTED
[08:40:01.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:29:0) after 8m58s
[08:48:59.384] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:29:0)
[08:48:59.403] Build status: STARTED
[08:48:59.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:30:0) after 9m47s
[08:58:46.503] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:30:0)
[08:58:46.607] Build status: STARTED
[08:58:46.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:31:0) after 1m5s
[08:59:51.698] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:31:0)
[08:59:51.713] Build status: STARTED
[08:59:51.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:32:0) after 4m10s
[09:04:01.863] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:32:0)
[09:04:01.887] Build status: STARTED
[09:04:01.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:33:0) after 7m9s
[09:11:11.150] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:33:0)
[09:11:11.178] Build status: STARTED
[09:11:11.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:34:0) after 4m14s
[09:15:25.444] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:34:0)
[09:15:25.466] Build status: STARTED
[09:15:25.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:35:0) after 1m36s
[09:17:01.655] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:35:0)
[09:17:01.735] Build status: STARTED
[09:17:01.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:36:0) after 4m29s
[09:21:30.831] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:36:0)
[09:21:30.869] Build status: STARTED
[09:21:30.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:37:0) after 9m45s
[09:31:16.090] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:37:0)
[09:31:16.111] Build status: STARTED
[09:31:16.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:38:0) after 3m26s
[09:34:42.198] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:38:0)
[09:34:42.215] Build status: STARTED
[09:34:42.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:39:0) after 4m58s
[09:39:40.243] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:39:0)
[09:39:40.273] Build status: STARTED
[09:39:40.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:40:0) after 3m39s
[09:43:19.299] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:40:0)
[09:43:19.321] Build status: STARTED
[09:43:19.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:41:0) after 5m13s
[09:48:32.344] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:41:0)
[09:48:32.362] Build status: STARTED
[09:48:32.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:42:0) after 9m19s
[09:57:51.489] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:42:0)
[09:57:51.507] Build status: STARTED
[09:57:51.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:43:0) after 1m14s
[09:59:05.731] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:43:0)
[09:59:05.780] Build status: STARTED
[09:59:05.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8933165916880091408:44:0) after 2m42s
[10:01:13.343] Received PubSub notification, asking Buildbucket for the build status
[10:01:13.388] Build:
{
"id": "8767735854862091569",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-rel-cft"
},
"number": 3508,
"createdBy": "project:chromium",
"createTime": "2023-10-09T06:22:04.391131107Z",
"startTime": "2023-10-09T06:24:06.805155253Z",
"endTime": "2023-10-09T10:01:12.797894956Z",
"updateTime": "2023-10-09T10:01:12.797894956Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "dd05b9906d67632aa847c41fb67d5c0b23f6cf3b",
"ref": "refs/heads/main"
}
}
}
[10:01:13.388] Invocation finished in 3h39m10.305366433s with status SUCCEEDED