[22:57:22.583] New invocation is queued and will start shortly
[22:57:23.676] Starting the invocation (attempt 1)
[22:57:23.704] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9280576e4d8b435c113ab2be586ed604cf88a176"
[22:57:23.704] Popped gitiles commit info from properties and tags
[22:57:23.704] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:57:23.704] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:57:23.705] Buildbucket request:
{
"requestId": "8845133642074332928",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8845133642074332928",
"job": "chromium-m149/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "89aa40b19cd65864f7f4aee44dc2fbd80297a8a8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@89aa40b19cd65864f7f4aee44dc2fbd80297a8a8",
"title": "89aa40b19cd65864f7f4aee44dc2fbd80297a8a8",
"url": "https://chromium.googlesource.com/chromium/src/+/89aa40b19cd65864f7f4aee44dc2fbd80297a8a8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "46df77bc65911e48f28929685f262143d1bed5cb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@46df77bc65911e48f28929685f262143d1bed5cb",
"title": "46df77bc65911e48f28929685f262143d1bed5cb",
"url": "https://chromium.googlesource.com/chromium/src/+/46df77bc65911e48f28929685f262143d1bed5cb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "eed89eaba436c85ced1c92891f5c1a81ed8bf75c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@eed89eaba436c85ced1c92891f5c1a81ed8bf75c",
"title": "eed89eaba436c85ced1c92891f5c1a81ed8bf75c",
"url": "https://chromium.googlesource.com/chromium/src/+/eed89eaba436c85ced1c92891f5c1a81ed8bf75c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9000846c25e104483a1ba85dbcfbef17fd80f45e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@9000846c25e104483a1ba85dbcfbef17fd80f45e",
"title": "9000846c25e104483a1ba85dbcfbef17fd80f45e",
"url": "https://chromium.googlesource.com/chromium/src/+/9000846c25e104483a1ba85dbcfbef17fd80f45e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5374b2e124796ac224a162ba81559dc42672969e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@5374b2e124796ac224a162ba81559dc42672969e",
"title": "5374b2e124796ac224a162ba81559dc42672969e",
"url": "https://chromium.googlesource.com/chromium/src/+/5374b2e124796ac224a162ba81559dc42672969e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7415519e1a6bf9d66087c78d134dc599204e02d3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@7415519e1a6bf9d66087c78d134dc599204e02d3",
"title": "7415519e1a6bf9d66087c78d134dc599204e02d3",
"url": "https://chromium.googlesource.com/chromium/src/+/7415519e1a6bf9d66087c78d134dc599204e02d3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e5539d8952eb6d8a2174c346054f435e973f62f7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@e5539d8952eb6d8a2174c346054f435e973f62f7",
"title": "e5539d8952eb6d8a2174c346054f435e973f62f7",
"url": "https://chromium.googlesource.com/chromium/src/+/e5539d8952eb6d8a2174c346054f435e973f62f7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9280576e4d8b435c113ab2be586ed604cf88a176"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@9280576e4d8b435c113ab2be586ed604cf88a176",
"title": "9280576e4d8b435c113ab2be586ed604cf88a176",
"url": "https://chromium.googlesource.com/chromium/src/+/9280576e4d8b435c113ab2be586ed604cf88a176"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9280576e4d8b435c113ab2be586ed604cf88a176",
"ref": "refs/branch-heads/7827"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8845133642074332928"
},
{
"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"
}
}
[22:57:24.428] Scheduled build:
{
"id": "8679703580141942769",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win-official"
},
"number": 239,
"createdBy": "project:chromium-m149",
"createTime": "2026-06-06T22:57:23.807295035Z",
"updateTime": "2026-06-06T22:57:23.807295035Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9280576e4d8b435c113ab2be586ed604cf88a176",
"ref": "refs/branch-heads/7827"
}
}
}
[22:57:24.428] Task URL: https://cr-buildbucket.appspot.com/build/8679703580141942769
[22:57:24.428] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:2:0) after 6m33s
[22:57:25.337] Received PubSub notification, asking Buildbucket for the build status
[22:57:25.363] Build status: SCHEDULED
[22:57:29.685] Received PubSub notification, asking Buildbucket for the build status
[22:57:29.711] Build status: STARTED
[23:03:57.443] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:2:0)
[23:03:57.463] Build status: STARTED
[23:03:57.463] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:5:0) after 3m56s
[23:07:53.480] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:5:0)
[23:07:53.507] Build status: STARTED
[23:07:53.507] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:6:0) after 4m56s
[23:12:49.525] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:6:0)
[23:12:49.542] Build status: STARTED
[23:12:49.542] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:7:0) after 2m37s
[23:15:26.577] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:7:0)
[23:15:26.602] Build status: STARTED
[23:15:26.602] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:8:0) after 6m59s
[23:22:25.618] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:8:0)
[23:22:25.639] Build status: STARTED
[23:22:25.639] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:9:0) after 5m24s
[23:27:49.651] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:9:0)
[23:27:49.760] Build status: STARTED
[23:27:49.761] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:10:0) after 4m15s
[23:32:04.726] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:10:0)
[23:32:04.743] Build status: STARTED
[23:32:04.744] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:11:0) after 4m42s
[23:36:46.892] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:11:0)
[23:36:46.917] Build status: STARTED
[23:36:46.917] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:12:0) after 9m48s
[23:46:34.912] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:12:0)
[23:46:34.931] Build status: STARTED
[23:46:34.931] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:13:0) after 9m1s
[23:55:35.946] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:13:0)
[23:55:35.964] Build status: STARTED
[23:55:35.964] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:14:0) after 9m5s
[00:04:41.079] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:14:0)
[00:04:41.102] Build status: STARTED
[00:04:41.103] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:15:0) after 9m41s
[00:14:22.182] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:15:0)
[00:14:22.198] Build status: STARTED
[00:14:22.198] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:16:0) after 2m32s
[00:16:54.217] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:16:0)
[00:16:54.247] Build status: STARTED
[00:16:54.247] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:17:0) after 4m53s
[00:21:47.397] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:17:0)
[00:21:47.416] Build status: STARTED
[00:21:47.416] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:18:0) after 5m17s
[00:27:04.434] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:18:0)
[00:27:04.458] Build status: STARTED
[00:27:04.458] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:19:0) after 6m54s
[00:33:58.593] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:19:0)
[00:33:58.611] Build status: STARTED
[00:33:58.611] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:20:0) after 8m27s
[00:42:25.629] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:20:0)
[00:42:25.687] Build status: STARTED
[00:42:25.687] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:21:0) after 1m7s
[00:43:32.779] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:21:0)
[00:43:32.799] Build status: STARTED
[00:43:32.800] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:22:0) after 3m49s
[00:47:22.004] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:22:0)
[00:47:22.322] Build status: STARTED
[00:47:22.322] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:23:0) after 1m34s
[00:48:56.501] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:23:0)
[00:48:56.529] Build status: STARTED
[00:48:56.529] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:24:0) after 8m48s
[00:57:44.572] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:24:0)
[00:57:44.589] Build status: STARTED
[00:57:44.589] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:25:0) after 6m41s
[01:04:25.955] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:25:0)
[01:04:25.977] Build status: STARTED
[01:04:25.977] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:26:0) after 1m14s
[01:05:40.067] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:26:0)
[01:05:40.150] Build status: STARTED
[01:05:40.150] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:27:0) after 7m11s
[01:12:51.384] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:27:0)
[01:12:51.403] Build status: STARTED
[01:12:51.403] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:28:0) after 4m9s
[01:17:00.468] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:28:0)
[01:17:00.531] Build status: STARTED
[01:17:00.531] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:29:0) after 2m44s
[01:19:44.548] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:29:0)
[01:19:44.567] Build status: STARTED
[01:19:44.567] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:30:0) after 3m27s
[01:23:11.567] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:30:0)
[01:23:11.587] Build status: STARTED
[01:23:11.587] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:31:0) after 4m44s
[01:27:55.921] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:31:0)
[01:27:55.938] Build status: STARTED
[01:27:55.938] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:32:0) after 5m25s
[01:33:21.053] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:32:0)
[01:33:21.071] Build status: STARTED
[01:33:21.071] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:33:0) after 6m44s
[01:40:05.111] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:33:0)
[01:40:05.138] Build status: STARTED
[01:40:05.138] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:34:0) after 3m53s
[01:43:58.157] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:34:0)
[01:43:58.188] Build status: STARTED
[01:43:58.188] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:35:0) after 3m21s
[01:47:19.216] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:35:0)
[01:47:19.235] Build status: STARTED
[01:47:19.235] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:36:0) after 9m13s
[01:56:32.769] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:36:0)
[01:56:32.785] Build status: STARTED
[01:56:32.785] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:37:0) after 9m3s
[02:05:36.112] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:37:0)
[02:05:36.127] Build status: STARTED
[02:05:36.127] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:38:0) after 3m43s
[02:09:19.145] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:38:0)
[02:09:19.165] Build status: STARTED
[02:09:19.165] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8845133642074332928:39:0) after 6m54s
[02:15:31.719] Received PubSub notification, asking Buildbucket for the build status
[02:15:31.770] Build:
{
"id": "8679703580141942769",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win-official"
},
"number": 239,
"createdBy": "project:chromium-m149",
"createTime": "2026-06-06T22:57:23.807295035Z",
"startTime": "2026-06-06T22:57:28.224884440Z",
"endTime": "2026-06-07T02:15:31.346620911Z",
"updateTime": "2026-06-07T02:15:31.346620911Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9280576e4d8b435c113ab2be586ed604cf88a176",
"ref": "refs/branch-heads/7827"
}
}
}
[02:15:31.771] Invocation finished in 3h18m9.190648364s with status SUCCEEDED