[14:00:12.213] New invocation is queued and will start shortly
[14:00:13.322] Starting the invocation (attempt 1)
[14:00:13.426] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e9c6ab5e7c9b2215ee726ba7f8525ac4da3cf771"
[14:00:13.426] Popped gitiles commit info from properties and tags
[14:00:13.426] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:00:13.428] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:00:13.428] Buildbucket request:
{
"requestId": "8887838609249679744",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e9c6ab5e7c9b2215ee726ba7f8525ac4da3cf771",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8887838609249679744"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-arm64-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:00:13.983] Scheduled build:
{
"id": "8722408547201369985",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 3999,
"createdBy": "project:chromium",
"createTime": "2025-02-20T14:00:13.539628775Z",
"updateTime": "2025-02-20T14:00:13.539628775Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e9c6ab5e7c9b2215ee726ba7f8525ac4da3cf771",
"ref": "refs/heads/main"
}
}
}
[14:00:13.984] Task URL: https://cr-buildbucket.appspot.com/build/8722408547201369985
[14:00:13.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:2:0) after 4m22s
[14:00:14.378] Received PubSub notification, asking Buildbucket for the build status
[14:00:14.421] Build status: SCHEDULED
[14:00:20.680] Received PubSub notification, asking Buildbucket for the build status
[14:00:20.711] Build status: STARTED
[14:04:36.035] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:2:0)
[14:04:36.068] Build status: STARTED
[14:04:36.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:5:0) after 9m30s
[14:14:06.625] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:5:0)
[14:14:06.648] Build status: STARTED
[14:14:06.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:6:0) after 2m33s
[14:16:40.069] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:6:0)
[14:16:40.097] Build status: STARTED
[14:16:40.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:7:0) after 9m36s
[14:26:16.244] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:7:0)
[14:26:16.322] Build status: STARTED
[14:26:16.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:8:0) after 3m5s
[14:29:21.310] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:8:0)
[14:29:21.341] Build status: STARTED
[14:29:21.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:9:0) after 5m34s
[14:34:55.367] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:9:0)
[14:34:55.449] Build status: STARTED
[14:34:55.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:10:0) after 5m43s
[14:40:38.516] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:10:0)
[14:40:38.537] Build status: STARTED
[14:40:38.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:11:0) after 6m49s
[14:47:27.575] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:11:0)
[14:47:27.597] Build status: STARTED
[14:47:27.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:12:0) after 5m2s
[14:52:29.623] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:12:0)
[14:52:29.815] Build status: STARTED
[14:52:29.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:13:0) after 5m10s
[14:57:39.886] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:13:0)
[14:57:39.903] Build status: STARTED
[14:57:39.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:14:0) after 8m34s
[15:06:13.974] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:14:0)
[15:06:13.990] Build status: STARTED
[15:06:13.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:15:0) after 1m29s
[15:07:43.009] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:15:0)
[15:07:43.027] Build status: STARTED
[15:07:43.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:16:0) after 6m31s
[15:14:14.189] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:16:0)
[15:14:14.212] Build status: STARTED
[15:14:14.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:17:0) after 8m0s
[15:22:14.279] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:17:0)
[15:22:14.301] Build status: STARTED
[15:22:14.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:18:0) after 8m33s
[15:30:47.398] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:18:0)
[15:30:47.413] Build status: STARTED
[15:30:47.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:19:0) after 1m15s
[15:32:02.485] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:19:0)
[15:32:02.504] Build status: STARTED
[15:32:02.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:20:0) after 7m48s
[15:39:50.542] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:20:0)
[15:39:50.580] Build status: STARTED
[15:39:50.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:21:0) after 6m51s
[15:46:41.611] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:21:0)
[15:46:41.629] Build status: STARTED
[15:46:41.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:22:0) after 6m43s
[15:53:25.237] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:22:0)
[15:53:25.251] Build status: STARTED
[15:53:25.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:23:0) after 1m35s
[15:55:00.318] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:23:0)
[15:55:00.346] Build status: STARTED
[15:55:00.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:24:0) after 7m19s
[16:02:19.455] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:24:0)
[16:02:19.713] Build status: STARTED
[16:02:19.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:25:0) after 8m59s
[16:11:18.727] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:25:0)
[16:11:18.808] Build status: STARTED
[16:11:18.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:26:0) after 9m11s
[16:20:29.838] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:26:0)
[16:20:29.853] Build status: STARTED
[16:20:29.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:27:0) after 2m19s
[16:22:48.871] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:27:0)
[16:22:48.889] Build status: STARTED
[16:22:48.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:28:0) after 7m53s
[16:30:41.876] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:28:0)
[16:30:41.899] Build status: STARTED
[16:30:41.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:29:0) after 4m4s
[16:34:45.934] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:29:0)
[16:34:45.953] Build status: STARTED
[16:34:45.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:30:0) after 6m39s
[16:41:25.140] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:30:0)
[16:41:25.161] Build status: STARTED
[16:41:25.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:31:0) after 7m24s
[16:48:49.241] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:31:0)
[16:48:49.259] Build status: STARTED
[16:48:49.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:32:0) after 5m58s
[16:54:47.285] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:32:0)
[16:54:47.305] Build status: STARTED
[16:54:47.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:33:0) after 2m0s
[16:56:47.369] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:33:0)
[16:56:47.384] Build status: STARTED
[16:56:47.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:34:0) after 2m32s
[16:59:19.437] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:34:0)
[16:59:19.462] Build status: STARTED
[16:59:19.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:35:0) after 4m27s
[17:03:46.478] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:35:0)
[17:03:46.497] Build status: STARTED
[17:03:46.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-rel:8887838609249679744:36:0) after 9m38s
[17:05:10.600] Received PubSub notification, asking Buildbucket for the build status
[17:05:10.619] Build:
{
"id": "8722408547201369985",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 3999,
"createdBy": "project:chromium",
"createTime": "2025-02-20T14:00:13.539628775Z",
"startTime": "2025-02-20T14:00:20.461541646Z",
"endTime": "2025-02-20T17:05:10.126736031Z",
"updateTime": "2025-02-20T17:05:10.126736031Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e9c6ab5e7c9b2215ee726ba7f8525ac4da3cf771",
"ref": "refs/heads/main"
}
}
}
[17:05:10.619] Invocation finished in 3h4m58.417162672s with status SUCCEEDED