[03:16:48.820] New invocation is queued and will start shortly
[03:16:49.929] Starting the invocation (attempt 1)
[03:16:49.953] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/000747f145ec1e26e4dc39da7b6502dead0b4055"
[03:16:49.953] Popped gitiles commit info from properties and tags
[03:16:49.953] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:16:49.953] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:16:49.954] Buildbucket request:
{
"requestId": "8833430311027169440",
"builder": {
"project": "chromium-m157",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8833430311027169440",
"job": "chromium-m157/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/8103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a3c11f6db0092ab79d5cb22a5862ff137fc13de1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8103@a3c11f6db0092ab79d5cb22a5862ff137fc13de1",
"title": "a3c11f6db0092ab79d5cb22a5862ff137fc13de1",
"url": "https://chromium.googlesource.com/chromium/src/+/a3c11f6db0092ab79d5cb22a5862ff137fc13de1"
},
{
"gitiles": {
"ref": "refs/branch-heads/8103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "470e70d5755d732d7329c20b54ec29b263aa86aa"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8103@470e70d5755d732d7329c20b54ec29b263aa86aa",
"title": "470e70d5755d732d7329c20b54ec29b263aa86aa",
"url": "https://chromium.googlesource.com/chromium/src/+/470e70d5755d732d7329c20b54ec29b263aa86aa"
},
{
"gitiles": {
"ref": "refs/branch-heads/8103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dc5f87c8feca135ffd585e2ed385d7e5c5cabef3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8103@dc5f87c8feca135ffd585e2ed385d7e5c5cabef3",
"title": "dc5f87c8feca135ffd585e2ed385d7e5c5cabef3",
"url": "https://chromium.googlesource.com/chromium/src/+/dc5f87c8feca135ffd585e2ed385d7e5c5cabef3"
},
{
"gitiles": {
"ref": "refs/branch-heads/8103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8d43c788b2d84cc0428fb2c35d721b336b23f956"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8103@8d43c788b2d84cc0428fb2c35d721b336b23f956",
"title": "8d43c788b2d84cc0428fb2c35d721b336b23f956",
"url": "https://chromium.googlesource.com/chromium/src/+/8d43c788b2d84cc0428fb2c35d721b336b23f956"
},
{
"gitiles": {
"ref": "refs/branch-heads/8103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9b430b3ecc5ef6cb9fa4adc66a73fa882a33055d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8103@9b430b3ecc5ef6cb9fa4adc66a73fa882a33055d",
"title": "9b430b3ecc5ef6cb9fa4adc66a73fa882a33055d",
"url": "https://chromium.googlesource.com/chromium/src/+/9b430b3ecc5ef6cb9fa4adc66a73fa882a33055d"
},
{
"gitiles": {
"ref": "refs/branch-heads/8103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7af78f20b601542241e330c9dcf4992718885d32"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8103@7af78f20b601542241e330c9dcf4992718885d32",
"title": "7af78f20b601542241e330c9dcf4992718885d32",
"url": "https://chromium.googlesource.com/chromium/src/+/7af78f20b601542241e330c9dcf4992718885d32"
},
{
"gitiles": {
"ref": "refs/branch-heads/8103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "42acd9a19cb793b089ee1729c4d9cd0a389e2d48"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8103@42acd9a19cb793b089ee1729c4d9cd0a389e2d48",
"title": "42acd9a19cb793b089ee1729c4d9cd0a389e2d48",
"url": "https://chromium.googlesource.com/chromium/src/+/42acd9a19cb793b089ee1729c4d9cd0a389e2d48"
},
{
"gitiles": {
"ref": "refs/branch-heads/8103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b7163941b159f4fe309c5398c1ef463292657e75"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8103@b7163941b159f4fe309c5398c1ef463292657e75",
"title": "b7163941b159f4fe309c5398c1ef463292657e75",
"url": "https://chromium.googlesource.com/chromium/src/+/b7163941b159f4fe309c5398c1ef463292657e75"
},
{
"gitiles": {
"ref": "refs/branch-heads/8103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "be0d5a33a48b7a35645a26df5de8c08fc2d41df9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8103@be0d5a33a48b7a35645a26df5de8c08fc2d41df9",
"title": "be0d5a33a48b7a35645a26df5de8c08fc2d41df9",
"url": "https://chromium.googlesource.com/chromium/src/+/be0d5a33a48b7a35645a26df5de8c08fc2d41df9"
},
{
"gitiles": {
"ref": "refs/branch-heads/8103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "000747f145ec1e26e4dc39da7b6502dead0b4055"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/8103@000747f145ec1e26e4dc39da7b6502dead0b4055",
"title": "000747f145ec1e26e4dc39da7b6502dead0b4055",
"url": "https://chromium.googlesource.com/chromium/src/+/000747f145ec1e26e4dc39da7b6502dead0b4055"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "000747f145ec1e26e4dc39da7b6502dead0b4055",
"ref": "refs/branch-heads/8103"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8833430311027169440"
},
{
"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"
}
}
[03:16:50.615] Scheduled build:
{
"id": "8668000249041227025",
"builder": {
"project": "chromium-m157",
"bucket": "ci",
"builder": "win32-official"
},
"number": 5,
"createdBy": "project:chromium-m157",
"createTime": "2026-10-14T03:16:50.094444233Z",
"updateTime": "2026-10-14T03:16:50.094444233Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "000747f145ec1e26e4dc39da7b6502dead0b4055",
"ref": "refs/branch-heads/8103"
}
},
"canary": true
}
[03:16:50.615] Task URL: https://cr-buildbucket.appspot.com/build/8668000249041227025
[03:16:50.615] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:2:0) after 6m52s
[03:16:51.524] Received PubSub notification, asking Buildbucket for the build status
[03:16:51.574] Build status: SCHEDULED
[03:23:42.667] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:2:0)
[03:23:42.689] Build status: SCHEDULED
[03:23:42.689] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:4:0) after 5m21s
[03:29:03.706] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:4:0)
[03:29:03.736] Build status: SCHEDULED
[03:29:03.736] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:5:0) after 7m49s
[03:36:52.810] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:5:0)
[03:36:52.832] Build status: SCHEDULED
[03:36:52.832] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:6:0) after 8m19s
[03:45:11.853] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:6:0)
[03:45:11.884] Build status: SCHEDULED
[03:45:11.884] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:7:0) after 4m29s
[03:49:40.953] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:7:0)
[03:49:40.989] Build status: SCHEDULED
[03:49:40.989] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:8:0) after 2m22s
[03:52:03.211] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:8:0)
[03:52:03.226] Build status: SCHEDULED
[03:52:03.226] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:9:0) after 1m18s
[03:53:21.355] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:9:0)
[03:53:21.380] Build status: SCHEDULED
[03:53:21.380] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:10:0) after 5m49s
[03:59:10.394] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:10:0)
[03:59:10.451] Build status: SCHEDULED
[03:59:10.451] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:11:0) after 4m47s
[04:03:57.469] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:11:0)
[04:03:57.488] Build status: SCHEDULED
[04:03:57.488] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:12:0) after 1m0s
[04:04:57.555] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:12:0)
[04:04:57.964] Build status: SCHEDULED
[04:04:57.964] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:13:0) after 3m46s
[04:08:44.085] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:13:0)
[04:08:44.113] Build status: SCHEDULED
[04:08:44.113] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:14:0) after 6m6s
[04:14:50.163] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:14:0)
[04:14:50.185] Build status: SCHEDULED
[04:14:50.185] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:15:0) after 3m47s
[04:18:37.200] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:15:0)
[04:18:37.283] Build status: SCHEDULED
[04:18:37.283] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:16:0) after 2m19s
[04:20:56.279] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:16:0)
[04:20:56.311] Build status: SCHEDULED
[04:20:56.311] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:17:0) after 9m26s
[04:30:22.331] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:17:0)
[04:30:22.358] Build status: SCHEDULED
[04:30:22.359] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:18:0) after 2m50s
[04:33:12.387] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:18:0)
[04:33:12.409] Build status: SCHEDULED
[04:33:12.409] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:19:0) after 1m23s
[04:34:35.558] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:19:0)
[04:34:35.593] Build status: SCHEDULED
[04:34:35.593] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:20:0) after 8m35s
[04:43:10.660] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:20:0)
[04:43:10.687] Build status: SCHEDULED
[04:43:10.687] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:21:0) after 9m54s
[04:53:04.965] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:21:0)
[04:53:04.982] Build status: SCHEDULED
[04:53:04.983] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:22:0) after 8m44s
[05:01:49.040] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:22:0)
[05:01:49.062] Build status: SCHEDULED
[05:01:49.062] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:23:0) after 9m3s
[05:10:52.188] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:23:0)
[05:10:52.210] Build status: SCHEDULED
[05:10:52.210] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:24:0) after 1m48s
[05:12:40.245] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:24:0)
[05:12:40.308] Build status: SCHEDULED
[05:12:40.308] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:25:0) after 8m5s
[05:20:45.319] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:25:0)
[05:20:45.339] Build status: SCHEDULED
[05:20:45.339] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:26:0) after 4m16s
[05:25:01.356] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:26:0)
[05:25:01.384] Build status: SCHEDULED
[05:25:01.384] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:27:0) after 2m28s
[05:27:29.452] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:27:0)
[05:27:29.482] Build status: SCHEDULED
[05:27:29.482] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:28:0) after 4m15s
[05:31:44.499] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:28:0)
[05:31:44.576] Build status: SCHEDULED
[05:31:44.576] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:29:0) after 1m11s
[05:32:55.769] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:29:0)
[05:32:55.834] Build status: SCHEDULED
[05:32:55.834] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:30:0) after 8m23s
[05:41:18.853] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:30:0)
[05:41:18.878] Build status: SCHEDULED
[05:41:18.878] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:31:0) after 7m9s
[05:48:27.979] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:31:0)
[05:48:28.005] Build status: SCHEDULED
[05:48:28.006] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:32:0) after 1m50s
[05:50:18.076] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:32:0)
[05:50:18.096] Build status: SCHEDULED
[05:50:18.096] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:33:0) after 2m31s
[05:52:49.162] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:33:0)
[05:52:49.183] Build status: SCHEDULED
[05:52:49.183] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:34:0) after 8m54s
[06:01:43.264] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:34:0)
[06:01:43.283] Build status: SCHEDULED
[06:01:43.283] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:35:0) after 3m24s
[06:05:07.354] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:35:0)
[06:05:07.382] Build status: SCHEDULED
[06:05:07.382] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:36:0) after 9m6s
[06:14:13.749] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:36:0)
[06:14:13.772] Build status: SCHEDULED
[06:14:13.772] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:37:0) after 1m30s
[06:15:44.044] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:37:0)
[06:15:44.071] Build status: SCHEDULED
[06:15:44.071] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:38:0) after 8m21s
[06:24:05.089] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:38:0)
[06:24:05.113] Build status: SCHEDULED
[06:24:05.113] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:39:0) after 5m1s
[06:29:06.171] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:39:0)
[06:29:06.202] Build status: SCHEDULED
[06:29:06.202] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:40:0) after 8m29s
[06:37:35.310] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:40:0)
[06:37:35.375] Build status: SCHEDULED
[06:37:35.375] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:41:0) after 3m43s
[06:41:18.456] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:41:0)
[06:41:18.475] Build status: SCHEDULED
[06:41:18.475] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:42:0) after 9m47s
[06:51:05.627] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:42:0)
[06:51:05.659] Build status: SCHEDULED
[06:51:05.659] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:43:0) after 4m43s
[06:55:48.739] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:43:0)
[06:55:48.773] Build status: SCHEDULED
[06:55:48.774] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:44:0) after 6m36s
[07:02:24.840] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:44:0)
[07:02:24.864] Build status: SCHEDULED
[07:02:24.865] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:45:0) after 4m49s
[07:05:45.941] Received PubSub notification, asking Buildbucket for the build status
[07:05:45.965] Build status: STARTED
[07:07:13.885] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:45:0)
[07:07:13.910] Build status: STARTED
[07:07:13.910] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:47:0) after 6m41s
[07:13:54.976] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:47:0)
[07:13:54.996] Build status: STARTED
[07:13:54.996] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:48:0) after 9m28s
[07:23:23.163] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:48:0)
[07:23:23.184] Build status: STARTED
[07:23:23.184] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:49:0) after 9m42s
[07:33:05.206] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:49:0)
[07:33:05.264] Build status: STARTED
[07:33:05.264] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:50:0) after 9m57s
[07:43:02.304] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:50:0)
[07:43:02.320] Build status: STARTED
[07:43:02.320] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:51:0) after 1m58s
[07:45:00.527] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:51:0)
[07:45:00.551] Build status: STARTED
[07:45:00.551] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:52:0) after 3m40s
[07:48:40.592] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:52:0)
[07:48:40.614] Build status: STARTED
[07:48:40.614] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:53:0) after 4m6s
[07:52:46.658] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:53:0)
[07:52:46.680] Build status: STARTED
[07:52:46.680] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:54:0) after 9m54s
[08:02:40.783] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:54:0)
[08:02:40.836] Build status: STARTED
[08:02:40.836] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:55:0) after 3m48s
[08:06:28.858] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:55:0)
[08:06:28.878] Build status: STARTED
[08:06:28.878] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:56:0) after 2m12s
[08:08:41.045] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:56:0)
[08:08:41.118] Build status: STARTED
[08:08:41.118] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:57:0) after 7m7s
[08:15:48.101] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:57:0)
[08:15:48.169] Build status: STARTED
[08:15:48.169] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:58:0) after 7m11s
[08:22:59.219] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:58:0)
[08:22:59.253] Build status: STARTED
[08:22:59.253] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:59:0) after 2m59s
[08:25:58.317] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:59:0)
[08:25:58.342] Build status: STARTED
[08:25:58.343] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:60:0) after 1m25s
[08:27:23.317] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:60:0)
[08:27:23.339] Build status: STARTED
[08:27:23.339] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:61:0) after 8m57s
[08:36:20.400] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:61:0)
[08:36:20.428] Build status: STARTED
[08:36:20.428] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:62:0) after 2m25s
[08:38:45.534] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:62:0)
[08:38:45.554] Build status: STARTED
[08:38:45.554] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:63:0) after 9m18s
[08:48:03.670] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:63:0)
[08:48:03.710] Build status: STARTED
[08:48:03.710] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:64:0) after 2m59s
[08:51:02.776] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:64:0)
[08:51:02.795] Build status: STARTED
[08:51:02.795] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:65:0) after 9m36s
[09:00:38.915] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:65:0)
[09:00:38.935] Build status: STARTED
[09:00:38.935] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:66:0) after 4m23s
[09:05:02.014] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:66:0)
[09:05:02.062] Build status: STARTED
[09:05:02.062] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:67:0) after 9m5s
[09:14:07.132] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:67:0)
[09:14:07.153] Build status: STARTED
[09:14:07.154] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:68:0) after 9m33s
[09:23:40.173] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:68:0)
[09:23:40.191] Build status: STARTED
[09:23:40.192] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:69:0) after 8m49s
[09:32:29.257] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:69:0)
[09:32:29.278] Build status: STARTED
[09:32:29.278] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:70:0) after 8m3s
[09:40:32.450] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:70:0)
[09:40:32.496] Build status: STARTED
[09:40:32.496] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:71:0) after 2m37s
[09:43:09.521] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:71:0)
[09:43:09.542] Build status: STARTED
[09:43:09.542] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:72:0) after 7m51s
[09:51:00.559] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:72:0)
[09:51:00.578] Build status: STARTED
[09:51:00.578] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:73:0) after 7m27s
[09:58:27.597] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:73:0)
[09:58:27.630] Build status: STARTED
[09:58:27.630] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:74:0) after 4m18s
[10:02:45.723] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:74:0)
[10:02:45.744] Build status: STARTED
[10:02:45.744] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:75:0) after 4m17s
[10:07:02.813] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:75:0)
[10:07:02.838] Build status: STARTED
[10:07:02.838] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:76:0) after 8m10s
[10:15:12.947] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:76:0)
[10:15:12.964] Build status: STARTED
[10:15:12.964] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:77:0) after 7m22s
[10:22:35.017] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:77:0)
[10:22:35.048] Build status: STARTED
[10:22:35.048] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:78:0) after 8m31s
[10:31:06.169] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:78:0)
[10:31:06.185] Build status: STARTED
[10:31:06.185] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:79:0) after 1m41s
[10:32:47.248] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:79:0)
[10:32:47.285] Build status: STARTED
[10:32:47.285] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:80:0) after 8m34s
[10:41:21.305] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:80:0)
[10:41:21.423] Build status: STARTED
[10:41:21.423] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:81:0) after 4m1s
[10:45:22.545] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:81:0)
[10:45:22.570] Build status: STARTED
[10:45:22.570] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:82:0) after 2m43s
[10:48:05.550] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:82:0)
[10:48:05.568] Build status: STARTED
[10:48:05.568] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:83:0) after 8m12s
[10:56:17.588] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:83:0)
[10:56:17.608] Build status: STARTED
[10:56:17.608] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:84:0) after 8m57s
[11:05:14.689] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:84:0)
[11:05:14.747] Build status: STARTED
[11:05:14.747] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:85:0) after 4m48s
[11:10:02.817] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:85:0)
[11:10:02.883] Build status: STARTED
[11:10:02.883] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:86:0) after 8m7s
[11:18:09.949] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:86:0)
[11:18:10.004] Build status: STARTED
[11:18:10.004] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:87:0) after 7m45s
[11:25:55.033] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:87:0)
[11:25:55.060] Build status: STARTED
[11:25:55.060] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:88:0) after 2m41s
[11:28:36.098] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:88:0)
[11:28:36.151] Build status: STARTED
[11:28:36.151] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:89:0) after 7m17s
[11:35:53.165] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:89:0)
[11:35:53.188] Build status: STARTED
[11:35:53.188] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:90:0) after 9m22s
[11:45:15.210] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:90:0)
[11:45:15.322] Build status: STARTED
[11:45:15.322] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:91:0) after 4m3s
[11:49:18.343] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:91:0)
[11:49:18.376] Build status: STARTED
[11:49:18.376] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:92:0) after 6m33s
[11:55:51.521] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:92:0)
[11:55:51.540] Build status: STARTED
[11:55:51.540] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:93:0) after 8m8s
[12:03:59.527] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:93:0)
[12:03:59.679] Build status: STARTED
[12:03:59.679] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:94:0) after 2m20s
[12:06:19.702] Handling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:94:0)
[12:06:19.747] Build status: STARTED
[12:06:19.747] Scheduling timer "check-buildbucket-build-status" (chromium-m157/win32-official:8833430311027169440:95:0) after 4m40s
[12:07:32.484] Received PubSub notification, asking Buildbucket for the build status
[12:07:32.506] Build:
{
"id": "8668000249041227025",
"builder": {
"project": "chromium-m157",
"bucket": "ci",
"builder": "win32-official"
},
"number": 5,
"createdBy": "project:chromium-m157",
"createTime": "2026-10-14T03:16:50.094444233Z",
"startTime": "2026-10-14T07:05:44.058287021Z",
"endTime": "2026-10-14T12:07:32.160525779Z",
"updateTime": "2026-10-14T12:07:32.160525779Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "000747f145ec1e26e4dc39da7b6502dead0b4055",
"ref": "refs/branch-heads/8103"
}
},
"canary": true
}
[12:07:32.506] Invocation finished in 8h50m43.689668895s with status SUCCEEDED