[03:23:57.259] New invocation is queued and will start shortly
[03:23:58.382] Starting the invocation (attempt 1)
[03:23:58.406] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/49e4b007ae9051b86487740c1790efe5e4c3e06a"
[03:23:58.406] Popped gitiles commit info from properties and tags
[03:23:58.406] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:23:58.406] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:23:58.407] Buildbucket request:
{
"requestId": "8858072236692385104",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8858072236692385104",
"job": "chromium-m144/win-arm64-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "04f4290015f9e6c116be5d5c562e301b04e9e6b1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@04f4290015f9e6c116be5d5c562e301b04e9e6b1",
"title": "04f4290015f9e6c116be5d5c562e301b04e9e6b1",
"url": "https://chromium.googlesource.com/chromium/src/+/04f4290015f9e6c116be5d5c562e301b04e9e6b1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "66d7edb94f978b4c9fb20ae2be2a9b0e0a251404"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@66d7edb94f978b4c9fb20ae2be2a9b0e0a251404",
"title": "66d7edb94f978b4c9fb20ae2be2a9b0e0a251404",
"url": "https://chromium.googlesource.com/chromium/src/+/66d7edb94f978b4c9fb20ae2be2a9b0e0a251404"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "48e7e1c3288d08d1c65ccc9ac1be215b43c91f22"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@48e7e1c3288d08d1c65ccc9ac1be215b43c91f22",
"title": "48e7e1c3288d08d1c65ccc9ac1be215b43c91f22",
"url": "https://chromium.googlesource.com/chromium/src/+/48e7e1c3288d08d1c65ccc9ac1be215b43c91f22"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1fe0b60932391493fe60e913be6c07b1da75bb2d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@1fe0b60932391493fe60e913be6c07b1da75bb2d",
"title": "1fe0b60932391493fe60e913be6c07b1da75bb2d",
"url": "https://chromium.googlesource.com/chromium/src/+/1fe0b60932391493fe60e913be6c07b1da75bb2d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3bd5aba4355e7c438a66ee1d42ebb866c20b5d5e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@3bd5aba4355e7c438a66ee1d42ebb866c20b5d5e",
"title": "3bd5aba4355e7c438a66ee1d42ebb866c20b5d5e",
"url": "https://chromium.googlesource.com/chromium/src/+/3bd5aba4355e7c438a66ee1d42ebb866c20b5d5e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6863baa42d144ef94049cda48cab8570cc2ad8e5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@6863baa42d144ef94049cda48cab8570cc2ad8e5",
"title": "6863baa42d144ef94049cda48cab8570cc2ad8e5",
"url": "https://chromium.googlesource.com/chromium/src/+/6863baa42d144ef94049cda48cab8570cc2ad8e5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "07f475e7fe78204ba64ee958484b8f638c8a0f14"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@07f475e7fe78204ba64ee958484b8f638c8a0f14",
"title": "07f475e7fe78204ba64ee958484b8f638c8a0f14",
"url": "https://chromium.googlesource.com/chromium/src/+/07f475e7fe78204ba64ee958484b8f638c8a0f14"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2a3b5114c64754f1ea42eb2b370daf25ac53c61d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@2a3b5114c64754f1ea42eb2b370daf25ac53c61d",
"title": "2a3b5114c64754f1ea42eb2b370daf25ac53c61d",
"url": "https://chromium.googlesource.com/chromium/src/+/2a3b5114c64754f1ea42eb2b370daf25ac53c61d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "82bebb8946714f65e8388aa1023aa1072700d28a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@82bebb8946714f65e8388aa1023aa1072700d28a",
"title": "82bebb8946714f65e8388aa1023aa1072700d28a",
"url": "https://chromium.googlesource.com/chromium/src/+/82bebb8946714f65e8388aa1023aa1072700d28a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2670b80d704d705007d764d7f99fa3a892d6a253"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@2670b80d704d705007d764d7f99fa3a892d6a253",
"title": "2670b80d704d705007d764d7f99fa3a892d6a253",
"url": "https://chromium.googlesource.com/chromium/src/+/2670b80d704d705007d764d7f99fa3a892d6a253"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "49e4b007ae9051b86487740c1790efe5e4c3e06a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@49e4b007ae9051b86487740c1790efe5e4c3e06a",
"title": "49e4b007ae9051b86487740c1790efe5e4c3e06a",
"url": "https://chromium.googlesource.com/chromium/src/+/49e4b007ae9051b86487740c1790efe5e4c3e06a"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "49e4b007ae9051b86487740c1790efe5e4c3e06a",
"ref": "refs/branch-heads/7559"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8858072236692385104"
},
{
"key": "scheduler_job_id",
"value": "chromium-m144/win-arm64-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:23:59.445] Scheduled build:
{
"id": "8692642174658237969",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 437,
"createdBy": "project:chromium-m144",
"createTime": "2026-01-15T03:23:58.526849865Z",
"updateTime": "2026-01-15T03:23:58.526849865Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "49e4b007ae9051b86487740c1790efe5e4c3e06a",
"ref": "refs/branch-heads/7559"
}
}
}
[03:23:59.446] Task URL: https://cr-buildbucket.appspot.com/build/8692642174658237969
[03:23:59.446] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:2:0) after 4m7s
[03:24:00.226] Received PubSub notification, asking Buildbucket for the build status
[03:24:00.260] Build status: SCHEDULED
[03:24:05.004] Received PubSub notification, asking Buildbucket for the build status
[03:24:05.029] Build status: STARTED
[03:28:06.582] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:2:0)
[03:28:06.597] Build status: STARTED
[03:28:06.597] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:5:0) after 1m53s
[03:29:59.747] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:5:0)
[03:29:59.766] Build status: STARTED
[03:29:59.766] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:6:0) after 8m39s
[03:38:38.890] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:6:0)
[03:38:39.000] Build status: STARTED
[03:38:39.000] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:7:0) after 7m37s
[03:46:16.296] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:7:0)
[03:46:16.320] Build status: STARTED
[03:46:16.320] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:8:0) after 5m46s
[03:52:02.336] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:8:0)
[03:52:02.352] Build status: STARTED
[03:52:02.352] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:9:0) after 3m51s
[03:55:53.381] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:9:0)
[03:55:53.401] Build status: STARTED
[03:55:53.401] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:10:0) after 9m45s
[04:05:38.456] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:10:0)
[04:05:38.473] Build status: STARTED
[04:05:38.474] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:11:0) after 2m15s
[04:07:53.450] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:11:0)
[04:07:53.468] Build status: STARTED
[04:07:53.468] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:12:0) after 3m29s
[04:11:22.785] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:12:0)
[04:11:22.811] Build status: STARTED
[04:11:22.811] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:13:0) after 5m23s
[04:16:46.166] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:13:0)
[04:16:46.218] Build status: STARTED
[04:16:46.218] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:14:0) after 4m53s
[04:21:39.233] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:14:0)
[04:21:39.249] Build status: STARTED
[04:21:39.249] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:15:0) after 9m14s
[04:30:53.264] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:15:0)
[04:30:53.289] Build status: STARTED
[04:30:53.289] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:16:0) after 5m2s
[04:35:55.307] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:16:0)
[04:35:55.336] Build status: STARTED
[04:35:55.336] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:17:0) after 2m57s
[04:38:52.513] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:17:0)
[04:38:52.575] Build status: STARTED
[04:38:52.575] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:18:0) after 1m56s
[04:40:49.097] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:18:0)
[04:40:49.116] Build status: STARTED
[04:40:49.116] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:19:0) after 9m9s
[04:49:58.465] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:19:0)
[04:49:58.543] Build status: STARTED
[04:49:58.543] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:20:0) after 4m27s
[04:54:25.666] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:20:0)
[04:54:25.684] Build status: STARTED
[04:54:25.684] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:21:0) after 3m12s
[04:57:37.740] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:21:0)
[04:57:37.860] Build status: STARTED
[04:57:37.860] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:22:0) after 8m44s
[05:06:21.877] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:22:0)
[05:06:21.892] Build status: STARTED
[05:06:21.892] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:23:0) after 4m52s
[05:11:13.920] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:23:0)
[05:11:13.946] Build status: STARTED
[05:11:13.946] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:24:0) after 8m1s
[05:19:14.961] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:24:0)
[05:19:14.975] Build status: STARTED
[05:19:14.975] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:25:0) after 9m10s
[05:28:25.132] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:25:0)
[05:28:25.153] Build status: STARTED
[05:28:25.153] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:26:0) after 5m37s
[05:34:02.170] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:26:0)
[05:34:02.186] Build status: STARTED
[05:34:02.186] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:27:0) after 4m6s
[05:38:08.363] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:27:0)
[05:38:08.379] Build status: STARTED
[05:38:08.379] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:28:0) after 3m24s
[05:41:32.400] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:28:0)
[05:41:32.428] Build status: STARTED
[05:41:32.428] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:29:0) after 1m20s
[05:42:52.444] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:29:0)
[05:42:52.462] Build status: STARTED
[05:42:52.462] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:30:0) after 3m52s
[05:46:44.689] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:30:0)
[05:46:44.709] Build status: STARTED
[05:46:44.709] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:31:0) after 5m14s
[05:51:59.004] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:31:0)
[05:51:59.131] Build status: STARTED
[05:51:59.131] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:32:0) after 9m7s
[06:01:06.245] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:32:0)
[06:01:06.272] Build status: STARTED
[06:01:06.272] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:33:0) after 1m0s
[06:02:06.344] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:33:0)
[06:02:06.360] Build status: STARTED
[06:02:06.360] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:34:0) after 7m59s
[06:10:05.375] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:34:0)
[06:10:05.390] Build status: STARTED
[06:10:05.390] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:35:0) after 1m21s
[06:11:26.411] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:35:0)
[06:11:26.464] Build status: STARTED
[06:11:26.464] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:36:0) after 1m53s
[06:13:19.469] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:36:0)
[06:13:19.489] Build status: STARTED
[06:13:19.489] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8858072236692385104:37:0) after 6m9s
[06:14:43.751] Received PubSub notification, asking Buildbucket for the build status
[06:14:43.769] Build:
{
"id": "8692642174658237969",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 437,
"createdBy": "project:chromium-m144",
"createTime": "2026-01-15T03:23:58.526849865Z",
"startTime": "2026-01-15T03:24:04.806782975Z",
"endTime": "2026-01-15T06:14:43.428521692Z",
"updateTime": "2026-01-15T06:14:43.428521692Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "49e4b007ae9051b86487740c1790efe5e4c3e06a",
"ref": "refs/branch-heads/7559"
}
}
}
[06:14:43.769] Invocation finished in 2h50m46.566249797s with status SUCCEEDED