[01:23:31.641] New invocation is queued and will start shortly
[01:23:32.747] Starting the invocation (attempt 1)
[01:23:32.775] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b1ce8190944ad6f9ab8a26b4ddab321b4d04ebe6"
[01:23:32.776] Popped gitiles commit info from properties and tags
[01:23:32.776] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:23:32.778] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:23:32.778] Buildbucket request:
{
"requestId": "8854818322463707744",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8854818322463707744",
"job": "chromium-m145/win-arm64-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6badbcdb89210b3db7db901e3fa8c97759bfc5e4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@6badbcdb89210b3db7db901e3fa8c97759bfc5e4",
"title": "6badbcdb89210b3db7db901e3fa8c97759bfc5e4",
"url": "https://chromium.googlesource.com/chromium/src/+/6badbcdb89210b3db7db901e3fa8c97759bfc5e4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0ffd4573a1da213861fb55ee21b4d00da4b7a9ab"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@0ffd4573a1da213861fb55ee21b4d00da4b7a9ab",
"title": "0ffd4573a1da213861fb55ee21b4d00da4b7a9ab",
"url": "https://chromium.googlesource.com/chromium/src/+/0ffd4573a1da213861fb55ee21b4d00da4b7a9ab"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4497786dccd8b4be0d5112561679cc97a0362627"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@4497786dccd8b4be0d5112561679cc97a0362627",
"title": "4497786dccd8b4be0d5112561679cc97a0362627",
"url": "https://chromium.googlesource.com/chromium/src/+/4497786dccd8b4be0d5112561679cc97a0362627"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "afec44a426c9cd0db9cd27681539eec8f80b6c6e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@afec44a426c9cd0db9cd27681539eec8f80b6c6e",
"title": "afec44a426c9cd0db9cd27681539eec8f80b6c6e",
"url": "https://chromium.googlesource.com/chromium/src/+/afec44a426c9cd0db9cd27681539eec8f80b6c6e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fe5a82a4268500479be81895138717e3f3041c3f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@fe5a82a4268500479be81895138717e3f3041c3f",
"title": "fe5a82a4268500479be81895138717e3f3041c3f",
"url": "https://chromium.googlesource.com/chromium/src/+/fe5a82a4268500479be81895138717e3f3041c3f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bb3be633a35be4274f7baf64cb2e180f234b0e10"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@bb3be633a35be4274f7baf64cb2e180f234b0e10",
"title": "bb3be633a35be4274f7baf64cb2e180f234b0e10",
"url": "https://chromium.googlesource.com/chromium/src/+/bb3be633a35be4274f7baf64cb2e180f234b0e10"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4092876d00df91f760f03552e5f2fa4bac37199a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@4092876d00df91f760f03552e5f2fa4bac37199a",
"title": "4092876d00df91f760f03552e5f2fa4bac37199a",
"url": "https://chromium.googlesource.com/chromium/src/+/4092876d00df91f760f03552e5f2fa4bac37199a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ab352349f40c991a53541b7769809f6c226a68a5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@ab352349f40c991a53541b7769809f6c226a68a5",
"title": "ab352349f40c991a53541b7769809f6c226a68a5",
"url": "https://chromium.googlesource.com/chromium/src/+/ab352349f40c991a53541b7769809f6c226a68a5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b1ce8190944ad6f9ab8a26b4ddab321b4d04ebe6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@b1ce8190944ad6f9ab8a26b4ddab321b4d04ebe6",
"title": "b1ce8190944ad6f9ab8a26b4ddab321b4d04ebe6",
"url": "https://chromium.googlesource.com/chromium/src/+/b1ce8190944ad6f9ab8a26b4ddab321b4d04ebe6"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b1ce8190944ad6f9ab8a26b4ddab321b4d04ebe6",
"ref": "refs/branch-heads/7632"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8854818322463707744"
},
{
"key": "scheduler_job_id",
"value": "chromium-m145/win-arm64-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:23:33.743] Scheduled build:
{
"id": "8689388260441805505",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 371,
"createdBy": "project:chromium-m145",
"createTime": "2026-02-20T01:23:32.942677136Z",
"updateTime": "2026-02-20T01:23:32.942677136Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b1ce8190944ad6f9ab8a26b4ddab321b4d04ebe6",
"ref": "refs/branch-heads/7632"
}
},
"canary": true
}
[01:23:33.743] Task URL: https://cr-buildbucket.appspot.com/build/8689388260441805505
[01:23:33.743] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:2:0) after 2m55s
[01:23:34.570] Received PubSub notification, asking Buildbucket for the build status
[01:23:34.602] Build status: SCHEDULED
[01:24:59.836] Received PubSub notification, asking Buildbucket for the build status
[01:24:59.885] Build status: STARTED
[01:26:28.755] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:2:0)
[01:26:28.795] Build status: STARTED
[01:26:28.795] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:5:0) after 8m44s
[01:35:12.819] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:5:0)
[01:35:12.836] Build status: STARTED
[01:35:12.836] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:6:0) after 8m38s
[01:43:50.854] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:6:0)
[01:43:50.890] Build status: STARTED
[01:43:50.890] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:7:0) after 5m39s
[01:49:29.893] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:7:0)
[01:49:29.913] Build status: STARTED
[01:49:29.913] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:8:0) after 5m50s
[01:55:19.928] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:8:0)
[01:55:19.959] Build status: STARTED
[01:55:19.959] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:9:0) after 5m20s
[02:00:39.929] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:9:0)
[02:00:39.954] Build status: STARTED
[02:00:39.954] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:10:0) after 5m19s
[02:05:58.968] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:10:0)
[02:05:58.998] Build status: STARTED
[02:05:58.998] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:11:0) after 5m27s
[02:11:26.013] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:11:0)
[02:11:26.109] Build status: STARTED
[02:11:26.109] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:12:0) after 1m26s
[02:12:52.115] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:12:0)
[02:12:52.135] Build status: STARTED
[02:12:52.135] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:13:0) after 9m48s
[02:22:40.157] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:13:0)
[02:22:40.183] Build status: STARTED
[02:22:40.183] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:14:0) after 1m3s
[02:23:43.200] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:14:0)
[02:23:43.222] Build status: STARTED
[02:23:43.222] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:15:0) after 4m30s
[02:28:13.235] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:15:0)
[02:28:13.250] Build status: STARTED
[02:28:13.251] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:16:0) after 3m34s
[02:31:47.313] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:16:0)
[02:31:47.328] Build status: STARTED
[02:31:47.328] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:17:0) after 3m22s
[02:35:09.341] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:17:0)
[02:35:09.365] Build status: STARTED
[02:35:09.365] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:18:0) after 6m40s
[02:41:49.406] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:18:0)
[02:41:49.433] Build status: STARTED
[02:41:49.433] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:19:0) after 8m30s
[02:50:19.448] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:19:0)
[02:50:19.467] Build status: STARTED
[02:50:19.467] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:20:0) after 7m11s
[02:57:30.482] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:20:0)
[02:57:30.505] Build status: STARTED
[02:57:30.505] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:21:0) after 1m15s
[02:58:45.719] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:21:0)
[02:58:45.765] Build status: STARTED
[02:58:45.765] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:22:0) after 2m56s
[03:01:41.761] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:22:0)
[03:01:41.780] Build status: STARTED
[03:01:41.780] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:23:0) after 8m56s
[03:10:37.797] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:23:0)
[03:10:37.818] Build status: STARTED
[03:10:37.818] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:24:0) after 1m31s
[03:12:08.833] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:24:0)
[03:12:08.848] Build status: STARTED
[03:12:08.848] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:25:0) after 9m13s
[03:21:21.864] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:25:0)
[03:21:21.878] Build status: STARTED
[03:21:21.878] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:26:0) after 2m47s
[03:24:08.977] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:26:0)
[03:24:08.991] Build status: STARTED
[03:24:08.991] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:27:0) after 6m27s
[03:30:36.008] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:27:0)
[03:30:36.040] Build status: STARTED
[03:30:36.040] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:28:0) after 4m31s
[03:35:07.158] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:28:0)
[03:35:07.229] Build status: STARTED
[03:35:07.229] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:29:0) after 3m37s
[03:38:44.243] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:29:0)
[03:38:44.258] Build status: STARTED
[03:38:44.258] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:30:0) after 2m42s
[03:41:26.278] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:30:0)
[03:41:26.295] Build status: STARTED
[03:41:26.295] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:31:0) after 5m5s
[03:46:31.472] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:31:0)
[03:46:31.494] Build status: STARTED
[03:46:31.494] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:32:0) after 1m15s
[03:47:46.559] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:32:0)
[03:47:46.590] Build status: STARTED
[03:47:46.590] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:33:0) after 2m5s
[03:49:51.649] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:33:0)
[03:49:51.674] Build status: STARTED
[03:49:51.674] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:34:0) after 2m31s
[03:52:22.688] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:34:0)
[03:52:22.732] Build status: STARTED
[03:52:22.732] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:35:0) after 8m48s
[04:01:10.750] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:35:0)
[04:01:10.774] Build status: STARTED
[04:01:10.775] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:36:0) after 8m44s
[04:09:54.796] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:36:0)
[04:09:54.820] Build status: STARTED
[04:09:54.820] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:37:0) after 3m37s
[04:13:31.835] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:37:0)
[04:13:31.856] Build status: STARTED
[04:13:31.856] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:38:0) after 4m18s
[04:17:49.978] Handling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:38:0)
[04:17:49.996] Build status: STARTED
[04:17:49.996] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-arm64-rel:8854818322463707744:39:0) after 4m35s
[04:19:04.237] Received PubSub notification, asking Buildbucket for the build status
[04:19:04.255] Build:
{
"id": "8689388260441805505",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 371,
"createdBy": "project:chromium-m145",
"createTime": "2026-02-20T01:23:32.942677136Z",
"startTime": "2026-02-20T01:24:59.663230067Z",
"endTime": "2026-02-20T04:19:03.955839121Z",
"updateTime": "2026-02-20T04:19:03.955839121Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b1ce8190944ad6f9ab8a26b4ddab321b4d04ebe6",
"ref": "refs/branch-heads/7632"
}
},
"canary": true
}
[04:19:04.255] Invocation finished in 2h55m32.625118693s with status SUCCEEDED