[04:04:08.516] New invocation is queued and will start shortly
[04:04:11.127] Starting the invocation (attempt 1)
[04:04:11.240] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/51ebaa890edb73d7a444c4b1e07f85992afeea23"
[04:04:11.240] Popped gitiles commit info from properties and tags
[04:04:11.240] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:04:11.240] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:04:11.241] Buildbucket request:
{
"requestId": "8849734787352046704",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8849734787352046704",
"job": "chromium-m147/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4c58ca2cb3820488214fae87c8d4f31a3d194496"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@4c58ca2cb3820488214fae87c8d4f31a3d194496",
"title": "4c58ca2cb3820488214fae87c8d4f31a3d194496",
"url": "https://chromium.googlesource.com/chromium/src/+/4c58ca2cb3820488214fae87c8d4f31a3d194496"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4ede348d98f80b2fe84666629ea18942f64cd14c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@4ede348d98f80b2fe84666629ea18942f64cd14c",
"title": "4ede348d98f80b2fe84666629ea18942f64cd14c",
"url": "https://chromium.googlesource.com/chromium/src/+/4ede348d98f80b2fe84666629ea18942f64cd14c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7051535f994b42ad39d6ba5106379f3e4139607d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@7051535f994b42ad39d6ba5106379f3e4139607d",
"title": "7051535f994b42ad39d6ba5106379f3e4139607d",
"url": "https://chromium.googlesource.com/chromium/src/+/7051535f994b42ad39d6ba5106379f3e4139607d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f75fbb033e4bfc03d8482776e054d4084e262618"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@f75fbb033e4bfc03d8482776e054d4084e262618",
"title": "f75fbb033e4bfc03d8482776e054d4084e262618",
"url": "https://chromium.googlesource.com/chromium/src/+/f75fbb033e4bfc03d8482776e054d4084e262618"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "928432c817eaab29992123659c39140a41ec4518"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@928432c817eaab29992123659c39140a41ec4518",
"title": "928432c817eaab29992123659c39140a41ec4518",
"url": "https://chromium.googlesource.com/chromium/src/+/928432c817eaab29992123659c39140a41ec4518"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5f11fd4216106cca6ac38b9b67e4ddcbbb381971"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@5f11fd4216106cca6ac38b9b67e4ddcbbb381971",
"title": "5f11fd4216106cca6ac38b9b67e4ddcbbb381971",
"url": "https://chromium.googlesource.com/chromium/src/+/5f11fd4216106cca6ac38b9b67e4ddcbbb381971"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "eb9d011d22b397cad2221d8f96ee10bef24229e1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@eb9d011d22b397cad2221d8f96ee10bef24229e1",
"title": "eb9d011d22b397cad2221d8f96ee10bef24229e1",
"url": "https://chromium.googlesource.com/chromium/src/+/eb9d011d22b397cad2221d8f96ee10bef24229e1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "570ecfb2b78d2a6603bee3f15120471fb6e0e349"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@570ecfb2b78d2a6603bee3f15120471fb6e0e349",
"title": "570ecfb2b78d2a6603bee3f15120471fb6e0e349",
"url": "https://chromium.googlesource.com/chromium/src/+/570ecfb2b78d2a6603bee3f15120471fb6e0e349"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cac0cef5525d8447099b0b0d462445a25fe454de"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@cac0cef5525d8447099b0b0d462445a25fe454de",
"title": "cac0cef5525d8447099b0b0d462445a25fe454de",
"url": "https://chromium.googlesource.com/chromium/src/+/cac0cef5525d8447099b0b0d462445a25fe454de"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e2668d54f6b321ecfb54a6fcd0f7caedaea09439"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@e2668d54f6b321ecfb54a6fcd0f7caedaea09439",
"title": "e2668d54f6b321ecfb54a6fcd0f7caedaea09439",
"url": "https://chromium.googlesource.com/chromium/src/+/e2668d54f6b321ecfb54a6fcd0f7caedaea09439"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1794fec253e3c5cdd668d9f1a99fdf4d78774638"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@1794fec253e3c5cdd668d9f1a99fdf4d78774638",
"title": "1794fec253e3c5cdd668d9f1a99fdf4d78774638",
"url": "https://chromium.googlesource.com/chromium/src/+/1794fec253e3c5cdd668d9f1a99fdf4d78774638"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c0a17d6c927f92dde723da4a8c69b732c97d3bca"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@c0a17d6c927f92dde723da4a8c69b732c97d3bca",
"title": "c0a17d6c927f92dde723da4a8c69b732c97d3bca",
"url": "https://chromium.googlesource.com/chromium/src/+/c0a17d6c927f92dde723da4a8c69b732c97d3bca"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f1d581579c44e879c23a31733543fe34540aec87"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@f1d581579c44e879c23a31733543fe34540aec87",
"title": "f1d581579c44e879c23a31733543fe34540aec87",
"url": "https://chromium.googlesource.com/chromium/src/+/f1d581579c44e879c23a31733543fe34540aec87"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "20f6d5711f8b3b8ef6d88a8b3e38603b790ba34b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@20f6d5711f8b3b8ef6d88a8b3e38603b790ba34b",
"title": "20f6d5711f8b3b8ef6d88a8b3e38603b790ba34b",
"url": "https://chromium.googlesource.com/chromium/src/+/20f6d5711f8b3b8ef6d88a8b3e38603b790ba34b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b83c3691aadb423293151803f095890b1eaf685d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@b83c3691aadb423293151803f095890b1eaf685d",
"title": "b83c3691aadb423293151803f095890b1eaf685d",
"url": "https://chromium.googlesource.com/chromium/src/+/b83c3691aadb423293151803f095890b1eaf685d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7727",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "51ebaa890edb73d7a444c4b1e07f85992afeea23"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7727@51ebaa890edb73d7a444c4b1e07f85992afeea23",
"title": "51ebaa890edb73d7a444c4b1e07f85992afeea23",
"url": "https://chromium.googlesource.com/chromium/src/+/51ebaa890edb73d7a444c4b1e07f85992afeea23"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "51ebaa890edb73d7a444c4b1e07f85992afeea23",
"ref": "refs/branch-heads/7727"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8849734787352046704"
},
{
"key": "scheduler_job_id",
"value": "chromium-m147/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:04:11.813] Scheduled build:
{
"id": "8684304723718679697",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "win-official"
},
"number": 277,
"createdBy": "project:chromium-m147",
"createTime": "2026-04-17T04:04:11.352373875Z",
"updateTime": "2026-04-17T04:04:11.352373875Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "51ebaa890edb73d7a444c4b1e07f85992afeea23",
"ref": "refs/branch-heads/7727"
}
}
}
[04:04:11.813] Task URL: https://cr-buildbucket.appspot.com/build/8684304723718679697
[04:04:11.813] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:2:0) after 6m1s
[04:04:12.787] Received PubSub notification, asking Buildbucket for the build status
[04:04:12.805] Build status: SCHEDULED
[04:04:19.332] Received PubSub notification, asking Buildbucket for the build status
[04:04:19.376] Build status: STARTED
[04:10:12.864] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:2:0)
[04:10:12.888] Build status: STARTED
[04:10:12.888] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:5:0) after 9m54s
[04:20:06.999] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:5:0)
[04:20:07.086] Build status: STARTED
[04:20:07.087] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:6:0) after 1m30s
[04:21:37.114] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:6:0)
[04:21:37.132] Build status: STARTED
[04:21:37.132] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:7:0) after 5m3s
[04:26:40.148] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:7:0)
[04:26:40.207] Build status: STARTED
[04:26:40.207] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:8:0) after 3m6s
[04:29:46.274] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:8:0)
[04:29:46.347] Build status: STARTED
[04:29:46.347] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:9:0) after 5m40s
[04:35:26.380] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:9:0)
[04:35:26.398] Build status: STARTED
[04:35:26.398] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:10:0) after 2m41s
[04:38:07.464] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:10:0)
[04:38:07.695] Build status: STARTED
[04:38:07.696] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:11:0) after 7m5s
[04:45:12.709] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:11:0)
[04:45:12.731] Build status: STARTED
[04:45:12.731] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:12:0) after 9m22s
[04:54:34.810] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:12:0)
[04:54:34.828] Build status: STARTED
[04:54:34.828] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:13:0) after 6m2s
[05:00:36.865] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:13:0)
[05:00:36.886] Build status: STARTED
[05:00:36.886] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:14:0) after 1m10s
[05:01:47.031] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:14:0)
[05:01:47.052] Build status: STARTED
[05:01:47.052] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:15:0) after 1m53s
[05:03:40.114] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:15:0)
[05:03:40.134] Build status: STARTED
[05:03:40.134] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:16:0) after 9m38s
[05:13:18.231] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:16:0)
[05:13:18.294] Build status: STARTED
[05:13:18.294] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:17:0) after 8m35s
[05:21:53.312] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:17:0)
[05:21:53.332] Build status: STARTED
[05:21:53.332] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:18:0) after 2m51s
[05:24:44.358] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:18:0)
[05:24:44.373] Build status: STARTED
[05:24:44.373] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:19:0) after 5m2s
[05:29:46.436] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:19:0)
[05:29:46.451] Build status: STARTED
[05:29:46.451] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:20:0) after 1m6s
[05:30:52.470] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:20:0)
[05:30:52.500] Build status: STARTED
[05:30:52.500] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:21:0) after 2m33s
[05:33:46.736] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:21:0)
[05:33:46.754] Build status: STARTED
[05:33:46.754] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:22:0) after 5m57s
[05:39:43.767] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:22:0)
[05:39:43.793] Build status: STARTED
[05:39:43.793] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:23:0) after 8m41s
[05:48:24.809] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:23:0)
[05:48:24.830] Build status: STARTED
[05:48:24.830] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:24:0) after 3m6s
[05:51:30.922] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:24:0)
[05:51:30.940] Build status: STARTED
[05:51:30.940] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:25:0) after 9m4s
[06:00:35.022] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:25:0)
[06:00:35.039] Build status: STARTED
[06:00:35.039] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:26:0) after 7m55s
[06:08:30.054] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:26:0)
[06:08:30.069] Build status: STARTED
[06:08:30.069] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:27:0) after 6m30s
[06:15:00.080] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:27:0)
[06:15:00.104] Build status: STARTED
[06:15:00.104] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:28:0) after 1m38s
[06:16:38.169] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:28:0)
[06:16:38.187] Build status: STARTED
[06:16:38.187] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:29:0) after 6m58s
[06:23:36.272] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:29:0)
[06:23:36.390] Build status: STARTED
[06:23:36.390] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:30:0) after 3m25s
[06:27:01.509] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:30:0)
[06:27:01.537] Build status: STARTED
[06:27:01.537] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:31:0) after 9m51s
[06:36:52.602] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:31:0)
[06:36:52.619] Build status: STARTED
[06:36:52.619] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:32:0) after 7m0s
[06:43:52.692] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:32:0)
[06:43:52.709] Build status: STARTED
[06:43:52.710] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:33:0) after 6m59s
[06:50:51.733] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:33:0)
[06:50:51.749] Build status: STARTED
[06:50:51.750] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:34:0) after 4m35s
[06:55:26.733] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:34:0)
[06:55:26.747] Build status: STARTED
[06:55:26.747] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:35:0) after 4m26s
[06:59:52.785] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:35:0)
[06:59:52.819] Build status: STARTED
[06:59:52.819] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:36:0) after 9m11s
[07:09:03.847] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:36:0)
[07:09:03.867] Build status: STARTED
[07:09:03.867] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:37:0) after 7m41s
[07:16:44.863] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:37:0)
[07:16:44.879] Build status: STARTED
[07:16:44.879] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:38:0) after 4m13s
[07:20:57.929] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:38:0)
[07:20:57.956] Build status: STARTED
[07:20:57.956] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:39:0) after 1m40s
[07:22:37.970] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:39:0)
[07:22:37.990] Build status: STARTED
[07:22:37.990] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:40:0) after 1m37s
[07:24:15.007] Handling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:40:0)
[07:24:15.022] Build status: STARTED
[07:24:15.022] Scheduling timer "check-buildbucket-build-status" (chromium-m147/win-official:8849734787352046704:41:0) after 7m20s
[07:27:43.139] Received PubSub notification, asking Buildbucket for the build status
[07:27:43.206] Build:
{
"id": "8684304723718679697",
"builder": {
"project": "chromium-m147",
"bucket": "ci",
"builder": "win-official"
},
"number": 277,
"createdBy": "project:chromium-m147",
"createTime": "2026-04-17T04:04:11.352373875Z",
"startTime": "2026-04-17T04:04:19.178126759Z",
"endTime": "2026-04-17T07:27:42.866437512Z",
"updateTime": "2026-04-17T07:27:42.866437512Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "51ebaa890edb73d7a444c4b1e07f85992afeea23",
"ref": "refs/branch-heads/7727"
}
}
}
[07:27:43.206] Invocation finished in 3h23m34.702917276s with status SUCCEEDED