[17:53:46.948] New invocation is queued and will start shortly
[17:53:48.164] Starting the invocation (attempt 1)
[17:53:48.204] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/23a962aac08fbdb8f34c1fb0af203df40639fddc"
[17:53:48.204] Popped gitiles commit info from properties and tags
[17:53:48.204] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:53:48.204] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:53:48.205] Buildbucket request:
{
"requestId": "8843250206259481552",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8843250206259481552",
"job": "chromium-m150/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d67a68d22db735b541c6d9c25109c013ccdea85f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@d67a68d22db735b541c6d9c25109c013ccdea85f",
"title": "d67a68d22db735b541c6d9c25109c013ccdea85f",
"url": "https://chromium.googlesource.com/chromium/src/+/d67a68d22db735b541c6d9c25109c013ccdea85f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4e94d2e94132d9c7b7d6ea2f0cd975313ccac6d5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@4e94d2e94132d9c7b7d6ea2f0cd975313ccac6d5",
"title": "4e94d2e94132d9c7b7d6ea2f0cd975313ccac6d5",
"url": "https://chromium.googlesource.com/chromium/src/+/4e94d2e94132d9c7b7d6ea2f0cd975313ccac6d5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "21d002076bdd0461fdea90a4a2e6dfccc8b8aa02"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@21d002076bdd0461fdea90a4a2e6dfccc8b8aa02",
"title": "21d002076bdd0461fdea90a4a2e6dfccc8b8aa02",
"url": "https://chromium.googlesource.com/chromium/src/+/21d002076bdd0461fdea90a4a2e6dfccc8b8aa02"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a7db357cf0ea89a83fd4bd1f3915fed3073eafb5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@a7db357cf0ea89a83fd4bd1f3915fed3073eafb5",
"title": "a7db357cf0ea89a83fd4bd1f3915fed3073eafb5",
"url": "https://chromium.googlesource.com/chromium/src/+/a7db357cf0ea89a83fd4bd1f3915fed3073eafb5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "877f5b7093f0f264a9a577bcb59ad0a1a5347c4e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@877f5b7093f0f264a9a577bcb59ad0a1a5347c4e",
"title": "877f5b7093f0f264a9a577bcb59ad0a1a5347c4e",
"url": "https://chromium.googlesource.com/chromium/src/+/877f5b7093f0f264a9a577bcb59ad0a1a5347c4e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ffe0ce32460c7d4d38ef0978b864bdf1c833b014"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@ffe0ce32460c7d4d38ef0978b864bdf1c833b014",
"title": "ffe0ce32460c7d4d38ef0978b864bdf1c833b014",
"url": "https://chromium.googlesource.com/chromium/src/+/ffe0ce32460c7d4d38ef0978b864bdf1c833b014"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "65254fab50c4d5308983400f3c711bc99d4757e9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@65254fab50c4d5308983400f3c711bc99d4757e9",
"title": "65254fab50c4d5308983400f3c711bc99d4757e9",
"url": "https://chromium.googlesource.com/chromium/src/+/65254fab50c4d5308983400f3c711bc99d4757e9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4a47703481d032f372b4b1743971b2fb8bf675e9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@4a47703481d032f372b4b1743971b2fb8bf675e9",
"title": "4a47703481d032f372b4b1743971b2fb8bf675e9",
"url": "https://chromium.googlesource.com/chromium/src/+/4a47703481d032f372b4b1743971b2fb8bf675e9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7def9537cea48d2bf6772e5a70c5afb3802e3cf3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@7def9537cea48d2bf6772e5a70c5afb3802e3cf3",
"title": "7def9537cea48d2bf6772e5a70c5afb3802e3cf3",
"url": "https://chromium.googlesource.com/chromium/src/+/7def9537cea48d2bf6772e5a70c5afb3802e3cf3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "40f95e2925a371af9253c2d69fb7daab89e2bef1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@40f95e2925a371af9253c2d69fb7daab89e2bef1",
"title": "40f95e2925a371af9253c2d69fb7daab89e2bef1",
"url": "https://chromium.googlesource.com/chromium/src/+/40f95e2925a371af9253c2d69fb7daab89e2bef1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "23a962aac08fbdb8f34c1fb0af203df40639fddc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@23a962aac08fbdb8f34c1fb0af203df40639fddc",
"title": "23a962aac08fbdb8f34c1fb0af203df40639fddc",
"url": "https://chromium.googlesource.com/chromium/src/+/23a962aac08fbdb8f34c1fb0af203df40639fddc"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "23a962aac08fbdb8f34c1fb0af203df40639fddc",
"ref": "refs/branch-heads/7871"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8843250206259481552"
},
{
"key": "scheduler_job_id",
"value": "chromium-m150/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:53:48.900] Scheduled build:
{
"id": "8677820144074339281",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "win-official"
},
"number": 183,
"createdBy": "project:chromium-m150",
"createTime": "2026-06-27T17:53:48.411748125Z",
"updateTime": "2026-06-27T17:53:48.411748125Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "23a962aac08fbdb8f34c1fb0af203df40639fddc",
"ref": "refs/branch-heads/7871"
}
}
}
[17:53:48.900] Task URL: https://cr-buildbucket.appspot.com/build/8677820144074339281
[17:53:48.900] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:2:0) after 6m54s
[17:53:49.849] Received PubSub notification, asking Buildbucket for the build status
[17:53:49.926] Build status: SCHEDULED
[17:55:45.579] Received PubSub notification, asking Buildbucket for the build status
[17:55:45.599] Build status: STARTED
[18:00:43.008] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:2:0)
[18:00:43.037] Build status: STARTED
[18:00:43.037] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:5:0) after 6m15s
[18:06:58.521] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:5:0)
[18:06:58.552] Build status: STARTED
[18:06:58.552] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:6:0) after 1m59s
[18:08:57.717] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:6:0)
[18:08:57.737] Build status: STARTED
[18:08:57.737] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:7:0) after 3m23s
[18:12:20.878] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:7:0)
[18:12:20.896] Build status: STARTED
[18:12:20.896] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:8:0) after 5m22s
[18:17:43.240] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:8:0)
[18:17:43.275] Build status: STARTED
[18:17:43.275] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:9:0) after 1m1s
[18:18:44.290] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:9:0)
[18:18:44.309] Build status: STARTED
[18:18:44.309] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:10:0) after 6m40s
[18:25:24.384] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:10:0)
[18:25:24.446] Build status: STARTED
[18:25:24.446] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:11:0) after 7m13s
[18:32:37.762] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:11:0)
[18:32:37.781] Build status: STARTED
[18:32:37.781] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:12:0) after 2m46s
[18:35:24.203] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:12:0)
[18:35:24.224] Build status: STARTED
[18:35:24.224] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:13:0) after 8m49s
[18:44:13.244] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:13:0)
[18:44:13.268] Build status: STARTED
[18:44:13.268] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:14:0) after 9m51s
[18:54:04.293] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:14:0)
[18:54:04.316] Build status: STARTED
[18:54:04.317] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:15:0) after 9m8s
[19:03:12.615] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:15:0)
[19:03:12.700] Build status: STARTED
[19:03:12.701] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:16:0) after 4m26s
[19:07:38.715] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:16:0)
[19:07:38.745] Build status: STARTED
[19:07:38.746] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:17:0) after 9m9s
[19:16:47.866] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:17:0)
[19:16:47.920] Build status: STARTED
[19:16:47.920] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:18:0) after 3m14s
[19:20:02.119] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:18:0)
[19:20:02.155] Build status: STARTED
[19:20:02.155] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:19:0) after 3m24s
[19:23:26.375] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:19:0)
[19:23:26.401] Build status: STARTED
[19:23:26.402] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:20:0) after 9m52s
[19:33:18.416] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:20:0)
[19:33:18.434] Build status: STARTED
[19:33:18.434] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:21:0) after 3m41s
[19:36:59.451] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:21:0)
[19:36:59.471] Build status: STARTED
[19:36:59.471] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:22:0) after 1m30s
[19:38:29.499] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:22:0)
[19:38:29.556] Build status: STARTED
[19:38:29.556] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:23:0) after 1m14s
[19:39:43.756] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:23:0)
[19:39:43.775] Build status: STARTED
[19:39:43.775] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:24:0) after 2m11s
[19:41:54.842] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:24:0)
[19:41:54.859] Build status: STARTED
[19:41:54.859] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:25:0) after 6m46s
[19:48:41.010] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:25:0)
[19:48:41.064] Build status: STARTED
[19:48:41.064] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:26:0) after 5m40s
[19:54:21.070] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:26:0)
[19:54:21.091] Build status: STARTED
[19:54:21.091] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:27:0) after 9m5s
[20:03:26.107] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:27:0)
[20:03:26.122] Build status: STARTED
[20:03:26.122] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:28:0) after 5m59s
[20:09:25.140] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:28:0)
[20:09:25.161] Build status: STARTED
[20:09:25.161] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:29:0) after 9m56s
[20:19:21.183] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:29:0)
[20:19:21.205] Build status: STARTED
[20:19:21.205] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:30:0) after 9m36s
[20:28:57.222] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:30:0)
[20:28:57.246] Build status: STARTED
[20:28:57.246] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:31:0) after 8m31s
[20:37:28.264] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:31:0)
[20:37:28.284] Build status: STARTED
[20:37:28.284] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:32:0) after 3m48s
[20:41:16.584] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:32:0)
[20:41:16.661] Build status: STARTED
[20:41:16.661] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:33:0) after 6m51s
[20:48:07.779] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:33:0)
[20:48:07.794] Build status: STARTED
[20:48:07.795] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:34:0) after 1m31s
[20:49:38.781] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:34:0)
[20:49:38.797] Build status: STARTED
[20:49:38.797] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:35:0) after 3m38s
[20:53:16.927] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:35:0)
[20:53:16.947] Build status: STARTED
[20:53:16.947] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:36:0) after 6m57s
[21:00:14.085] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:36:0)
[21:00:14.105] Build status: STARTED
[21:00:14.105] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:37:0) after 3m36s
[21:03:50.121] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:37:0)
[21:03:50.142] Build status: STARTED
[21:03:50.142] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:38:0) after 2m6s
[21:05:56.250] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:38:0)
[21:05:56.270] Build status: STARTED
[21:05:56.270] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:39:0) after 2m50s
[21:08:46.579] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:39:0)
[21:08:46.600] Build status: STARTED
[21:08:46.600] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:40:0) after 1m54s
[21:10:41.123] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:40:0)
[21:10:41.146] Build status: STARTED
[21:10:41.146] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:41:0) after 1m51s
[21:12:32.163] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:41:0)
[21:12:32.182] Build status: STARTED
[21:12:32.182] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:42:0) after 2m10s
[21:14:42.200] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:42:0)
[21:14:42.218] Build status: STARTED
[21:14:42.218] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:43:0) after 2m27s
[21:17:09.480] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:43:0)
[21:17:09.495] Build status: STARTED
[21:17:09.495] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843250206259481552:44:0) after 9m56s
[21:23:58.343] Received PubSub notification, asking Buildbucket for the build status
[21:23:58.385] Build:
{
"id": "8677820144074339281",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "win-official"
},
"number": 183,
"createdBy": "project:chromium-m150",
"createTime": "2026-06-27T17:53:48.411748125Z",
"startTime": "2026-06-27T17:55:36.807561089Z",
"endTime": "2026-06-27T21:23:58.008243967Z",
"updateTime": "2026-06-27T21:23:58.008243967Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "23a962aac08fbdb8f34c1fb0af203df40639fddc",
"ref": "refs/branch-heads/7871"
}
}
}
[21:23:58.385] Invocation finished in 3h30m11.440575265s with status SUCCEEDED