[12:58:40.940] New invocation is queued and will start shortly
[12:58:42.055] Starting the invocation (attempt 1)
[12:58:42.076] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c69ce5c352ab7f0c3ec83397a25c4f52a2d5e922"
[12:58:42.076] Popped gitiles commit info from properties and tags
[12:58:42.076] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:58:42.076] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:58:42.076] Buildbucket request:
{
"requestId": "8865374432160854960",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8865374432160854960",
"job": "chromium-m142/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cfe0ed9beb1f47126a26bbd42208729b07fef160"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@cfe0ed9beb1f47126a26bbd42208729b07fef160",
"title": "cfe0ed9beb1f47126a26bbd42208729b07fef160",
"url": "https://chromium.googlesource.com/chromium/src/+/cfe0ed9beb1f47126a26bbd42208729b07fef160"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9f10d9a0652324dca2b60794ee764dfa76153d8f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@9f10d9a0652324dca2b60794ee764dfa76153d8f",
"title": "9f10d9a0652324dca2b60794ee764dfa76153d8f",
"url": "https://chromium.googlesource.com/chromium/src/+/9f10d9a0652324dca2b60794ee764dfa76153d8f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cc31f17dbe666e1a8ed4d9f2279430644df56db1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@cc31f17dbe666e1a8ed4d9f2279430644df56db1",
"title": "cc31f17dbe666e1a8ed4d9f2279430644df56db1",
"url": "https://chromium.googlesource.com/chromium/src/+/cc31f17dbe666e1a8ed4d9f2279430644df56db1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "95c8dc9cbbb75155dfc85ae0a307fe050edd27d6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@95c8dc9cbbb75155dfc85ae0a307fe050edd27d6",
"title": "95c8dc9cbbb75155dfc85ae0a307fe050edd27d6",
"url": "https://chromium.googlesource.com/chromium/src/+/95c8dc9cbbb75155dfc85ae0a307fe050edd27d6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d7f802f5c71561623e325994f107b40351630567"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@d7f802f5c71561623e325994f107b40351630567",
"title": "d7f802f5c71561623e325994f107b40351630567",
"url": "https://chromium.googlesource.com/chromium/src/+/d7f802f5c71561623e325994f107b40351630567"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6684e41db9e892daef4c8b2267ad5eb71a618395"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@6684e41db9e892daef4c8b2267ad5eb71a618395",
"title": "6684e41db9e892daef4c8b2267ad5eb71a618395",
"url": "https://chromium.googlesource.com/chromium/src/+/6684e41db9e892daef4c8b2267ad5eb71a618395"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4a688e5ccb3dbd0cb69a6d65684ac4077bb54a72"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@4a688e5ccb3dbd0cb69a6d65684ac4077bb54a72",
"title": "4a688e5ccb3dbd0cb69a6d65684ac4077bb54a72",
"url": "https://chromium.googlesource.com/chromium/src/+/4a688e5ccb3dbd0cb69a6d65684ac4077bb54a72"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ee0d6fd5d6c888524b96d5a33b1c6c43e1a4fe2e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@ee0d6fd5d6c888524b96d5a33b1c6c43e1a4fe2e",
"title": "ee0d6fd5d6c888524b96d5a33b1c6c43e1a4fe2e",
"url": "https://chromium.googlesource.com/chromium/src/+/ee0d6fd5d6c888524b96d5a33b1c6c43e1a4fe2e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c69ce5c352ab7f0c3ec83397a25c4f52a2d5e922"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@c69ce5c352ab7f0c3ec83397a25c4f52a2d5e922",
"title": "c69ce5c352ab7f0c3ec83397a25c4f52a2d5e922",
"url": "https://chromium.googlesource.com/chromium/src/+/c69ce5c352ab7f0c3ec83397a25c4f52a2d5e922"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c69ce5c352ab7f0c3ec83397a25c4f52a2d5e922",
"ref": "refs/branch-heads/7444"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8865374432160854960"
},
{
"key": "scheduler_job_id",
"value": "chromium-m142/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:58:42.643] Scheduled build:
{
"id": "8699944370259466177",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "win-official"
},
"number": 220,
"createdBy": "project:chromium-m142",
"createTime": "2025-10-26T12:58:42.128078384Z",
"updateTime": "2025-10-26T12:58:42.128078384Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c69ce5c352ab7f0c3ec83397a25c4f52a2d5e922",
"ref": "refs/branch-heads/7444"
}
}
}
[12:58:42.643] Task URL: https://cr-buildbucket.appspot.com/build/8699944370259466177
[12:58:42.643] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:2:0) after 1m18s
[12:58:46.038] Received PubSub notification, asking Buildbucket for the build status
[12:58:46.054] Build status: SCHEDULED
[12:58:59.327] Received PubSub notification, asking Buildbucket for the build status
[12:58:59.363] Build status: STARTED
[13:00:00.771] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:2:0)
[13:00:00.798] Build status: STARTED
[13:00:00.798] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:5:0) after 2m15s
[13:02:15.951] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:5:0)
[13:02:15.974] Build status: STARTED
[13:02:15.974] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:6:0) after 2m33s
[13:04:48.962] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:6:0)
[13:04:49.005] Build status: STARTED
[13:04:49.005] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:7:0) after 1m28s
[13:06:17.123] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:7:0)
[13:06:17.146] Build status: STARTED
[13:06:17.146] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:8:0) after 7m24s
[13:13:41.262] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:8:0)
[13:13:41.282] Build status: STARTED
[13:13:41.283] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:9:0) after 9m34s
[13:23:15.438] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:9:0)
[13:23:15.453] Build status: STARTED
[13:23:15.453] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:10:0) after 2m54s
[13:26:09.470] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:10:0)
[13:26:09.536] Build status: STARTED
[13:26:09.536] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:11:0) after 5m5s
[13:31:14.603] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:11:0)
[13:31:14.617] Build status: STARTED
[13:31:14.617] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:12:0) after 5m5s
[13:36:19.637] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:12:0)
[13:36:19.651] Build status: STARTED
[13:36:19.651] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:13:0) after 3m37s
[13:39:56.666] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:13:0)
[13:39:56.683] Build status: STARTED
[13:39:56.683] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:14:0) after 9m19s
[13:49:15.778] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:14:0)
[13:49:15.803] Build status: STARTED
[13:49:15.803] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:15:0) after 5m15s
[13:54:30.919] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:15:0)
[13:54:30.953] Build status: STARTED
[13:54:30.953] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:16:0) after 5m22s
[13:59:53.085] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:16:0)
[13:59:53.098] Build status: STARTED
[13:59:53.098] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:17:0) after 4m24s
[14:04:17.665] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:17:0)
[14:04:17.692] Build status: STARTED
[14:04:17.692] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:18:0) after 6m16s
[14:10:33.709] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:18:0)
[14:10:33.959] Build status: STARTED
[14:10:33.959] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:19:0) after 3m11s
[14:13:45.024] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:19:0)
[14:13:45.065] Build status: STARTED
[14:13:45.065] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:20:0) after 9m34s
[14:23:19.185] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:20:0)
[14:23:19.201] Build status: STARTED
[14:23:19.201] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:21:0) after 4m27s
[14:27:46.334] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:21:0)
[14:27:46.349] Build status: STARTED
[14:27:46.349] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:22:0) after 3m49s
[14:31:35.379] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:22:0)
[14:31:35.393] Build status: STARTED
[14:31:35.393] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:23:0) after 6m57s
[14:38:32.541] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:23:0)
[14:38:32.555] Build status: STARTED
[14:38:32.555] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:24:0) after 2m34s
[14:41:06.618] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:24:0)
[14:41:06.643] Build status: STARTED
[14:41:06.644] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:25:0) after 2m45s
[14:43:51.656] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:25:0)
[14:43:51.675] Build status: STARTED
[14:43:51.675] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:26:0) after 1m2s
[14:44:53.740] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:26:0)
[14:44:53.763] Build status: STARTED
[14:44:53.763] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:27:0) after 6m35s
[14:51:29.075] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:27:0)
[14:51:29.093] Build status: STARTED
[14:51:29.093] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:28:0) after 1m35s
[14:53:04.114] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:28:0)
[14:53:04.155] Build status: STARTED
[14:53:04.155] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:29:0) after 9m20s
[15:02:24.338] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:29:0)
[15:02:24.356] Build status: STARTED
[15:02:24.356] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:30:0) after 7m1s
[15:09:25.683] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:30:0)
[15:09:25.701] Build status: STARTED
[15:09:25.701] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:31:0) after 6m53s
[15:16:18.719] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:31:0)
[15:16:18.736] Build status: STARTED
[15:16:18.737] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:32:0) after 2m51s
[15:19:09.751] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:32:0)
[15:19:09.769] Build status: STARTED
[15:19:09.769] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:33:0) after 9m30s
[15:28:39.785] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:33:0)
[15:28:39.803] Build status: STARTED
[15:28:39.803] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:34:0) after 4m48s
[15:33:27.830] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:34:0)
[15:33:27.915] Build status: STARTED
[15:33:27.915] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:35:0) after 2m48s
[15:36:16.146] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:35:0)
[15:36:16.161] Build status: STARTED
[15:36:16.161] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:36:0) after 1m32s
[15:37:48.276] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:36:0)
[15:37:48.292] Build status: STARTED
[15:37:48.292] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:37:0) after 4m33s
[15:42:21.448] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:37:0)
[15:42:21.463] Build status: STARTED
[15:42:21.463] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:38:0) after 3m45s
[15:46:06.482] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:38:0)
[15:46:06.512] Build status: STARTED
[15:46:06.512] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:39:0) after 1m39s
[15:47:45.525] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:39:0)
[15:47:45.540] Build status: STARTED
[15:47:45.540] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:40:0) after 6m42s
[15:54:27.554] Handling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:40:0)
[15:54:27.568] Build status: STARTED
[15:54:27.568] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win-official:8865374432160854960:41:0) after 7m55s
[15:58:52.268] Received PubSub notification, asking Buildbucket for the build status
[15:58:52.285] Build:
{
"id": "8699944370259466177",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "win-official"
},
"number": 220,
"createdBy": "project:chromium-m142",
"createTime": "2025-10-26T12:58:42.128078384Z",
"startTime": "2025-10-26T12:58:59.148752280Z",
"endTime": "2025-10-26T15:58:51.835568137Z",
"updateTime": "2025-10-26T15:58:51.835568137Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c69ce5c352ab7f0c3ec83397a25c4f52a2d5e922",
"ref": "refs/branch-heads/7444"
}
}
}
[15:58:52.285] Invocation finished in 3h0m11.353891111s with status SUCCEEDED