[19:38:58.056] New invocation is queued and will start shortly
[19:38:59.127] Starting the invocation (attempt 1)
[19:38:59.158] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ed5cc741cd97a9259ef2b176041913e4f05fc62b"
[19:38:59.158] Popped gitiles commit info from properties and tags
[19:38:59.158] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[19:38:59.161] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[19:38:59.161] Buildbucket request:
{
"requestId": "8867795366481654464",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-rel-dev"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ed5cc741cd97a9259ef2b176041913e4f05fc62b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8867795366481654464"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-rel-dev"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[19:38:59.835] Scheduled build:
{
"id": "8702365304597448129",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-rel-dev"
},
"number": 19960,
"createdBy": "project:chromium",
"createTime": "2025-09-29T19:38:59.228256029Z",
"updateTime": "2025-09-29T19:38:59.228256029Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ed5cc741cd97a9259ef2b176041913e4f05fc62b",
"ref": "refs/heads/main"
}
},
"canary": true
}
[19:38:59.835] Task URL: https://cr-buildbucket-dev.appspot.com/build/8702365304597448129
[19:38:59.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:2:0) after 1m55s
[19:39:00.821] Received PubSub notification, asking Buildbucket for the build status
[19:39:00.849] Build status: SCHEDULED
[19:40:54.856] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:2:0)
[19:40:54.885] Build status: SCHEDULED
[19:40:54.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:4:0) after 4m0s
[19:44:54.905] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:4:0)
[19:44:54.926] Build status: SCHEDULED
[19:44:54.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:5:0) after 1m12s
[19:46:06.946] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:5:0)
[19:46:07.099] Build status: SCHEDULED
[19:46:07.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:6:0) after 8m10s
[19:54:17.118] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:6:0)
[19:54:17.145] Build status: SCHEDULED
[19:54:17.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:7:0) after 1m21s
[19:55:38.168] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:7:0)
[19:55:38.194] Build status: SCHEDULED
[19:55:38.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:8:0) after 8m25s
[20:04:03.218] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:8:0)
[20:04:03.247] Build status: SCHEDULED
[20:04:03.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:9:0) after 7m58s
[20:12:01.271] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:9:0)
[20:12:01.299] Build status: SCHEDULED
[20:12:01.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:10:0) after 2m23s
[20:14:24.339] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:10:0)
[20:14:24.362] Build status: SCHEDULED
[20:14:24.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:11:0) after 5m28s
[20:15:39.143] Received PubSub notification, asking Buildbucket for the build status
[20:15:39.168] Build status: STARTED
[20:19:52.380] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:11:0)
[20:19:52.404] Build status: STARTED
[20:19:52.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:13:0) after 2m22s
[20:22:14.426] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:13:0)
[20:22:14.461] Build status: STARTED
[20:22:14.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:14:0) after 3m11s
[20:25:25.490] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:14:0)
[20:25:25.513] Build status: STARTED
[20:25:25.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:15:0) after 6m5s
[20:31:30.531] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:15:0)
[20:31:30.568] Build status: STARTED
[20:31:30.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:16:0) after 2m58s
[20:34:28.589] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:16:0)
[20:34:28.609] Build status: STARTED
[20:34:28.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:17:0) after 6m41s
[20:41:09.763] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:17:0)
[20:41:09.832] Build status: STARTED
[20:41:09.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:18:0) after 7m19s
[20:48:28.853] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:18:0)
[20:48:28.876] Build status: STARTED
[20:48:28.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:19:0) after 1m26s
[20:49:54.900] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:19:0)
[20:49:54.920] Build status: STARTED
[20:49:54.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:20:0) after 3m36s
[20:53:30.939] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:20:0)
[20:53:30.959] Build status: STARTED
[20:53:30.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:21:0) after 9m4s
[21:02:35.015] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:21:0)
[21:02:35.045] Build status: STARTED
[21:02:35.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:22:0) after 3m36s
[21:06:11.066] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:22:0)
[21:06:11.098] Build status: STARTED
[21:06:11.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:23:0) after 5m14s
[21:11:25.118] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:23:0)
[21:11:25.155] Build status: STARTED
[21:11:25.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:24:0) after 5m9s
[21:16:34.176] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:24:0)
[21:16:34.302] Build status: STARTED
[21:16:34.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:25:0) after 7m0s
[21:23:34.323] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:25:0)
[21:23:34.343] Build status: STARTED
[21:23:34.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:26:0) after 3m51s
[21:27:25.368] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:26:0)
[21:27:25.393] Build status: STARTED
[21:27:25.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:27:0) after 7m13s
[21:34:38.425] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:27:0)
[21:34:38.447] Build status: STARTED
[21:34:38.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:28:0) after 2m46s
[21:37:24.493] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:28:0)
[21:37:24.521] Build status: STARTED
[21:37:24.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:29:0) after 8m47s
[21:46:11.543] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:29:0)
[21:46:11.563] Build status: STARTED
[21:46:11.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:30:0) after 7m25s
[21:53:36.583] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:30:0)
[21:53:36.613] Build status: STARTED
[21:53:36.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:31:0) after 8m22s
[22:01:58.643] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:31:0)
[22:01:58.756] Build status: STARTED
[22:01:58.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:32:0) after 6m19s
[22:08:17.778] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:32:0)
[22:08:17.847] Build status: STARTED
[22:08:17.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:33:0) after 1m53s
[22:10:10.868] Handling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:33:0)
[22:10:10.895] Build status: STARTED
[22:10:10.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-dev:8867795366481654464:34:0) after 5m26s
[22:11:58.580] Received PubSub notification, asking Buildbucket for the build status
[22:11:58.607] Build:
{
"id": "8702365304597448129",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-rel-dev"
},
"number": 19960,
"createdBy": "project:chromium",
"createTime": "2025-09-29T19:38:59.228256029Z",
"startTime": "2025-09-29T20:15:38.914472151Z",
"endTime": "2025-09-29T22:11:57.628950742Z",
"updateTime": "2025-09-29T22:11:57.628950742Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ed5cc741cd97a9259ef2b176041913e4f05fc62b",
"ref": "refs/heads/main"
}
},
"canary": true
}
[22:11:58.607] Invocation finished in 2h33m0.558601995s with status SUCCEEDED