[04:42:36.813] New invocation is queued and will start shortly
[04:42:37.915] Starting the invocation (attempt 1)
[04:42:37.938] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c2d8abc44bd3ed5ae8778957684a05e3d7e5f3b7"
[04:42:37.938] Popped gitiles commit info from properties and tags
[04:42:37.938] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:42:37.938] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:42:37.938] Buildbucket request:
{
"requestId": "8853809230407506496",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8853809230407506496",
"job": "chromium-m146/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5d2f672e887f4515161acaf086bc63f43c1d87c3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@5d2f672e887f4515161acaf086bc63f43c1d87c3",
"title": "5d2f672e887f4515161acaf086bc63f43c1d87c3",
"url": "https://chromium.googlesource.com/chromium/src/+/5d2f672e887f4515161acaf086bc63f43c1d87c3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "174dd74f347709b530baf63ee2f64ce6a0d2d557"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@174dd74f347709b530baf63ee2f64ce6a0d2d557",
"title": "174dd74f347709b530baf63ee2f64ce6a0d2d557",
"url": "https://chromium.googlesource.com/chromium/src/+/174dd74f347709b530baf63ee2f64ce6a0d2d557"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5cd824c45daf51d59ccab95d1128338d95a39bb7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@5cd824c45daf51d59ccab95d1128338d95a39bb7",
"title": "5cd824c45daf51d59ccab95d1128338d95a39bb7",
"url": "https://chromium.googlesource.com/chromium/src/+/5cd824c45daf51d59ccab95d1128338d95a39bb7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "66322c2e156ecca8647bee3d773340a12813761a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@66322c2e156ecca8647bee3d773340a12813761a",
"title": "66322c2e156ecca8647bee3d773340a12813761a",
"url": "https://chromium.googlesource.com/chromium/src/+/66322c2e156ecca8647bee3d773340a12813761a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a7fdf70ba5bf12e673f9ac6ce0b52b32715d0863"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@a7fdf70ba5bf12e673f9ac6ce0b52b32715d0863",
"title": "a7fdf70ba5bf12e673f9ac6ce0b52b32715d0863",
"url": "https://chromium.googlesource.com/chromium/src/+/a7fdf70ba5bf12e673f9ac6ce0b52b32715d0863"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "21aaf410e9494aa2ec398473fbc23746a9cc94b3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@21aaf410e9494aa2ec398473fbc23746a9cc94b3",
"title": "21aaf410e9494aa2ec398473fbc23746a9cc94b3",
"url": "https://chromium.googlesource.com/chromium/src/+/21aaf410e9494aa2ec398473fbc23746a9cc94b3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5dac4643c0ae6cec349581b87adb168aa7f0a8f1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@5dac4643c0ae6cec349581b87adb168aa7f0a8f1",
"title": "5dac4643c0ae6cec349581b87adb168aa7f0a8f1",
"url": "https://chromium.googlesource.com/chromium/src/+/5dac4643c0ae6cec349581b87adb168aa7f0a8f1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "eb248b0770795cb67bf4894d96fa9e051ecae46d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@eb248b0770795cb67bf4894d96fa9e051ecae46d",
"title": "eb248b0770795cb67bf4894d96fa9e051ecae46d",
"url": "https://chromium.googlesource.com/chromium/src/+/eb248b0770795cb67bf4894d96fa9e051ecae46d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b156040f83f1d920024d9c596a8cdf7f39c87d35"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@b156040f83f1d920024d9c596a8cdf7f39c87d35",
"title": "b156040f83f1d920024d9c596a8cdf7f39c87d35",
"url": "https://chromium.googlesource.com/chromium/src/+/b156040f83f1d920024d9c596a8cdf7f39c87d35"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e0a640c1c44436e156163021af8f69be2d635a14"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@e0a640c1c44436e156163021af8f69be2d635a14",
"title": "e0a640c1c44436e156163021af8f69be2d635a14",
"url": "https://chromium.googlesource.com/chromium/src/+/e0a640c1c44436e156163021af8f69be2d635a14"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "309671cf0a02c8dceb9c1d8a24a19c352ea8538a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@309671cf0a02c8dceb9c1d8a24a19c352ea8538a",
"title": "309671cf0a02c8dceb9c1d8a24a19c352ea8538a",
"url": "https://chromium.googlesource.com/chromium/src/+/309671cf0a02c8dceb9c1d8a24a19c352ea8538a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ecc081bc9e9fe7ff7bc22c6c6ca0cd6753e6bc00"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@ecc081bc9e9fe7ff7bc22c6c6ca0cd6753e6bc00",
"title": "ecc081bc9e9fe7ff7bc22c6c6ca0cd6753e6bc00",
"url": "https://chromium.googlesource.com/chromium/src/+/ecc081bc9e9fe7ff7bc22c6c6ca0cd6753e6bc00"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c2d8abc44bd3ed5ae8778957684a05e3d7e5f3b7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@c2d8abc44bd3ed5ae8778957684a05e3d7e5f3b7",
"title": "c2d8abc44bd3ed5ae8778957684a05e3d7e5f3b7",
"url": "https://chromium.googlesource.com/chromium/src/+/c2d8abc44bd3ed5ae8778957684a05e3d7e5f3b7"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c2d8abc44bd3ed5ae8778957684a05e3d7e5f3b7",
"ref": "refs/branch-heads/7680"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8853809230407506496"
},
{
"key": "scheduler_job_id",
"value": "chromium-m146/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:42:38.514] Scheduled build:
{
"id": "8688379168491476849",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win-official"
},
"number": 158,
"createdBy": "project:chromium-m146",
"createTime": "2026-03-03T04:42:38.018544956Z",
"updateTime": "2026-03-03T04:42:38.018544956Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c2d8abc44bd3ed5ae8778957684a05e3d7e5f3b7",
"ref": "refs/branch-heads/7680"
}
}
}
[04:42:38.514] Task URL: https://cr-buildbucket.appspot.com/build/8688379168491476849
[04:42:38.514] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:2:0) after 8m45s
[04:42:39.480] Received PubSub notification, asking Buildbucket for the build status
[04:42:39.496] Build status: SCHEDULED
[04:42:42.697] Received PubSub notification, asking Buildbucket for the build status
[04:42:42.710] Build status: STARTED
[04:51:23.537] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:2:0)
[04:51:23.599] Build status: STARTED
[04:51:23.599] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:5:0) after 3m29s
[04:54:52.612] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:5:0)
[04:54:52.632] Build status: STARTED
[04:54:52.632] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:6:0) after 8m34s
[05:03:26.603] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:6:0)
[05:03:26.617] Build status: STARTED
[05:03:26.617] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:7:0) after 6m15s
[05:09:41.630] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:7:0)
[05:09:41.646] Build status: STARTED
[05:09:41.646] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:8:0) after 4m36s
[05:14:17.805] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:8:0)
[05:14:17.825] Build status: STARTED
[05:14:17.826] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:9:0) after 6m49s
[05:21:06.876] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:9:0)
[05:21:06.952] Build status: STARTED
[05:21:06.952] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:10:0) after 1m3s
[05:22:09.967] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:10:0)
[05:22:09.998] Build status: STARTED
[05:22:09.998] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:11:0) after 8m27s
[05:30:37.013] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:11:0)
[05:30:37.123] Build status: STARTED
[05:30:37.123] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:12:0) after 5m39s
[05:36:16.141] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:12:0)
[05:36:16.166] Build status: STARTED
[05:36:16.166] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:13:0) after 7m55s
[05:44:11.153] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:13:0)
[05:44:11.171] Build status: STARTED
[05:44:11.171] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:14:0) after 3m54s
[05:48:05.286] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:14:0)
[05:48:05.313] Build status: STARTED
[05:48:05.313] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:15:0) after 8m35s
[05:56:40.316] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:15:0)
[05:56:40.384] Build status: STARTED
[05:56:40.384] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:16:0) after 3m28s
[06:00:08.399] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:16:0)
[06:00:08.455] Build status: STARTED
[06:00:08.455] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:17:0) after 2m53s
[06:03:01.472] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:17:0)
[06:03:01.499] Build status: STARTED
[06:03:01.499] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:18:0) after 9m45s
[06:12:46.516] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:18:0)
[06:12:46.533] Build status: STARTED
[06:12:46.534] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:19:0) after 7m22s
[06:20:08.552] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:19:0)
[06:20:08.580] Build status: STARTED
[06:20:08.580] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:20:0) after 7m15s
[06:27:23.599] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:20:0)
[06:27:23.620] Build status: STARTED
[06:27:23.620] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:21:0) after 3m57s
[06:31:20.636] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:21:0)
[06:31:20.666] Build status: STARTED
[06:31:20.666] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:22:0) after 9m15s
[06:40:35.731] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:22:0)
[06:40:35.751] Build status: STARTED
[06:40:35.751] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:23:0) after 4m43s
[06:45:18.859] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:23:0)
[06:45:18.884] Build status: STARTED
[06:45:18.884] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:24:0) after 8m36s
[06:53:54.901] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:24:0)
[06:53:54.922] Build status: STARTED
[06:53:54.922] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:25:0) after 2m1s
[06:55:55.950] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:25:0)
[06:55:55.976] Build status: STARTED
[06:55:55.976] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:26:0) after 4m54s
[07:00:50.064] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:26:0)
[07:00:50.092] Build status: STARTED
[07:00:50.092] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:27:0) after 1m39s
[07:02:29.110] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:27:0)
[07:02:29.125] Build status: STARTED
[07:02:29.125] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:28:0) after 5m55s
[07:08:24.253] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:28:0)
[07:08:24.312] Build status: STARTED
[07:08:24.312] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:29:0) after 6m15s
[07:14:39.446] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:29:0)
[07:14:39.469] Build status: STARTED
[07:14:39.469] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:30:0) after 9m34s
[07:24:13.484] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:30:0)
[07:24:13.502] Build status: STARTED
[07:24:13.502] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:31:0) after 5m22s
[07:29:35.586] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:31:0)
[07:29:35.713] Build status: STARTED
[07:29:35.713] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:32:0) after 4m40s
[07:34:15.717] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:32:0)
[07:34:15.744] Build status: STARTED
[07:34:15.744] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8853809230407506496:33:0) after 8m33s
[07:38:09.757] Received PubSub notification, asking Buildbucket for the build status
[07:38:09.777] Build:
{
"id": "8688379168491476849",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win-official"
},
"number": 158,
"createdBy": "project:chromium-m146",
"createTime": "2026-03-03T04:42:38.018544956Z",
"startTime": "2026-03-03T04:42:42.543548396Z",
"endTime": "2026-03-03T07:38:09.530329284Z",
"updateTime": "2026-03-03T07:38:09.530329284Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c2d8abc44bd3ed5ae8778957684a05e3d7e5f3b7",
"ref": "refs/branch-heads/7680"
}
}
}
[07:38:09.777] Invocation finished in 2h55m32.969987751s with status SUCCEEDED