[12:45:14.684] New invocation is queued and will start shortly
[12:45:15.790] Starting the invocation (attempt 1)
[12:45:15.812] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a92298d13c4f49258978feeda8513459a356ab36"
[12:45:15.812] Popped gitiles commit info from properties and tags
[12:45:15.812] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:45:15.812] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:45:15.813] Buildbucket request:
{
"requestId": "8847708869129505696",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8847708869129505696",
"job": "chromium-m148/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9e986358ac3e11de444b2dced1cb5f4b1c72a8f7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@9e986358ac3e11de444b2dced1cb5f4b1c72a8f7",
"title": "9e986358ac3e11de444b2dced1cb5f4b1c72a8f7",
"url": "https://chromium.googlesource.com/chromium/src/+/9e986358ac3e11de444b2dced1cb5f4b1c72a8f7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8420c6e7db97ae496973d81f424b92cbf1d1d0fe"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@8420c6e7db97ae496973d81f424b92cbf1d1d0fe",
"title": "8420c6e7db97ae496973d81f424b92cbf1d1d0fe",
"url": "https://chromium.googlesource.com/chromium/src/+/8420c6e7db97ae496973d81f424b92cbf1d1d0fe"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4007238d803fed7542673c7f6c2430e342b7fbb3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@4007238d803fed7542673c7f6c2430e342b7fbb3",
"title": "4007238d803fed7542673c7f6c2430e342b7fbb3",
"url": "https://chromium.googlesource.com/chromium/src/+/4007238d803fed7542673c7f6c2430e342b7fbb3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "31ae64ca0e0453f4b56e836796b17a4dd20e9165"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@31ae64ca0e0453f4b56e836796b17a4dd20e9165",
"title": "31ae64ca0e0453f4b56e836796b17a4dd20e9165",
"url": "https://chromium.googlesource.com/chromium/src/+/31ae64ca0e0453f4b56e836796b17a4dd20e9165"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d6bd13fb141c2aff62a18035c2771d637cf4dc32"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@d6bd13fb141c2aff62a18035c2771d637cf4dc32",
"title": "d6bd13fb141c2aff62a18035c2771d637cf4dc32",
"url": "https://chromium.googlesource.com/chromium/src/+/d6bd13fb141c2aff62a18035c2771d637cf4dc32"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "866bec430faffee0c4ecfeef64fe39c86d6eefc3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@866bec430faffee0c4ecfeef64fe39c86d6eefc3",
"title": "866bec430faffee0c4ecfeef64fe39c86d6eefc3",
"url": "https://chromium.googlesource.com/chromium/src/+/866bec430faffee0c4ecfeef64fe39c86d6eefc3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "417057007a9b0867aa7daaab02ada3a8f14f38cc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@417057007a9b0867aa7daaab02ada3a8f14f38cc",
"title": "417057007a9b0867aa7daaab02ada3a8f14f38cc",
"url": "https://chromium.googlesource.com/chromium/src/+/417057007a9b0867aa7daaab02ada3a8f14f38cc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4e4257f314653926032a5a7823a07afdb9f10ba6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@4e4257f314653926032a5a7823a07afdb9f10ba6",
"title": "4e4257f314653926032a5a7823a07afdb9f10ba6",
"url": "https://chromium.googlesource.com/chromium/src/+/4e4257f314653926032a5a7823a07afdb9f10ba6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f4720e1b430ec3ed87fe17786ec36a0d04fd4181"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@f4720e1b430ec3ed87fe17786ec36a0d04fd4181",
"title": "f4720e1b430ec3ed87fe17786ec36a0d04fd4181",
"url": "https://chromium.googlesource.com/chromium/src/+/f4720e1b430ec3ed87fe17786ec36a0d04fd4181"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a92298d13c4f49258978feeda8513459a356ab36"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@a92298d13c4f49258978feeda8513459a356ab36",
"title": "a92298d13c4f49258978feeda8513459a356ab36",
"url": "https://chromium.googlesource.com/chromium/src/+/a92298d13c4f49258978feeda8513459a356ab36"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a92298d13c4f49258978feeda8513459a356ab36",
"ref": "refs/branch-heads/7778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8847708869129505696"
},
{
"key": "scheduler_job_id",
"value": "chromium-m148/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:45:16.267] Scheduled build:
{
"id": "8682278807237141601",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win32-official"
},
"number": 179,
"createdBy": "project:chromium-m148",
"createTime": "2026-05-09T12:45:15.867376708Z",
"updateTime": "2026-05-09T12:45:15.867376708Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a92298d13c4f49258978feeda8513459a356ab36",
"ref": "refs/branch-heads/7778"
}
}
}
[12:45:16.267] Task URL: https://cr-buildbucket.appspot.com/build/8682278807237141601
[12:45:16.267] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:2:0) after 7m25s
[12:45:19.321] Received PubSub notification, asking Buildbucket for the build status
[12:45:19.355] Build status: SCHEDULED
[12:45:28.151] Received PubSub notification, asking Buildbucket for the build status
[12:45:28.172] Build status: STARTED
[12:52:41.284] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:2:0)
[12:52:41.306] Build status: STARTED
[12:52:41.306] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:5:0) after 6m31s
[12:59:12.423] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:5:0)
[12:59:12.494] Build status: STARTED
[12:59:12.494] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:6:0) after 9m17s
[13:08:29.721] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:6:0)
[13:08:29.752] Build status: STARTED
[13:08:29.752] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:7:0) after 2m31s
[13:11:00.768] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:7:0)
[13:11:00.787] Build status: STARTED
[13:11:00.787] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:8:0) after 6m45s
[13:17:45.801] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:8:0)
[13:17:45.837] Build status: STARTED
[13:17:45.837] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:9:0) after 9m36s
[13:27:21.841] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:9:0)
[13:27:21.858] Build status: STARTED
[13:27:21.858] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:10:0) after 1m11s
[13:28:32.873] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:10:0)
[13:28:32.925] Build status: STARTED
[13:28:32.925] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:11:0) after 5m2s
[13:33:34.942] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:11:0)
[13:33:34.962] Build status: STARTED
[13:33:34.962] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:12:0) after 4m33s
[13:38:08.075] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:12:0)
[13:38:08.107] Build status: STARTED
[13:38:08.107] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:13:0) after 4m6s
[13:42:14.379] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:13:0)
[13:42:14.400] Build status: STARTED
[13:42:14.400] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:14:0) after 7m53s
[13:50:07.416] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:14:0)
[13:50:07.438] Build status: STARTED
[13:50:07.438] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:15:0) after 4m58s
[13:55:05.636] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:15:0)
[13:55:05.709] Build status: STARTED
[13:55:05.709] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:16:0) after 7m32s
[14:02:37.755] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:16:0)
[14:02:37.778] Build status: STARTED
[14:02:37.778] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:17:0) after 3m45s
[14:06:22.959] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:17:0)
[14:06:22.975] Build status: STARTED
[14:06:22.975] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:18:0) after 6m20s
[14:12:43.073] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:18:0)
[14:12:43.093] Build status: STARTED
[14:12:43.093] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:19:0) after 3m28s
[14:16:11.859] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:19:0)
[14:16:11.881] Build status: STARTED
[14:16:11.881] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:20:0) after 1m43s
[14:17:55.148] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:20:0)
[14:17:55.168] Build status: STARTED
[14:17:55.168] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:21:0) after 5m26s
[14:23:21.703] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:21:0)
[14:23:21.737] Build status: STARTED
[14:23:21.738] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:22:0) after 1m27s
[14:24:48.829] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:22:0)
[14:24:48.847] Build status: STARTED
[14:24:48.847] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:23:0) after 1m18s
[14:26:06.938] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:23:0)
[14:26:07.023] Build status: STARTED
[14:26:07.023] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:24:0) after 7m57s
[14:34:04.041] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:24:0)
[14:34:04.061] Build status: STARTED
[14:34:04.061] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:25:0) after 6m28s
[14:40:32.068] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:25:0)
[14:40:32.089] Build status: STARTED
[14:40:32.090] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:26:0) after 8m6s
[14:48:38.130] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:26:0)
[14:48:38.149] Build status: STARTED
[14:48:38.149] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:27:0) after 5m40s
[14:54:18.162] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:27:0)
[14:54:18.182] Build status: STARTED
[14:54:18.183] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:28:0) after 6m40s
[15:00:58.244] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:28:0)
[15:00:58.320] Build status: STARTED
[15:00:58.320] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:29:0) after 6m54s
[15:07:52.339] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:29:0)
[15:07:52.378] Build status: STARTED
[15:07:52.378] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:30:0) after 3m48s
[15:11:40.394] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:30:0)
[15:11:40.443] Build status: STARTED
[15:11:40.443] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:31:0) after 5m46s
[15:17:26.509] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:31:0)
[15:17:26.528] Build status: STARTED
[15:17:26.528] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:32:0) after 5m54s
[15:23:21.063] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:32:0)
[15:23:21.084] Build status: STARTED
[15:23:21.084] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:33:0) after 9m42s
[15:33:03.106] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:33:0)
[15:33:03.136] Build status: STARTED
[15:33:03.136] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:34:0) after 9m10s
[15:42:13.438] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:34:0)
[15:42:13.468] Build status: STARTED
[15:42:13.468] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:35:0) after 2m3s
[15:44:16.904] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:35:0)
[15:44:16.961] Build status: STARTED
[15:44:16.961] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:36:0) after 2m39s
[15:46:56.107] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:36:0)
[15:46:56.129] Build status: STARTED
[15:46:56.129] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:37:0) after 1m2s
[15:47:58.145] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:37:0)
[15:47:58.166] Build status: STARTED
[15:47:58.166] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:38:0) after 4m38s
[15:52:36.154] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:38:0)
[15:52:36.183] Build status: STARTED
[15:52:36.183] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:39:0) after 4m26s
[15:57:02.258] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:39:0)
[15:57:02.296] Build status: STARTED
[15:57:02.296] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:40:0) after 8m9s
[16:05:11.413] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:40:0)
[16:05:11.498] Build status: STARTED
[16:05:11.498] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:41:0) after 9m31s
[16:14:42.682] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:41:0)
[16:14:42.737] Build status: STARTED
[16:14:42.737] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:42:0) after 5m18s
[16:20:00.752] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:42:0)
[16:20:00.773] Build status: STARTED
[16:20:00.773] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:43:0) after 2m1s
[16:22:01.920] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:43:0)
[16:22:01.939] Build status: STARTED
[16:22:01.939] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:44:0) after 9m51s
[16:31:52.995] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:44:0)
[16:31:53.020] Build status: STARTED
[16:31:53.020] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:45:0) after 7m33s
[16:39:26.949] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:45:0)
[16:39:26.972] Build status: STARTED
[16:39:26.972] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:46:0) after 5m24s
[16:44:50.990] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:46:0)
[16:44:51.012] Build status: STARTED
[16:44:51.012] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:47:0) after 6m44s
[16:51:35.096] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:47:0)
[16:51:35.115] Build status: STARTED
[16:51:35.115] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:48:0) after 5m17s
[16:56:52.246] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:48:0)
[16:56:52.269] Build status: STARTED
[16:56:52.269] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:49:0) after 9m33s
[17:06:25.285] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:49:0)
[17:06:25.375] Build status: STARTED
[17:06:25.375] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:50:0) after 4m17s
[17:10:42.660] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:50:0)
[17:10:42.677] Build status: STARTED
[17:10:42.678] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8847708869129505696:51:0) after 5m21s
[17:12:28.629] Received PubSub notification, asking Buildbucket for the build status
[17:12:28.652] Build:
{
"id": "8682278807237141601",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win32-official"
},
"number": 179,
"createdBy": "project:chromium-m148",
"createTime": "2026-05-09T12:45:15.867376708Z",
"startTime": "2026-05-09T12:45:23.964227426Z",
"endTime": "2026-05-09T17:12:28.290288489Z",
"updateTime": "2026-05-09T17:12:28.290288489Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a92298d13c4f49258978feeda8513459a356ab36",
"ref": "refs/branch-heads/7778"
}
}
}
[17:12:28.652] Invocation finished in 4h27m13.973820779s with status SUCCEEDED