[21:08:10.637] New invocation is queued and will start shortly
[21:08:12.904] Starting the invocation (attempt 1)
[21:08:12.932] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ba5019a39f287be8d3b0b03409cc0f7aba154c1"
[21:08:12.932] Popped gitiles commit info from properties and tags
[21:08:12.932] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:08:12.932] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:08:12.933] Buildbucket request:
{
"requestId": "8845865288021864656",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8845865288021864656",
"job": "chromium-m149/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "494a2b81f48de79fbbffad10deba35a1dc657daa"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@494a2b81f48de79fbbffad10deba35a1dc657daa",
"title": "494a2b81f48de79fbbffad10deba35a1dc657daa",
"url": "https://chromium.googlesource.com/chromium/src/+/494a2b81f48de79fbbffad10deba35a1dc657daa"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "43f93dd80062e08592d32f9998bb2f56288f45c5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@43f93dd80062e08592d32f9998bb2f56288f45c5",
"title": "43f93dd80062e08592d32f9998bb2f56288f45c5",
"url": "https://chromium.googlesource.com/chromium/src/+/43f93dd80062e08592d32f9998bb2f56288f45c5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8f406965edcd2c965f57bdfb379e24d70c0f98ba"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@8f406965edcd2c965f57bdfb379e24d70c0f98ba",
"title": "8f406965edcd2c965f57bdfb379e24d70c0f98ba",
"url": "https://chromium.googlesource.com/chromium/src/+/8f406965edcd2c965f57bdfb379e24d70c0f98ba"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0d7b4f0bf16edfb58d1b64a74143720895c96680"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@0d7b4f0bf16edfb58d1b64a74143720895c96680",
"title": "0d7b4f0bf16edfb58d1b64a74143720895c96680",
"url": "https://chromium.googlesource.com/chromium/src/+/0d7b4f0bf16edfb58d1b64a74143720895c96680"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1cf08f8a9501e5c8af3285b685e5ecc9460b5c63"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@1cf08f8a9501e5c8af3285b685e5ecc9460b5c63",
"title": "1cf08f8a9501e5c8af3285b685e5ecc9460b5c63",
"url": "https://chromium.googlesource.com/chromium/src/+/1cf08f8a9501e5c8af3285b685e5ecc9460b5c63"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3bdb696b50af07a4fd0a1bba2977d753b15bb02a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@3bdb696b50af07a4fd0a1bba2977d753b15bb02a",
"title": "3bdb696b50af07a4fd0a1bba2977d753b15bb02a",
"url": "https://chromium.googlesource.com/chromium/src/+/3bdb696b50af07a4fd0a1bba2977d753b15bb02a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5f537d76e3b5ec13031b090b5e4c963b1dff815e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@5f537d76e3b5ec13031b090b5e4c963b1dff815e",
"title": "5f537d76e3b5ec13031b090b5e4c963b1dff815e",
"url": "https://chromium.googlesource.com/chromium/src/+/5f537d76e3b5ec13031b090b5e4c963b1dff815e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d933ea7eb2b36863733abb62063ae81ebd7f3f76"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@d933ea7eb2b36863733abb62063ae81ebd7f3f76",
"title": "d933ea7eb2b36863733abb62063ae81ebd7f3f76",
"url": "https://chromium.googlesource.com/chromium/src/+/d933ea7eb2b36863733abb62063ae81ebd7f3f76"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0aa3fa0b1c2303b58f198f2465331b608467ca9d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@0aa3fa0b1c2303b58f198f2465331b608467ca9d",
"title": "0aa3fa0b1c2303b58f198f2465331b608467ca9d",
"url": "https://chromium.googlesource.com/chromium/src/+/0aa3fa0b1c2303b58f198f2465331b608467ca9d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c3bc7cc69315d8b2cef2ace2a07ce27ab0327971"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@c3bc7cc69315d8b2cef2ace2a07ce27ab0327971",
"title": "c3bc7cc69315d8b2cef2ace2a07ce27ab0327971",
"url": "https://chromium.googlesource.com/chromium/src/+/c3bc7cc69315d8b2cef2ace2a07ce27ab0327971"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4ba5019a39f287be8d3b0b03409cc0f7aba154c1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@4ba5019a39f287be8d3b0b03409cc0f7aba154c1",
"title": "4ba5019a39f287be8d3b0b03409cc0f7aba154c1",
"url": "https://chromium.googlesource.com/chromium/src/+/4ba5019a39f287be8d3b0b03409cc0f7aba154c1"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4ba5019a39f287be8d3b0b03409cc0f7aba154c1",
"ref": "refs/branch-heads/7827"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8845865288021864656"
},
{
"key": "scheduler_job_id",
"value": "chromium-m149/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:08:13.608] Scheduled build:
{
"id": "8680435224835173361",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win-official"
},
"number": 181,
"createdBy": "project:chromium-m149",
"createTime": "2026-05-29T21:08:13.054300824Z",
"updateTime": "2026-05-29T21:08:13.054300824Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4ba5019a39f287be8d3b0b03409cc0f7aba154c1",
"ref": "refs/branch-heads/7827"
}
}
}
[21:08:13.608] Task URL: https://cr-buildbucket.appspot.com/build/8680435224835173361
[21:08:13.608] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:2:0) after 7m24s
[21:08:15.484] Received PubSub notification, asking Buildbucket for the build status
[21:08:15.507] Build status: SCHEDULED
[21:09:44.454] Received PubSub notification, asking Buildbucket for the build status
[21:09:44.476] Build status: STARTED
[21:15:37.672] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:2:0)
[21:15:37.695] Build status: STARTED
[21:15:37.695] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:5:0) after 8m35s
[21:24:12.802] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:5:0)
[21:24:12.843] Build status: STARTED
[21:24:12.843] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:6:0) after 2m32s
[21:26:44.964] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:6:0)
[21:26:44.985] Build status: STARTED
[21:26:44.985] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:7:0) after 5m23s
[21:32:08.039] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:7:0)
[21:32:08.101] Build status: STARTED
[21:32:08.101] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:8:0) after 6m17s
[21:38:25.737] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:8:0)
[21:38:25.752] Build status: STARTED
[21:38:25.752] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:9:0) after 1m50s
[21:40:15.720] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:9:0)
[21:40:15.738] Build status: STARTED
[21:40:15.738] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:10:0) after 7m19s
[21:47:34.761] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:10:0)
[21:47:34.784] Build status: STARTED
[21:47:34.785] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:11:0) after 6m45s
[21:54:19.869] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:11:0)
[21:54:19.888] Build status: STARTED
[21:54:19.888] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:12:0) after 9m36s
[22:03:55.878] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:12:0)
[22:03:55.894] Build status: STARTED
[22:03:55.895] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:13:0) after 3m8s
[22:07:03.898] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:13:0)
[22:07:03.919] Build status: STARTED
[22:07:03.919] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:14:0) after 7m18s
[22:14:21.971] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:14:0)
[22:14:21.987] Build status: STARTED
[22:14:21.987] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:15:0) after 4m5s
[22:18:27.002] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:15:0)
[22:18:27.020] Build status: STARTED
[22:18:27.020] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:16:0) after 8m48s
[22:27:15.073] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:16:0)
[22:27:15.091] Build status: STARTED
[22:27:15.091] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:17:0) after 3m43s
[22:30:58.227] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:17:0)
[22:30:58.243] Build status: STARTED
[22:30:58.243] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:18:0) after 6m39s
[22:37:37.263] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:18:0)
[22:37:37.278] Build status: STARTED
[22:37:37.278] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:19:0) after 3m28s
[22:41:05.335] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:19:0)
[22:41:05.384] Build status: STARTED
[22:41:05.384] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:20:0) after 5m32s
[22:46:37.481] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:20:0)
[22:46:37.508] Build status: STARTED
[22:46:37.509] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:21:0) after 7m49s
[22:54:26.549] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:21:0)
[22:54:26.564] Build status: STARTED
[22:54:26.564] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:22:0) after 2m46s
[22:57:12.663] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:22:0)
[22:57:12.679] Build status: STARTED
[22:57:12.679] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:23:0) after 3m0s
[23:00:12.797] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:23:0)
[23:00:12.822] Build status: STARTED
[23:00:12.822] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:24:0) after 8m25s
[23:08:37.791] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:24:0)
[23:08:37.805] Build status: STARTED
[23:08:37.805] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:25:0) after 8m6s
[23:16:43.987] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:25:0)
[23:16:44.021] Build status: STARTED
[23:16:44.021] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:26:0) after 8m41s
[23:25:25.223] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:26:0)
[23:25:25.251] Build status: STARTED
[23:25:25.251] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:27:0) after 6m15s
[23:31:40.266] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:27:0)
[23:31:40.285] Build status: STARTED
[23:31:40.286] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:28:0) after 8m8s
[23:39:48.351] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:28:0)
[23:39:48.375] Build status: STARTED
[23:39:48.375] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:29:0) after 2m28s
[23:42:16.494] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:29:0)
[23:42:16.574] Build status: STARTED
[23:42:16.574] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:30:0) after 8m10s
[23:50:26.639] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:30:0)
[23:50:26.656] Build status: STARTED
[23:50:26.656] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:31:0) after 7m0s
[23:57:26.725] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:31:0)
[23:57:26.807] Build status: STARTED
[23:57:26.807] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:32:0) after 9m46s
[00:07:12.828] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:32:0)
[00:07:12.842] Build status: STARTED
[00:07:12.842] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845865288021864656:33:0) after 7m23s
[00:07:13.475] Received PubSub notification, asking Buildbucket for the build status
[00:07:13.508] Build:
{
"id": "8680435224835173361",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win-official"
},
"number": 181,
"createdBy": "project:chromium-m149",
"createTime": "2026-05-29T21:08:13.054300824Z",
"startTime": "2026-05-29T21:09:44.289926964Z",
"endTime": "2026-05-30T00:07:13.009682267Z",
"updateTime": "2026-05-30T00:07:13.009682267Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4ba5019a39f287be8d3b0b03409cc0f7aba154c1",
"ref": "refs/branch-heads/7827"
}
}
}
[00:07:13.508] Invocation finished in 2h59m2.877298479s with status SUCCEEDED