[17:53:50.739] New invocation is queued and will start shortly
[17:53:52.764] Starting the invocation (attempt 1)
[17:53:52.791] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/04a2e5529db5672df4ea3381f7efc13f7fc99efc"
[17:53:52.791] Popped gitiles commit info from properties and tags
[17:53:52.791] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:53:52.791] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:53:52.792] Buildbucket request:
{
"requestId": "8851856914091394912",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8851856914091394912",
"job": "chromium-m146/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ef7f799729a0fde7f20d7cf8bd52aecbebaa92f5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@ef7f799729a0fde7f20d7cf8bd52aecbebaa92f5",
"title": "ef7f799729a0fde7f20d7cf8bd52aecbebaa92f5",
"url": "https://chromium.googlesource.com/chromium/src/+/ef7f799729a0fde7f20d7cf8bd52aecbebaa92f5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4767b9da59c06ef988473e1738d504eced31a1de"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@4767b9da59c06ef988473e1738d504eced31a1de",
"title": "4767b9da59c06ef988473e1738d504eced31a1de",
"url": "https://chromium.googlesource.com/chromium/src/+/4767b9da59c06ef988473e1738d504eced31a1de"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f9d45f535b1f69b26f8d1d34f82737aa8d86f5b6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@f9d45f535b1f69b26f8d1d34f82737aa8d86f5b6",
"title": "f9d45f535b1f69b26f8d1d34f82737aa8d86f5b6",
"url": "https://chromium.googlesource.com/chromium/src/+/f9d45f535b1f69b26f8d1d34f82737aa8d86f5b6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8878bf91337d910dd69228a99ce0a86e1bbb0f9c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@8878bf91337d910dd69228a99ce0a86e1bbb0f9c",
"title": "8878bf91337d910dd69228a99ce0a86e1bbb0f9c",
"url": "https://chromium.googlesource.com/chromium/src/+/8878bf91337d910dd69228a99ce0a86e1bbb0f9c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f2cd2590073228070e291a05025ffe760937db77"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@f2cd2590073228070e291a05025ffe760937db77",
"title": "f2cd2590073228070e291a05025ffe760937db77",
"url": "https://chromium.googlesource.com/chromium/src/+/f2cd2590073228070e291a05025ffe760937db77"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4f86b2046d1a29303a5e6ef6f06b65e945ab9420"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@4f86b2046d1a29303a5e6ef6f06b65e945ab9420",
"title": "4f86b2046d1a29303a5e6ef6f06b65e945ab9420",
"url": "https://chromium.googlesource.com/chromium/src/+/4f86b2046d1a29303a5e6ef6f06b65e945ab9420"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "aa7e7987b026ab8de4ec68a6e1b01a83091a5d28"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@aa7e7987b026ab8de4ec68a6e1b01a83091a5d28",
"title": "aa7e7987b026ab8de4ec68a6e1b01a83091a5d28",
"url": "https://chromium.googlesource.com/chromium/src/+/aa7e7987b026ab8de4ec68a6e1b01a83091a5d28"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1d1c729d3b6a9663f61deb36700c2e4531596e75"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@1d1c729d3b6a9663f61deb36700c2e4531596e75",
"title": "1d1c729d3b6a9663f61deb36700c2e4531596e75",
"url": "https://chromium.googlesource.com/chromium/src/+/1d1c729d3b6a9663f61deb36700c2e4531596e75"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8e1b8c9da50721036f963c70f2b47cdef630bbef"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@8e1b8c9da50721036f963c70f2b47cdef630bbef",
"title": "8e1b8c9da50721036f963c70f2b47cdef630bbef",
"url": "https://chromium.googlesource.com/chromium/src/+/8e1b8c9da50721036f963c70f2b47cdef630bbef"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "57cc8839dde18bae6fdcd0853bd12b4850aa14fc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@57cc8839dde18bae6fdcd0853bd12b4850aa14fc",
"title": "57cc8839dde18bae6fdcd0853bd12b4850aa14fc",
"url": "https://chromium.googlesource.com/chromium/src/+/57cc8839dde18bae6fdcd0853bd12b4850aa14fc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "14a0b950aff33291cc006395ab20fd5126d3b2b5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@14a0b950aff33291cc006395ab20fd5126d3b2b5",
"title": "14a0b950aff33291cc006395ab20fd5126d3b2b5",
"url": "https://chromium.googlesource.com/chromium/src/+/14a0b950aff33291cc006395ab20fd5126d3b2b5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7680",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "04a2e5529db5672df4ea3381f7efc13f7fc99efc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7680@04a2e5529db5672df4ea3381f7efc13f7fc99efc",
"title": "04a2e5529db5672df4ea3381f7efc13f7fc99efc",
"url": "https://chromium.googlesource.com/chromium/src/+/04a2e5529db5672df4ea3381f7efc13f7fc99efc"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "04a2e5529db5672df4ea3381f7efc13f7fc99efc",
"ref": "refs/branch-heads/7680"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8851856914091394912"
},
{
"key": "scheduler_job_id",
"value": "chromium-m146/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:53:53.621] Scheduled build:
{
"id": "8686426851232762689",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win-official"
},
"number": 321,
"createdBy": "project:chromium-m146",
"createTime": "2026-03-24T17:53:52.845922319Z",
"updateTime": "2026-03-24T17:53:52.845922319Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "04a2e5529db5672df4ea3381f7efc13f7fc99efc",
"ref": "refs/branch-heads/7680"
}
},
"canary": true
}
[17:53:53.621] Task URL: https://cr-buildbucket.appspot.com/build/8686426851232762689
[17:53:53.621] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:2:0) after 2m19s
[17:53:54.424] Received PubSub notification, asking Buildbucket for the build status
[17:53:54.453] Build status: SCHEDULED
[17:53:57.674] Received PubSub notification, asking Buildbucket for the build status
[17:53:57.701] Build status: STARTED
[17:56:12.761] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:2:0)
[17:56:12.780] Build status: STARTED
[17:56:12.780] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:5:0) after 7m3s
[18:03:15.897] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:5:0)
[18:03:15.916] Build status: STARTED
[18:03:15.916] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:6:0) after 4m38s
[18:07:53.935] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:6:0)
[18:07:53.952] Build status: STARTED
[18:07:53.952] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:7:0) after 7m40s
[18:15:33.925] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:7:0)
[18:15:33.971] Build status: STARTED
[18:15:33.971] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:8:0) after 2m6s
[18:17:39.946] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:8:0)
[18:17:39.965] Build status: STARTED
[18:17:39.965] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:9:0) after 1m35s
[18:19:14.954] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:9:0)
[18:19:14.977] Build status: STARTED
[18:19:14.978] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:10:0) after 4m7s
[18:23:22.222] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:10:0)
[18:23:22.244] Build status: STARTED
[18:23:22.244] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:11:0) after 3m33s
[18:26:55.260] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:11:0)
[18:26:55.281] Build status: STARTED
[18:26:55.281] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:12:0) after 9m45s
[18:36:40.383] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:12:0)
[18:36:40.411] Build status: STARTED
[18:36:40.411] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:13:0) after 3m57s
[18:40:37.523] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:13:0)
[18:40:37.535] Build status: STARTED
[18:40:37.535] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:14:0) after 5m2s
[18:45:39.706] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:14:0)
[18:45:39.786] Build status: STARTED
[18:45:39.786] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:15:0) after 2m37s
[18:48:16.813] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:15:0)
[18:48:16.829] Build status: STARTED
[18:48:16.829] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:16:0) after 2m15s
[18:50:31.820] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:16:0)
[18:50:31.891] Build status: STARTED
[18:50:31.891] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:17:0) after 4m20s
[18:54:51.958] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:17:0)
[18:54:51.978] Build status: STARTED
[18:54:51.978] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:18:0) after 6m15s
[19:01:07.084] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:18:0)
[19:01:07.107] Build status: STARTED
[19:01:07.107] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:19:0) after 5m0s
[19:06:07.247] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:19:0)
[19:06:07.270] Build status: STARTED
[19:06:07.270] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:20:0) after 5m28s
[19:11:35.431] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:20:0)
[19:11:35.455] Build status: STARTED
[19:11:35.455] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:21:0) after 4m42s
[19:16:17.456] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:21:0)
[19:16:17.472] Build status: STARTED
[19:16:17.473] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:22:0) after 4m40s
[19:20:57.541] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:22:0)
[19:20:57.565] Build status: STARTED
[19:20:57.565] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:23:0) after 1m34s
[19:22:31.630] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:23:0)
[19:22:31.648] Build status: STARTED
[19:22:31.648] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:24:0) after 2m43s
[19:25:14.815] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:24:0)
[19:25:14.846] Build status: STARTED
[19:25:14.846] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:25:0) after 9m9s
[19:34:23.912] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:25:0)
[19:34:23.931] Build status: STARTED
[19:34:23.931] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:26:0) after 1m48s
[19:36:12.001] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:26:0)
[19:36:12.059] Build status: STARTED
[19:36:12.059] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:27:0) after 7m8s
[19:43:20.228] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:27:0)
[19:43:20.246] Build status: STARTED
[19:43:20.246] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:28:0) after 3m3s
[19:46:23.305] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:28:0)
[19:46:23.324] Build status: STARTED
[19:46:23.324] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:29:0) after 9m24s
[19:55:47.341] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:29:0)
[19:55:47.360] Build status: STARTED
[19:55:47.360] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:30:0) after 6m20s
[20:02:07.413] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:30:0)
[20:02:07.432] Build status: STARTED
[20:02:07.433] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:31:0) after 5m30s
[20:07:37.459] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:31:0)
[20:07:37.490] Build status: STARTED
[20:07:37.491] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:32:0) after 5m1s
[20:12:38.501] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:32:0)
[20:12:38.528] Build status: STARTED
[20:12:38.528] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:33:0) after 8m59s
[20:21:37.548] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:33:0)
[20:21:37.602] Build status: STARTED
[20:21:37.602] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:34:0) after 4m17s
[20:25:54.616] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:34:0)
[20:25:54.674] Build status: STARTED
[20:25:54.674] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:35:0) after 4m17s
[20:30:11.703] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:35:0)
[20:30:11.725] Build status: STARTED
[20:30:11.725] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:36:0) after 1m58s
[20:32:09.877] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:36:0)
[20:32:09.913] Build status: STARTED
[20:32:09.913] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:37:0) after 4m38s
[20:36:47.930] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:37:0)
[20:36:47.956] Build status: STARTED
[20:36:47.956] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:38:0) after 8m41s
[20:45:29.017] Handling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:38:0)
[20:45:29.037] Build status: STARTED
[20:45:29.037] Scheduling timer "check-buildbucket-build-status" (chromium-m146/win-official:8851856914091394912:39:0) after 8m18s
[20:45:52.234] Received PubSub notification, asking Buildbucket for the build status
[20:45:52.282] Build:
{
"id": "8686426851232762689",
"builder": {
"project": "chromium-m146",
"bucket": "ci",
"builder": "win-official"
},
"number": 321,
"createdBy": "project:chromium-m146",
"createTime": "2026-03-24T17:53:52.845922319Z",
"startTime": "2026-03-24T17:53:57.497978152Z",
"endTime": "2026-03-24T20:45:34.410957718Z",
"updateTime": "2026-03-24T20:45:34.410957718Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "04a2e5529db5672df4ea3381f7efc13f7fc99efc",
"ref": "refs/branch-heads/7680"
}
},
"canary": true
}
[20:45:52.282] Invocation finished in 2h52m1.546452814s with status SUCCEEDED