[11:44:50.575] New invocation is queued and will start shortly
[11:44:51.779] Starting the invocation (attempt 1)
[11:44:51.804] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1b6146ff9bd6621e0163dce63017bf1b69948f5b"
[11:44:51.804] Popped gitiles commit info from properties and tags
[11:44:51.804] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:44:51.805] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:44:51.805] Buildbucket request:
{
"requestId": "8922998748360212352",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-presubmit"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1b6146ff9bd6621e0163dce63017bf1b69948f5b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8922998748360212352"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-presubmit"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:44:52.379] Scheduled build:
{
"id": "8757568686366040273",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-presubmit"
},
"number": 5169,
"createdBy": "project:chromium",
"createTime": "2024-01-29T11:44:51.857621903Z",
"updateTime": "2024-01-29T11:44:51.857621903Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1b6146ff9bd6621e0163dce63017bf1b69948f5b",
"ref": "refs/heads/main"
}
}
}
[11:44:52.379] Task URL: https://cr-buildbucket.appspot.com/build/8757568686366040273
[11:44:52.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:2:0) after 7m44s
[11:44:53.272] Received PubSub notification, asking Buildbucket for the build status
[11:44:53.289] Build status: SCHEDULED
[11:50:10.219] Received PubSub notification, asking Buildbucket for the build status
[11:50:10.239] Build status: STARTED
[11:52:36.492] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:2:0)
[11:52:36.510] Build status: STARTED
[11:52:36.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:5:0) after 7m3s
[11:59:39.735] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:5:0)
[11:59:39.782] Build status: STARTED
[11:59:39.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:6:0) after 2m12s
[12:01:51.895] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:6:0)
[12:01:52.052] Build status: STARTED
[12:01:52.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:7:0) after 5m38s
[12:07:30.071] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:7:0)
[12:07:30.092] Build status: STARTED
[12:07:30.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:8:0) after 5m23s
[12:12:53.114] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:8:0)
[12:12:53.159] Build status: STARTED
[12:12:53.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:9:0) after 3m18s
[12:16:11.511] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:9:0)
[12:16:11.527] Build status: STARTED
[12:16:11.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:10:0) after 9m38s
[12:25:49.718] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:10:0)
[12:25:49.735] Build status: STARTED
[12:25:49.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:11:0) after 1m49s
[12:27:38.809] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:11:0)
[12:27:38.829] Build status: STARTED
[12:27:38.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:12:0) after 5m39s
[12:33:17.863] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:12:0)
[12:33:17.882] Build status: STARTED
[12:33:17.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:13:0) after 6m15s
[12:39:32.965] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:13:0)
[12:39:33.132] Build status: STARTED
[12:39:33.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:14:0) after 5m30s
[12:45:03.551] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:14:0)
[12:45:03.566] Build status: STARTED
[12:45:03.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:15:0) after 5m7s
[12:50:10.821] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:15:0)
[12:50:10.841] Build status: STARTED
[12:50:10.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:16:0) after 1m9s
[12:51:20.104] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:16:0)
[12:51:20.145] Build status: STARTED
[12:51:20.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:17:0) after 5m49s
[12:57:09.335] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:17:0)
[12:57:09.355] Build status: STARTED
[12:57:09.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:18:0) after 7m13s
[13:04:22.383] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:18:0)
[13:04:22.410] Build status: STARTED
[13:04:22.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:19:0) after 1m4s
[13:05:26.545] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:19:0)
[13:05:26.575] Build status: STARTED
[13:05:26.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:20:0) after 5m13s
[13:10:39.558] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:20:0)
[13:10:39.638] Build status: STARTED
[13:10:39.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:21:0) after 1m30s
[13:12:09.760] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:21:0)
[13:12:09.805] Build status: STARTED
[13:12:09.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:22:0) after 5m30s
[13:17:39.926] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:22:0)
[13:17:39.948] Build status: STARTED
[13:17:39.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:23:0) after 7m1s
[13:24:40.964] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:23:0)
[13:24:41.195] Build status: STARTED
[13:24:41.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:24:0) after 3m35s
[13:28:16.215] Handling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:24:0)
[13:28:16.248] Build status: STARTED
[13:28:16.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-presubmit:8922998748360212352:25:0) after 4m46s
[13:30:49.316] Received PubSub notification, asking Buildbucket for the build status
[13:30:49.340] Build:
{
"id": "8757568686366040273",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-presubmit"
},
"number": 5169,
"createdBy": "project:chromium",
"createTime": "2024-01-29T11:44:51.857621903Z",
"startTime": "2024-01-29T11:50:08.805953Z",
"endTime": "2024-01-29T13:30:48.687311Z",
"updateTime": "2024-01-29T13:30:48.687311Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1b6146ff9bd6621e0163dce63017bf1b69948f5b",
"ref": "refs/heads/main"
}
}
}
[13:30:49.340] Invocation finished in 1h45m58.769313198s with status SUCCEEDED