[08:15:54.495] New invocation is queued and will start shortly
[08:15:55.807] Starting the invocation (attempt 1)
[08:15:55.840] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b972eb1bb0412c3ab1e917d6625ded26b748d0f8"
[08:15:55.840] Popped gitiles commit info from properties and tags
[08:15:55.840] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:15:55.840] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:15:55.840] Buildbucket request:
{
"requestId": "8853977004998694800",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8853977004998694800",
"job": "chromium-m145/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0a30823d1bcb415cb4a30947bbe6892294666b2e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@0a30823d1bcb415cb4a30947bbe6892294666b2e",
"title": "0a30823d1bcb415cb4a30947bbe6892294666b2e",
"url": "https://chromium.googlesource.com/chromium/src/+/0a30823d1bcb415cb4a30947bbe6892294666b2e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "024a02fe184d85720adc0b62046717ad1ca2463c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@024a02fe184d85720adc0b62046717ad1ca2463c",
"title": "024a02fe184d85720adc0b62046717ad1ca2463c",
"url": "https://chromium.googlesource.com/chromium/src/+/024a02fe184d85720adc0b62046717ad1ca2463c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "52cd7658cbf9c1911a8317d861daba8ec3d8554f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@52cd7658cbf9c1911a8317d861daba8ec3d8554f",
"title": "52cd7658cbf9c1911a8317d861daba8ec3d8554f",
"url": "https://chromium.googlesource.com/chromium/src/+/52cd7658cbf9c1911a8317d861daba8ec3d8554f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "aa0133588a18deb0ddb03054519ac2550f014f4c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@aa0133588a18deb0ddb03054519ac2550f014f4c",
"title": "aa0133588a18deb0ddb03054519ac2550f014f4c",
"url": "https://chromium.googlesource.com/chromium/src/+/aa0133588a18deb0ddb03054519ac2550f014f4c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1ef8144ec353315a3c39dbb6e9a1abdd1ef47c10"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@1ef8144ec353315a3c39dbb6e9a1abdd1ef47c10",
"title": "1ef8144ec353315a3c39dbb6e9a1abdd1ef47c10",
"url": "https://chromium.googlesource.com/chromium/src/+/1ef8144ec353315a3c39dbb6e9a1abdd1ef47c10"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8472c52122c714b56d703528b5e70d32aca1b6e0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@8472c52122c714b56d703528b5e70d32aca1b6e0",
"title": "8472c52122c714b56d703528b5e70d32aca1b6e0",
"url": "https://chromium.googlesource.com/chromium/src/+/8472c52122c714b56d703528b5e70d32aca1b6e0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "50eec453746f5b1fc968a5cda5c5a94fa9cb3a8c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@50eec453746f5b1fc968a5cda5c5a94fa9cb3a8c",
"title": "50eec453746f5b1fc968a5cda5c5a94fa9cb3a8c",
"url": "https://chromium.googlesource.com/chromium/src/+/50eec453746f5b1fc968a5cda5c5a94fa9cb3a8c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "349e8a8b555e00465446b68aaa8f35fcc213f363"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@349e8a8b555e00465446b68aaa8f35fcc213f363",
"title": "349e8a8b555e00465446b68aaa8f35fcc213f363",
"url": "https://chromium.googlesource.com/chromium/src/+/349e8a8b555e00465446b68aaa8f35fcc213f363"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b972eb1bb0412c3ab1e917d6625ded26b748d0f8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@b972eb1bb0412c3ab1e917d6625ded26b748d0f8",
"title": "b972eb1bb0412c3ab1e917d6625ded26b748d0f8",
"url": "https://chromium.googlesource.com/chromium/src/+/b972eb1bb0412c3ab1e917d6625ded26b748d0f8"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b972eb1bb0412c3ab1e917d6625ded26b748d0f8",
"ref": "refs/branch-heads/7632"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8853977004998694800"
},
{
"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"
}
}
[08:15:56.832] Scheduled build:
{
"id": "8688546942827958193",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"number": 268,
"createdBy": "project:chromium-m145",
"createTime": "2026-03-01T08:15:55.942100852Z",
"updateTime": "2026-03-01T08:15:55.942100852Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b972eb1bb0412c3ab1e917d6625ded26b748d0f8",
"ref": "refs/branch-heads/7632"
}
}
}
[08:15:56.832] Task URL: https://cr-buildbucket.appspot.com/build/8688546942827958193
[08:15:56.832] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:2:0) after 9m45s
[08:15:59.111] Received PubSub notification, asking Buildbucket for the build status
[08:15:59.150] Build status: SCHEDULED
[08:17:07.908] Received PubSub notification, asking Buildbucket for the build status
[08:17:07.983] Build status: STARTED
[08:25:42.182] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:2:0)
[08:25:42.203] Build status: STARTED
[08:25:42.203] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:5:0) after 7m49s
[08:33:31.268] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:5:0)
[08:33:31.287] Build status: STARTED
[08:33:31.287] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:6:0) after 5m52s
[08:39:23.355] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:6:0)
[08:39:23.381] Build status: STARTED
[08:39:23.381] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:7:0) after 6m40s
[08:46:03.402] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:7:0)
[08:46:03.418] Build status: STARTED
[08:46:03.418] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:8:0) after 7m26s
[08:53:29.669] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:8:0)
[08:53:29.695] Build status: STARTED
[08:53:29.695] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:9:0) after 6m23s
[08:59:52.711] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:9:0)
[08:59:52.736] Build status: STARTED
[08:59:52.736] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:10:0) after 9m12s
[09:09:04.955] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:10:0)
[09:09:04.984] Build status: STARTED
[09:09:04.984] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:11:0) after 7m26s
[09:16:31.087] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:11:0)
[09:16:31.123] Build status: STARTED
[09:16:31.123] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:12:0) after 2m42s
[09:19:13.115] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:12:0)
[09:19:13.152] Build status: STARTED
[09:19:13.152] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:13:0) after 6m17s
[09:25:30.200] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:13:0)
[09:25:30.229] Build status: STARTED
[09:25:30.229] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:14:0) after 2m44s
[09:28:14.248] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:14:0)
[09:28:14.266] Build status: STARTED
[09:28:14.266] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:15:0) after 1m49s
[09:30:03.403] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:15:0)
[09:30:03.484] Build status: STARTED
[09:30:03.484] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:16:0) after 9m20s
[09:39:23.583] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:16:0)
[09:39:23.606] Build status: STARTED
[09:39:23.606] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:17:0) after 5m19s
[09:44:42.600] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:17:0)
[09:44:42.615] Build status: STARTED
[09:44:42.615] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:18:0) after 4m24s
[09:49:06.732] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:18:0)
[09:49:06.751] Build status: STARTED
[09:49:06.751] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:19:0) after 7m16s
[09:56:22.767] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:19:0)
[09:56:22.805] Build status: STARTED
[09:56:22.805] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:20:0) after 6m10s
[10:02:32.819] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:20:0)
[10:02:32.839] Build status: STARTED
[10:02:32.839] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:21:0) after 5m29s
[10:08:01.879] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:21:0)
[10:08:01.903] Build status: STARTED
[10:08:01.903] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:22:0) after 4m30s
[10:12:32.186] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:22:0)
[10:12:32.219] Build status: STARTED
[10:12:32.219] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:23:0) after 5m31s
[10:18:03.235] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:23:0)
[10:18:03.255] Build status: STARTED
[10:18:03.255] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:24:0) after 5m51s
[10:23:54.276] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:24:0)
[10:23:54.289] Build status: STARTED
[10:23:54.289] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:25:0) after 7m0s
[10:30:54.303] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:25:0)
[10:30:54.331] Build status: STARTED
[10:30:54.331] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:26:0) after 7m3s
[10:37:57.405] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:26:0)
[10:37:57.422] Build status: STARTED
[10:37:57.422] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:27:0) after 2m29s
[10:40:26.449] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:27:0)
[10:40:26.469] Build status: STARTED
[10:40:26.469] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:28:0) after 4m21s
[10:44:47.485] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:28:0)
[10:44:47.501] Build status: STARTED
[10:44:47.501] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:29:0) after 2m18s
[10:47:05.654] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:29:0)
[10:47:05.673] Build status: STARTED
[10:47:05.673] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:30:0) after 4m58s
[10:52:03.714] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:30:0)
[10:52:03.742] Build status: STARTED
[10:52:03.742] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:31:0) after 3m6s
[10:55:09.758] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:31:0)
[10:55:09.773] Build status: STARTED
[10:55:09.773] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:32:0) after 3m40s
[10:58:49.870] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:32:0)
[10:58:49.900] Build status: STARTED
[10:58:49.900] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:33:0) after 3m27s
[11:02:16.926] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:33:0)
[11:02:16.954] Build status: STARTED
[11:02:16.954] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:34:0) after 5m0s
[11:07:17.014] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:34:0)
[11:07:17.043] Build status: STARTED
[11:07:17.043] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:35:0) after 5m56s
[11:13:13.200] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:35:0)
[11:13:13.221] Build status: STARTED
[11:13:13.221] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:36:0) after 6m36s
[11:19:49.292] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:36:0)
[11:19:49.363] Build status: STARTED
[11:19:49.363] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:37:0) after 8m31s
[11:28:20.480] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:37:0)
[11:28:20.497] Build status: STARTED
[11:28:20.497] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:38:0) after 1m49s
[11:30:09.512] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:38:0)
[11:30:09.528] Build status: STARTED
[11:30:09.528] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:39:0) after 8m30s
[11:38:39.542] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:39:0)
[11:38:39.638] Build status: STARTED
[11:38:39.638] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:40:0) after 5m59s
[11:44:38.788] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:40:0)
[11:44:38.810] Build status: STARTED
[11:44:38.810] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:41:0) after 9m39s
[11:54:17.828] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:41:0)
[11:54:17.852] Build status: STARTED
[11:54:17.852] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:42:0) after 1m6s
[11:55:23.872] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:42:0)
[11:55:23.929] Build status: STARTED
[11:55:23.929] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:43:0) after 1m33s
[11:56:57.042] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:43:0)
[11:56:57.199] Build status: STARTED
[11:56:57.199] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:44:0) after 1m55s
[11:58:52.215] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:44:0)
[11:58:52.229] Build status: STARTED
[11:58:52.229] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:45:0) after 4m23s
[12:03:15.275] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:45:0)
[12:03:15.296] Build status: STARTED
[12:03:15.296] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:46:0) after 6m10s
[12:09:25.613] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:46:0)
[12:09:25.628] Build status: STARTED
[12:09:25.628] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:47:0) after 3m34s
[12:12:59.724] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:47:0)
[12:12:59.740] Build status: STARTED
[12:12:59.740] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:48:0) after 9m46s
[12:22:45.961] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:48:0)
[12:22:45.976] Build status: STARTED
[12:22:45.977] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:49:0) after 4m31s
[12:27:16.995] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:49:0)
[12:27:17.009] Build status: STARTED
[12:27:17.009] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853977004998694800:50:0) after 9m44s
[12:29:55.496] Received PubSub notification, asking Buildbucket for the build status
[12:29:55.509] Build:
{
"id": "8688546942827958193",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"number": 268,
"createdBy": "project:chromium-m145",
"createTime": "2026-03-01T08:15:55.942100852Z",
"startTime": "2026-03-01T08:17:07.650282413Z",
"endTime": "2026-03-01T12:29:54.783147817Z",
"updateTime": "2026-03-01T12:29:54.783147817Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b972eb1bb0412c3ab1e917d6625ded26b748d0f8",
"ref": "refs/branch-heads/7632"
}
}
}
[12:29:55.509] Invocation finished in 4h14m1.020369045s with status SUCCEEDED