[14:37:22.752] New invocation is queued and will start shortly
[14:37:23.787] Starting the invocation (attempt 1)
[14:37:23.816] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9d149ff313cfee6551259d37328823105eda2fb6"
[14:37:23.816] Popped gitiles commit info from properties and tags
[14:37:23.816] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:37:23.816] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:37:23.817] Buildbucket request:
{
"requestId": "8838279729734256480",
"builder": {
"project": "chromium-m151",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8838279729734256480",
"job": "chromium-m151/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a2c9e9254873c06e0fbf8813acc58d80630b4695"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@a2c9e9254873c06e0fbf8813acc58d80630b4695",
"title": "a2c9e9254873c06e0fbf8813acc58d80630b4695",
"url": "https://chromium.googlesource.com/chromium/src/+/a2c9e9254873c06e0fbf8813acc58d80630b4695"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "98782cd1171f416bde5478048ed64483faec5ca8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@98782cd1171f416bde5478048ed64483faec5ca8",
"title": "98782cd1171f416bde5478048ed64483faec5ca8",
"url": "https://chromium.googlesource.com/chromium/src/+/98782cd1171f416bde5478048ed64483faec5ca8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "aff63d34c6eb89b17a37946e99c0de83569ffacb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@aff63d34c6eb89b17a37946e99c0de83569ffacb",
"title": "aff63d34c6eb89b17a37946e99c0de83569ffacb",
"url": "https://chromium.googlesource.com/chromium/src/+/aff63d34c6eb89b17a37946e99c0de83569ffacb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b85d784d1d626d190efe3ddde6abece581303a35"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@b85d784d1d626d190efe3ddde6abece581303a35",
"title": "b85d784d1d626d190efe3ddde6abece581303a35",
"url": "https://chromium.googlesource.com/chromium/src/+/b85d784d1d626d190efe3ddde6abece581303a35"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "48c5aa118753f60fbbc4f2fd390e4c1ed372f9ca"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@48c5aa118753f60fbbc4f2fd390e4c1ed372f9ca",
"title": "48c5aa118753f60fbbc4f2fd390e4c1ed372f9ca",
"url": "https://chromium.googlesource.com/chromium/src/+/48c5aa118753f60fbbc4f2fd390e4c1ed372f9ca"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bc7099380dc067d11d4515c79167a1dbd8969d8b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@bc7099380dc067d11d4515c79167a1dbd8969d8b",
"title": "bc7099380dc067d11d4515c79167a1dbd8969d8b",
"url": "https://chromium.googlesource.com/chromium/src/+/bc7099380dc067d11d4515c79167a1dbd8969d8b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7922",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9d149ff313cfee6551259d37328823105eda2fb6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7922@9d149ff313cfee6551259d37328823105eda2fb6",
"title": "9d149ff313cfee6551259d37328823105eda2fb6",
"url": "https://chromium.googlesource.com/chromium/src/+/9d149ff313cfee6551259d37328823105eda2fb6"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9d149ff313cfee6551259d37328823105eda2fb6",
"ref": "refs/branch-heads/7922"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8838279729734256480"
},
{
"key": "scheduler_job_id",
"value": "chromium-m151/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:37:24.829] Scheduled build:
{
"id": "8672849667872396625",
"builder": {
"project": "chromium-m151",
"bucket": "ci",
"builder": "win32-official"
},
"number": 282,
"createdBy": "project:chromium-m151",
"createTime": "2026-08-21T14:37:23.905098254Z",
"updateTime": "2026-08-21T14:37:23.905098254Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9d149ff313cfee6551259d37328823105eda2fb6",
"ref": "refs/branch-heads/7922"
}
},
"canary": true
}
[14:37:24.829] Task URL: https://cr-buildbucket.appspot.com/build/8672849667872396625
[14:37:24.829] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:2:0) after 7m28s
[14:37:25.753] Received PubSub notification, asking Buildbucket for the build status
[14:37:25.778] Build status: SCHEDULED
[14:44:53.030] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:2:0)
[14:44:53.058] Build status: SCHEDULED
[14:44:53.058] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:4:0) after 1m32s
[14:46:25.130] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:4:0)
[14:46:25.159] Build status: SCHEDULED
[14:46:25.159] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:5:0) after 3m39s
[14:50:04.173] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:5:0)
[14:50:04.192] Build status: SCHEDULED
[14:50:04.195] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:6:0) after 6m21s
[14:53:52.682] Received PubSub notification, asking Buildbucket for the build status
[14:53:52.797] Build status: STARTED
[14:56:25.340] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:6:0)
[14:56:25.356] Build status: STARTED
[14:56:25.356] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:8:0) after 7m24s
[15:03:49.372] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:8:0)
[15:03:49.408] Build status: STARTED
[15:03:49.408] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:9:0) after 5m37s
[15:09:26.436] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:9:0)
[15:09:26.463] Build status: STARTED
[15:09:26.463] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:10:0) after 6m13s
[15:15:39.597] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:10:0)
[15:15:39.622] Build status: STARTED
[15:15:39.622] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:11:0) after 7m18s
[15:22:57.640] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:11:0)
[15:22:57.663] Build status: STARTED
[15:22:57.663] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:12:0) after 7m25s
[15:30:22.705] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:12:0)
[15:30:22.731] Build status: STARTED
[15:30:22.731] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:13:0) after 1m30s
[15:31:52.795] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:13:0)
[15:31:52.932] Build status: STARTED
[15:31:52.932] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:14:0) after 7m25s
[15:39:17.950] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:14:0)
[15:39:17.982] Build status: STARTED
[15:39:17.982] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:15:0) after 2m25s
[15:41:42.999] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:15:0)
[15:41:43.032] Build status: STARTED
[15:41:43.032] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:16:0) after 1m58s
[15:43:41.035] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:16:0)
[15:43:41.083] Build status: STARTED
[15:43:41.083] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:17:0) after 6m59s
[15:50:40.233] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:17:0)
[15:50:40.256] Build status: STARTED
[15:50:40.256] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:18:0) after 7m8s
[15:57:48.450] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:18:0)
[15:57:48.555] Build status: STARTED
[15:57:48.555] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:19:0) after 6m25s
[16:04:13.606] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:19:0)
[16:04:13.629] Build status: STARTED
[16:04:13.630] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:20:0) after 2m29s
[16:06:42.653] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:20:0)
[16:06:42.687] Build status: STARTED
[16:06:42.687] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:21:0) after 6m19s
[16:13:01.811] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:21:0)
[16:13:01.837] Build status: STARTED
[16:13:01.837] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:22:0) after 7m29s
[16:20:31.063] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:22:0)
[16:20:31.085] Build status: STARTED
[16:20:31.085] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:23:0) after 4m54s
[16:25:25.101] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:23:0)
[16:25:25.123] Build status: STARTED
[16:25:25.124] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:24:0) after 8m13s
[16:33:38.184] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:24:0)
[16:33:38.206] Build status: STARTED
[16:33:38.206] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:25:0) after 5m28s
[16:39:06.221] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:25:0)
[16:39:06.241] Build status: STARTED
[16:39:06.241] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:26:0) after 4m52s
[16:43:58.283] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:26:0)
[16:43:58.312] Build status: STARTED
[16:43:58.312] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:27:0) after 7m31s
[16:51:29.376] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:27:0)
[16:51:29.403] Build status: STARTED
[16:51:29.403] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:28:0) after 7m24s
[16:58:53.498] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:28:0)
[16:58:53.520] Build status: STARTED
[16:58:53.520] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:29:0) after 7m47s
[17:06:40.577] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:29:0)
[17:06:40.599] Build status: STARTED
[17:06:40.599] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:30:0) after 4m45s
[17:11:25.645] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:30:0)
[17:11:25.665] Build status: STARTED
[17:11:25.665] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:31:0) after 7m25s
[17:18:50.692] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:31:0)
[17:18:50.710] Build status: STARTED
[17:18:50.710] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:32:0) after 7m52s
[17:26:42.748] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:32:0)
[17:26:42.772] Build status: STARTED
[17:26:42.772] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:33:0) after 7m45s
[17:34:27.799] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:33:0)
[17:34:27.848] Build status: STARTED
[17:34:27.849] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:34:0) after 9m7s
[17:43:34.883] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:34:0)
[17:43:34.901] Build status: STARTED
[17:43:34.901] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:35:0) after 3m17s
[17:46:51.922] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:35:0)
[17:46:51.967] Build status: STARTED
[17:46:51.967] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:36:0) after 9m36s
[17:56:28.037] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:36:0)
[17:56:28.082] Build status: STARTED
[17:56:28.082] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:37:0) after 5m2s
[18:01:30.114] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:37:0)
[18:01:30.156] Build status: STARTED
[18:01:30.156] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:38:0) after 6m57s
[18:08:27.254] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:38:0)
[18:08:27.274] Build status: STARTED
[18:08:27.274] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:39:0) after 7m5s
[18:15:32.501] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:39:0)
[18:15:32.536] Build status: STARTED
[18:15:32.536] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:40:0) after 2m23s
[18:17:55.564] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:40:0)
[18:17:55.582] Build status: STARTED
[18:17:55.582] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:41:0) after 2m35s
[18:20:30.598] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:41:0)
[18:20:30.617] Build status: STARTED
[18:20:30.617] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:42:0) after 3m59s
[18:24:29.741] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:42:0)
[18:24:29.771] Build status: STARTED
[18:24:29.771] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:43:0) after 1m48s
[18:26:17.786] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:43:0)
[18:26:17.828] Build status: STARTED
[18:26:17.828] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:44:0) after 9m54s
[18:36:11.847] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:44:0)
[18:36:11.882] Build status: STARTED
[18:36:11.882] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:45:0) after 4m33s
[18:40:44.898] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:45:0)
[18:40:44.940] Build status: STARTED
[18:40:44.940] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:46:0) after 9m50s
[18:50:35.060] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:46:0)
[18:50:35.081] Build status: STARTED
[18:50:35.081] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:47:0) after 5m10s
[18:55:45.146] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:47:0)
[18:55:45.166] Build status: STARTED
[18:55:45.166] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:48:0) after 7m51s
[19:03:36.237] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:48:0)
[19:03:36.270] Build status: STARTED
[19:03:36.270] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:49:0) after 3m28s
[19:07:04.302] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:49:0)
[19:07:04.350] Build status: STARTED
[19:07:04.350] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:50:0) after 8m25s
[19:15:29.366] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:50:0)
[19:15:29.387] Build status: STARTED
[19:15:29.387] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:51:0) after 7m33s
[19:23:02.400] Handling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:51:0)
[19:23:02.426] Build status: STARTED
[19:23:02.426] Scheduling timer "check-buildbucket-build-status" (chromium-m151/win32-official:8838279729734256480:52:0) after 3m59s
[19:23:18.272] Received PubSub notification, asking Buildbucket for the build status
[19:23:18.299] Build:
{
"id": "8672849667872396625",
"builder": {
"project": "chromium-m151",
"bucket": "ci",
"builder": "win32-official"
},
"number": 282,
"createdBy": "project:chromium-m151",
"createTime": "2026-08-21T14:37:23.905098254Z",
"startTime": "2026-08-21T14:53:52.465644664Z",
"endTime": "2026-08-21T19:23:17.945077997Z",
"updateTime": "2026-08-21T19:23:17.945077997Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9d149ff313cfee6551259d37328823105eda2fb6",
"ref": "refs/branch-heads/7922"
}
},
"canary": true
}
[19:23:18.299] Invocation finished in 4h45m55.553144732s with status SUCCEEDED