[17:12:40.351] New invocation is queued and will start shortly
[17:12:41.552] Starting the invocation (attempt 1)
[17:12:41.580] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d7524ec447f2885a6fee5263b2777de3a6527930"
[17:12:41.580] Popped gitiles commit info from properties and tags
[17:12:41.580] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:12:41.581] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:12:41.581] Buildbucket request:
{
"requestId": "8833468320309646624",
"builder": {
"project": "chromium-m157",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8833468320309646624",
"job": "chromium-m157/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/8103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d7524ec447f2885a6fee5263b2777de3a6527930"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8103@d7524ec447f2885a6fee5263b2777de3a6527930",
"title": "d7524ec447f2885a6fee5263b2777de3a6527930",
"url": "https://chromium.googlesource.com/chromium/src/+/d7524ec447f2885a6fee5263b2777de3a6527930"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d7524ec447f2885a6fee5263b2777de3a6527930",
"ref": "refs/branch-heads/8103"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8833468320309646624"
},
{
"key": "scheduler_job_id",
"value": "chromium-m157/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:12:49.790] Scheduled build:
{
"id": "8668038250320882145",
"builder": {
"project": "chromium-m157",
"bucket": "ci",
"builder": "win32-official"
},
"number": 4,
"createdBy": "project:chromium-m157",
"createTime": "2026-10-13T17:12:49.250694284Z",
"updateTime": "2026-10-13T17:12:49.250694284Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d7524ec447f2885a6fee5263b2777de3a6527930",
"ref": "refs/branch-heads/8103"
}
},
"canary": true
}
[17:12:49.790] Task URL: https://cr-buildbucket.appspot.com/build/8668038250320882145
[17:12:49.790] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:2:0) after 5m22s
[17:12:52.380] Received PubSub notification, asking Buildbucket for the build status
[17:12:52.398] Build status: SCHEDULED
[17:18:11.845] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:2:0)
[17:18:11.869] Build status: SCHEDULED
[17:18:11.869] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:4:0) after 9m3s
[17:27:14.898] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:4:0)
[17:27:14.920] Build status: SCHEDULED
[17:27:14.920] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:5:0) after 1m57s
[17:29:11.937] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:5:0)
[17:29:11.956] Build status: SCHEDULED
[17:29:11.957] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:6:0) after 7m52s
[17:37:04.092] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:6:0)
[17:37:04.141] Build status: SCHEDULED
[17:37:04.142] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:7:0) after 6m19s
[17:43:23.155] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:7:0)
[17:43:23.177] Build status: SCHEDULED
[17:43:23.177] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:8:0) after 7m8s
[17:50:31.243] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:8:0)
[17:50:31.274] Build status: SCHEDULED
[17:50:31.274] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:9:0) after 1m52s
[17:52:23.287] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:9:0)
[17:52:23.312] Build status: SCHEDULED
[17:52:23.312] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:10:0) after 1m16s
[17:53:39.361] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:10:0)
[17:53:39.392] Build status: SCHEDULED
[17:53:39.392] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:11:0) after 9m28s
[18:03:07.758] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:11:0)
[18:03:07.775] Build status: SCHEDULED
[18:03:07.775] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:12:0) after 3m1s
[18:06:08.791] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:12:0)
[18:06:08.968] Build status: SCHEDULED
[18:06:08.968] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:13:0) after 9m40s
[18:15:49.125] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:13:0)
[18:15:49.148] Build status: SCHEDULED
[18:15:49.148] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:14:0) after 5m3s
[18:20:52.205] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:14:0)
[18:20:52.227] Build status: SCHEDULED
[18:20:52.227] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:15:0) after 1m35s
[18:22:27.276] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:15:0)
[18:22:27.296] Build status: SCHEDULED
[18:22:27.296] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:16:0) after 1m26s
[18:23:53.309] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:16:0)
[18:23:53.333] Build status: SCHEDULED
[18:23:53.333] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:17:0) after 1m12s
[18:25:05.346] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:17:0)
[18:25:05.374] Build status: SCHEDULED
[18:25:05.374] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:18:0) after 1m5s
[18:26:10.469] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:18:0)
[18:26:10.499] Build status: SCHEDULED
[18:26:10.499] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:19:0) after 5m3s
[18:31:13.538] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:19:0)
[18:31:13.694] Build status: SCHEDULED
[18:31:13.695] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:20:0) after 3m57s
[18:35:10.721] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:20:0)
[18:35:10.743] Build status: SCHEDULED
[18:35:10.743] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:21:0) after 9m25s
[18:44:35.756] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:21:0)
[18:44:35.779] Build status: SCHEDULED
[18:44:35.779] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:22:0) after 9m43s
[18:54:18.928] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:22:0)
[18:54:18.950] Build status: SCHEDULED
[18:54:18.950] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:23:0) after 5m50s
[19:00:09.023] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:23:0)
[19:00:09.063] Build status: SCHEDULED
[19:00:09.063] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:24:0) after 3m57s
[19:04:06.220] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:24:0)
[19:04:06.244] Build status: SCHEDULED
[19:04:06.245] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:25:0) after 6m46s
[19:10:52.260] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:25:0)
[19:10:52.280] Build status: SCHEDULED
[19:10:52.280] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:26:0) after 4m18s
[19:15:10.294] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:26:0)
[19:15:10.316] Build status: SCHEDULED
[19:15:10.316] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:27:0) after 8m3s
[19:23:13.591] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:27:0)
[19:23:13.615] Build status: SCHEDULED
[19:23:13.615] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:28:0) after 2m14s
[19:25:27.797] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:28:0)
[19:25:27.816] Build status: SCHEDULED
[19:25:27.816] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:29:0) after 9m28s
[19:34:55.853] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:29:0)
[19:34:55.876] Build status: SCHEDULED
[19:34:55.876] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:30:0) after 5m39s
[19:40:34.941] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:30:0)
[19:40:34.998] Build status: SCHEDULED
[19:40:34.999] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:31:0) after 2m53s
[19:43:28.120] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:31:0)
[19:43:28.156] Build status: SCHEDULED
[19:43:28.156] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:32:0) after 8m48s
[19:52:16.130] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:32:0)
[19:52:16.164] Build status: SCHEDULED
[19:52:16.164] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:33:0) after 3m29s
[19:55:45.287] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:33:0)
[19:55:45.368] Build status: SCHEDULED
[19:55:45.368] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:34:0) after 7m24s
[20:03:09.887] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:34:0)
[20:03:09.940] Build status: SCHEDULED
[20:03:09.941] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:35:0) after 7m23s
[20:10:33.055] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:35:0)
[20:10:33.084] Build status: SCHEDULED
[20:10:33.084] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:36:0) after 5m50s
[20:16:23.128] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:36:0)
[20:16:23.150] Build status: SCHEDULED
[20:16:23.150] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:37:0) after 8m2s
[20:24:25.218] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:37:0)
[20:24:25.284] Build status: SCHEDULED
[20:24:25.284] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:38:0) after 8m37s
[20:33:02.378] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:38:0)
[20:33:02.454] Build status: SCHEDULED
[20:33:02.454] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:39:0) after 5m32s
[20:38:34.512] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:39:0)
[20:38:34.564] Build status: SCHEDULED
[20:38:34.565] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:40:0) after 7m23s
[20:45:57.583] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:40:0)
[20:45:57.607] Build status: SCHEDULED
[20:45:57.607] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:41:0) after 8m18s
[20:54:15.742] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:41:0)
[20:54:15.765] Build status: SCHEDULED
[20:54:15.765] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:42:0) after 8m2s
[21:02:17.852] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:42:0)
[21:02:17.874] Build status: SCHEDULED
[21:02:17.875] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:43:0) after 9m21s
[21:11:38.944] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:43:0)
[21:11:38.974] Build status: SCHEDULED
[21:11:38.975] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:44:0) after 7m57s
[21:19:36.039] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:44:0)
[21:19:36.060] Build status: SCHEDULED
[21:19:36.060] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:45:0) after 7m44s
[21:27:20.111] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:45:0)
[21:27:20.141] Build status: SCHEDULED
[21:27:20.141] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:46:0) after 4m17s
[21:31:37.238] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:46:0)
[21:31:37.259] Build status: SCHEDULED
[21:31:37.259] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:47:0) after 4m36s
[21:36:13.450] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:47:0)
[21:36:13.500] Build status: SCHEDULED
[21:36:13.500] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:48:0) after 8m7s
[21:44:20.608] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:48:0)
[21:44:20.631] Build status: SCHEDULED
[21:44:20.631] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:49:0) after 5m37s
[21:49:57.633] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:49:0)
[21:49:57.653] Build status: SCHEDULED
[21:49:57.653] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:50:0) after 1m40s
[21:51:37.713] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:50:0)
[21:51:37.737] Build status: SCHEDULED
[21:51:37.737] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:51:0) after 7m4s
[21:58:41.807] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:51:0)
[21:58:41.828] Build status: SCHEDULED
[21:58:41.828] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:52:0) after 2m28s
[22:01:09.976] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:52:0)
[22:01:10.000] Build status: SCHEDULED
[22:01:10.001] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:53:0) after 3m50s
[22:01:40.568] Received PubSub notification, asking Buildbucket for the build status
[22:01:40.641] Build status: STARTED
[22:05:00.050] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:53:0)
[22:05:00.072] Build status: STARTED
[22:05:00.072] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:55:0) after 5m45s
[22:10:45.197] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:55:0)
[22:10:45.259] Build status: STARTED
[22:10:45.259] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:56:0) after 2m1s
[22:12:46.378] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:56:0)
[22:12:46.393] Build status: STARTED
[22:12:46.394] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:57:0) after 3m27s
[22:16:13.409] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:57:0)
[22:16:13.436] Build status: STARTED
[22:16:13.436] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:58:0) after 1m50s
[22:18:03.465] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:58:0)
[22:18:03.495] Build status: STARTED
[22:18:03.495] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:59:0) after 6m43s
[22:24:46.572] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:59:0)
[22:24:46.591] Build status: STARTED
[22:24:46.591] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:60:0) after 7m55s
[22:32:41.737] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:60:0)
[22:32:41.756] Build status: STARTED
[22:32:41.756] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:61:0) after 7m14s
[22:39:55.903] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:61:0)
[22:39:55.921] Build status: STARTED
[22:39:55.921] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:62:0) after 9m37s
[22:49:32.936] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:62:0)
[22:49:32.977] Build status: STARTED
[22:49:32.977] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:63:0) after 9m32s
[22:59:05.025] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:63:0)
[22:59:05.044] Build status: STARTED
[22:59:05.044] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:64:0) after 3m37s
[23:02:42.192] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:64:0)
[23:02:42.217] Build status: STARTED
[23:02:42.217] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:65:0) after 4m4s
[23:06:46.447] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:65:0)
[23:06:46.513] Build status: STARTED
[23:06:46.513] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:66:0) after 8m18s
[23:15:04.533] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:66:0)
[23:15:04.589] Build status: STARTED
[23:15:04.589] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:67:0) after 6m2s
[23:21:06.886] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:67:0)
[23:21:06.909] Build status: STARTED
[23:21:06.909] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:68:0) after 2m36s
[23:23:43.022] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:68:0)
[23:23:43.062] Build status: STARTED
[23:23:43.062] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:69:0) after 2m14s
[23:25:57.217] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:69:0)
[23:25:57.236] Build status: STARTED
[23:25:57.237] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:70:0) after 3m47s
[23:29:44.283] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:70:0)
[23:29:44.335] Build status: STARTED
[23:29:44.335] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:71:0) after 7m51s
[23:37:35.401] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:71:0)
[23:37:35.427] Build status: STARTED
[23:37:35.427] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:72:0) after 5m15s
[23:42:50.447] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:72:0)
[23:42:50.469] Build status: STARTED
[23:42:50.469] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:73:0) after 9m15s
[23:52:05.493] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:73:0)
[23:52:05.563] Build status: STARTED
[23:52:05.563] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:74:0) after 4m30s
[23:56:35.612] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:74:0)
[23:56:35.629] Build status: STARTED
[23:56:35.629] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:75:0) after 4m3s
[00:00:38.786] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:75:0)
[00:00:38.812] Build status: STARTED
[00:00:38.816] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:76:0) after 1m8s
[00:01:46.836] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:76:0)
[00:01:46.854] Build status: STARTED
[00:01:46.854] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:77:0) after 1m48s
[00:03:34.927] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:77:0)
[00:03:34.946] Build status: STARTED
[00:03:34.946] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:78:0) after 9m36s
[00:13:11.084] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:78:0)
[00:13:11.208] Build status: STARTED
[00:13:11.208] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:79:0) after 6m2s
[00:19:13.244] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:79:0)
[00:19:13.333] Build status: STARTED
[00:19:13.333] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:80:0) after 1m49s
[00:21:02.435] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:80:0)
[00:21:02.537] Build status: STARTED
[00:21:02.537] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:81:0) after 1m0s
[00:22:02.550] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:81:0)
[00:22:02.596] Build status: STARTED
[00:22:02.596] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:82:0) after 8m53s
[00:30:55.611] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:82:0)
[00:30:55.830] Build status: STARTED
[00:30:55.830] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:83:0) after 1m3s
[00:31:58.878] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:83:0)
[00:31:58.900] Build status: STARTED
[00:31:58.900] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:84:0) after 7m2s
[00:39:00.929] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:84:0)
[00:39:00.982] Build status: STARTED
[00:39:00.982] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:85:0) after 2m55s
[00:41:56.193] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:85:0)
[00:41:56.213] Build status: STARTED
[00:41:56.213] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:86:0) after 7m45s
[00:49:41.232] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:86:0)
[00:49:41.251] Build status: STARTED
[00:49:41.251] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:87:0) after 9m4s
[00:58:45.267] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:87:0)
[00:58:45.287] Build status: STARTED
[00:58:45.287] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:88:0) after 1m8s
[00:59:53.327] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:88:0)
[00:59:53.350] Build status: STARTED
[00:59:53.350] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:89:0) after 9m29s
[01:09:22.429] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:89:0)
[01:09:22.469] Build status: STARTED
[01:09:22.469] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:90:0) after 3m4s
[01:12:26.491] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:90:0)
[01:12:26.653] Build status: STARTED
[01:12:26.653] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:91:0) after 9m3s
[01:21:29.670] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:91:0)
[01:21:29.726] Build status: STARTED
[01:21:29.727] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:92:0) after 5m15s
[01:26:44.790] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:92:0)
[01:26:44.810] Build status: STARTED
[01:26:44.810] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:93:0) after 1m16s
[01:28:00.831] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:93:0)
[01:28:00.851] Build status: STARTED
[01:28:00.851] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:94:0) after 3m44s
[01:31:44.945] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:94:0)
[01:31:44.963] Build status: STARTED
[01:31:44.964] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:95:0) after 3m24s
[01:35:08.988] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:95:0)
[01:35:09.004] Build status: STARTED
[01:35:09.004] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:96:0) after 3m25s
[01:38:34.008] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:96:0)
[01:38:34.043] Build status: STARTED
[01:38:34.043] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:97:0) after 7m41s
[01:46:15.069] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:97:0)
[01:46:15.114] Build status: STARTED
[01:46:15.114] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:98:0) after 1m35s
[01:47:50.112] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:98:0)
[01:47:50.141] Build status: STARTED
[01:47:50.141] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:99:0) after 4m32s
[01:52:22.157] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:99:0)
[01:52:22.247] Build status: STARTED
[01:52:22.247] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:100:0) after 4m29s
[01:56:51.323] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:100:0)
[01:56:51.365] Build status: STARTED
[01:56:51.366] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:101:0) after 2m53s
[01:59:44.347] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:101:0)
[01:59:44.366] Build status: STARTED
[01:59:44.366] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:102:0) after 8m27s
[02:08:11.447] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:102:0)
[02:08:11.466] Build status: STARTED
[02:08:11.467] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:103:0) after 8m23s
[02:16:34.483] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:103:0)
[02:16:34.503] Build status: STARTED
[02:16:34.503] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:104:0) after 8m11s
[02:24:45.521] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:104:0)
[02:24:45.543] Build status: STARTED
[02:24:45.543] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:105:0) after 1m46s
[02:26:31.563] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:105:0)
[02:26:31.644] Build status: STARTED
[02:26:31.644] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:106:0) after 6m53s
[02:33:24.717] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:106:0)
[02:33:24.737] Build status: STARTED
[02:33:24.737] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:107:0) after 2m16s
[02:35:40.756] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:107:0)
[02:35:40.946] Build status: STARTED
[02:35:40.946] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:108:0) after 7m26s
[02:43:07.091] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:108:0)
[02:43:07.117] Build status: STARTED
[02:43:07.117] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:109:0) after 3m13s
[02:46:20.133] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:109:0)
[02:46:20.195] Build status: STARTED
[02:46:20.195] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:110:0) after 3m7s
[02:49:27.259] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:110:0)
[02:49:27.311] Build status: STARTED
[02:49:27.311] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:111:0) after 7m25s
[02:56:52.443] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:111:0)
[02:56:52.461] Build status: STARTED
[02:56:52.461] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:112:0) after 3m58s
[03:00:50.503] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:112:0)
[03:00:50.543] Build status: STARTED
[03:00:50.543] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:113:0) after 8m50s
[03:09:40.545] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:113:0)
[03:09:40.570] Build status: STARTED
[03:09:40.570] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:114:0) after 1m42s
[03:11:22.598] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:114:0)
[03:11:22.627] Build status: STARTED
[03:11:22.627] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:115:0) after 3m12s
[03:14:34.646] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:115:0)
[03:14:34.677] Build status: STARTED
[03:14:34.677] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833468320309646624:116:0) after 9m13s
[03:16:46.118] Received PubSub notification, asking Buildbucket for the build status
[03:16:46.157] Build:
{
"id": "8668038250320882145",
"builder": {
"project": "chromium-m157",
"bucket": "ci",
"builder": "win32-official"
},
"number": 4,
"createdBy": "project:chromium-m157",
"createTime": "2026-10-13T17:12:49.250694284Z",
"startTime": "2026-10-13T22:01:32.104540236Z",
"endTime": "2026-10-14T03:16:45.860301999Z",
"updateTime": "2026-10-14T03:16:45.860301999Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d7524ec447f2885a6fee5263b2777de3a6527930",
"ref": "refs/branch-heads/8103"
}
},
"canary": true
}
[03:16:46.157] Invocation finished in 10h4m5.816744106s with status SUCCEEDED