[11:59:46.175] New invocation is queued and will start shortly
[11:59:47.260] Starting the invocation (attempt 1)
[11:59:47.288] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c752e732a3d99943b6213d19d78a3313721e2726"
[11:59:47.289] Popped gitiles commit info from properties and tags
[11:59:47.289] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:59:47.289] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:59:47.290] Buildbucket request:
{
"requestId": "8856318441994728224",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8856318441994728224",
"job": "chromium-m144/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "42b45ad0eb73b6e37b65107d99bedb9a6880b3ea"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@42b45ad0eb73b6e37b65107d99bedb9a6880b3ea",
"title": "42b45ad0eb73b6e37b65107d99bedb9a6880b3ea",
"url": "https://chromium.googlesource.com/chromium/src/+/42b45ad0eb73b6e37b65107d99bedb9a6880b3ea"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d325045be3b2a5804fa2400edf8240d81217e422"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@d325045be3b2a5804fa2400edf8240d81217e422",
"title": "d325045be3b2a5804fa2400edf8240d81217e422",
"url": "https://chromium.googlesource.com/chromium/src/+/d325045be3b2a5804fa2400edf8240d81217e422"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "210cd90ea43024d139a89bfa6437a78f9dbbd2be"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@210cd90ea43024d139a89bfa6437a78f9dbbd2be",
"title": "210cd90ea43024d139a89bfa6437a78f9dbbd2be",
"url": "https://chromium.googlesource.com/chromium/src/+/210cd90ea43024d139a89bfa6437a78f9dbbd2be"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ed780631ad0cd2c5613ffa28bcb89ddf073d1a2a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@ed780631ad0cd2c5613ffa28bcb89ddf073d1a2a",
"title": "ed780631ad0cd2c5613ffa28bcb89ddf073d1a2a",
"url": "https://chromium.googlesource.com/chromium/src/+/ed780631ad0cd2c5613ffa28bcb89ddf073d1a2a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c0a5d1b01ec7fa203dc18903cf4fce5e8c5b3bd7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@c0a5d1b01ec7fa203dc18903cf4fce5e8c5b3bd7",
"title": "c0a5d1b01ec7fa203dc18903cf4fce5e8c5b3bd7",
"url": "https://chromium.googlesource.com/chromium/src/+/c0a5d1b01ec7fa203dc18903cf4fce5e8c5b3bd7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2968f5f149904a8c199425f92ac36bf4bf1eacee"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@2968f5f149904a8c199425f92ac36bf4bf1eacee",
"title": "2968f5f149904a8c199425f92ac36bf4bf1eacee",
"url": "https://chromium.googlesource.com/chromium/src/+/2968f5f149904a8c199425f92ac36bf4bf1eacee"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8184bb4f5218b3b8350d07e4a8685ffd836df46f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@8184bb4f5218b3b8350d07e4a8685ffd836df46f",
"title": "8184bb4f5218b3b8350d07e4a8685ffd836df46f",
"url": "https://chromium.googlesource.com/chromium/src/+/8184bb4f5218b3b8350d07e4a8685ffd836df46f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ef7320202a906e68ba4ae1ba8e24b8f433e70dd0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@ef7320202a906e68ba4ae1ba8e24b8f433e70dd0",
"title": "ef7320202a906e68ba4ae1ba8e24b8f433e70dd0",
"url": "https://chromium.googlesource.com/chromium/src/+/ef7320202a906e68ba4ae1ba8e24b8f433e70dd0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c752e732a3d99943b6213d19d78a3313721e2726"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@c752e732a3d99943b6213d19d78a3313721e2726",
"title": "c752e732a3d99943b6213d19d78a3313721e2726",
"url": "https://chromium.googlesource.com/chromium/src/+/c752e732a3d99943b6213d19d78a3313721e2726"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c752e732a3d99943b6213d19d78a3313721e2726",
"ref": "refs/branch-heads/7559"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8856318441994728224"
},
{
"key": "scheduler_job_id",
"value": "chromium-m144/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:59:47.779] Scheduled build:
{
"id": "8690888380120745217",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win-official"
},
"number": 496,
"createdBy": "project:chromium-m144",
"createTime": "2026-02-03T11:59:47.333049725Z",
"updateTime": "2026-02-03T11:59:47.333049725Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c752e732a3d99943b6213d19d78a3313721e2726",
"ref": "refs/branch-heads/7559"
}
}
}
[11:59:47.779] Task URL: https://cr-buildbucket.appspot.com/build/8690888380120745217
[11:59:47.779] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:2:0) after 9m1s
[11:59:48.744] Received PubSub notification, asking Buildbucket for the build status
[11:59:48.762] Build status: SCHEDULED
[11:59:54.371] Received PubSub notification, asking Buildbucket for the build status
[11:59:54.445] Build status: STARTED
[12:08:49.021] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:2:0)
[12:08:49.056] Build status: STARTED
[12:08:49.056] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:5:0) after 7m44s
[12:16:33.094] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:5:0)
[12:16:33.116] Build status: STARTED
[12:16:33.116] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:6:0) after 4m26s
[12:20:59.128] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:6:0)
[12:20:59.143] Build status: STARTED
[12:20:59.144] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:7:0) after 5m41s
[12:26:40.252] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:7:0)
[12:26:40.270] Build status: STARTED
[12:26:40.270] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:8:0) after 4m7s
[12:30:47.281] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:8:0)
[12:30:47.315] Build status: STARTED
[12:30:47.315] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:9:0) after 2m44s
[12:33:31.425] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:9:0)
[12:33:31.441] Build status: STARTED
[12:33:31.441] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:10:0) after 7m52s
[12:41:23.457] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:10:0)
[12:41:23.481] Build status: STARTED
[12:41:23.481] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:11:0) after 3m36s
[12:44:59.447] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:11:0)
[12:44:59.465] Build status: STARTED
[12:44:59.465] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:12:0) after 8m58s
[12:53:57.497] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:12:0)
[12:53:57.515] Build status: STARTED
[12:53:57.515] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:13:0) after 1m40s
[12:55:37.594] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:13:0)
[12:55:37.671] Build status: STARTED
[12:55:37.671] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:14:0) after 6m28s
[13:02:05.663] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:14:0)
[13:02:05.740] Build status: STARTED
[13:02:05.740] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:15:0) after 8m58s
[13:11:03.812] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:15:0)
[13:11:03.838] Build status: STARTED
[13:11:03.838] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:16:0) after 4m31s
[13:15:34.901] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:16:0)
[13:15:34.943] Build status: STARTED
[13:15:34.943] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:17:0) after 8m38s
[13:24:12.970] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:17:0)
[13:24:12.985] Build status: STARTED
[13:24:12.985] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:18:0) after 5m40s
[13:29:52.957] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:18:0)
[13:29:52.971] Build status: STARTED
[13:29:52.971] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:19:0) after 2m15s
[13:32:07.994] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:19:0)
[13:32:08.010] Build status: STARTED
[13:32:08.010] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:20:0) after 8m24s
[13:40:32.043] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:20:0)
[13:40:32.058] Build status: STARTED
[13:40:32.058] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:21:0) after 3m44s
[13:44:16.074] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:21:0)
[13:44:16.091] Build status: STARTED
[13:44:16.091] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:22:0) after 1m0s
[13:45:16.102] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:22:0)
[13:45:16.127] Build status: STARTED
[13:45:16.127] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:23:0) after 9m4s
[13:54:20.204] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:23:0)
[13:54:20.226] Build status: STARTED
[13:54:20.226] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:24:0) after 3m1s
[13:57:21.289] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:24:0)
[13:57:21.307] Build status: STARTED
[13:57:21.307] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:25:0) after 7m21s
[14:04:42.348] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:25:0)
[14:04:42.379] Build status: STARTED
[14:04:42.379] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:26:0) after 1m31s
[14:06:13.356] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:26:0)
[14:06:13.372] Build status: STARTED
[14:06:13.373] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:27:0) after 6m4s
[14:12:17.451] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:27:0)
[14:12:17.466] Build status: STARTED
[14:12:17.466] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:28:0) after 3m23s
[14:15:40.479] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:28:0)
[14:15:40.498] Build status: STARTED
[14:15:40.498] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:29:0) after 9m32s
[14:25:12.584] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:29:0)
[14:25:12.606] Build status: STARTED
[14:25:12.606] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:30:0) after 7m57s
[14:33:09.657] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:30:0)
[14:33:09.810] Build status: STARTED
[14:33:09.810] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:31:0) after 2m27s
[14:35:36.825] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:31:0)
[14:35:36.842] Build status: STARTED
[14:35:36.842] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:32:0) after 4m43s
[14:40:19.859] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:32:0)
[14:40:19.871] Build status: STARTED
[14:40:19.871] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:33:0) after 9m43s
[14:50:02.963] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:33:0)
[14:50:03.027] Build status: STARTED
[14:50:03.027] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:34:0) after 6m31s
[14:56:34.095] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:34:0)
[14:56:34.112] Build status: STARTED
[14:56:34.112] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:35:0) after 3m0s
[14:59:34.128] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:35:0)
[14:59:34.148] Build status: STARTED
[14:59:34.148] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:36:0) after 8m6s
[15:07:40.133] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:36:0)
[15:07:40.164] Build status: STARTED
[15:07:40.164] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:37:0) after 2m0s
[15:09:40.200] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:37:0)
[15:09:40.235] Build status: STARTED
[15:09:40.235] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:38:0) after 5m13s
[15:14:53.383] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:38:0)
[15:14:53.397] Build status: STARTED
[15:14:53.397] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:39:0) after 4m13s
[15:19:06.419] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:39:0)
[15:19:06.438] Build status: STARTED
[15:19:06.438] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:40:0) after 2m6s
[15:21:12.479] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:40:0)
[15:21:12.496] Build status: STARTED
[15:21:12.496] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:41:0) after 5m51s
[15:27:03.508] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:41:0)
[15:27:03.522] Build status: STARTED
[15:27:03.522] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:42:0) after 6m30s
[15:33:33.605] Handling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:42:0)
[15:33:33.624] Build status: STARTED
[15:33:33.624] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-official:8856318441994728224:43:0) after 5m9s
[15:35:06.731] Received PubSub notification, asking Buildbucket for the build status
[15:35:06.750] Build:
{
"id": "8690888380120745217",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win-official"
},
"number": 496,
"createdBy": "project:chromium-m144",
"createTime": "2026-02-03T11:59:47.333049725Z",
"startTime": "2026-02-03T11:59:52.010517201Z",
"endTime": "2026-02-03T15:35:06.312160493Z",
"updateTime": "2026-02-03T15:35:06.312160493Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c752e732a3d99943b6213d19d78a3313721e2726",
"ref": "refs/branch-heads/7559"
}
}
}
[15:35:06.750] Invocation finished in 3h35m20.588357995s with status SUCCEEDED