[22:10:46.050] New invocation is queued and will start shortly
[22:10:47.145] Starting the invocation (attempt 1)
[22:10:47.196] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/987b3529e1ecd7eb0fb4b94d556185ecd328217b"
[22:10:47.196] Popped gitiles commit info from properties and tags
[22:10:47.196] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:10:47.196] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:10:47.197] Buildbucket request:
{
"requestId": "8851659556038411520",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8851659556038411520",
"job": "chromium-m146/win-arm64-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "475af7e29b9683f6beb0b19850e3850c1b26800d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@475af7e29b9683f6beb0b19850e3850c1b26800d",
"title": "475af7e29b9683f6beb0b19850e3850c1b26800d",
"url": "https://chromium.googlesource.com/chromium/src/+/475af7e29b9683f6beb0b19850e3850c1b26800d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "49defaa8eb807f0bd9e1040741286f70d9e04068"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@49defaa8eb807f0bd9e1040741286f70d9e04068",
"title": "49defaa8eb807f0bd9e1040741286f70d9e04068",
"url": "https://chromium.googlesource.com/chromium/src/+/49defaa8eb807f0bd9e1040741286f70d9e04068"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bb3d2cc7e7558fe35b8d5e0832c19d026d9ddf27"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@bb3d2cc7e7558fe35b8d5e0832c19d026d9ddf27",
"title": "bb3d2cc7e7558fe35b8d5e0832c19d026d9ddf27",
"url": "https://chromium.googlesource.com/chromium/src/+/bb3d2cc7e7558fe35b8d5e0832c19d026d9ddf27"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "433df0420b1c5d4963206c3f125d05bd5cb49bfa"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@433df0420b1c5d4963206c3f125d05bd5cb49bfa",
"title": "433df0420b1c5d4963206c3f125d05bd5cb49bfa",
"url": "https://chromium.googlesource.com/chromium/src/+/433df0420b1c5d4963206c3f125d05bd5cb49bfa"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0809b49af66c2186ec7e3023cf9332c7d24d44d9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@0809b49af66c2186ec7e3023cf9332c7d24d44d9",
"title": "0809b49af66c2186ec7e3023cf9332c7d24d44d9",
"url": "https://chromium.googlesource.com/chromium/src/+/0809b49af66c2186ec7e3023cf9332c7d24d44d9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cb1fdb1521f9993c548cd98951bc8eb74c60618c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@cb1fdb1521f9993c548cd98951bc8eb74c60618c",
"title": "cb1fdb1521f9993c548cd98951bc8eb74c60618c",
"url": "https://chromium.googlesource.com/chromium/src/+/cb1fdb1521f9993c548cd98951bc8eb74c60618c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e5d87ab4eb43e8df2c41a2855f25f37b76626b22"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@e5d87ab4eb43e8df2c41a2855f25f37b76626b22",
"title": "e5d87ab4eb43e8df2c41a2855f25f37b76626b22",
"url": "https://chromium.googlesource.com/chromium/src/+/e5d87ab4eb43e8df2c41a2855f25f37b76626b22"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6ed268eaafc9525e7e4871f20544b7759c185431"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@6ed268eaafc9525e7e4871f20544b7759c185431",
"title": "6ed268eaafc9525e7e4871f20544b7759c185431",
"url": "https://chromium.googlesource.com/chromium/src/+/6ed268eaafc9525e7e4871f20544b7759c185431"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "237f00909048f1056ed5cfea09e8de20578ebcb0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@237f00909048f1056ed5cfea09e8de20578ebcb0",
"title": "237f00909048f1056ed5cfea09e8de20578ebcb0",
"url": "https://chromium.googlesource.com/chromium/src/+/237f00909048f1056ed5cfea09e8de20578ebcb0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "987b3529e1ecd7eb0fb4b94d556185ecd328217b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@987b3529e1ecd7eb0fb4b94d556185ecd328217b",
"title": "987b3529e1ecd7eb0fb4b94d556185ecd328217b",
"url": "https://chromium.googlesource.com/chromium/src/+/987b3529e1ecd7eb0fb4b94d556185ecd328217b"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "987b3529e1ecd7eb0fb4b94d556185ecd328217b",
"ref": "refs/branch-heads/7680"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8851659556038411520"
},
{
"key": "scheduler_job_id",
"value": "chromium-m146/win-arm64-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:10:47.880] Scheduled build:
{
"id": "8686229494118426561",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 416,
"createdBy": "project:chromium-m146",
"createTime": "2026-03-26T22:10:47.256079199Z",
"updateTime": "2026-03-26T22:10:47.256079199Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "987b3529e1ecd7eb0fb4b94d556185ecd328217b",
"ref": "refs/branch-heads/7680"
}
}
}
[22:10:47.880] Task URL: https://cr-buildbucket.appspot.com/build/8686229494118426561
[22:10:47.880] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:2:0) after 1m56s
[22:10:48.811] Received PubSub notification, asking Buildbucket for the build status
[22:10:48.879] Build status: SCHEDULED
[22:10:55.833] Received PubSub notification, asking Buildbucket for the build status
[22:10:55.871] Build status: STARTED
[22:12:43.863] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:2:0)
[22:12:43.886] Build status: STARTED
[22:12:43.886] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:5:0) after 8m3s
[22:20:46.952] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:5:0)
[22:20:46.985] Build status: STARTED
[22:20:46.985] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:6:0) after 2m44s
[22:23:31.008] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:6:0)
[22:23:31.035] Build status: STARTED
[22:23:31.035] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:7:0) after 5m37s
[22:29:08.055] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:7:0)
[22:29:08.085] Build status: STARTED
[22:29:08.086] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:8:0) after 5m22s
[22:34:30.226] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:8:0)
[22:34:30.291] Build status: STARTED
[22:34:30.292] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:9:0) after 5m38s
[22:40:08.333] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:9:0)
[22:40:08.433] Build status: STARTED
[22:40:08.433] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:10:0) after 3m37s
[22:43:45.508] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:10:0)
[22:43:45.526] Build status: STARTED
[22:43:45.526] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:11:0) after 1m26s
[22:45:11.596] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:11:0)
[22:45:11.614] Build status: STARTED
[22:45:11.614] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:12:0) after 2m59s
[22:48:10.627] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:12:0)
[22:48:10.643] Build status: STARTED
[22:48:10.643] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:13:0) after 7m48s
[22:55:58.789] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:13:0)
[22:55:58.869] Build status: STARTED
[22:55:58.870] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:14:0) after 6m42s
[23:02:41.018] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:14:0)
[23:02:41.098] Build status: STARTED
[23:02:41.098] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:15:0) after 6m15s
[23:08:56.208] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:15:0)
[23:08:56.238] Build status: STARTED
[23:08:56.238] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:16:0) after 8m25s
[23:17:21.362] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:16:0)
[23:17:21.390] Build status: STARTED
[23:17:21.390] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:17:0) after 7m6s
[23:24:27.471] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:17:0)
[23:24:27.486] Build status: STARTED
[23:24:27.486] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:18:0) after 1m48s
[23:26:15.515] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:18:0)
[23:26:15.535] Build status: STARTED
[23:26:15.535] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:19:0) after 1m10s
[23:27:25.592] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:19:0)
[23:27:25.611] Build status: STARTED
[23:27:25.611] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:20:0) after 7m27s
[23:34:52.645] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:20:0)
[23:34:52.665] Build status: STARTED
[23:34:52.665] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:21:0) after 2m10s
[23:37:02.733] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:21:0)
[23:37:02.754] Build status: STARTED
[23:37:02.754] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:22:0) after 6m43s
[23:43:45.883] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:22:0)
[23:43:45.909] Build status: STARTED
[23:43:45.909] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:23:0) after 4m15s
[23:48:00.967] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:23:0)
[23:48:00.986] Build status: STARTED
[23:48:00.986] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:24:0) after 9m10s
[23:57:11.003] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:24:0)
[23:57:11.037] Build status: STARTED
[23:57:11.037] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:25:0) after 2m10s
[23:59:21.054] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:25:0)
[23:59:21.091] Build status: STARTED
[23:59:21.091] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:26:0) after 8m35s
[00:07:56.208] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:26:0)
[00:07:56.237] Build status: STARTED
[00:07:56.237] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:27:0) after 6m42s
[00:14:38.336] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:27:0)
[00:14:38.360] Build status: STARTED
[00:14:38.361] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:28:0) after 3m49s
[00:18:27.422] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:28:0)
[00:18:27.441] Build status: STARTED
[00:18:27.441] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:29:0) after 4m3s
[00:22:30.705] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:29:0)
[00:22:30.724] Build status: STARTED
[00:22:30.724] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:30:0) after 6m16s
[00:28:46.744] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:30:0)
[00:28:46.769] Build status: STARTED
[00:28:46.769] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:31:0) after 6m37s
[00:35:23.799] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:31:0)
[00:35:23.818] Build status: STARTED
[00:35:23.818] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:32:0) after 5m23s
[00:40:47.221] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:32:0)
[00:40:47.241] Build status: STARTED
[00:40:47.241] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:33:0) after 4m23s
[00:45:10.361] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:33:0)
[00:45:10.379] Build status: STARTED
[00:45:10.379] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:34:0) after 7m46s
[00:52:56.440] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:34:0)
[00:52:56.465] Build status: STARTED
[00:52:56.465] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:35:0) after 1m4s
[00:54:00.445] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:35:0)
[00:54:00.475] Build status: STARTED
[00:54:00.475] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:36:0) after 1m10s
[00:55:10.558] Handling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:36:0)
[00:55:10.581] Build status: STARTED
[00:55:10.581] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-arm64-rel:8851659556038411520:37:0) after 5m28s
[00:56:28.086] Received PubSub notification, asking Buildbucket for the build status
[00:56:28.135] Build:
{
"id": "8686229494118426561",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 416,
"createdBy": "project:chromium-m146",
"createTime": "2026-03-26T22:10:47.256079199Z",
"startTime": "2026-03-26T22:10:55.590259472Z",
"endTime": "2026-03-27T00:56:27.780257815Z",
"updateTime": "2026-03-27T00:56:27.780257815Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "987b3529e1ecd7eb0fb4b94d556185ecd328217b",
"ref": "refs/branch-heads/7680"
}
}
}
[00:56:28.135] Invocation finished in 2h45m42.093084002s with status SUCCEEDED