[20:29:35.832] New invocation is queued and will start shortly
[20:29:36.966] Starting the invocation (attempt 1)
[20:29:36.995] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2720e3f585dfc3d9223a15ba613fd763e6b2f6d7"
[20:29:36.995] Popped gitiles commit info from properties and tags
[20:29:36.995] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:29:36.996] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:29:36.997] Buildbucket request:
{
"requestId": "8834633691437751888",
"builder": {
"project": "chromium-m155",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8834633691437751888",
"job": "chromium-m155/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/8059",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a64648fa0c2770cbc3b054e3cbc7443fa5f392d9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8059@a64648fa0c2770cbc3b054e3cbc7443fa5f392d9",
"title": "a64648fa0c2770cbc3b054e3cbc7443fa5f392d9",
"url": "https://chromium.googlesource.com/chromium/src/+/a64648fa0c2770cbc3b054e3cbc7443fa5f392d9"
},
{
"gitiles": {
"ref": "refs/branch-heads/8059",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9ec4c150b47a51dd38a111302b15053bd474e6b1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8059@9ec4c150b47a51dd38a111302b15053bd474e6b1",
"title": "9ec4c150b47a51dd38a111302b15053bd474e6b1",
"url": "https://chromium.googlesource.com/chromium/src/+/9ec4c150b47a51dd38a111302b15053bd474e6b1"
},
{
"gitiles": {
"ref": "refs/branch-heads/8059",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4e444c859b9311f827805868b022f1dd63d010c6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8059@4e444c859b9311f827805868b022f1dd63d010c6",
"title": "4e444c859b9311f827805868b022f1dd63d010c6",
"url": "https://chromium.googlesource.com/chromium/src/+/4e444c859b9311f827805868b022f1dd63d010c6"
},
{
"gitiles": {
"ref": "refs/branch-heads/8059",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c59b6033426244c7131bbf5fd0e86278fe4c5025"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8059@c59b6033426244c7131bbf5fd0e86278fe4c5025",
"title": "c59b6033426244c7131bbf5fd0e86278fe4c5025",
"url": "https://chromium.googlesource.com/chromium/src/+/c59b6033426244c7131bbf5fd0e86278fe4c5025"
},
{
"gitiles": {
"ref": "refs/branch-heads/8059",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2720e3f585dfc3d9223a15ba613fd763e6b2f6d7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8059@2720e3f585dfc3d9223a15ba613fd763e6b2f6d7",
"title": "2720e3f585dfc3d9223a15ba613fd763e6b2f6d7",
"url": "https://chromium.googlesource.com/chromium/src/+/2720e3f585dfc3d9223a15ba613fd763e6b2f6d7"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2720e3f585dfc3d9223a15ba613fd763e6b2f6d7",
"ref": "refs/branch-heads/8059"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8834633691437751888"
},
{
"key": "scheduler_job_id",
"value": "chromium-m155/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:29:37.640] Scheduled build:
{
"id": "8669203629482597921",
"builder": {
"project": "chromium-m155",
"bucket": "ci",
"builder": "win-official"
},
"number": 76,
"createdBy": "project:chromium-m155",
"createTime": "2026-09-30T20:29:37.074080224Z",
"updateTime": "2026-09-30T20:29:37.074080224Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2720e3f585dfc3d9223a15ba613fd763e6b2f6d7",
"ref": "refs/branch-heads/8059"
}
}
}
[20:29:37.642] Task URL: https://cr-buildbucket.appspot.com/build/8669203629482597921
[20:29:37.642] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:2:0) after 1m7s
[20:29:38.512] Received PubSub notification, asking Buildbucket for the build status
[20:29:38.660] Build status: SCHEDULED
[20:30:44.622] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:2:0)
[20:30:44.643] Build status: SCHEDULED
[20:30:44.643] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:4:0) after 1m22s
[20:32:06.658] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:4:0)
[20:32:06.675] Build status: SCHEDULED
[20:32:06.675] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:5:0) after 6m58s
[20:39:04.702] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:5:0)
[20:39:04.724] Build status: SCHEDULED
[20:39:04.724] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:6:0) after 3m23s
[20:42:27.738] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:6:0)
[20:42:27.759] Build status: SCHEDULED
[20:42:27.759] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:7:0) after 4m27s
[20:46:54.827] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:7:0)
[20:46:54.854] Build status: SCHEDULED
[20:46:54.854] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:8:0) after 6m30s
[20:53:24.917] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:8:0)
[20:53:24.938] Build status: SCHEDULED
[20:53:24.938] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:9:0) after 3m34s
[20:56:58.918] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:9:0)
[20:56:58.950] Build status: SCHEDULED
[20:56:58.950] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:10:0) after 9m8s
[21:03:54.977] Received PubSub notification, asking Buildbucket for the build status
[21:03:54.998] Build status: STARTED
[21:06:07.072] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:10:0)
[21:06:07.097] Build status: STARTED
[21:06:07.097] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:12:0) after 8m16s
[21:14:23.234] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:12:0)
[21:14:23.257] Build status: STARTED
[21:14:23.257] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:13:0) after 3m25s
[21:17:48.290] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:13:0)
[21:17:48.311] Build status: STARTED
[21:17:48.311] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:14:0) after 7m13s
[21:25:01.436] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:14:0)
[21:25:01.468] Build status: STARTED
[21:25:01.468] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:15:0) after 8m4s
[21:33:05.517] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:15:0)
[21:33:05.536] Build status: STARTED
[21:33:05.536] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:16:0) after 6m35s
[21:39:40.569] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:16:0)
[21:39:40.590] Build status: STARTED
[21:39:40.591] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:17:0) after 9m31s
[21:49:11.611] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:17:0)
[21:49:11.641] Build status: STARTED
[21:49:11.641] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:18:0) after 7m52s
[21:57:03.833] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:18:0)
[21:57:03.852] Build status: STARTED
[21:57:03.852] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:19:0) after 1m40s
[21:58:43.859] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:19:0)
[21:58:43.885] Build status: STARTED
[21:58:43.885] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:20:0) after 9m11s
[22:07:54.977] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:20:0)
[22:07:55.001] Build status: STARTED
[22:07:55.001] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:21:0) after 4m49s
[22:12:43.984] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:21:0)
[22:12:44.013] Build status: STARTED
[22:12:44.013] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:22:0) after 2m42s
[22:15:26.066] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:22:0)
[22:15:26.113] Build status: STARTED
[22:15:26.113] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:23:0) after 3m45s
[22:19:11.152] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:23:0)
[22:19:11.172] Build status: STARTED
[22:19:11.173] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:24:0) after 3m29s
[22:22:40.206] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:24:0)
[22:22:40.224] Build status: STARTED
[22:22:40.224] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:25:0) after 7m52s
[22:30:32.289] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:25:0)
[22:30:32.315] Build status: STARTED
[22:30:32.315] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:26:0) after 3m12s
[22:33:44.357] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:26:0)
[22:33:44.381] Build status: STARTED
[22:33:44.381] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:27:0) after 6m40s
[22:40:24.489] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:27:0)
[22:40:24.511] Build status: STARTED
[22:40:24.511] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:28:0) after 8m3s
[22:48:27.858] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:28:0)
[22:48:27.923] Build status: STARTED
[22:48:27.924] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:29:0) after 4m9s
[22:52:36.908] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:29:0)
[22:52:36.931] Build status: STARTED
[22:52:36.931] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:30:0) after 2m27s
[22:55:04.021] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:30:0)
[22:55:04.051] Build status: STARTED
[22:55:04.052] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:31:0) after 9m10s
[23:04:14.071] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:31:0)
[23:04:14.137] Build status: STARTED
[23:04:14.137] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:32:0) after 4m37s
[23:08:51.346] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:32:0)
[23:08:51.370] Build status: STARTED
[23:08:51.370] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:33:0) after 3m15s
[23:12:06.442] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:33:0)
[23:12:06.493] Build status: STARTED
[23:12:06.494] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:34:0) after 4m56s
[23:17:02.580] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:34:0)
[23:17:02.601] Build status: STARTED
[23:17:02.601] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:35:0) after 1m29s
[23:18:31.730] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:35:0)
[23:18:31.758] Build status: STARTED
[23:18:31.758] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:36:0) after 9m24s
[23:27:55.985] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:36:0)
[23:27:56.003] Build status: STARTED
[23:27:56.003] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:37:0) after 8m25s
[23:36:21.022] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:37:0)
[23:36:21.047] Build status: STARTED
[23:36:21.047] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:38:0) after 9m15s
[23:45:36.104] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:38:0)
[23:45:36.135] Build status: STARTED
[23:45:36.135] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:39:0) after 6m19s
[23:51:55.175] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:39:0)
[23:51:55.198] Build status: STARTED
[23:51:55.198] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:40:0) after 4m52s
[23:56:47.325] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:40:0)
[23:56:47.356] Build status: STARTED
[23:56:47.357] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:41:0) after 8m48s
[00:05:35.480] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:41:0)
[00:05:35.503] Build status: STARTED
[00:05:35.503] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:42:0) after 4m17s
[00:09:52.599] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:42:0)
[00:09:52.626] Build status: STARTED
[00:09:52.626] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:43:0) after 4m17s
[00:14:09.718] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:43:0)
[00:14:09.737] Build status: STARTED
[00:14:09.738] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:44:0) after 5m31s
[00:19:40.776] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:44:0)
[00:19:40.793] Build status: STARTED
[00:19:40.793] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:45:0) after 4m41s
[00:24:22.048] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:45:0)
[00:24:22.087] Build status: STARTED
[00:24:22.087] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:46:0) after 5m43s
[00:30:05.295] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:46:0)
[00:30:05.319] Build status: STARTED
[00:30:05.319] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:47:0) after 2m27s
[00:32:32.338] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:47:0)
[00:32:32.374] Build status: STARTED
[00:32:32.374] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:48:0) after 1m23s
[00:33:55.395] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:48:0)
[00:33:55.430] Build status: STARTED
[00:33:55.430] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:49:0) after 6m49s
[00:40:44.497] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:49:0)
[00:40:44.552] Build status: STARTED
[00:40:44.552] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:50:0) after 5m39s
[00:46:23.875] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:50:0)
[00:46:23.954] Build status: STARTED
[00:46:23.954] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:51:0) after 8m36s
[00:55:00.075] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:51:0)
[00:55:00.096] Build status: STARTED
[00:55:00.096] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:52:0) after 3m51s
[00:58:51.117] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:52:0)
[00:58:51.136] Build status: STARTED
[00:58:51.136] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:53:0) after 5m46s
[01:04:37.154] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:53:0)
[01:04:37.221] Build status: STARTED
[01:04:37.221] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:54:0) after 3m27s
[01:08:04.332] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:54:0)
[01:08:04.349] Build status: STARTED
[01:08:04.349] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:55:0) after 9m35s
[01:17:39.361] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:55:0)
[01:17:39.420] Build status: STARTED
[01:17:39.420] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:56:0) after 1m22s
[01:19:01.487] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:56:0)
[01:19:01.508] Build status: STARTED
[01:19:01.508] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:57:0) after 1m48s
[01:20:49.523] Handling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:57:0)
[01:20:49.545] Build status: STARTED
[01:20:49.546] Scheduling timer "check-buildbucket-build-status" (chromium-m155/win-official:8834633691437751888:58:0) after 4m44s
[01:24:17.997] Received PubSub notification, asking Buildbucket for the build status
[01:24:18.105] Build:
{
"id": "8669203629482597921",
"builder": {
"project": "chromium-m155",
"bucket": "ci",
"builder": "win-official"
},
"number": 76,
"createdBy": "project:chromium-m155",
"createTime": "2026-09-30T20:29:37.074080224Z",
"startTime": "2026-09-30T21:03:54.531808110Z",
"endTime": "2026-10-01T01:24:17.647976716Z",
"updateTime": "2026-10-01T01:24:17.647976716Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2720e3f585dfc3d9223a15ba613fd763e6b2f6d7",
"ref": "refs/branch-heads/8059"
}
}
}
[01:24:18.105] Invocation finished in 4h54m42.278662062s with status SUCCEEDED