[19:46:52.604] New invocation is queued and will start shortly
[19:46:54.560] Starting the invocation (attempt 1)
[19:46:54.594] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bdd6c17e6a990de43f73deb7c7c2486dd4ba2838"
[19:46:54.594] Popped gitiles commit info from properties and tags
[19:46:54.594] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:46:54.595] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:46:54.596] Buildbucket request:
{
"requestId": "8845236224243796160",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8845236224243796160",
"job": "chromium-m150/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "28e15e86d74381142d9417794513d0cf68c67a7b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@28e15e86d74381142d9417794513d0cf68c67a7b",
"title": "28e15e86d74381142d9417794513d0cf68c67a7b",
"url": "https://chromium.googlesource.com/chromium/src/+/28e15e86d74381142d9417794513d0cf68c67a7b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b2d20cecea4530de928b16795167b3459e6838ca"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@b2d20cecea4530de928b16795167b3459e6838ca",
"title": "b2d20cecea4530de928b16795167b3459e6838ca",
"url": "https://chromium.googlesource.com/chromium/src/+/b2d20cecea4530de928b16795167b3459e6838ca"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "182315364f74cc5a5f946dcecef2a325739c6da0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@182315364f74cc5a5f946dcecef2a325739c6da0",
"title": "182315364f74cc5a5f946dcecef2a325739c6da0",
"url": "https://chromium.googlesource.com/chromium/src/+/182315364f74cc5a5f946dcecef2a325739c6da0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d82543cb115ce178aa23531c6efd4b9027cf1943"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@d82543cb115ce178aa23531c6efd4b9027cf1943",
"title": "d82543cb115ce178aa23531c6efd4b9027cf1943",
"url": "https://chromium.googlesource.com/chromium/src/+/d82543cb115ce178aa23531c6efd4b9027cf1943"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "aff74d71761d5be0ae21c65d40f18804b630a9b5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@aff74d71761d5be0ae21c65d40f18804b630a9b5",
"title": "aff74d71761d5be0ae21c65d40f18804b630a9b5",
"url": "https://chromium.googlesource.com/chromium/src/+/aff74d71761d5be0ae21c65d40f18804b630a9b5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "239623b7670fb5d9682a145ec103b2bdd57e06e9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@239623b7670fb5d9682a145ec103b2bdd57e06e9",
"title": "239623b7670fb5d9682a145ec103b2bdd57e06e9",
"url": "https://chromium.googlesource.com/chromium/src/+/239623b7670fb5d9682a145ec103b2bdd57e06e9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f9da738125f17ac3030ceec0cbebca8809212861"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@f9da738125f17ac3030ceec0cbebca8809212861",
"title": "f9da738125f17ac3030ceec0cbebca8809212861",
"url": "https://chromium.googlesource.com/chromium/src/+/f9da738125f17ac3030ceec0cbebca8809212861"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "de9c56d01bc8d8db2b967e9bc1e753cb055b9b76"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@de9c56d01bc8d8db2b967e9bc1e753cb055b9b76",
"title": "de9c56d01bc8d8db2b967e9bc1e753cb055b9b76",
"url": "https://chromium.googlesource.com/chromium/src/+/de9c56d01bc8d8db2b967e9bc1e753cb055b9b76"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "54701638aed7de6162261b5b813262a259645e22"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@54701638aed7de6162261b5b813262a259645e22",
"title": "54701638aed7de6162261b5b813262a259645e22",
"url": "https://chromium.googlesource.com/chromium/src/+/54701638aed7de6162261b5b813262a259645e22"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f5d337aeda0887e4a81dc16358803268aaa022ed"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@f5d337aeda0887e4a81dc16358803268aaa022ed",
"title": "f5d337aeda0887e4a81dc16358803268aaa022ed",
"url": "https://chromium.googlesource.com/chromium/src/+/f5d337aeda0887e4a81dc16358803268aaa022ed"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "79fedca1a43166bd6c0eaf63325cbd52f5a32feb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@79fedca1a43166bd6c0eaf63325cbd52f5a32feb",
"title": "79fedca1a43166bd6c0eaf63325cbd52f5a32feb",
"url": "https://chromium.googlesource.com/chromium/src/+/79fedca1a43166bd6c0eaf63325cbd52f5a32feb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3795bbe737c83fada10817a4de2f451506abc2c3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@3795bbe737c83fada10817a4de2f451506abc2c3",
"title": "3795bbe737c83fada10817a4de2f451506abc2c3",
"url": "https://chromium.googlesource.com/chromium/src/+/3795bbe737c83fada10817a4de2f451506abc2c3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "219caa5ea46fda5e64c70d0ac1d249763afb7a9c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@219caa5ea46fda5e64c70d0ac1d249763afb7a9c",
"title": "219caa5ea46fda5e64c70d0ac1d249763afb7a9c",
"url": "https://chromium.googlesource.com/chromium/src/+/219caa5ea46fda5e64c70d0ac1d249763afb7a9c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3f0bcaa39c00dd840874ac917c235f81383524c8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@3f0bcaa39c00dd840874ac917c235f81383524c8",
"title": "3f0bcaa39c00dd840874ac917c235f81383524c8",
"url": "https://chromium.googlesource.com/chromium/src/+/3f0bcaa39c00dd840874ac917c235f81383524c8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c4c13c41054bb72b871812f4a438e9c19253a8a0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@c4c13c41054bb72b871812f4a438e9c19253a8a0",
"title": "c4c13c41054bb72b871812f4a438e9c19253a8a0",
"url": "https://chromium.googlesource.com/chromium/src/+/c4c13c41054bb72b871812f4a438e9c19253a8a0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "849b6f62d222928c4b503c9f192b3611303dd445"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@849b6f62d222928c4b503c9f192b3611303dd445",
"title": "849b6f62d222928c4b503c9f192b3611303dd445",
"url": "https://chromium.googlesource.com/chromium/src/+/849b6f62d222928c4b503c9f192b3611303dd445"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "26ec1236bf73ed0d08f0e24629c321e9f6e3da09"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@26ec1236bf73ed0d08f0e24629c321e9f6e3da09",
"title": "26ec1236bf73ed0d08f0e24629c321e9f6e3da09",
"url": "https://chromium.googlesource.com/chromium/src/+/26ec1236bf73ed0d08f0e24629c321e9f6e3da09"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bdd6c17e6a990de43f73deb7c7c2486dd4ba2838"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@bdd6c17e6a990de43f73deb7c7c2486dd4ba2838",
"title": "bdd6c17e6a990de43f73deb7c7c2486dd4ba2838",
"url": "https://chromium.googlesource.com/chromium/src/+/bdd6c17e6a990de43f73deb7c7c2486dd4ba2838"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bdd6c17e6a990de43f73deb7c7c2486dd4ba2838",
"ref": "refs/branch-heads/7871"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8845236224243796160"
},
{
"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"
}
}
[19:46:55.216] Scheduled build:
{
"id": "8679806161422584433",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "win-official"
},
"number": 25,
"createdBy": "project:chromium-m150",
"createTime": "2026-06-05T19:46:54.674704021Z",
"updateTime": "2026-06-05T19:46:54.674704021Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bdd6c17e6a990de43f73deb7c7c2486dd4ba2838",
"ref": "refs/branch-heads/7871"
}
}
}
[19:46:55.216] Task URL: https://cr-buildbucket.appspot.com/build/8679806161422584433
[19:46:55.216] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:2:0) after 2m18s
[19:47:03.653] Received PubSub notification, asking Buildbucket for the build status
[19:47:03.674] Build status: SCHEDULED
[19:47:16.769] Received PubSub notification, asking Buildbucket for the build status
[19:47:16.797] Build status: STARTED
[19:49:13.237] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:2:0)
[19:49:13.254] Build status: STARTED
[19:49:13.254] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:5:0) after 4m59s
[19:54:12.334] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:5:0)
[19:54:12.373] Build status: STARTED
[19:54:12.373] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:6:0) after 3m15s
[19:57:27.424] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:6:0)
[19:57:27.446] Build status: STARTED
[19:57:27.446] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:7:0) after 9m24s
[20:06:51.512] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:7:0)
[20:06:51.536] Build status: STARTED
[20:06:51.536] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:8:0) after 1m7s
[20:07:58.607] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:8:0)
[20:07:58.630] Build status: STARTED
[20:07:58.630] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:9:0) after 9m56s
[20:17:54.647] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:9:0)
[20:17:54.736] Build status: STARTED
[20:17:54.736] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:10:0) after 7m57s
[20:25:51.752] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:10:0)
[20:25:51.845] Build status: STARTED
[20:25:51.845] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:11:0) after 7m38s
[20:33:29.991] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:11:0)
[20:33:30.012] Build status: STARTED
[20:33:30.012] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:12:0) after 3m43s
[20:37:13.091] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:12:0)
[20:37:13.110] Build status: STARTED
[20:37:13.110] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:13:0) after 8m18s
[20:45:31.231] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:13:0)
[20:45:31.260] Build status: STARTED
[20:45:31.261] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:14:0) after 2m13s
[20:47:44.328] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:14:0)
[20:47:44.459] Build status: STARTED
[20:47:44.459] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:15:0) after 7m29s
[20:55:13.616] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:15:0)
[20:55:13.637] Build status: STARTED
[20:55:13.637] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:16:0) after 4m53s
[21:00:06.756] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:16:0)
[21:00:06.776] Build status: STARTED
[21:00:06.776] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:17:0) after 3m16s
[21:03:22.796] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:17:0)
[21:03:22.813] Build status: STARTED
[21:03:22.813] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:18:0) after 4m25s
[21:07:47.780] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:18:0)
[21:07:47.796] Build status: STARTED
[21:07:47.796] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:19:0) after 4m1s
[21:11:48.881] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:19:0)
[21:11:48.899] Build status: STARTED
[21:11:48.900] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:20:0) after 8m10s
[21:19:58.914] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:20:0)
[21:19:58.933] Build status: STARTED
[21:19:58.933] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:21:0) after 3m8s
[21:23:06.919] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:21:0)
[21:23:06.945] Build status: STARTED
[21:23:06.945] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:22:0) after 9m26s
[21:32:32.926] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:22:0)
[21:32:33.011] Build status: STARTED
[21:32:33.011] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:23:0) after 2m6s
[21:34:39.025] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:23:0)
[21:34:39.050] Build status: STARTED
[21:34:39.050] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:24:0) after 1m54s
[21:36:33.108] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:24:0)
[21:36:33.126] Build status: STARTED
[21:36:33.126] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:25:0) after 6m19s
[21:42:52.218] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:25:0)
[21:42:52.234] Build status: STARTED
[21:42:52.234] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:26:0) after 4m29s
[21:47:21.248] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:26:0)
[21:47:21.267] Build status: STARTED
[21:47:21.267] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:27:0) after 5m38s
[21:52:59.284] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:27:0)
[21:52:59.333] Build status: STARTED
[21:52:59.333] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:28:0) after 6m3s
[21:59:02.352] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:28:0)
[21:59:02.374] Build status: STARTED
[21:59:02.374] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:29:0) after 4m59s
[22:04:01.485] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:29:0)
[22:04:01.504] Build status: STARTED
[22:04:01.504] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:30:0) after 3m6s
[22:07:07.523] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:30:0)
[22:07:07.542] Build status: STARTED
[22:07:07.542] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:31:0) after 5m56s
[22:13:03.993] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:31:0)
[22:13:04.022] Build status: STARTED
[22:13:04.022] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:32:0) after 8m13s
[22:21:17.045] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:32:0)
[22:21:17.088] Build status: STARTED
[22:21:17.088] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:33:0) after 6m59s
[22:28:16.104] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:33:0)
[22:28:16.141] Build status: STARTED
[22:28:16.141] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:34:0) after 7m6s
[22:35:22.249] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:34:0)
[22:35:22.274] Build status: STARTED
[22:35:22.274] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:35:0) after 8m34s
[22:43:56.424] Handling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:35:0)
[22:43:56.445] Build status: STARTED
[22:43:56.445] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-official:8845236224243796160:36:0) after 6m16s
[22:49:45.274] Received PubSub notification, asking Buildbucket for the build status
[22:49:45.291] Build:
{
"id": "8679806161422584433",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "win-official"
},
"number": 25,
"createdBy": "project:chromium-m150",
"createTime": "2026-06-05T19:46:54.674704021Z",
"startTime": "2026-06-05T19:47:08.156579825Z",
"endTime": "2026-06-05T22:49:45.009679707Z",
"updateTime": "2026-06-05T22:49:45.009679707Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bdd6c17e6a990de43f73deb7c7c2486dd4ba2838",
"ref": "refs/branch-heads/7871"
}
}
}
[22:49:45.291] Invocation finished in 3h2m52.690541941s with status SUCCEEDED