[11:00:28.277] New invocation is queued and will start shortly
[11:00:29.366] Starting the invocation (attempt 1)
[11:00:29.390] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b27517a587d64f6a31aaae3b7e5013566b07337d"
[11:00:29.390] Popped gitiles commit info from properties and tags
[11:00:29.390] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:00:29.391] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:00:29.391] Buildbucket request:
{
"requestId": "8857228142381529728",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8857228142381529728",
"job": "chromium-m145/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "15a1abe2892642d86a7c47aa248163fb4c47348f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@15a1abe2892642d86a7c47aa248163fb4c47348f",
"title": "15a1abe2892642d86a7c47aa248163fb4c47348f",
"url": "https://chromium.googlesource.com/chromium/src/+/15a1abe2892642d86a7c47aa248163fb4c47348f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8d9ed53d051a02fdc1122b15e93dcc93afae850d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@8d9ed53d051a02fdc1122b15e93dcc93afae850d",
"title": "8d9ed53d051a02fdc1122b15e93dcc93afae850d",
"url": "https://chromium.googlesource.com/chromium/src/+/8d9ed53d051a02fdc1122b15e93dcc93afae850d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "aea805e2fcf50a86b3a636eb07a9ee22196e6bff"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@aea805e2fcf50a86b3a636eb07a9ee22196e6bff",
"title": "aea805e2fcf50a86b3a636eb07a9ee22196e6bff",
"url": "https://chromium.googlesource.com/chromium/src/+/aea805e2fcf50a86b3a636eb07a9ee22196e6bff"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7f4ed68c32fd33f48be5e091c2ed54a694ad324b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@7f4ed68c32fd33f48be5e091c2ed54a694ad324b",
"title": "7f4ed68c32fd33f48be5e091c2ed54a694ad324b",
"url": "https://chromium.googlesource.com/chromium/src/+/7f4ed68c32fd33f48be5e091c2ed54a694ad324b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c9a6383693dd4e466d6a8c3fe5220a10526ba76c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@c9a6383693dd4e466d6a8c3fe5220a10526ba76c",
"title": "c9a6383693dd4e466d6a8c3fe5220a10526ba76c",
"url": "https://chromium.googlesource.com/chromium/src/+/c9a6383693dd4e466d6a8c3fe5220a10526ba76c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8775ddf5784e69d5d090d7490b1f3e1e8f46bbbb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@8775ddf5784e69d5d090d7490b1f3e1e8f46bbbb",
"title": "8775ddf5784e69d5d090d7490b1f3e1e8f46bbbb",
"url": "https://chromium.googlesource.com/chromium/src/+/8775ddf5784e69d5d090d7490b1f3e1e8f46bbbb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9eaf0f17b0bf7dfed8a69653befa6d4d1f4445f9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@9eaf0f17b0bf7dfed8a69653befa6d4d1f4445f9",
"title": "9eaf0f17b0bf7dfed8a69653befa6d4d1f4445f9",
"url": "https://chromium.googlesource.com/chromium/src/+/9eaf0f17b0bf7dfed8a69653befa6d4d1f4445f9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4764e0066942f8d6bbcd370b6bd9c052e8b44508"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@4764e0066942f8d6bbcd370b6bd9c052e8b44508",
"title": "4764e0066942f8d6bbcd370b6bd9c052e8b44508",
"url": "https://chromium.googlesource.com/chromium/src/+/4764e0066942f8d6bbcd370b6bd9c052e8b44508"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "31d25d07107d9f19e21eef1ae0b754e6c704693e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@31d25d07107d9f19e21eef1ae0b754e6c704693e",
"title": "31d25d07107d9f19e21eef1ae0b754e6c704693e",
"url": "https://chromium.googlesource.com/chromium/src/+/31d25d07107d9f19e21eef1ae0b754e6c704693e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b27517a587d64f6a31aaae3b7e5013566b07337d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@b27517a587d64f6a31aaae3b7e5013566b07337d",
"title": "b27517a587d64f6a31aaae3b7e5013566b07337d",
"url": "https://chromium.googlesource.com/chromium/src/+/b27517a587d64f6a31aaae3b7e5013566b07337d"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b27517a587d64f6a31aaae3b7e5013566b07337d",
"ref": "refs/branch-heads/7632"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8857228142381529728"
},
{
"key": "scheduler_job_id",
"value": "chromium-m145/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:00:29.908] Scheduled build:
{
"id": "8691798080457613777",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"number": 61,
"createdBy": "project:chromium-m145",
"createTime": "2026-01-24T11:00:29.486057942Z",
"updateTime": "2026-01-24T11:00:29.486057942Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b27517a587d64f6a31aaae3b7e5013566b07337d",
"ref": "refs/branch-heads/7632"
}
},
"canary": true
}
[11:00:29.908] Task URL: https://cr-buildbucket.appspot.com/build/8691798080457613777
[11:00:29.908] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:2:0) after 7m36s
[11:00:30.308] Received PubSub notification, asking Buildbucket for the build status
[11:00:30.333] Build status: SCHEDULED
[11:00:34.895] Received PubSub notification, asking Buildbucket for the build status
[11:00:34.926] Build status: STARTED
[11:08:06.352] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:2:0)
[11:08:06.382] Build status: STARTED
[11:08:06.382] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:5:0) after 4m15s
[11:12:21.433] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:5:0)
[11:12:21.451] Build status: STARTED
[11:12:21.451] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:6:0) after 6m23s
[11:18:44.556] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:6:0)
[11:18:44.637] Build status: STARTED
[11:18:44.637] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:7:0) after 6m17s
[11:25:01.822] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:7:0)
[11:25:01.887] Build status: STARTED
[11:25:01.887] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:8:0) after 3m45s
[11:28:46.903] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:8:0)
[11:28:47.052] Build status: STARTED
[11:28:47.052] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:9:0) after 8m14s
[11:37:01.319] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:9:0)
[11:37:01.386] Build status: STARTED
[11:37:01.386] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:10:0) after 7m54s
[11:44:55.758] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:10:0)
[11:44:55.778] Build status: STARTED
[11:44:55.778] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:11:0) after 5m25s
[11:50:20.794] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:11:0)
[11:50:20.811] Build status: STARTED
[11:50:20.811] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:12:0) after 1m14s
[11:51:34.997] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:12:0)
[11:51:35.019] Build status: STARTED
[11:51:35.019] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:13:0) after 5m25s
[11:57:00.244] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:13:0)
[11:57:00.257] Build status: STARTED
[11:57:00.257] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:14:0) after 9m1s
[12:06:01.304] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:14:0)
[12:06:01.327] Build status: STARTED
[12:06:01.327] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:15:0) after 2m1s
[12:08:02.432] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:15:0)
[12:08:02.460] Build status: STARTED
[12:08:02.460] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:16:0) after 7m56s
[12:15:58.544] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:16:0)
[12:15:58.595] Build status: STARTED
[12:15:58.595] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:17:0) after 6m29s
[12:22:27.612] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:17:0)
[12:22:27.675] Build status: STARTED
[12:22:27.675] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:18:0) after 6m44s
[12:29:11.849] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:18:0)
[12:29:11.934] Build status: STARTED
[12:29:11.934] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:19:0) after 6m37s
[12:35:49.241] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:19:0)
[12:35:49.264] Build status: STARTED
[12:35:49.264] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:20:0) after 6m27s
[12:42:16.277] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:20:0)
[12:42:16.292] Build status: STARTED
[12:42:16.292] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:21:0) after 5m51s
[12:48:07.309] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:21:0)
[12:48:07.325] Build status: STARTED
[12:48:07.325] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:22:0) after 2m57s
[12:51:04.342] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:22:0)
[12:51:04.366] Build status: STARTED
[12:51:04.366] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:23:0) after 7m30s
[12:58:34.510] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:23:0)
[12:58:34.529] Build status: STARTED
[12:58:34.529] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:24:0) after 9m43s
[13:08:17.686] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:24:0)
[13:08:17.722] Build status: STARTED
[13:08:17.722] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:25:0) after 4m30s
[13:12:47.763] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:25:0)
[13:12:47.812] Build status: STARTED
[13:12:47.812] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:26:0) after 2m27s
[13:15:14.827] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:26:0)
[13:15:14.855] Build status: STARTED
[13:15:14.855] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:27:0) after 7m3s
[13:22:17.873] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:27:0)
[13:22:17.892] Build status: STARTED
[13:22:17.892] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:28:0) after 2m50s
[13:25:08.021] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:28:0)
[13:25:08.037] Build status: STARTED
[13:25:08.037] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:29:0) after 8m37s
[13:33:45.104] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:29:0)
[13:33:45.131] Build status: STARTED
[13:33:45.131] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:30:0) after 7m18s
[13:41:03.219] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:30:0)
[13:41:03.248] Build status: STARTED
[13:41:03.248] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:31:0) after 6m57s
[13:48:00.239] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:31:0)
[13:48:00.256] Build status: STARTED
[13:48:00.256] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:32:0) after 1m15s
[13:49:15.272] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:32:0)
[13:49:15.287] Build status: STARTED
[13:49:15.287] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:33:0) after 3m35s
[13:52:50.303] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:33:0)
[13:52:50.318] Build status: STARTED
[13:52:50.318] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:34:0) after 9m31s
[14:02:21.494] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:34:0)
[14:02:21.509] Build status: STARTED
[14:02:21.509] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:35:0) after 9m51s
[14:12:12.567] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:35:0)
[14:12:12.585] Build status: STARTED
[14:12:12.585] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:36:0) after 4m44s
[14:16:56.646] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:36:0)
[14:16:56.662] Build status: STARTED
[14:16:56.662] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:37:0) after 5m52s
[14:22:48.678] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:37:0)
[14:22:48.775] Build status: STARTED
[14:22:48.775] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:38:0) after 7m37s
[14:30:25.889] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:38:0)
[14:30:25.908] Build status: STARTED
[14:30:25.908] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:39:0) after 8m49s
[14:39:15.091] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:39:0)
[14:39:15.248] Build status: STARTED
[14:39:15.248] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8857228142381529728:40:0) after 7m57s
[14:46:24.172] Received PubSub notification, asking Buildbucket for the build status
[14:46:24.189] Build:
{
"id": "8691798080457613777",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"number": 61,
"createdBy": "project:chromium-m145",
"createTime": "2026-01-24T11:00:29.486057942Z",
"startTime": "2026-01-24T11:00:34.117698326Z",
"endTime": "2026-01-24T14:45:36.304627786Z",
"updateTime": "2026-01-24T14:45:36.304627786Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b27517a587d64f6a31aaae3b7e5013566b07337d",
"ref": "refs/branch-heads/7632"
}
},
"canary": true
}
[14:46:24.189] Invocation finished in 3h45m55.921114275s with status SUCCEEDED