[14:54:17.006] New invocation is queued and will start shortly
[14:54:18.340] Starting the invocation (attempt 1)
[14:54:18.365] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/da17d68d8a9c464db9b78d685bee32acd556a491"
[14:54:18.365] Popped gitiles commit info from properties and tags
[14:54:18.366] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:54:18.366] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:54:18.366] Buildbucket request:
{
"requestId": "8839728217672487776",
"builder": {
"project": "chromium-m151",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8839728217672487776",
"job": "chromium-m151/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c25388a39acb763317b9662658adda9a3861527d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@c25388a39acb763317b9662658adda9a3861527d",
"title": "c25388a39acb763317b9662658adda9a3861527d",
"url": "https://chromium.googlesource.com/chromium/src/+/c25388a39acb763317b9662658adda9a3861527d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a66b382d73c31e9fb9dea5cb40525a5bb3a2210f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@a66b382d73c31e9fb9dea5cb40525a5bb3a2210f",
"title": "a66b382d73c31e9fb9dea5cb40525a5bb3a2210f",
"url": "https://chromium.googlesource.com/chromium/src/+/a66b382d73c31e9fb9dea5cb40525a5bb3a2210f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b706abf1cf6dea10a2a41a17ca1e5f5813c284d0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@b706abf1cf6dea10a2a41a17ca1e5f5813c284d0",
"title": "b706abf1cf6dea10a2a41a17ca1e5f5813c284d0",
"url": "https://chromium.googlesource.com/chromium/src/+/b706abf1cf6dea10a2a41a17ca1e5f5813c284d0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "004ffd539e37b10486536784f9b6459169701005"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@004ffd539e37b10486536784f9b6459169701005",
"title": "004ffd539e37b10486536784f9b6459169701005",
"url": "https://chromium.googlesource.com/chromium/src/+/004ffd539e37b10486536784f9b6459169701005"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6ef5a3eb2fe66693789aee5816b45b124a6de0fa"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@6ef5a3eb2fe66693789aee5816b45b124a6de0fa",
"title": "6ef5a3eb2fe66693789aee5816b45b124a6de0fa",
"url": "https://chromium.googlesource.com/chromium/src/+/6ef5a3eb2fe66693789aee5816b45b124a6de0fa"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "da17d68d8a9c464db9b78d685bee32acd556a491"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@da17d68d8a9c464db9b78d685bee32acd556a491",
"title": "da17d68d8a9c464db9b78d685bee32acd556a491",
"url": "https://chromium.googlesource.com/chromium/src/+/da17d68d8a9c464db9b78d685bee32acd556a491"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "da17d68d8a9c464db9b78d685bee32acd556a491",
"ref": "refs/branch-heads/7922"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8839728217672487776"
},
{
"key": "scheduler_job_id",
"value": "chromium-m151/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:54:18.962] Scheduled build:
{
"id": "8674298155507960097",
"builder": {
"project": "chromium-m151",
"bucket": "ci",
"builder": "win-official"
},
"number": 242,
"createdBy": "project:chromium-m151",
"createTime": "2026-08-05T14:54:18.449436116Z",
"updateTime": "2026-08-05T14:54:18.449436116Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "da17d68d8a9c464db9b78d685bee32acd556a491",
"ref": "refs/branch-heads/7922"
}
}
}
[14:54:18.962] Task URL: https://cr-buildbucket.appspot.com/build/8674298155507960097
[14:54:18.962] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:2:0) after 2m30s
[14:54:19.902] Received PubSub notification, asking Buildbucket for the build status
[14:54:19.921] Build status: SCHEDULED
[14:56:48.974] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:2:0)
[14:56:48.995] Build status: SCHEDULED
[14:56:48.995] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:4:0) after 1m44s
[14:58:33.153] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:4:0)
[14:58:33.181] Build status: SCHEDULED
[14:58:33.182] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:5:0) after 1m5s
[14:58:34.272] Received PubSub notification, asking Buildbucket for the build status
[14:58:34.293] Build status: STARTED
[14:59:38.198] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:5:0)
[14:59:38.218] Build status: STARTED
[14:59:38.218] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:7:0) after 7m46s
[15:07:24.353] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:7:0)
[15:07:24.379] Build status: STARTED
[15:07:24.379] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:8:0) after 5m49s
[15:13:13.439] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:8:0)
[15:13:13.502] Build status: STARTED
[15:13:13.502] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:9:0) after 8m37s
[15:21:50.517] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:9:0)
[15:21:50.542] Build status: STARTED
[15:21:50.542] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:10:0) after 8m35s
[15:30:25.707] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:10:0)
[15:30:25.728] Build status: STARTED
[15:30:25.728] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:11:0) after 4m42s
[15:35:07.700] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:11:0)
[15:35:07.725] Build status: STARTED
[15:35:07.725] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:12:0) after 7m37s
[15:42:44.791] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:12:0)
[15:42:44.821] Build status: STARTED
[15:42:44.821] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:13:0) after 2m35s
[15:45:19.835] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:13:0)
[15:45:19.858] Build status: STARTED
[15:45:19.858] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:14:0) after 6m36s
[15:51:55.910] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:14:0)
[15:51:55.930] Build status: STARTED
[15:51:55.930] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:15:0) after 7m33s
[15:59:28.961] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:15:0)
[15:59:28.977] Build status: STARTED
[15:59:28.977] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:16:0) after 5m3s
[16:04:32.131] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:16:0)
[16:04:32.153] Build status: STARTED
[16:04:32.153] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:17:0) after 3m13s
[16:07:45.167] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:17:0)
[16:07:45.230] Build status: STARTED
[16:07:45.230] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:18:0) after 3m33s
[16:11:18.281] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:18:0)
[16:11:18.300] Build status: STARTED
[16:11:18.301] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:19:0) after 7m52s
[16:19:10.413] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:19:0)
[16:19:10.440] Build status: STARTED
[16:19:10.440] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:20:0) after 6m32s
[16:25:42.508] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:20:0)
[16:25:42.589] Build status: STARTED
[16:25:42.589] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:21:0) after 1m35s
[16:27:17.648] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:21:0)
[16:27:17.667] Build status: STARTED
[16:27:17.667] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:22:0) after 9m34s
[16:36:51.708] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:22:0)
[16:36:51.729] Build status: STARTED
[16:36:51.729] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:23:0) after 1m50s
[16:38:41.739] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:23:0)
[16:38:41.761] Build status: STARTED
[16:38:41.761] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:24:0) after 8m15s
[16:46:56.822] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:24:0)
[16:46:56.851] Build status: STARTED
[16:46:56.851] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:25:0) after 3m41s
[16:50:37.946] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:25:0)
[16:50:37.967] Build status: STARTED
[16:50:37.967] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:26:0) after 2m41s
[16:53:19.031] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:26:0)
[16:53:19.049] Build status: STARTED
[16:53:19.049] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:27:0) after 1m53s
[16:55:12.219] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:27:0)
[16:55:12.283] Build status: STARTED
[16:55:12.283] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:28:0) after 1m57s
[16:57:09.329] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:28:0)
[16:57:09.351] Build status: STARTED
[16:57:09.351] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:29:0) after 2m11s
[16:59:20.367] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:29:0)
[16:59:20.438] Build status: STARTED
[16:59:20.438] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:30:0) after 3m8s
[17:02:28.453] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:30:0)
[17:02:28.472] Build status: STARTED
[17:02:28.472] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:31:0) after 7m17s
[17:09:45.579] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:31:0)
[17:09:45.601] Build status: STARTED
[17:09:45.602] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:32:0) after 3m17s
[17:13:02.770] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:32:0)
[17:13:02.814] Build status: STARTED
[17:13:02.814] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:33:0) after 2m3s
[17:15:05.938] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:33:0)
[17:15:05.962] Build status: STARTED
[17:15:05.962] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:34:0) after 4m33s
[17:19:39.057] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:34:0)
[17:19:39.078] Build status: STARTED
[17:19:39.078] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:35:0) after 4m2s
[17:23:41.149] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:35:0)
[17:23:41.166] Build status: STARTED
[17:23:41.166] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:36:0) after 5m55s
[17:29:36.192] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:36:0)
[17:29:36.215] Build status: STARTED
[17:29:36.215] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:37:0) after 6m27s
[17:36:03.238] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:37:0)
[17:36:03.289] Build status: STARTED
[17:36:03.289] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:38:0) after 9m18s
[17:45:21.340] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:38:0)
[17:45:21.444] Build status: STARTED
[17:45:21.444] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:39:0) after 7m10s
[17:52:31.465] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:39:0)
[17:52:31.484] Build status: STARTED
[17:52:31.484] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:40:0) after 8m22s
[18:00:53.567] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:40:0)
[18:00:53.611] Build status: STARTED
[18:00:53.611] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:41:0) after 8m3s
[18:08:56.620] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:41:0)
[18:08:56.678] Build status: STARTED
[18:08:56.678] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:42:0) after 4m18s
[18:13:14.730] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:42:0)
[18:13:14.746] Build status: STARTED
[18:13:14.746] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:43:0) after 1m59s
[18:15:13.815] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:43:0)
[18:15:13.840] Build status: STARTED
[18:15:13.840] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:44:0) after 9m35s
[18:24:48.915] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:44:0)
[18:24:48.935] Build status: STARTED
[18:24:48.935] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:45:0) after 2m39s
[18:27:28.002] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:45:0)
[18:27:28.038] Build status: STARTED
[18:27:28.039] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:46:0) after 8m17s
[18:35:45.111] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:46:0)
[18:35:45.135] Build status: STARTED
[18:35:45.135] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:47:0) after 8m29s
[18:44:14.164] Handling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:47:0)
[18:44:14.185] Build status: STARTED
[18:44:14.185] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win-official:8839728217672487776:48:0) after 7m22s
[18:47:17.032] Received PubSub notification, asking Buildbucket for the build status
[18:47:17.052] Build:
{
"id": "8674298155507960097",
"builder": {
"project": "chromium-m151",
"bucket": "ci",
"builder": "win-official"
},
"number": 242,
"createdBy": "project:chromium-m151",
"createTime": "2026-08-05T14:54:18.449436116Z",
"startTime": "2026-08-05T14:58:33.690358233Z",
"endTime": "2026-08-05T18:47:02.810341287Z",
"updateTime": "2026-08-05T18:47:02.810341287Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "da17d68d8a9c464db9b78d685bee32acd556a491",
"ref": "refs/branch-heads/7922"
}
}
}
[18:47:17.052] Invocation finished in 3h53m0.050554906s with status SUCCEEDED