[11:15:11.531] New invocation is queued and will start shortly
[11:15:12.637] Starting the invocation (attempt 1)
[11:15:12.665] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/666a6ec3e2bdf4aa4326e1c4d134f793107d4292"
[11:15:12.665] Popped gitiles commit info from properties and tags
[11:15:12.665] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:15:12.665] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:15:12.666] Buildbucket request:
{
"requestId": "8851519607337224720",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8851519607337224720",
"job": "chromium-m146/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6b39ec48d51102be70a47867245912f53caa6da4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@6b39ec48d51102be70a47867245912f53caa6da4",
"title": "6b39ec48d51102be70a47867245912f53caa6da4",
"url": "https://chromium.googlesource.com/chromium/src/+/6b39ec48d51102be70a47867245912f53caa6da4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "628e009b3bea3918cb4c9a2a82e0d0caeac3df4d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@628e009b3bea3918cb4c9a2a82e0d0caeac3df4d",
"title": "628e009b3bea3918cb4c9a2a82e0d0caeac3df4d",
"url": "https://chromium.googlesource.com/chromium/src/+/628e009b3bea3918cb4c9a2a82e0d0caeac3df4d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f225922435d943a89942c3f3a69db538d135cdc0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@f225922435d943a89942c3f3a69db538d135cdc0",
"title": "f225922435d943a89942c3f3a69db538d135cdc0",
"url": "https://chromium.googlesource.com/chromium/src/+/f225922435d943a89942c3f3a69db538d135cdc0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "34140636af6579d1ac5671cec9132ff1cb3eaa6c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@34140636af6579d1ac5671cec9132ff1cb3eaa6c",
"title": "34140636af6579d1ac5671cec9132ff1cb3eaa6c",
"url": "https://chromium.googlesource.com/chromium/src/+/34140636af6579d1ac5671cec9132ff1cb3eaa6c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "71b6ae5667ad92b5c65f3d81f2e7b88b0f0dbb52"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@71b6ae5667ad92b5c65f3d81f2e7b88b0f0dbb52",
"title": "71b6ae5667ad92b5c65f3d81f2e7b88b0f0dbb52",
"url": "https://chromium.googlesource.com/chromium/src/+/71b6ae5667ad92b5c65f3d81f2e7b88b0f0dbb52"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e0983873d701994b6ba96b394865f379f1dcf6f1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@e0983873d701994b6ba96b394865f379f1dcf6f1",
"title": "e0983873d701994b6ba96b394865f379f1dcf6f1",
"url": "https://chromium.googlesource.com/chromium/src/+/e0983873d701994b6ba96b394865f379f1dcf6f1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "793c0e0175146c7896730a754fbf0b70ba002b88"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@793c0e0175146c7896730a754fbf0b70ba002b88",
"title": "793c0e0175146c7896730a754fbf0b70ba002b88",
"url": "https://chromium.googlesource.com/chromium/src/+/793c0e0175146c7896730a754fbf0b70ba002b88"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "adbf685fc9594d849d455dad9bd11b903c9cf858"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@adbf685fc9594d849d455dad9bd11b903c9cf858",
"title": "adbf685fc9594d849d455dad9bd11b903c9cf858",
"url": "https://chromium.googlesource.com/chromium/src/+/adbf685fc9594d849d455dad9bd11b903c9cf858"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "666a6ec3e2bdf4aa4326e1c4d134f793107d4292"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@666a6ec3e2bdf4aa4326e1c4d134f793107d4292",
"title": "666a6ec3e2bdf4aa4326e1c4d134f793107d4292",
"url": "https://chromium.googlesource.com/chromium/src/+/666a6ec3e2bdf4aa4326e1c4d134f793107d4292"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "666a6ec3e2bdf4aa4326e1c4d134f793107d4292",
"ref": "refs/branch-heads/7680"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8851519607337224720"
},
{
"key": "scheduler_job_id",
"value": "chromium-m146/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:15:13.282] Scheduled build:
{
"id": "8686089545416670945",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win32-official"
},
"number": 261,
"createdBy": "project:chromium-m146",
"createTime": "2026-03-28T11:15:12.739027400Z",
"updateTime": "2026-03-28T11:15:12.739027400Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "666a6ec3e2bdf4aa4326e1c4d134f793107d4292",
"ref": "refs/branch-heads/7680"
}
}
}
[11:15:13.282] Task URL: https://cr-buildbucket.appspot.com/build/8686089545416670945
[11:15:13.282] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:2:0) after 4m17s
[11:15:13.524] Received PubSub notification, asking Buildbucket for the build status
[11:15:13.543] Build status: SCHEDULED
[11:15:17.747] Received PubSub notification, asking Buildbucket for the build status
[11:15:17.770] Build status: STARTED
[11:19:30.295] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:2:0)
[11:19:30.316] Build status: STARTED
[11:19:30.316] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:5:0) after 3m33s
[11:23:03.944] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:5:0)
[11:23:03.965] Build status: STARTED
[11:23:03.965] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:6:0) after 2m55s
[11:25:59.148] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:6:0)
[11:25:59.278] Build status: STARTED
[11:25:59.278] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:7:0) after 1m24s
[11:27:23.291] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:7:0)
[11:27:23.307] Build status: STARTED
[11:27:23.307] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:8:0) after 1m13s
[11:28:36.525] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:8:0)
[11:28:36.544] Build status: STARTED
[11:28:36.544] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:9:0) after 4m33s
[11:33:10.353] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:9:0)
[11:33:10.423] Build status: STARTED
[11:33:10.423] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:10:0) after 7m48s
[11:40:58.436] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:10:0)
[11:40:58.465] Build status: STARTED
[11:40:58.465] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:11:0) after 3m36s
[11:44:34.665] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:11:0)
[11:44:34.693] Build status: STARTED
[11:44:34.693] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:12:0) after 7m21s
[11:51:56.276] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:12:0)
[11:51:56.293] Build status: STARTED
[11:51:56.293] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:13:0) after 9m55s
[12:01:51.308] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:13:0)
[12:01:51.338] Build status: STARTED
[12:01:51.338] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:14:0) after 9m8s
[12:10:59.534] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:14:0)
[12:10:59.556] Build status: STARTED
[12:10:59.556] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:15:0) after 3m27s
[12:14:26.709] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:15:0)
[12:14:26.734] Build status: STARTED
[12:14:26.734] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:16:0) after 9m47s
[12:24:13.759] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:16:0)
[12:24:13.779] Build status: STARTED
[12:24:13.779] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:17:0) after 6m50s
[12:31:03.806] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:17:0)
[12:31:03.838] Build status: STARTED
[12:31:03.838] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:18:0) after 6m48s
[12:37:52.031] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:18:0)
[12:37:52.050] Build status: STARTED
[12:37:52.050] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:19:0) after 3m21s
[12:41:13.173] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:19:0)
[12:41:13.220] Build status: STARTED
[12:41:13.220] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:20:0) after 5m44s
[12:46:57.357] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:20:0)
[12:46:57.378] Build status: STARTED
[12:46:57.378] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:21:0) after 6m41s
[12:53:38.724] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:21:0)
[12:53:38.742] Build status: STARTED
[12:53:38.742] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:22:0) after 8m27s
[13:02:06.041] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:22:0)
[13:02:06.063] Build status: STARTED
[13:02:06.063] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:23:0) after 2m53s
[13:04:59.176] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:23:0)
[13:04:59.196] Build status: STARTED
[13:04:59.196] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:24:0) after 8m19s
[13:13:18.214] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:24:0)
[13:13:18.360] Build status: STARTED
[13:13:18.361] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:25:0) after 9m3s
[13:22:21.403] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:25:0)
[13:22:21.487] Build status: STARTED
[13:22:21.487] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:26:0) after 2m22s
[13:24:43.740] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:26:0)
[13:24:43.757] Build status: STARTED
[13:24:43.757] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:27:0) after 3m3s
[13:27:46.911] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:27:0)
[13:27:47.107] Build status: STARTED
[13:27:47.107] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:28:0) after 6m35s
[13:34:22.179] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:28:0)
[13:34:22.221] Build status: STARTED
[13:34:22.222] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:29:0) after 9m30s
[13:43:52.235] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:29:0)
[13:43:52.255] Build status: STARTED
[13:43:52.255] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:30:0) after 3m7s
[13:46:59.485] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:30:0)
[13:46:59.504] Build status: STARTED
[13:46:59.504] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:31:0) after 3m28s
[13:50:27.519] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:31:0)
[13:50:27.586] Build status: STARTED
[13:50:27.586] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:32:0) after 3m38s
[13:54:05.800] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:32:0)
[13:54:05.843] Build status: STARTED
[13:54:05.843] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:33:0) after 4m49s
[13:58:55.863] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:33:0)
[13:58:55.882] Build status: STARTED
[13:58:55.882] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:34:0) after 5m38s
[14:04:34.034] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:34:0)
[14:04:34.105] Build status: STARTED
[14:04:34.105] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:35:0) after 5m34s
[14:10:08.383] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:35:0)
[14:10:08.399] Build status: STARTED
[14:10:08.399] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:36:0) after 3m52s
[14:14:00.639] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:36:0)
[14:14:00.659] Build status: STARTED
[14:14:00.659] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:37:0) after 6m43s
[14:20:43.656] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:37:0)
[14:20:43.676] Build status: STARTED
[14:20:43.676] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:38:0) after 6m28s
[14:27:11.784] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:38:0)
[14:27:11.810] Build status: STARTED
[14:27:11.810] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:39:0) after 5m0s
[14:32:11.856] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:39:0)
[14:32:11.875] Build status: STARTED
[14:32:11.876] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:40:0) after 4m19s
[14:36:30.892] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:40:0)
[14:36:30.928] Build status: STARTED
[14:36:30.928] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:41:0) after 1m29s
[14:38:00.522] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:41:0)
[14:38:00.598] Build status: STARTED
[14:38:00.599] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:42:0) after 6m34s
[14:44:34.759] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:42:0)
[14:44:34.773] Build status: STARTED
[14:44:34.773] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:43:0) after 9m11s
[14:53:45.813] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:43:0)
[14:53:45.851] Build status: STARTED
[14:53:45.851] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:44:0) after 3m30s
[14:57:16.140] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:44:0)
[14:57:16.157] Build status: STARTED
[14:57:16.157] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:45:0) after 2m34s
[14:59:50.917] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:45:0)
[14:59:50.933] Build status: STARTED
[14:59:50.933] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:46:0) after 2m7s
[15:01:58.052] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:46:0)
[15:01:58.069] Build status: STARTED
[15:01:58.069] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:47:0) after 7m42s
[15:09:40.086] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:47:0)
[15:09:40.114] Build status: STARTED
[15:09:40.114] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:48:0) after 1m45s
[15:11:25.484] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:48:0)
[15:11:25.504] Build status: STARTED
[15:11:25.504] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:49:0) after 8m38s
[15:20:03.747] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:49:0)
[15:20:03.769] Build status: STARTED
[15:20:03.769] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:50:0) after 9m23s
[15:29:26.787] Handling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:50:0)
[15:29:26.848] Build status: STARTED
[15:29:26.848] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win32-official:8851519607337224720:51:0) after 8m47s
[15:35:30.056] Received PubSub notification, asking Buildbucket for the build status
[15:35:30.110] Build:
{
"id": "8686089545416670945",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win32-official"
},
"number": 261,
"createdBy": "project:chromium-m146",
"createTime": "2026-03-28T11:15:12.739027400Z",
"startTime": "2026-03-28T11:15:17.506047412Z",
"endTime": "2026-03-28T15:35:29.796570180Z",
"updateTime": "2026-03-28T15:35:29.796570180Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "666a6ec3e2bdf4aa4326e1c4d134f793107d4292",
"ref": "refs/branch-heads/7680"
}
}
}
[15:35:30.110] Invocation finished in 4h20m18.585173067s with status SUCCEEDED