[05:41:49.124] New invocation is queued and will start shortly
[05:41:50.241] Starting the invocation (attempt 1)
[05:41:50.266] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fc8b27780820967eed61ec4db0e010f41e9b9bbc"
[05:41:50.266] Popped gitiles commit info from properties and tags
[05:41:50.266] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:41:50.267] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:41:50.267] Buildbucket request:
{
"requestId": "8857972966003454320",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8857972966003454320",
"job": "chromium-m144/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "34fdf9698b4e32790601ee04a0ca0cbe2ce5f493"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@34fdf9698b4e32790601ee04a0ca0cbe2ce5f493",
"title": "34fdf9698b4e32790601ee04a0ca0cbe2ce5f493",
"url": "https://chromium.googlesource.com/chromium/src/+/34fdf9698b4e32790601ee04a0ca0cbe2ce5f493"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7492a5fbf877f1f566efb77238c1ae62c0be0817"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@7492a5fbf877f1f566efb77238c1ae62c0be0817",
"title": "7492a5fbf877f1f566efb77238c1ae62c0be0817",
"url": "https://chromium.googlesource.com/chromium/src/+/7492a5fbf877f1f566efb77238c1ae62c0be0817"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5b14f1db3a6be8646380d93d88567d1a2482c6d1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@5b14f1db3a6be8646380d93d88567d1a2482c6d1",
"title": "5b14f1db3a6be8646380d93d88567d1a2482c6d1",
"url": "https://chromium.googlesource.com/chromium/src/+/5b14f1db3a6be8646380d93d88567d1a2482c6d1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "08831a9454546cd4b293542ecbcf2927aaec8451"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@08831a9454546cd4b293542ecbcf2927aaec8451",
"title": "08831a9454546cd4b293542ecbcf2927aaec8451",
"url": "https://chromium.googlesource.com/chromium/src/+/08831a9454546cd4b293542ecbcf2927aaec8451"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ce848e0a74907c63d0122051e2982a2cc390684b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@ce848e0a74907c63d0122051e2982a2cc390684b",
"title": "ce848e0a74907c63d0122051e2982a2cc390684b",
"url": "https://chromium.googlesource.com/chromium/src/+/ce848e0a74907c63d0122051e2982a2cc390684b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "abb8265b0b584f60f72155396a7e7cd3ac1e4021"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@abb8265b0b584f60f72155396a7e7cd3ac1e4021",
"title": "abb8265b0b584f60f72155396a7e7cd3ac1e4021",
"url": "https://chromium.googlesource.com/chromium/src/+/abb8265b0b584f60f72155396a7e7cd3ac1e4021"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "57d26f08533a485b51d2afb250b9237933054e58"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@57d26f08533a485b51d2afb250b9237933054e58",
"title": "57d26f08533a485b51d2afb250b9237933054e58",
"url": "https://chromium.googlesource.com/chromium/src/+/57d26f08533a485b51d2afb250b9237933054e58"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "34a4e24467f30756b5d14d4f6072e75e4057276b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@34a4e24467f30756b5d14d4f6072e75e4057276b",
"title": "34a4e24467f30756b5d14d4f6072e75e4057276b",
"url": "https://chromium.googlesource.com/chromium/src/+/34a4e24467f30756b5d14d4f6072e75e4057276b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "de8cbea0ba39a65bdaa02f1e7e4766d78c5726db"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@de8cbea0ba39a65bdaa02f1e7e4766d78c5726db",
"title": "de8cbea0ba39a65bdaa02f1e7e4766d78c5726db",
"url": "https://chromium.googlesource.com/chromium/src/+/de8cbea0ba39a65bdaa02f1e7e4766d78c5726db"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "27ef818d463a357aab2babccb506d212d2bb9bbb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@27ef818d463a357aab2babccb506d212d2bb9bbb",
"title": "27ef818d463a357aab2babccb506d212d2bb9bbb",
"url": "https://chromium.googlesource.com/chromium/src/+/27ef818d463a357aab2babccb506d212d2bb9bbb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "195223e64f7a4e5b05dc99edb175bed1df61e512"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@195223e64f7a4e5b05dc99edb175bed1df61e512",
"title": "195223e64f7a4e5b05dc99edb175bed1df61e512",
"url": "https://chromium.googlesource.com/chromium/src/+/195223e64f7a4e5b05dc99edb175bed1df61e512"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fc8b27780820967eed61ec4db0e010f41e9b9bbc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@fc8b27780820967eed61ec4db0e010f41e9b9bbc",
"title": "fc8b27780820967eed61ec4db0e010f41e9b9bbc",
"url": "https://chromium.googlesource.com/chromium/src/+/fc8b27780820967eed61ec4db0e010f41e9b9bbc"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fc8b27780820967eed61ec4db0e010f41e9b9bbc",
"ref": "refs/branch-heads/7559"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8857972966003454320"
},
{
"key": "scheduler_job_id",
"value": "chromium-m144/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:41:50.902] Scheduled build:
{
"id": "8692542904037049809",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win-official"
},
"number": 358,
"createdBy": "project:chromium-m144",
"createTime": "2026-01-16T05:41:50.368430494Z",
"updateTime": "2026-01-16T05:41:50.368430494Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fc8b27780820967eed61ec4db0e010f41e9b9bbc",
"ref": "refs/branch-heads/7559"
}
},
"canary": true
}
[05:41:50.902] Task URL: https://cr-buildbucket.appspot.com/build/8692542904037049809
[05:41:50.902] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:2:0) after 6m18s
[05:41:51.873] Received PubSub notification, asking Buildbucket for the build status
[05:41:51.896] Build status: SCHEDULED
[05:41:55.110] Received PubSub notification, asking Buildbucket for the build status
[05:41:55.138] Build status: STARTED
[05:48:08.923] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:2:0)
[05:48:08.946] Build status: STARTED
[05:48:08.946] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:5:0) after 6m7s
[05:54:15.947] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:5:0)
[05:54:15.964] Build status: STARTED
[05:54:15.964] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:6:0) after 1m27s
[05:55:42.979] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:6:0)
[05:55:42.999] Build status: STARTED
[05:55:42.999] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:7:0) after 4m19s
[06:00:02.017] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:7:0)
[06:00:02.037] Build status: STARTED
[06:00:02.037] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:8:0) after 5m28s
[06:05:30.063] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:8:0)
[06:05:30.077] Build status: STARTED
[06:05:30.077] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:9:0) after 9m28s
[06:14:58.066] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:9:0)
[06:14:58.083] Build status: STARTED
[06:14:58.083] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:10:0) after 7m53s
[06:22:51.144] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:10:0)
[06:22:51.169] Build status: STARTED
[06:22:51.169] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:11:0) after 2m56s
[06:25:47.188] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:11:0)
[06:25:47.210] Build status: STARTED
[06:25:47.210] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:12:0) after 1m20s
[06:27:07.263] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:12:0)
[06:27:07.357] Build status: STARTED
[06:27:07.357] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:13:0) after 4m57s
[06:32:04.579] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:13:0)
[06:32:04.599] Build status: STARTED
[06:32:04.599] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:14:0) after 5m49s
[06:37:53.676] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:14:0)
[06:37:53.783] Build status: STARTED
[06:37:53.783] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:15:0) after 9m2s
[06:46:55.797] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:15:0)
[06:46:55.811] Build status: STARTED
[06:46:55.811] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:16:0) after 8m25s
[06:55:20.955] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:16:0)
[06:55:20.986] Build status: STARTED
[06:55:20.986] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:17:0) after 5m58s
[07:01:19.020] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:17:0)
[07:01:19.038] Build status: STARTED
[07:01:19.038] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:18:0) after 7m5s
[07:08:24.106] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:18:0)
[07:08:24.167] Build status: STARTED
[07:08:24.167] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:19:0) after 4m25s
[07:12:49.228] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:19:0)
[07:12:49.243] Build status: STARTED
[07:12:49.243] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:20:0) after 5m56s
[07:18:45.266] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:20:0)
[07:18:45.291] Build status: STARTED
[07:18:45.291] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:21:0) after 5m39s
[07:24:24.264] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:21:0)
[07:24:24.279] Build status: STARTED
[07:24:24.279] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:22:0) after 3m2s
[07:27:26.303] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:22:0)
[07:27:26.462] Build status: STARTED
[07:27:26.462] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:23:0) after 8m31s
[07:35:57.527] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:23:0)
[07:35:57.613] Build status: STARTED
[07:35:57.613] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:24:0) after 6m19s
[07:42:16.633] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:24:0)
[07:42:16.688] Build status: STARTED
[07:42:16.688] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:25:0) after 1m48s
[07:44:04.706] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:25:0)
[07:44:04.723] Build status: STARTED
[07:44:04.723] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:26:0) after 6m13s
[07:50:17.802] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:26:0)
[07:50:17.822] Build status: STARTED
[07:50:17.822] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:27:0) after 3m39s
[07:53:56.894] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:27:0)
[07:53:56.910] Build status: STARTED
[07:53:56.910] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:28:0) after 7m51s
[08:01:47.987] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:28:0)
[08:01:48.092] Build status: STARTED
[08:01:48.092] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:29:0) after 3m38s
[08:05:26.100] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:29:0)
[08:05:26.136] Build status: STARTED
[08:05:26.136] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:30:0) after 4m22s
[08:09:48.158] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:30:0)
[08:09:48.176] Build status: STARTED
[08:09:48.177] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:31:0) after 3m39s
[08:13:27.240] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:31:0)
[08:13:27.257] Build status: STARTED
[08:13:27.258] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:32:0) after 4m31s
[08:17:58.310] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:32:0)
[08:17:58.393] Build status: STARTED
[08:17:58.393] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:33:0) after 1m33s
[08:19:31.557] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:33:0)
[08:19:31.620] Build status: STARTED
[08:19:31.620] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:34:0) after 9m57s
[08:29:28.640] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:34:0)
[08:29:28.659] Build status: STARTED
[08:29:28.659] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:35:0) after 8m2s
[08:37:30.747] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:35:0)
[08:37:30.830] Build status: STARTED
[08:37:30.830] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:36:0) after 4m5s
[08:41:35.817] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:36:0)
[08:41:35.832] Build status: STARTED
[08:41:35.832] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:37:0) after 7m13s
[08:48:48.921] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:37:0)
[08:48:48.954] Build status: STARTED
[08:48:48.954] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:38:0) after 3m27s
[08:52:15.938] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:38:0)
[08:52:15.955] Build status: STARTED
[08:52:15.955] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:39:0) after 9m4s
[09:01:19.969] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:39:0)
[09:01:19.986] Build status: STARTED
[09:01:19.987] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:40:0) after 1m19s
[09:02:39.037] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:40:0)
[09:02:39.053] Build status: STARTED
[09:02:39.053] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:41:0) after 4m4s
[09:06:43.180] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:41:0)
[09:06:43.257] Build status: STARTED
[09:06:43.257] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8857972966003454320:42:0) after 9m12s
[09:09:21.414] Received PubSub notification, asking Buildbucket for the build status
[09:09:21.433] Build:
{
"id": "8692542904037049809",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win-official"
},
"number": 358,
"createdBy": "project:chromium-m144",
"createTime": "2026-01-16T05:41:50.368430494Z",
"startTime": "2026-01-16T05:41:54.885820632Z",
"endTime": "2026-01-16T09:09:21.086954371Z",
"updateTime": "2026-01-16T09:09:21.086954371Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fc8b27780820967eed61ec4db0e010f41e9b9bbc",
"ref": "refs/branch-heads/7559"
}
},
"canary": true
}
[09:09:21.433] Invocation finished in 3h27m32.324494184s with status SUCCEEDED