[23:32:50.283] New invocation is queued and will start shortly
[23:32:51.321] Starting the invocation (attempt 1)
[23:32:51.341] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/07034c6f5ee7ad5faef6095a065815527514cf07"
[23:32:51.341] Popped gitiles commit info from properties and tags
[23:32:51.341] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:32:51.341] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:32:51.342] Buildbucket request:
{
"requestId": "8859355134747744256",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8859355134747744256",
"job": "chromium-m144/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9ad1f601938400ff046920027c452418cbe9e3d4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@9ad1f601938400ff046920027c452418cbe9e3d4",
"title": "9ad1f601938400ff046920027c452418cbe9e3d4",
"url": "https://chromium.googlesource.com/chromium/src/+/9ad1f601938400ff046920027c452418cbe9e3d4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "665bbdece1aab4e1a6429cd6d5fb570a363c91a7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@665bbdece1aab4e1a6429cd6d5fb570a363c91a7",
"title": "665bbdece1aab4e1a6429cd6d5fb570a363c91a7",
"url": "https://chromium.googlesource.com/chromium/src/+/665bbdece1aab4e1a6429cd6d5fb570a363c91a7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4332dc50c4df6b499443d8b8bc556f6fa9c368cc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@4332dc50c4df6b499443d8b8bc556f6fa9c368cc",
"title": "4332dc50c4df6b499443d8b8bc556f6fa9c368cc",
"url": "https://chromium.googlesource.com/chromium/src/+/4332dc50c4df6b499443d8b8bc556f6fa9c368cc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a577e123ff69a0516f1172a75c99584749cc48b0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@a577e123ff69a0516f1172a75c99584749cc48b0",
"title": "a577e123ff69a0516f1172a75c99584749cc48b0",
"url": "https://chromium.googlesource.com/chromium/src/+/a577e123ff69a0516f1172a75c99584749cc48b0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5bb7abe91f96d2ca9be9d107759cea35fede4589"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@5bb7abe91f96d2ca9be9d107759cea35fede4589",
"title": "5bb7abe91f96d2ca9be9d107759cea35fede4589",
"url": "https://chromium.googlesource.com/chromium/src/+/5bb7abe91f96d2ca9be9d107759cea35fede4589"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5c926a99cb3c3b9a8332f478bee148126a0119d8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@5c926a99cb3c3b9a8332f478bee148126a0119d8",
"title": "5c926a99cb3c3b9a8332f478bee148126a0119d8",
"url": "https://chromium.googlesource.com/chromium/src/+/5c926a99cb3c3b9a8332f478bee148126a0119d8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "23aafe8828f673b4cbd05da0689d3de5cbb2253d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@23aafe8828f673b4cbd05da0689d3de5cbb2253d",
"title": "23aafe8828f673b4cbd05da0689d3de5cbb2253d",
"url": "https://chromium.googlesource.com/chromium/src/+/23aafe8828f673b4cbd05da0689d3de5cbb2253d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "469c12dc190312bf48c25ee01dfb1783cc61ab37"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@469c12dc190312bf48c25ee01dfb1783cc61ab37",
"title": "469c12dc190312bf48c25ee01dfb1783cc61ab37",
"url": "https://chromium.googlesource.com/chromium/src/+/469c12dc190312bf48c25ee01dfb1783cc61ab37"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "47a178cb64b259105f82b55c57eb86c28c4a1500"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@47a178cb64b259105f82b55c57eb86c28c4a1500",
"title": "47a178cb64b259105f82b55c57eb86c28c4a1500",
"url": "https://chromium.googlesource.com/chromium/src/+/47a178cb64b259105f82b55c57eb86c28c4a1500"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "07034c6f5ee7ad5faef6095a065815527514cf07"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@07034c6f5ee7ad5faef6095a065815527514cf07",
"title": "07034c6f5ee7ad5faef6095a065815527514cf07",
"url": "https://chromium.googlesource.com/chromium/src/+/07034c6f5ee7ad5faef6095a065815527514cf07"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "07034c6f5ee7ad5faef6095a065815527514cf07",
"ref": "refs/branch-heads/7559"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8859355134747744256"
},
{
"key": "scheduler_job_id",
"value": "chromium-m144/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:32:51.827] Scheduled build:
{
"id": "8693925072931769905",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win32-official"
},
"number": 186,
"createdBy": "project:chromium-m144",
"createTime": "2025-12-31T23:32:51.392810288Z",
"updateTime": "2025-12-31T23:32:51.392810288Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "07034c6f5ee7ad5faef6095a065815527514cf07",
"ref": "refs/branch-heads/7559"
}
}
}
[23:32:51.827] Task URL: https://cr-buildbucket.appspot.com/build/8693925072931769905
[23:32:51.827] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:2:0) after 8m54s
[23:33:09.029] Received PubSub notification, asking Buildbucket for the build status
[23:33:09.091] Build status: SCHEDULED
[23:33:59.272] Received PubSub notification, asking Buildbucket for the build status
[23:33:59.290] Build status: STARTED
[23:41:45.862] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:2:0)
[23:41:45.889] Build status: STARTED
[23:41:45.889] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:5:0) after 4m29s
[23:46:15.023] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:5:0)
[23:46:15.036] Build status: STARTED
[23:46:15.036] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:6:0) after 7m38s
[23:53:53.052] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:6:0)
[23:53:53.081] Build status: STARTED
[23:53:53.081] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:7:0) after 1m12s
[23:55:05.099] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:7:0)
[23:55:05.117] Build status: STARTED
[23:55:05.117] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:8:0) after 3m49s
[23:58:54.135] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:8:0)
[23:58:54.151] Build status: STARTED
[23:58:54.151] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:9:0) after 8m16s
[00:07:10.173] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:9:0)
[00:07:10.190] Build status: STARTED
[00:07:10.190] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:10:0) after 3m14s
[00:10:24.209] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:10:0)
[00:10:24.221] Build status: STARTED
[00:10:24.222] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:11:0) after 6m46s
[00:17:10.239] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:11:0)
[00:17:10.258] Build status: STARTED
[00:17:10.258] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:12:0) after 5m57s
[00:23:07.399] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:12:0)
[00:23:07.415] Build status: STARTED
[00:23:07.415] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:13:0) after 8m25s
[00:31:32.445] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:13:0)
[00:31:32.459] Build status: STARTED
[00:31:32.459] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:14:0) after 4m42s
[00:36:14.441] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:14:0)
[00:36:14.509] Build status: STARTED
[00:36:14.510] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:15:0) after 3m5s
[00:39:19.521] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:15:0)
[00:39:19.537] Build status: STARTED
[00:39:19.537] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:16:0) after 5m52s
[00:45:11.696] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:16:0)
[00:45:11.716] Build status: STARTED
[00:45:11.716] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:17:0) after 8m58s
[00:54:09.904] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:17:0)
[00:54:09.965] Build status: STARTED
[00:54:09.965] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:18:0) after 2m14s
[00:56:23.982] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:18:0)
[00:56:24.000] Build status: STARTED
[00:56:24.000] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:19:0) after 7m21s
[01:03:45.014] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:19:0)
[01:03:45.039] Build status: STARTED
[01:03:45.039] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:20:0) after 9m49s
[01:13:34.101] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:20:0)
[01:13:34.177] Build status: STARTED
[01:13:34.177] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:21:0) after 9m8s
[01:22:42.195] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:21:0)
[01:22:42.214] Build status: STARTED
[01:22:42.215] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:22:0) after 1m26s
[01:24:08.225] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:22:0)
[01:24:08.243] Build status: STARTED
[01:24:08.243] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:23:0) after 2m26s
[01:26:34.343] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:23:0)
[01:26:34.362] Build status: STARTED
[01:26:34.362] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:24:0) after 4m0s
[01:30:34.374] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:24:0)
[01:30:34.388] Build status: STARTED
[01:30:34.388] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:25:0) after 6m31s
[01:37:05.443] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:25:0)
[01:37:05.460] Build status: STARTED
[01:37:05.460] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:26:0) after 9m34s
[01:46:39.508] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:26:0)
[01:46:39.530] Build status: STARTED
[01:46:39.530] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:27:0) after 6m39s
[01:53:18.578] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:27:0)
[01:53:18.599] Build status: STARTED
[01:53:18.599] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:28:0) after 8m48s
[02:02:06.605] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:28:0)
[02:02:06.665] Build status: STARTED
[02:02:06.665] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:29:0) after 9m55s
[02:12:01.816] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:29:0)
[02:12:01.831] Build status: STARTED
[02:12:01.831] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:30:0) after 1m32s
[02:13:33.841] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:30:0)
[02:13:33.858] Build status: STARTED
[02:13:33.858] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:31:0) after 5m44s
[02:19:18.187] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:31:0)
[02:19:18.203] Build status: STARTED
[02:19:18.203] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:32:0) after 2m59s
[02:22:17.431] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:32:0)
[02:22:17.449] Build status: STARTED
[02:22:17.449] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:33:0) after 9m9s
[02:31:26.467] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:33:0)
[02:31:26.485] Build status: STARTED
[02:31:26.485] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:34:0) after 3m59s
[02:35:25.590] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:34:0)
[02:35:25.607] Build status: STARTED
[02:35:25.607] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:35:0) after 2m50s
[02:38:15.575] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:35:0)
[02:38:15.647] Build status: STARTED
[02:38:15.647] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:36:0) after 6m20s
[02:44:35.720] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:36:0)
[02:44:35.734] Build status: STARTED
[02:44:35.734] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:37:0) after 7m4s
[02:51:39.869] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:37:0)
[02:51:39.884] Build status: STARTED
[02:51:39.884] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:38:0) after 1m39s
[02:53:18.868] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:38:0)
[02:53:18.889] Build status: STARTED
[02:53:18.889] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:39:0) after 4m53s
[02:58:11.916] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:39:0)
[02:58:11.933] Build status: STARTED
[02:58:11.933] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:40:0) after 5m53s
[03:04:05.018] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:40:0)
[03:04:05.090] Build status: STARTED
[03:04:05.090] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:41:0) after 7m35s
[03:11:40.204] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:41:0)
[03:11:40.218] Build status: STARTED
[03:11:40.218] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:42:0) after 8m33s
[03:20:13.232] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:42:0)
[03:20:13.245] Build status: STARTED
[03:20:13.246] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:43:0) after 9m3s
[03:29:16.354] Handling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:43:0)
[03:29:16.370] Build status: STARTED
[03:29:16.370] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win32-official:8859355134747744256:44:0) after 8m4s
[03:30:45.580] Received PubSub notification, asking Buildbucket for the build status
[03:30:45.604] Build:
{
"id": "8693925072931769905",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win32-official"
},
"number": 186,
"createdBy": "project:chromium-m144",
"createTime": "2025-12-31T23:32:51.392810288Z",
"startTime": "2025-12-31T23:33:59.128804994Z",
"endTime": "2026-01-01T03:30:30.916572945Z",
"updateTime": "2026-01-01T03:30:30.916572945Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "07034c6f5ee7ad5faef6095a065815527514cf07",
"ref": "refs/branch-heads/7559"
}
}
}
[03:30:45.604] Invocation finished in 3h57m55.327741451s with status SUCCEEDED