[17:30:32.792] New invocation is queued and will start shortly
[17:30:34.122] Starting the invocation (attempt 1)
[17:30:34.143] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fac97d839f5ad545c132c3c25ce90f73d5f478bc"
[17:30:34.143] Popped gitiles commit info from properties and tags
[17:30:34.143] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:30:34.143] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:30:34.143] Buildbucket request:
{
"requestId": "8877678515374193152",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8877678515374193152",
"job": "chromium-m137/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "322343ae5b9e4d16a4568dd8b1da20dedf778dac"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@322343ae5b9e4d16a4568dd8b1da20dedf778dac",
"title": "322343ae5b9e4d16a4568dd8b1da20dedf778dac",
"url": "https://chromium.googlesource.com/chromium/src/+/322343ae5b9e4d16a4568dd8b1da20dedf778dac"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "93de14dd820246d0111a7ea219a181ce575d1b8f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@93de14dd820246d0111a7ea219a181ce575d1b8f",
"title": "93de14dd820246d0111a7ea219a181ce575d1b8f",
"url": "https://chromium.googlesource.com/chromium/src/+/93de14dd820246d0111a7ea219a181ce575d1b8f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1775f28af3fb993d851010c0be5bb22d461cf577"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@1775f28af3fb993d851010c0be5bb22d461cf577",
"title": "1775f28af3fb993d851010c0be5bb22d461cf577",
"url": "https://chromium.googlesource.com/chromium/src/+/1775f28af3fb993d851010c0be5bb22d461cf577"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "07fccb29db27af95dbeb334683ed583fae64c0d6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@07fccb29db27af95dbeb334683ed583fae64c0d6",
"title": "07fccb29db27af95dbeb334683ed583fae64c0d6",
"url": "https://chromium.googlesource.com/chromium/src/+/07fccb29db27af95dbeb334683ed583fae64c0d6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f28a3d3f3017fe28fadc3e948369dcbd59caec65"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@f28a3d3f3017fe28fadc3e948369dcbd59caec65",
"title": "f28a3d3f3017fe28fadc3e948369dcbd59caec65",
"url": "https://chromium.googlesource.com/chromium/src/+/f28a3d3f3017fe28fadc3e948369dcbd59caec65"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2ec877b51ed5b10f020d91a9814dda7509a62f44"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@2ec877b51ed5b10f020d91a9814dda7509a62f44",
"title": "2ec877b51ed5b10f020d91a9814dda7509a62f44",
"url": "https://chromium.googlesource.com/chromium/src/+/2ec877b51ed5b10f020d91a9814dda7509a62f44"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b680f511a9ea83cd921e99e725da315cd331da01"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@b680f511a9ea83cd921e99e725da315cd331da01",
"title": "b680f511a9ea83cd921e99e725da315cd331da01",
"url": "https://chromium.googlesource.com/chromium/src/+/b680f511a9ea83cd921e99e725da315cd331da01"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6c61e4b93abc42d22a64f007e6ddcdf45ff88403"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@6c61e4b93abc42d22a64f007e6ddcdf45ff88403",
"title": "6c61e4b93abc42d22a64f007e6ddcdf45ff88403",
"url": "https://chromium.googlesource.com/chromium/src/+/6c61e4b93abc42d22a64f007e6ddcdf45ff88403"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8c023cf6dd233a0c91faca5068bc2ab8fad7c2d9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@8c023cf6dd233a0c91faca5068bc2ab8fad7c2d9",
"title": "8c023cf6dd233a0c91faca5068bc2ab8fad7c2d9",
"url": "https://chromium.googlesource.com/chromium/src/+/8c023cf6dd233a0c91faca5068bc2ab8fad7c2d9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fac97d839f5ad545c132c3c25ce90f73d5f478bc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@fac97d839f5ad545c132c3c25ce90f73d5f478bc",
"title": "fac97d839f5ad545c132c3c25ce90f73d5f478bc",
"url": "https://chromium.googlesource.com/chromium/src/+/fac97d839f5ad545c132c3c25ce90f73d5f478bc"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fac97d839f5ad545c132c3c25ce90f73d5f478bc",
"ref": "refs/branch-heads/7151"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8877678515374193152"
},
{
"key": "scheduler_job_id",
"value": "chromium-m137/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:30:34.661] Scheduled build:
{
"id": "8712248453237465473",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win-official"
},
"number": 329,
"createdBy": "project:chromium-m137",
"createTime": "2025-06-12T17:30:34.205183243Z",
"updateTime": "2025-06-12T17:30:34.205183243Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fac97d839f5ad545c132c3c25ce90f73d5f478bc",
"ref": "refs/branch-heads/7151"
}
}
}
[17:30:34.661] Task URL: https://cr-buildbucket.appspot.com/build/8712248453237465473
[17:30:34.661] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:2:0) after 1m2s
[17:30:47.508] Received PubSub notification, asking Buildbucket for the build status
[17:30:47.527] Build status: SCHEDULED
[17:31:34.724] Received PubSub notification, asking Buildbucket for the build status
[17:31:34.756] Build status: STARTED
[17:31:36.834] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:2:0)
[17:31:36.860] Build status: STARTED
[17:31:36.860] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:5:0) after 4m20s
[17:35:56.924] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:5:0)
[17:35:56.950] Build status: STARTED
[17:35:56.950] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:6:0) after 9m50s
[17:45:46.965] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:6:0)
[17:45:47.042] Build status: STARTED
[17:45:47.042] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:7:0) after 5m17s
[17:51:04.055] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:7:0)
[17:51:04.075] Build status: STARTED
[17:51:04.075] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:8:0) after 4m11s
[17:55:15.107] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:8:0)
[17:55:18.479] Build status: STARTED
[17:55:18.480] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:9:0) after 8m27s
[18:03:45.521] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:9:0)
[18:03:50.554] Build status: STARTED
[18:03:50.554] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:10:0) after 8m8s
[18:11:58.569] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:10:0)
[18:12:03.628] Build status: STARTED
[18:12:03.628] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:11:0) after 7m39s
[18:19:42.651] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:11:0)
[18:19:47.682] Build status: STARTED
[18:19:47.682] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:12:0) after 7m18s
[18:27:05.715] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:12:0)
[18:27:05.740] Build status: STARTED
[18:27:05.740] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:13:0) after 6m7s
[18:33:12.772] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:13:0)
[18:33:17.798] Build status: STARTED
[18:33:17.798] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:14:0) after 1m20s
[18:34:37.945] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:14:0)
[18:34:42.977] Build status: STARTED
[18:34:42.977] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:15:0) after 5m46s
[18:40:29.535] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:15:0)
[18:40:29.555] Build status: STARTED
[18:40:29.555] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:16:0) after 9m54s
[18:50:27.067] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:16:0)
[18:50:27.086] Build status: STARTED
[18:50:27.086] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:17:0) after 9m0s
[18:59:28.983] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:17:0)
[18:59:29.018] Build status: STARTED
[18:59:29.018] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:18:0) after 9m38s
[19:09:07.058] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:18:0)
[19:09:07.082] Build status: STARTED
[19:09:07.082] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:19:0) after 5m24s
[19:14:34.515] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:19:0)
[19:14:34.556] Build status: STARTED
[19:14:34.556] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:20:0) after 4m57s
[19:19:31.605] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:20:0)
[19:19:31.624] Build status: STARTED
[19:19:31.624] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:21:0) after 4m1s
[19:23:33.162] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:21:0)
[19:23:33.187] Build status: STARTED
[19:23:33.187] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:22:0) after 2m37s
[19:26:23.633] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:22:0)
[19:26:23.672] Build status: STARTED
[19:26:23.672] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:23:0) after 1m54s
[19:28:17.710] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:23:0)
[19:28:17.840] Build status: STARTED
[19:28:17.840] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:24:0) after 2m59s
[19:31:17.334] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:24:0)
[19:31:17.356] Build status: STARTED
[19:31:17.356] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:25:0) after 5m19s
[19:37:13.702] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:25:0)
[19:37:14.979] Build status: STARTED
[19:37:14.979] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:26:0) after 6m41s
[20:16:49.570] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:26:0)
[20:16:49.593] Build status: STARTED
[20:16:49.593] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:27:0) after 5m10s
[20:21:59.620] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:27:0)
[20:21:59.651] Build status: STARTED
[20:21:59.651] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:28:0) after 8m49s
[20:30:49.344] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:28:0)
[20:30:49.388] Build status: STARTED
[20:30:49.388] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:29:0) after 7m51s
[20:38:40.474] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:29:0)
[20:38:40.514] Build status: STARTED
[20:38:40.514] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:30:0) after 9m26s
[20:48:06.634] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:30:0)
[20:48:06.668] Build status: STARTED
[20:48:06.668] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:31:0) after 6m32s
[20:54:38.737] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:31:0)
[20:54:38.771] Build status: STARTED
[20:54:38.771] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:32:0) after 5m13s
[20:59:51.833] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:32:0)
[20:59:51.865] Build status: STARTED
[20:59:51.865] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:33:0) after 8m58s
[21:08:49.891] Handling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:33:0)
[21:08:49.909] Build status: STARTED
[21:08:49.909] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-official:8877678515374193152:34:0) after 4m3s
[21:09:02.137] Received PubSub notification, asking Buildbucket for the build status
[21:09:02.169] Build:
{
"id": "8712248453237465473",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win-official"
},
"number": 329,
"createdBy": "project:chromium-m137",
"createTime": "2025-06-12T17:30:34.205183243Z",
"startTime": "2025-06-12T17:31:28.737717464Z",
"endTime": "2025-06-12T21:09:01.874276443Z",
"updateTime": "2025-06-12T21:09:01.874276443Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fac97d839f5ad545c132c3c25ce90f73d5f478bc",
"ref": "refs/branch-heads/7151"
}
}
}
[21:09:02.169] Invocation finished in 3h38m29.38462211s with status SUCCEEDED