[18:32:23.118] New invocation is queued and will start shortly
[18:32:25.338] Starting the invocation (attempt 1)
[18:32:25.364] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/aea0f97734ce5df09c3bf38c408e52b1fa091561"
[18:32:25.364] Popped gitiles commit info from properties and tags
[18:32:25.364] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:32:25.364] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:32:25.365] Buildbucket request:
{
"requestId": "8849227177398764208",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8849227177398764208",
"job": "chromium-m148/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "479ea81ef35eec21a74de825e78e3252911c009e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@479ea81ef35eec21a74de825e78e3252911c009e",
"title": "479ea81ef35eec21a74de825e78e3252911c009e",
"url": "https://chromium.googlesource.com/chromium/src/+/479ea81ef35eec21a74de825e78e3252911c009e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e48782b088c49d1e0c807984169fd6261ea5c509"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@e48782b088c49d1e0c807984169fd6261ea5c509",
"title": "e48782b088c49d1e0c807984169fd6261ea5c509",
"url": "https://chromium.googlesource.com/chromium/src/+/e48782b088c49d1e0c807984169fd6261ea5c509"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "522ac4d269c82f7417a2e04ac3b01d085d1571b5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@522ac4d269c82f7417a2e04ac3b01d085d1571b5",
"title": "522ac4d269c82f7417a2e04ac3b01d085d1571b5",
"url": "https://chromium.googlesource.com/chromium/src/+/522ac4d269c82f7417a2e04ac3b01d085d1571b5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2e9c31b43532fc89fbcbdb20295502e44f95782b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@2e9c31b43532fc89fbcbdb20295502e44f95782b",
"title": "2e9c31b43532fc89fbcbdb20295502e44f95782b",
"url": "https://chromium.googlesource.com/chromium/src/+/2e9c31b43532fc89fbcbdb20295502e44f95782b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6ebd8f7212de7f5529539ba605c053b23b749222"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@6ebd8f7212de7f5529539ba605c053b23b749222",
"title": "6ebd8f7212de7f5529539ba605c053b23b749222",
"url": "https://chromium.googlesource.com/chromium/src/+/6ebd8f7212de7f5529539ba605c053b23b749222"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "85091f31bbdf5882ce5ed5db1308db7304f38a39"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@85091f31bbdf5882ce5ed5db1308db7304f38a39",
"title": "85091f31bbdf5882ce5ed5db1308db7304f38a39",
"url": "https://chromium.googlesource.com/chromium/src/+/85091f31bbdf5882ce5ed5db1308db7304f38a39"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b89c106078e007696a1cd89d516fa256dc4716b8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@b89c106078e007696a1cd89d516fa256dc4716b8",
"title": "b89c106078e007696a1cd89d516fa256dc4716b8",
"url": "https://chromium.googlesource.com/chromium/src/+/b89c106078e007696a1cd89d516fa256dc4716b8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f989012ab4d458d5c5fbab300088aad466c1c0a2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@f989012ab4d458d5c5fbab300088aad466c1c0a2",
"title": "f989012ab4d458d5c5fbab300088aad466c1c0a2",
"url": "https://chromium.googlesource.com/chromium/src/+/f989012ab4d458d5c5fbab300088aad466c1c0a2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "aea0f97734ce5df09c3bf38c408e52b1fa091561"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@aea0f97734ce5df09c3bf38c408e52b1fa091561",
"title": "aea0f97734ce5df09c3bf38c408e52b1fa091561",
"url": "https://chromium.googlesource.com/chromium/src/+/aea0f97734ce5df09c3bf38c408e52b1fa091561"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "aea0f97734ce5df09c3bf38c408e52b1fa091561",
"ref": "refs/branch-heads/7778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8849227177398764208"
},
{
"key": "scheduler_job_id",
"value": "chromium-m148/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:32:25.851] Scheduled build:
{
"id": "8683797114295885697",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win32-official"
},
"number": 87,
"createdBy": "project:chromium-m148",
"createTime": "2026-04-22T18:32:25.421301300Z",
"updateTime": "2026-04-22T18:32:25.421301300Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "aea0f97734ce5df09c3bf38c408e52b1fa091561",
"ref": "refs/branch-heads/7778"
}
}
}
[18:32:25.851] Task URL: https://cr-buildbucket.appspot.com/build/8683797114295885697
[18:32:25.851] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:2:0) after 6m44s
[18:32:26.811] Received PubSub notification, asking Buildbucket for the build status
[18:32:26.836] Build status: SCHEDULED
[18:33:59.564] Received PubSub notification, asking Buildbucket for the build status
[18:33:59.639] Build status: STARTED
[18:39:09.926] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:2:0)
[18:39:09.953] Build status: STARTED
[18:39:09.953] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:5:0) after 6m10s
[18:45:20.128] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:5:0)
[18:45:20.144] Build status: STARTED
[18:45:20.144] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:6:0) after 1m57s
[18:47:17.253] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:6:0)
[18:47:17.271] Build status: STARTED
[18:47:17.272] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:7:0) after 5m32s
[18:52:49.287] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:7:0)
[18:52:49.403] Build status: STARTED
[18:52:49.403] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:8:0) after 4m7s
[18:56:56.715] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:8:0)
[18:56:56.736] Build status: STARTED
[18:56:56.736] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:9:0) after 6m59s
[19:03:55.830] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:9:0)
[19:03:55.960] Build status: STARTED
[19:03:55.960] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:10:0) after 2m27s
[19:06:23.136] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:10:0)
[19:06:23.163] Build status: STARTED
[19:06:23.163] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:11:0) after 7m18s
[19:13:41.231] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:11:0)
[19:13:41.255] Build status: STARTED
[19:13:41.256] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:12:0) after 2m22s
[19:16:03.226] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:12:0)
[19:16:03.247] Build status: STARTED
[19:16:03.247] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:13:0) after 5m16s
[19:21:19.302] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:13:0)
[19:21:19.330] Build status: STARTED
[19:21:19.330] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:14:0) after 4m12s
[19:25:31.344] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:14:0)
[19:25:31.362] Build status: STARTED
[19:25:31.362] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:15:0) after 3m35s
[19:29:06.427] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:15:0)
[19:29:06.449] Build status: STARTED
[19:29:06.449] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:16:0) after 8m45s
[19:37:51.448] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:16:0)
[19:37:51.474] Build status: STARTED
[19:37:51.474] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:17:0) after 7m19s
[19:45:10.748] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:17:0)
[19:45:10.800] Build status: STARTED
[19:45:10.800] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:18:0) after 5m4s
[19:50:14.880] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:18:0)
[19:50:14.941] Build status: STARTED
[19:50:14.941] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:19:0) after 3m3s
[19:53:17.956] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:19:0)
[19:53:17.977] Build status: STARTED
[19:53:17.977] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:20:0) after 1m37s
[19:54:55.037] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:20:0)
[19:54:55.060] Build status: STARTED
[19:54:55.060] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:21:0) after 7m5s
[20:02:00.074] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:21:0)
[20:02:00.092] Build status: STARTED
[20:02:00.092] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:22:0) after 4m47s
[20:06:47.108] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:22:0)
[20:06:47.129] Build status: STARTED
[20:06:47.129] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:23:0) after 2m53s
[20:09:40.238] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:23:0)
[20:09:40.258] Build status: STARTED
[20:09:40.258] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:24:0) after 7m16s
[20:16:56.276] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:24:0)
[20:16:56.294] Build status: STARTED
[20:16:56.294] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:25:0) after 3m54s
[20:20:50.375] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:25:0)
[20:20:50.391] Build status: STARTED
[20:20:50.391] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:26:0) after 7m33s
[20:28:23.389] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:26:0)
[20:28:23.524] Build status: STARTED
[20:28:23.524] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:27:0) after 9m0s
[20:37:23.536] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:27:0)
[20:37:23.559] Build status: STARTED
[20:37:23.559] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:28:0) after 1m8s
[20:38:31.577] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:28:0)
[20:38:31.604] Build status: STARTED
[20:38:31.604] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:29:0) after 2m47s
[20:41:18.623] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:29:0)
[20:41:18.648] Build status: STARTED
[20:41:18.648] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:30:0) after 3m49s
[20:45:07.762] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:30:0)
[20:45:07.791] Build status: STARTED
[20:45:07.791] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:31:0) after 1m32s
[20:46:39.822] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:31:0)
[20:46:39.878] Build status: STARTED
[20:46:39.878] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:32:0) after 7m45s
[20:54:24.902] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:32:0)
[20:54:24.928] Build status: STARTED
[20:54:24.928] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:33:0) after 3m57s
[20:58:22.219] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:33:0)
[20:58:22.243] Build status: STARTED
[20:58:22.243] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:34:0) after 9m56s
[21:08:18.262] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:34:0)
[21:08:18.283] Build status: STARTED
[21:08:18.283] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:35:0) after 7m54s
[21:16:12.358] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:35:0)
[21:16:12.378] Build status: STARTED
[21:16:12.378] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:36:0) after 9m56s
[21:26:08.421] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:36:0)
[21:26:08.557] Build status: STARTED
[21:26:08.557] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:37:0) after 4m30s
[21:30:38.637] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:37:0)
[21:30:38.679] Build status: STARTED
[21:30:38.679] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:38:0) after 1m16s
[21:31:54.777] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:38:0)
[21:31:54.835] Build status: STARTED
[21:31:54.835] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:39:0) after 7m32s
[21:39:26.981] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:39:0)
[21:39:26.999] Build status: STARTED
[21:39:26.999] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:40:0) after 6m17s
[21:45:44.098] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:40:0)
[21:45:44.116] Build status: STARTED
[21:45:44.116] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:41:0) after 6m56s
[21:52:40.145] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:41:0)
[21:52:40.164] Build status: STARTED
[21:52:40.164] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:42:0) after 6m41s
[21:59:21.299] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:42:0)
[21:59:21.319] Build status: STARTED
[21:59:21.319] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:43:0) after 7m7s
[22:06:28.406] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:43:0)
[22:06:28.431] Build status: STARTED
[22:06:28.431] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:44:0) after 8m54s
[22:15:22.437] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:44:0)
[22:15:22.461] Build status: STARTED
[22:15:22.461] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:45:0) after 2m31s
[22:17:53.574] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:45:0)
[22:17:53.594] Build status: STARTED
[22:17:53.594] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:46:0) after 6m28s
[22:24:21.607] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:46:0)
[22:24:21.628] Build status: STARTED
[22:24:21.628] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:47:0) after 3m16s
[22:27:37.646] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:47:0)
[22:27:37.667] Build status: STARTED
[22:27:37.667] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:48:0) after 6m11s
[22:33:48.690] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:48:0)
[22:33:48.728] Build status: STARTED
[22:33:48.728] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:49:0) after 5m24s
[22:39:12.741] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:49:0)
[22:39:12.771] Build status: STARTED
[22:39:12.771] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:50:0) after 9m41s
[22:48:53.807] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:50:0)
[22:48:53.827] Build status: STARTED
[22:48:53.827] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:51:0) after 7m6s
[22:55:59.816] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:51:0)
[22:55:59.838] Build status: STARTED
[22:55:59.838] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:52:0) after 1m41s
[22:57:40.863] Handling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:52:0)
[22:57:41.176] Build status: STARTED
[22:57:41.176] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win32-official:8849227177398764208:53:0) after 9m55s
[23:04:54.128] Received PubSub notification, asking Buildbucket for the build status
[23:04:54.150] Build:
{
"id": "8683797114295885697",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win32-official"
},
"number": 87,
"createdBy": "project:chromium-m148",
"createTime": "2026-04-22T18:32:25.421301300Z",
"startTime": "2026-04-22T18:33:57.529315848Z",
"endTime": "2026-04-22T23:04:53.535605288Z",
"updateTime": "2026-04-22T23:04:53.535605288Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "aea0f97734ce5df09c3bf38c408e52b1fa091561",
"ref": "refs/branch-heads/7778"
}
}
}
[23:04:54.150] Invocation finished in 4h32m31.072422484s with status SUCCEEDED