[06:16:20.462] New invocation is queued and will start shortly
[06:16:22.160] Starting the invocation (attempt 1)
[06:16:22.193] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5ecb57d4413475be4daaa1788b9fd074ab0c8bac"
[06:16:22.193] Popped gitiles commit info from properties and tags
[06:16:22.193] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:16:22.193] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:16:22.193] Buildbucket request:
{
"requestId": "8872647502604651216",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5ecb57d4413475be4daaa1788b9fd074ab0c8bac",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8872647502604651216"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:16:22.711] Scheduled build:
{
"id": "8707217440068200017",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"number": 12702,
"createdBy": "project:chromium",
"createTime": "2025-08-07T06:16:22.250189881Z",
"updateTime": "2025-08-07T06:16:22.250189881Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5ecb57d4413475be4daaa1788b9fd074ab0c8bac",
"ref": "refs/heads/main"
}
}
}
[06:16:22.711] Task URL: https://cr-buildbucket.appspot.com/build/8707217440068200017
[06:16:22.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:2:0) after 2m8s
[06:16:23.700] Received PubSub notification, asking Buildbucket for the build status
[06:16:23.716] Build status: SCHEDULED
[06:16:30.794] Received PubSub notification, asking Buildbucket for the build status
[06:16:30.828] Build status: STARTED
[06:18:30.797] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:2:0)
[06:18:30.873] Build status: STARTED
[06:18:30.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:5:0) after 9m50s
[06:28:20.903] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:5:0)
[06:28:20.933] Build status: STARTED
[06:28:20.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:6:0) after 1m38s
[06:29:58.963] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:6:0)
[06:29:59.026] Build status: STARTED
[06:29:59.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:7:0) after 8m21s
[06:38:20.049] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:7:0)
[06:38:20.076] Build status: STARTED
[06:38:20.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:8:0) after 2m26s
[06:40:46.122] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:8:0)
[06:40:46.171] Build status: STARTED
[06:40:46.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:9:0) after 8m5s
[06:48:51.189] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:9:0)
[06:48:51.209] Build status: STARTED
[06:48:51.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:10:0) after 7m6s
[06:55:57.281] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:10:0)
[06:55:57.304] Build status: STARTED
[06:55:57.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:11:0) after 9m18s
[07:05:15.335] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:11:0)
[07:05:15.366] Build status: STARTED
[07:05:15.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:12:0) after 8m51s
[07:14:06.440] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:12:0)
[07:14:06.457] Build status: STARTED
[07:14:06.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:13:0) after 8m6s
[07:22:12.551] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:13:0)
[07:22:12.570] Build status: STARTED
[07:22:12.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:14:0) after 7m51s
[07:30:03.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:14:0)
[07:30:03.622] Build status: STARTED
[07:30:03.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:15:0) after 8m52s
[07:38:55.646] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:15:0)
[07:38:55.767] Build status: STARTED
[07:38:55.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:16:0) after 2m25s
[07:41:20.792] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:16:0)
[07:41:20.814] Build status: STARTED
[07:41:20.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:17:0) after 9m10s
[07:50:30.837] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:17:0)
[07:50:30.854] Build status: STARTED
[07:50:30.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:18:0) after 4m3s
[07:54:33.853] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:18:0)
[07:54:33.872] Build status: STARTED
[07:54:33.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:19:0) after 2m17s
[07:56:50.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:19:0)
[07:56:51.018] Build status: STARTED
[07:56:51.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:20:0) after 2m2s
[07:58:53.078] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:20:0)
[07:58:53.093] Build status: STARTED
[07:58:53.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:21:0) after 9m14s
[08:08:07.145] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:21:0)
[08:08:07.165] Build status: STARTED
[08:08:07.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:22:0) after 8m40s
[08:16:47.236] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:22:0)
[08:16:47.259] Build status: STARTED
[08:16:47.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:23:0) after 2m32s
[08:19:19.393] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:23:0)
[08:19:19.410] Build status: STARTED
[08:19:19.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:24:0) after 6m16s
[08:25:35.504] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:24:0)
[08:25:35.542] Build status: STARTED
[08:25:35.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:25:0) after 2m7s
[08:27:42.554] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:25:0)
[08:27:42.571] Build status: STARTED
[08:27:42.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:26:0) after 9m26s
[08:37:08.590] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:26:0)
[08:37:08.609] Build status: STARTED
[08:37:08.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:27:0) after 6m21s
[08:43:29.700] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:27:0)
[08:43:29.716] Build status: STARTED
[08:43:29.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:28:0) after 5m33s
[08:49:02.787] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:28:0)
[08:49:02.810] Build status: STARTED
[08:49:02.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:29:0) after 2m35s
[08:51:37.785] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:29:0)
[08:51:37.804] Build status: STARTED
[08:51:37.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:30:0) after 4m7s
[08:55:44.796] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:30:0)
[08:55:44.820] Build status: STARTED
[08:55:44.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:31:0) after 5m36s
[09:01:20.889] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:31:0)
[09:01:20.911] Build status: STARTED
[09:01:20.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:32:0) after 1m16s
[09:02:36.926] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:32:0)
[09:02:37.128] Build status: STARTED
[09:02:37.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:33:0) after 2m21s
[09:04:58.167] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:33:0)
[09:04:58.193] Build status: STARTED
[09:04:58.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:34:0) after 9m32s
[09:14:30.208] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:34:0)
[09:14:30.232] Build status: STARTED
[09:14:30.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:35:0) after 1m32s
[09:16:02.223] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:35:0)
[09:16:02.240] Build status: STARTED
[09:16:02.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:36:0) after 7m53s
[09:23:55.270] Handling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:36:0)
[09:23:55.288] Build status: STARTED
[09:23:55.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8872647502604651216:37:0) after 4m24s
[09:25:30.062] Received PubSub notification, asking Buildbucket for the build status
[09:25:30.086] Build:
{
"id": "8707217440068200017",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"number": 12702,
"createdBy": "project:chromium",
"createTime": "2025-08-07T06:16:22.250189881Z",
"startTime": "2025-08-07T06:16:27.421710173Z",
"endTime": "2025-08-07T09:25:29.759379105Z",
"updateTime": "2025-08-07T09:25:29.759379105Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5ecb57d4413475be4daaa1788b9fd074ab0c8bac",
"ref": "refs/heads/main"
}
}
}
[09:25:30.086] Invocation finished in 3h9m9.638979578s with status SUCCEEDED