[20:46:32.860] New invocation is queued and will start shortly
[20:46:33.928] Starting the invocation (attempt 1)
[20:46:33.952] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/891053f04261243d1c98e87ae681b6e250d020be"
[20:46:33.952] Popped gitiles commit info from properties and tags
[20:46:33.952] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:46:33.954] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:46:33.954] Buildbucket request:
{
"requestId": "8845051276141369312",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8845051276141369312",
"job": "chromium-m149/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "51d2e63c102dd7c562cd63cbf8db6f0941563afa"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@51d2e63c102dd7c562cd63cbf8db6f0941563afa",
"title": "51d2e63c102dd7c562cd63cbf8db6f0941563afa",
"url": "https://chromium.googlesource.com/chromium/src/+/51d2e63c102dd7c562cd63cbf8db6f0941563afa"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b699463e51d34668769fa18d4ee4667dc5056ff4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@b699463e51d34668769fa18d4ee4667dc5056ff4",
"title": "b699463e51d34668769fa18d4ee4667dc5056ff4",
"url": "https://chromium.googlesource.com/chromium/src/+/b699463e51d34668769fa18d4ee4667dc5056ff4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4a49ebc2b5cf6a827ad73892e58b8566a5af63c5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@4a49ebc2b5cf6a827ad73892e58b8566a5af63c5",
"title": "4a49ebc2b5cf6a827ad73892e58b8566a5af63c5",
"url": "https://chromium.googlesource.com/chromium/src/+/4a49ebc2b5cf6a827ad73892e58b8566a5af63c5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e9c8c27308ac007635ec7862f5290336b0d2aed0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@e9c8c27308ac007635ec7862f5290336b0d2aed0",
"title": "e9c8c27308ac007635ec7862f5290336b0d2aed0",
"url": "https://chromium.googlesource.com/chromium/src/+/e9c8c27308ac007635ec7862f5290336b0d2aed0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9c7ea776427b44e7677de7c6b470852e1b1f694e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@9c7ea776427b44e7677de7c6b470852e1b1f694e",
"title": "9c7ea776427b44e7677de7c6b470852e1b1f694e",
"url": "https://chromium.googlesource.com/chromium/src/+/9c7ea776427b44e7677de7c6b470852e1b1f694e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "891053f04261243d1c98e87ae681b6e250d020be"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@891053f04261243d1c98e87ae681b6e250d020be",
"title": "891053f04261243d1c98e87ae681b6e250d020be",
"url": "https://chromium.googlesource.com/chromium/src/+/891053f04261243d1c98e87ae681b6e250d020be"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "891053f04261243d1c98e87ae681b6e250d020be",
"ref": "refs/branch-heads/7827"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8845051276141369312"
},
{
"key": "scheduler_job_id",
"value": "chromium-m149/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:46:34.430] Scheduled build:
{
"id": "8679621214271492321",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win-official"
},
"number": 246,
"createdBy": "project:chromium-m149",
"createTime": "2026-06-07T20:46:34.022514146Z",
"updateTime": "2026-06-07T20:46:34.022514146Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "891053f04261243d1c98e87ae681b6e250d020be",
"ref": "refs/branch-heads/7827"
}
}
}
[20:46:34.430] Task URL: https://cr-buildbucket.appspot.com/build/8679621214271492321
[20:46:34.430] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:2:0) after 4m54s
[20:47:27.139] Received PubSub notification, asking Buildbucket for the build status
[20:47:27.157] Build status: STARTED
[20:47:31.601] Received PubSub notification, asking Buildbucket for the build status
[20:47:31.626] Build status: STARTED
[20:51:28.536] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:2:0)
[20:51:28.631] Build status: STARTED
[20:51:28.631] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:5:0) after 8m45s
[21:00:13.777] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:5:0)
[21:00:13.799] Build status: STARTED
[21:00:13.800] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:6:0) after 4m36s
[21:04:49.836] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:6:0)
[21:04:49.926] Build status: STARTED
[21:04:49.926] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:7:0) after 1m39s
[21:06:28.944] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:7:0)
[21:06:28.969] Build status: STARTED
[21:06:28.969] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:8:0) after 3m2s
[21:09:31.484] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:8:0)
[21:09:31.505] Build status: STARTED
[21:09:31.505] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:9:0) after 2m56s
[21:12:27.694] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:9:0)
[21:12:27.710] Build status: STARTED
[21:12:27.710] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:10:0) after 4m52s
[21:17:20.015] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:10:0)
[21:17:20.034] Build status: STARTED
[21:17:20.034] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:11:0) after 7m0s
[21:24:20.148] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:11:0)
[21:24:20.166] Build status: STARTED
[21:24:20.166] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:12:0) after 4m18s
[21:28:38.181] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:12:0)
[21:28:38.201] Build status: STARTED
[21:28:38.201] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:13:0) after 4m38s
[21:33:16.406] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:13:0)
[21:33:16.448] Build status: STARTED
[21:33:16.448] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:14:0) after 5m45s
[21:39:01.518] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:14:0)
[21:39:01.540] Build status: STARTED
[21:39:01.540] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:15:0) after 1m36s
[21:40:37.553] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:15:0)
[21:40:37.572] Build status: STARTED
[21:40:37.572] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:16:0) after 4m54s
[21:45:31.741] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:16:0)
[21:45:31.764] Build status: STARTED
[21:45:31.764] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:17:0) after 7m26s
[21:52:57.781] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:17:0)
[21:52:57.801] Build status: STARTED
[21:52:57.801] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:18:0) after 5m51s
[21:58:48.853] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:18:0)
[21:58:48.870] Build status: STARTED
[21:58:48.870] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:19:0) after 4m13s
[22:03:01.884] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:19:0)
[22:03:01.952] Build status: STARTED
[22:03:01.953] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:20:0) after 7m38s
[22:10:40.480] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:20:0)
[22:10:40.501] Build status: STARTED
[22:10:40.501] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:21:0) after 3m57s
[22:14:37.786] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:21:0)
[22:14:37.806] Build status: STARTED
[22:14:37.806] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:22:0) after 7m51s
[22:22:28.820] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:22:0)
[22:22:28.839] Build status: STARTED
[22:22:28.839] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:23:0) after 2m54s
[22:25:22.852] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:23:0)
[22:25:22.870] Build status: STARTED
[22:25:22.870] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:24:0) after 7m23s
[22:32:45.950] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:24:0)
[22:32:45.971] Build status: STARTED
[22:32:45.971] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:25:0) after 8m35s
[22:41:21.099] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:25:0)
[22:41:21.188] Build status: STARTED
[22:41:21.188] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:26:0) after 3m32s
[22:44:53.405] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:26:0)
[22:44:53.422] Build status: STARTED
[22:44:53.422] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:27:0) after 7m21s
[22:52:14.452] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:27:0)
[22:52:14.503] Build status: STARTED
[22:52:14.503] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:28:0) after 3m40s
[22:55:54.516] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:28:0)
[22:55:54.661] Build status: STARTED
[22:55:54.661] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:29:0) after 7m15s
[23:03:09.676] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:29:0)
[23:03:09.695] Build status: STARTED
[23:03:09.695] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:30:0) after 1m45s
[23:04:54.746] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:30:0)
[23:04:54.773] Build status: STARTED
[23:04:54.773] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:31:0) after 7m55s
[23:12:50.028] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:31:0)
[23:12:50.053] Build status: STARTED
[23:12:50.053] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:32:0) after 9m12s
[23:22:02.069] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:32:0)
[23:22:02.092] Build status: STARTED
[23:22:02.092] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:33:0) after 9m6s
[23:31:08.207] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:33:0)
[23:31:08.236] Build status: STARTED
[23:31:08.236] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:34:0) after 5m4s
[23:36:12.462] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:34:0)
[23:36:12.524] Build status: STARTED
[23:36:12.524] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:35:0) after 4m55s
[23:41:07.755] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:35:0)
[23:41:07.773] Build status: STARTED
[23:41:07.773] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:36:0) after 9m45s
[23:50:52.849] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:36:0)
[23:50:52.868] Build status: STARTED
[23:50:52.868] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:37:0) after 2m43s
[23:53:35.882] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:37:0)
[23:53:35.894] Build status: STARTED
[23:53:35.894] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:38:0) after 5m56s
[23:59:31.912] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:38:0)
[23:59:31.928] Build status: STARTED
[23:59:31.928] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:39:0) after 6m28s
[00:05:59.945] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:39:0)
[00:05:59.965] Build status: STARTED
[00:05:59.965] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845051276141369312:40:0) after 6m53s
[00:10:48.225] Received PubSub notification, asking Buildbucket for the build status
[00:10:48.240] Build:
{
"id": "8679621214271492321",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win-official"
},
"number": 246,
"createdBy": "project:chromium-m149",
"createTime": "2026-06-07T20:46:34.022514146Z",
"startTime": "2026-06-07T20:46:38.551815057Z",
"endTime": "2026-06-08T00:10:47.783736527Z",
"updateTime": "2026-06-08T00:10:47.783736527Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "891053f04261243d1c98e87ae681b6e250d020be",
"ref": "refs/branch-heads/7827"
}
}
}
[00:10:48.240] Invocation finished in 3h24m15.385404378s with status SUCCEEDED