[17:36:36.909] New invocation is queued and will start shortly
[17:36:38.172] Starting the invocation (attempt 1)
[17:36:38.199] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c47220a989d71f12dbfa799efd666b0637b3e5f3"
[17:36:38.199] Popped gitiles commit info from properties and tags
[17:36:38.199] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:36:38.199] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:36:38.200] Buildbucket request:
{
"requestId": "8845606807498987680",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8845606807498987680",
"job": "chromium-m148/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0bd6ea2717dcc8c048e2947868cf4557bc6ca86e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@0bd6ea2717dcc8c048e2947868cf4557bc6ca86e",
"title": "0bd6ea2717dcc8c048e2947868cf4557bc6ca86e",
"url": "https://chromium.googlesource.com/chromium/src/+/0bd6ea2717dcc8c048e2947868cf4557bc6ca86e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9569855892f5940292668c9e60f581fa43f3483f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@9569855892f5940292668c9e60f581fa43f3483f",
"title": "9569855892f5940292668c9e60f581fa43f3483f",
"url": "https://chromium.googlesource.com/chromium/src/+/9569855892f5940292668c9e60f581fa43f3483f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e664c12ca3483e796c4edfd130f37bf4a298f64b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@e664c12ca3483e796c4edfd130f37bf4a298f64b",
"title": "e664c12ca3483e796c4edfd130f37bf4a298f64b",
"url": "https://chromium.googlesource.com/chromium/src/+/e664c12ca3483e796c4edfd130f37bf4a298f64b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c69a76bf16619d29f92493df984564885e797c4b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@c69a76bf16619d29f92493df984564885e797c4b",
"title": "c69a76bf16619d29f92493df984564885e797c4b",
"url": "https://chromium.googlesource.com/chromium/src/+/c69a76bf16619d29f92493df984564885e797c4b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "136880de7a91a1f5a59759e582c4a558a725faa1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@136880de7a91a1f5a59759e582c4a558a725faa1",
"title": "136880de7a91a1f5a59759e582c4a558a725faa1",
"url": "https://chromium.googlesource.com/chromium/src/+/136880de7a91a1f5a59759e582c4a558a725faa1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "75f2755de3a863481501d6306e5ab92e57355da9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@75f2755de3a863481501d6306e5ab92e57355da9",
"title": "75f2755de3a863481501d6306e5ab92e57355da9",
"url": "https://chromium.googlesource.com/chromium/src/+/75f2755de3a863481501d6306e5ab92e57355da9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "add2f24ae600928819f47168d9cf87e45f680168"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@add2f24ae600928819f47168d9cf87e45f680168",
"title": "add2f24ae600928819f47168d9cf87e45f680168",
"url": "https://chromium.googlesource.com/chromium/src/+/add2f24ae600928819f47168d9cf87e45f680168"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "384c8e9cf3ab99e6e5126688a345e81ef8d82b0e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@384c8e9cf3ab99e6e5126688a345e81ef8d82b0e",
"title": "384c8e9cf3ab99e6e5126688a345e81ef8d82b0e",
"url": "https://chromium.googlesource.com/chromium/src/+/384c8e9cf3ab99e6e5126688a345e81ef8d82b0e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e34b6ac1b4d06e8788a999c768074d7599623b97"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@e34b6ac1b4d06e8788a999c768074d7599623b97",
"title": "e34b6ac1b4d06e8788a999c768074d7599623b97",
"url": "https://chromium.googlesource.com/chromium/src/+/e34b6ac1b4d06e8788a999c768074d7599623b97"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "28cb49d19db59438dd48e0fc957650b6969c509e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@28cb49d19db59438dd48e0fc957650b6969c509e",
"title": "28cb49d19db59438dd48e0fc957650b6969c509e",
"url": "https://chromium.googlesource.com/chromium/src/+/28cb49d19db59438dd48e0fc957650b6969c509e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cdef2e7e55bd9211765ef36275306e01b44c68bf"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@cdef2e7e55bd9211765ef36275306e01b44c68bf",
"title": "cdef2e7e55bd9211765ef36275306e01b44c68bf",
"url": "https://chromium.googlesource.com/chromium/src/+/cdef2e7e55bd9211765ef36275306e01b44c68bf"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c47220a989d71f12dbfa799efd666b0637b3e5f3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@c47220a989d71f12dbfa799efd666b0637b3e5f3",
"title": "c47220a989d71f12dbfa799efd666b0637b3e5f3",
"url": "https://chromium.googlesource.com/chromium/src/+/c47220a989d71f12dbfa799efd666b0637b3e5f3"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c47220a989d71f12dbfa799efd666b0637b3e5f3",
"ref": "refs/branch-heads/7778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8845606807498987680"
},
{
"key": "scheduler_job_id",
"value": "chromium-m148/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:36:38.745] Scheduled build:
{
"id": "8680176745385909841",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win-official"
},
"number": 400,
"createdBy": "project:chromium-m148",
"createTime": "2026-06-01T17:36:38.266424024Z",
"updateTime": "2026-06-01T17:36:38.266424024Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c47220a989d71f12dbfa799efd666b0637b3e5f3",
"ref": "refs/branch-heads/7778"
}
}
}
[17:36:38.745] Task URL: https://cr-buildbucket.appspot.com/build/8680176745385909841
[17:36:38.745] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:2:0) after 7m25s
[17:36:39.698] Received PubSub notification, asking Buildbucket for the build status
[17:36:39.723] Build status: SCHEDULED
[17:36:44.446] Received PubSub notification, asking Buildbucket for the build status
[17:36:44.466] Build status: STARTED
[17:44:03.762] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:2:0)
[17:44:03.791] Build status: STARTED
[17:44:03.791] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:5:0) after 9m51s
[17:53:54.807] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:5:0)
[17:53:54.867] Build status: STARTED
[17:53:54.867] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:6:0) after 7m41s
[18:01:35.983] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:6:0)
[18:01:36.015] Build status: STARTED
[18:01:36.015] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:7:0) after 6m48s
[18:08:24.026] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:7:0)
[18:08:24.063] Build status: STARTED
[18:08:24.063] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:8:0) after 1m36s
[18:10:00.164] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:8:0)
[18:10:00.307] Build status: STARTED
[18:10:00.307] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:9:0) after 2m36s
[18:12:36.307] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:9:0)
[18:12:36.323] Build status: STARTED
[18:12:36.323] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:10:0) after 3m18s
[18:15:54.337] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:10:0)
[18:15:54.369] Build status: STARTED
[18:15:54.369] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:11:0) after 4m26s
[18:20:20.527] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:11:0)
[18:20:20.965] Build status: STARTED
[18:20:20.965] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:12:0) after 6m9s
[18:26:30.030] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:12:0)
[18:26:30.058] Build status: STARTED
[18:26:30.058] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:13:0) after 6m58s
[18:33:28.240] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:13:0)
[18:33:28.272] Build status: STARTED
[18:33:28.272] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:14:0) after 5m8s
[18:38:36.423] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:14:0)
[18:38:36.448] Build status: STARTED
[18:38:36.448] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:15:0) after 2m43s
[18:41:19.485] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:15:0)
[18:41:19.515] Build status: STARTED
[18:41:19.515] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:16:0) after 7m3s
[18:48:22.535] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:16:0)
[18:48:22.554] Build status: STARTED
[18:48:22.554] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:17:0) after 7m22s
[18:55:44.640] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:17:0)
[18:55:44.657] Build status: STARTED
[18:55:44.657] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:18:0) after 3m25s
[18:59:09.744] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:18:0)
[18:59:09.763] Build status: STARTED
[18:59:09.763] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:19:0) after 7m2s
[19:06:11.833] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:19:0)
[19:06:11.851] Build status: STARTED
[19:06:11.851] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:20:0) after 5m3s
[19:11:14.893] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:20:0)
[19:11:14.910] Build status: STARTED
[19:11:14.910] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:21:0) after 2m3s
[19:13:17.977] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:21:0)
[19:13:18.022] Build status: STARTED
[19:13:18.022] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:22:0) after 7m34s
[19:20:52.033] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:22:0)
[19:20:52.090] Build status: STARTED
[19:20:52.090] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:23:0) after 6m57s
[19:27:49.105] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:23:0)
[19:27:49.167] Build status: STARTED
[19:27:49.167] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:24:0) after 1m12s
[19:29:01.186] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:24:0)
[19:29:01.203] Build status: STARTED
[19:29:01.203] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:25:0) after 7m4s
[19:36:05.243] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:25:0)
[19:36:05.264] Build status: STARTED
[19:36:05.264] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:26:0) after 8m6s
[19:44:11.329] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:26:0)
[19:44:11.346] Build status: STARTED
[19:44:11.346] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:27:0) after 7m35s
[19:51:46.436] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:27:0)
[19:51:46.452] Build status: STARTED
[19:51:46.452] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:28:0) after 2m32s
[19:54:18.560] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:28:0)
[19:54:18.583] Build status: STARTED
[19:54:18.583] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:29:0) after 5m16s
[19:59:34.600] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:29:0)
[19:59:34.619] Build status: STARTED
[19:59:34.619] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:30:0) after 1m47s
[20:01:21.631] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:30:0)
[20:01:21.652] Build status: STARTED
[20:01:21.652] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:31:0) after 8m4s
[20:09:25.665] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:31:0)
[20:09:25.681] Build status: STARTED
[20:09:25.681] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:32:0) after 2m29s
[20:11:54.716] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:32:0)
[20:11:54.739] Build status: STARTED
[20:11:54.739] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:33:0) after 7m40s
[20:19:34.873] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:33:0)
[20:19:34.902] Build status: STARTED
[20:19:34.902] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:34:0) after 6m14s
[20:25:49.036] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:34:0)
[20:25:49.073] Build status: STARTED
[20:25:49.073] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:35:0) after 5m26s
[20:31:15.073] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:35:0)
[20:31:15.098] Build status: STARTED
[20:31:15.098] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:36:0) after 4m5s
[20:35:20.152] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:36:0)
[20:35:20.262] Build status: STARTED
[20:35:20.262] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:37:0) after 6m34s
[20:41:54.328] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:37:0)
[20:41:54.348] Build status: STARTED
[20:41:54.348] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:38:0) after 1m17s
[20:43:11.368] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:38:0)
[20:43:11.393] Build status: STARTED
[20:43:11.393] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:39:0) after 1m46s
[20:44:57.402] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:39:0)
[20:44:57.446] Build status: STARTED
[20:44:57.446] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:40:0) after 6m14s
[20:51:11.457] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:40:0)
[20:51:11.529] Build status: STARTED
[20:51:11.529] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:41:0) after 2m10s
[20:53:21.543] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:41:0)
[20:53:21.608] Build status: STARTED
[20:53:21.608] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:42:0) after 1m31s
[20:54:52.681] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:42:0)
[20:54:52.700] Build status: STARTED
[20:54:52.700] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:43:0) after 6m44s
[21:01:36.716] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:43:0)
[21:01:36.793] Build status: STARTED
[21:01:36.793] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:44:0) after 5m28s
[21:07:04.909] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:44:0)
[21:07:04.932] Build status: STARTED
[21:07:04.932] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:45:0) after 4m57s
[21:12:02.092] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:45:0)
[21:12:02.111] Build status: STARTED
[21:12:02.111] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845606807498987680:46:0) after 3m2s
[21:12:50.576] Received PubSub notification, asking Buildbucket for the build status
[21:12:50.611] Build:
{
"id": "8680176745385909841",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win-official"
},
"number": 400,
"createdBy": "project:chromium-m148",
"createTime": "2026-06-01T17:36:38.266424024Z",
"startTime": "2026-06-01T17:36:44.023079891Z",
"endTime": "2026-06-01T21:12:50.293833587Z",
"updateTime": "2026-06-01T21:12:50.293833587Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c47220a989d71f12dbfa799efd666b0637b3e5f3",
"ref": "refs/branch-heads/7778"
}
}
}
[21:12:50.611] Invocation finished in 3h36m13.744190531s with status SUCCEEDED