[10:28:41.941] New invocation is queued and will start shortly
[10:28:43.218] Starting the invocation (attempt 1)
[10:28:43.246] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c3303c67a5a8b7223ea990353015c29c20791c61"
[10:28:43.246] Popped gitiles commit info from properties and tags
[10:28:43.246] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:28:43.247] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:28:43.247] Buildbucket request:
{
"requestId": "8843187611523676256",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8843187611523676256",
"job": "chromium-m150/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3be99cee9ffc62e134d863367363e22c65a307b7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@3be99cee9ffc62e134d863367363e22c65a307b7",
"title": "3be99cee9ffc62e134d863367363e22c65a307b7",
"url": "https://chromium.googlesource.com/chromium/src/+/3be99cee9ffc62e134d863367363e22c65a307b7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1d75347aaac6437de2d2f3e346dadd24ad0cbe7b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@1d75347aaac6437de2d2f3e346dadd24ad0cbe7b",
"title": "1d75347aaac6437de2d2f3e346dadd24ad0cbe7b",
"url": "https://chromium.googlesource.com/chromium/src/+/1d75347aaac6437de2d2f3e346dadd24ad0cbe7b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0cc762de92655e48a473822416dd3fbfa823ae4a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@0cc762de92655e48a473822416dd3fbfa823ae4a",
"title": "0cc762de92655e48a473822416dd3fbfa823ae4a",
"url": "https://chromium.googlesource.com/chromium/src/+/0cc762de92655e48a473822416dd3fbfa823ae4a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f530e0d4740a21bd7ce3cd667b10a71611cdc081"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@f530e0d4740a21bd7ce3cd667b10a71611cdc081",
"title": "f530e0d4740a21bd7ce3cd667b10a71611cdc081",
"url": "https://chromium.googlesource.com/chromium/src/+/f530e0d4740a21bd7ce3cd667b10a71611cdc081"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d6bfa2223c3bcc4ef8afec11831975264861205b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@d6bfa2223c3bcc4ef8afec11831975264861205b",
"title": "d6bfa2223c3bcc4ef8afec11831975264861205b",
"url": "https://chromium.googlesource.com/chromium/src/+/d6bfa2223c3bcc4ef8afec11831975264861205b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "da33cacaae825f742e5939ab97940aa15f1a4f81"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@da33cacaae825f742e5939ab97940aa15f1a4f81",
"title": "da33cacaae825f742e5939ab97940aa15f1a4f81",
"url": "https://chromium.googlesource.com/chromium/src/+/da33cacaae825f742e5939ab97940aa15f1a4f81"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "14e8d59c97e915523f71dc741f6146767da5414e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@14e8d59c97e915523f71dc741f6146767da5414e",
"title": "14e8d59c97e915523f71dc741f6146767da5414e",
"url": "https://chromium.googlesource.com/chromium/src/+/14e8d59c97e915523f71dc741f6146767da5414e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c3303c67a5a8b7223ea990353015c29c20791c61"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@c3303c67a5a8b7223ea990353015c29c20791c61",
"title": "c3303c67a5a8b7223ea990353015c29c20791c61",
"url": "https://chromium.googlesource.com/chromium/src/+/c3303c67a5a8b7223ea990353015c29c20791c61"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c3303c67a5a8b7223ea990353015c29c20791c61",
"ref": "refs/branch-heads/7871"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8843187611523676256"
},
{
"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"
}
}
[10:28:43.919] Scheduled build:
{
"id": "8677757549427083921",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "win-official"
},
"number": 188,
"createdBy": "project:chromium-m150",
"createTime": "2026-06-28T10:28:43.318259229Z",
"updateTime": "2026-06-28T10:28:43.318259229Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c3303c67a5a8b7223ea990353015c29c20791c61",
"ref": "refs/branch-heads/7871"
}
}
}
[10:28:43.919] Task URL: https://cr-buildbucket.appspot.com/build/8677757549427083921
[10:28:43.919] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:2:0) after 4m51s
[10:28:44.860] Received PubSub notification, asking Buildbucket for the build status
[10:28:44.881] Build status: SCHEDULED
[10:30:42.239] Received PubSub notification, asking Buildbucket for the build status
[10:30:42.266] Build status: STARTED
[10:33:34.943] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:2:0)
[10:33:34.970] Build status: STARTED
[10:33:34.970] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:5:0) after 7m46s
[10:41:21.030] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:5:0)
[10:41:21.111] Build status: STARTED
[10:41:21.111] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:6:0) after 9m41s
[10:51:02.216] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:6:0)
[10:51:02.242] Build status: STARTED
[10:51:02.242] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:7:0) after 1m15s
[10:52:17.402] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:7:0)
[10:52:17.420] Build status: STARTED
[10:52:17.420] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:8:0) after 5m50s
[10:58:07.435] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:8:0)
[10:58:07.454] Build status: STARTED
[10:58:07.454] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:9:0) after 6m1s
[11:04:08.557] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:9:0)
[11:04:08.578] Build status: STARTED
[11:04:08.578] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:10:0) after 7m28s
[11:11:36.595] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:10:0)
[11:11:36.612] Build status: STARTED
[11:11:36.613] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:11:0) after 7m5s
[11:18:41.668] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:11:0)
[11:18:41.717] Build status: STARTED
[11:18:41.717] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:12:0) after 8m9s
[11:26:50.735] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:12:0)
[11:26:50.759] Build status: STARTED
[11:26:50.759] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:13:0) after 5m48s
[11:32:39.061] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:13:0)
[11:32:39.085] Build status: STARTED
[11:32:39.085] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:14:0) after 2m30s
[11:35:09.221] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:14:0)
[11:35:09.242] Build status: STARTED
[11:35:09.242] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:15:0) after 7m43s
[11:42:52.280] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:15:0)
[11:42:52.298] Build status: STARTED
[11:42:52.298] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:16:0) after 9m56s
[11:52:48.342] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:16:0)
[11:52:48.366] Build status: STARTED
[11:52:48.366] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:17:0) after 4m19s
[11:57:07.509] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:17:0)
[11:57:07.532] Build status: STARTED
[11:57:07.532] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:18:0) after 1m7s
[11:58:14.677] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:18:0)
[11:58:14.732] Build status: STARTED
[11:58:14.732] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:19:0) after 8m0s
[12:06:14.827] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:19:0)
[12:06:14.845] Build status: STARTED
[12:06:14.845] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:20:0) after 9m24s
[12:15:38.860] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:20:0)
[12:15:38.877] Build status: STARTED
[12:15:38.877] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:21:0) after 7m28s
[12:23:07.137] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:21:0)
[12:23:07.173] Build status: STARTED
[12:23:07.173] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:22:0) after 7m39s
[12:30:46.328] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:22:0)
[12:30:46.359] Build status: STARTED
[12:30:46.359] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:23:0) after 9m11s
[12:39:57.381] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:23:0)
[12:39:57.403] Build status: STARTED
[12:39:57.403] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:24:0) after 2m11s
[12:42:08.516] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:24:0)
[12:42:08.566] Build status: STARTED
[12:42:08.566] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:25:0) after 5m49s
[12:47:57.680] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:25:0)
[12:47:57.701] Build status: STARTED
[12:47:57.701] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:26:0) after 9m40s
[12:57:37.744] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:26:0)
[12:57:37.763] Build status: STARTED
[12:57:37.763] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:27:0) after 5m49s
[13:03:26.786] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:27:0)
[13:03:26.804] Build status: STARTED
[13:03:26.804] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:28:0) after 1m12s
[13:04:38.821] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:28:0)
[13:04:38.841] Build status: STARTED
[13:04:38.841] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:29:0) after 2m4s
[13:06:42.906] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:29:0)
[13:06:42.924] Build status: STARTED
[13:06:42.924] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:30:0) after 8m22s
[13:15:05.065] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:30:0)
[13:15:05.096] Build status: STARTED
[13:15:05.096] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:31:0) after 2m7s
[13:17:12.140] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:31:0)
[13:17:12.163] Build status: STARTED
[13:17:12.163] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:32:0) after 1m4s
[13:18:16.184] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:32:0)
[13:18:16.206] Build status: STARTED
[13:18:16.206] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:33:0) after 5m44s
[13:24:00.236] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:33:0)
[13:24:00.259] Build status: STARTED
[13:24:00.259] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:34:0) after 4m44s
[13:28:44.406] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:34:0)
[13:28:44.422] Build status: STARTED
[13:28:44.422] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:35:0) after 7m48s
[13:36:32.729] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:35:0)
[13:36:32.750] Build status: STARTED
[13:36:32.750] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:36:0) after 6m51s
[13:43:23.949] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:36:0)
[13:43:23.966] Build status: STARTED
[13:43:23.966] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:37:0) after 2m11s
[13:45:34.981] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:37:0)
[13:45:35.003] Build status: STARTED
[13:45:35.003] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:38:0) after 7m31s
[13:53:06.018] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:38:0)
[13:53:06.075] Build status: STARTED
[13:53:06.075] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:39:0) after 2m36s
[13:55:42.188] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:39:0)
[13:55:42.209] Build status: STARTED
[13:55:42.209] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8843187611523676256:40:0) after 4m53s
[13:57:13.437] Received PubSub notification, asking Buildbucket for the build status
[13:57:13.507] Build:
{
"id": "8677757549427083921",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "win-official"
},
"number": 188,
"createdBy": "project:chromium-m150",
"createTime": "2026-06-28T10:28:43.318259229Z",
"startTime": "2026-06-28T10:30:42.029049854Z",
"endTime": "2026-06-28T13:57:13.110462988Z",
"updateTime": "2026-06-28T13:57:13.110462988Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c3303c67a5a8b7223ea990353015c29c20791c61",
"ref": "refs/branch-heads/7871"
}
}
}
[13:57:13.507] Invocation finished in 3h28m31.571756943s with status SUCCEEDED