[09:36:25.097] New invocation is queued and will start shortly
[09:36:26.204] Starting the invocation (attempt 1)
[09:36:26.240] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/91034ff1a1753223244b4219ba4e44b255f7c64b"
[09:36:26.240] Popped gitiles commit info from properties and tags
[09:36:26.240] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:36:26.240] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:36:26.240] Buildbucket request:
{
"requestId": "8914128325434707488",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Win x64 Builder (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "91034ff1a1753223244b4219ba4e44b255f7c64b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8914128325434707488"
},
{
"key": "scheduler_job_id",
"value": "chromium/Win x64 Builder (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[09:36:26.815] Scheduled build:
{
"id": "8748698263504294641",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Win x64 Builder (dbg)"
},
"number": 164524,
"createdBy": "project:chromium",
"createTime": "2024-05-06T09:36:26.315494207Z",
"updateTime": "2024-05-06T09:36:26.315494207Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "91034ff1a1753223244b4219ba4e44b255f7c64b",
"ref": "refs/heads/main"
}
}
}
[09:36:26.815] Task URL: https://cr-buildbucket.appspot.com/build/8748698263504294641
[09:36:26.815] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:2:0) after 2m49s
[09:36:45.177] Received PubSub notification, asking Buildbucket for the build status
[09:36:45.204] Build status: SCHEDULED
[09:39:15.945] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:2:0)
[09:39:15.965] Build status: SCHEDULED
[09:39:15.965] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:4:0) after 4m10s
[09:43:26.410] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:4:0)
[09:43:26.436] Build status: SCHEDULED
[09:43:26.436] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:5:0) after 1m2s
[09:44:28.502] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:5:0)
[09:44:28.579] Build status: SCHEDULED
[09:44:28.579] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:6:0) after 2m20s
[09:46:48.829] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:6:0)
[09:46:48.851] Build status: SCHEDULED
[09:46:48.851] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:7:0) after 9m44s
[09:56:32.868] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:7:0)
[09:56:32.901] Build status: SCHEDULED
[09:56:32.901] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:8:0) after 8m16s
[10:04:49.341] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:8:0)
[10:04:49.361] Build status: SCHEDULED
[10:04:49.361] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:9:0) after 2m3s
[10:06:52.433] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:9:0)
[10:06:52.464] Build status: SCHEDULED
[10:06:52.464] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:10:0) after 2m10s
[10:09:02.480] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:10:0)
[10:09:02.501] Build status: SCHEDULED
[10:09:02.501] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:11:0) after 3m48s
[10:12:50.313] Received PubSub notification, asking Buildbucket for the build status
[10:12:50.327] Build status: STARTED
[10:12:50.885] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:11:0)
[10:12:50.953] Build status: STARTED
[10:12:50.953] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:13:0) after 1m44s
[10:14:35.050] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:13:0)
[10:14:35.105] Build status: STARTED
[10:14:35.105] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:14:0) after 7m10s
[10:21:45.108] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:14:0)
[10:21:45.139] Build status: STARTED
[10:21:45.139] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:15:0) after 6m36s
[10:28:21.155] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:15:0)
[10:28:21.227] Build status: STARTED
[10:28:21.227] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:16:0) after 3m3s
[10:31:24.296] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:16:0)
[10:31:24.333] Build status: STARTED
[10:31:24.333] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:17:0) after 4m38s
[10:36:02.360] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:17:0)
[10:36:02.396] Build status: STARTED
[10:36:02.396] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:18:0) after 2m47s
[10:38:49.399] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:18:0)
[10:38:49.419] Build status: STARTED
[10:38:49.419] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:19:0) after 9m34s
[10:48:23.490] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:19:0)
[10:48:23.509] Build status: STARTED
[10:48:23.509] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:20:0) after 2m42s
[10:51:05.599] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:20:0)
[10:51:05.617] Build status: STARTED
[10:51:05.617] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:21:0) after 7m41s
[10:58:46.645] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:21:0)
[10:58:46.711] Build status: STARTED
[10:58:46.711] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:22:0) after 2m59s
[11:01:45.763] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:22:0)
[11:01:45.784] Build status: STARTED
[11:01:45.784] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:23:0) after 7m46s
[11:09:31.837] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:23:0)
[11:09:31.879] Build status: STARTED
[11:09:31.879] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:24:0) after 9m27s
[11:18:59.158] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:24:0)
[11:19:01.445] Build status: STARTED
[11:19:01.445] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:25:0) after 7m16s
[11:26:17.472] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:25:0)
[11:26:17.499] Build status: STARTED
[11:26:17.499] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:26:0) after 1m46s
[11:28:03.517] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:26:0)
[11:28:03.538] Build status: STARTED
[11:28:03.538] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:27:0) after 4m42s
[11:32:45.561] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:27:0)
[11:32:45.586] Build status: STARTED
[11:32:45.586] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:28:0) after 9m50s
[11:42:36.054] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:28:0)
[11:42:36.075] Build status: STARTED
[11:42:36.075] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:29:0) after 8m58s
[11:51:34.114] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:29:0)
[11:51:34.136] Build status: STARTED
[11:51:34.136] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:30:0) after 2m13s
[11:53:47.165] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:30:0)
[11:53:47.198] Build status: STARTED
[11:53:47.198] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:31:0) after 7m33s
[12:01:20.230] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:31:0)
[12:01:20.274] Build status: STARTED
[12:01:20.274] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:32:0) after 3m35s
[12:04:55.305] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:32:0)
[12:04:55.324] Build status: STARTED
[12:04:55.324] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:33:0) after 4m58s
[12:09:53.350] Handling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:33:0)
[12:09:53.374] Build status: STARTED
[12:09:53.374] Scheduling timer "check-buildbucket-build-status" (chromium/Win x64 Builder (dbg):8914128325434707488:34:0) after 4m53s
[12:13:17.649] Received PubSub notification, asking Buildbucket for the build status
[12:13:17.762] Build:
{
"id": "8748698263504294641",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Win x64 Builder (dbg)"
},
"number": 164524,
"createdBy": "project:chromium",
"createTime": "2024-05-06T09:36:26.315494207Z",
"startTime": "2024-05-06T10:12:49.054666680Z",
"endTime": "2024-05-06T12:13:16.255836329Z",
"updateTime": "2024-05-06T12:13:16.255836329Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "91034ff1a1753223244b4219ba4e44b255f7c64b",
"ref": "refs/heads/main"
}
}
}
[12:13:17.762] Invocation finished in 2h36m52.67072981s with status SUCCEEDED