[21:14:47.102] New invocation is queued and will start shortly
[21:14:48.318] Starting the invocation (attempt 1)
[21:14:48.339] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/71a0dbd6672e2ccb6d1008376cbb7acd315cb8d6"
[21:14:48.339] Popped gitiles commit info from properties and tags
[21:14:48.339] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:14:48.340] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:14:48.340] Buildbucket request:
{
"requestId": "8861447550525736448",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8861447550525736448",
"job": "chromium-m143/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dd19799a3cf16b13c2f70f45154c42b6351f2f6d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@dd19799a3cf16b13c2f70f45154c42b6351f2f6d",
"title": "dd19799a3cf16b13c2f70f45154c42b6351f2f6d",
"url": "https://chromium.googlesource.com/chromium/src/+/dd19799a3cf16b13c2f70f45154c42b6351f2f6d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5a4bb5107567b964ae6f4419810d0b0810a25e6f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@5a4bb5107567b964ae6f4419810d0b0810a25e6f",
"title": "5a4bb5107567b964ae6f4419810d0b0810a25e6f",
"url": "https://chromium.googlesource.com/chromium/src/+/5a4bb5107567b964ae6f4419810d0b0810a25e6f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "149a44771019cf510decff7e8b3079616d70f55a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@149a44771019cf510decff7e8b3079616d70f55a",
"title": "149a44771019cf510decff7e8b3079616d70f55a",
"url": "https://chromium.googlesource.com/chromium/src/+/149a44771019cf510decff7e8b3079616d70f55a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "52122138d6f363f1561fc050e4b5adc33046dffd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@52122138d6f363f1561fc050e4b5adc33046dffd",
"title": "52122138d6f363f1561fc050e4b5adc33046dffd",
"url": "https://chromium.googlesource.com/chromium/src/+/52122138d6f363f1561fc050e4b5adc33046dffd"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e26fab6af60509cc75fc66181910dea056530810"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@e26fab6af60509cc75fc66181910dea056530810",
"title": "e26fab6af60509cc75fc66181910dea056530810",
"url": "https://chromium.googlesource.com/chromium/src/+/e26fab6af60509cc75fc66181910dea056530810"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "71a0dbd6672e2ccb6d1008376cbb7acd315cb8d6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@71a0dbd6672e2ccb6d1008376cbb7acd315cb8d6",
"title": "71a0dbd6672e2ccb6d1008376cbb7acd315cb8d6",
"url": "https://chromium.googlesource.com/chromium/src/+/71a0dbd6672e2ccb6d1008376cbb7acd315cb8d6"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "71a0dbd6672e2ccb6d1008376cbb7acd315cb8d6",
"ref": "refs/branch-heads/7499"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8861447550525736448"
},
{
"key": "scheduler_job_id",
"value": "chromium-m143/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:14:48.852] Scheduled build:
{
"id": "8696017488513728465",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "win32-official"
},
"number": 256,
"createdBy": "project:chromium-m143",
"createTime": "2025-12-08T21:14:48.392371875Z",
"updateTime": "2025-12-08T21:14:48.392371875Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "71a0dbd6672e2ccb6d1008376cbb7acd315cb8d6",
"ref": "refs/branch-heads/7499"
}
}
}
[21:14:48.852] Task URL: https://cr-buildbucket.appspot.com/build/8696017488513728465
[21:14:48.852] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:2:0) after 1m41s
[21:14:49.820] Received PubSub notification, asking Buildbucket for the build status
[21:14:49.844] Build status: SCHEDULED
[21:14:52.700] Received PubSub notification, asking Buildbucket for the build status
[21:14:52.729] Build status: STARTED
[21:16:29.874] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:2:0)
[21:16:29.894] Build status: STARTED
[21:16:29.894] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:5:0) after 1m35s
[21:18:04.867] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:5:0)
[21:18:04.892] Build status: STARTED
[21:18:04.892] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:6:0) after 6m11s
[21:24:15.981] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:6:0)
[21:24:15.997] Build status: STARTED
[21:24:15.997] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:7:0) after 4m32s
[21:28:48.009] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:7:0)
[21:28:48.100] Build status: STARTED
[21:28:48.100] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:8:0) after 7m42s
[21:36:30.113] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:8:0)
[21:36:30.141] Build status: STARTED
[21:36:30.141] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:9:0) after 1m25s
[21:37:55.155] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:9:0)
[21:37:55.180] Build status: STARTED
[21:37:55.180] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:10:0) after 6m44s
[21:44:39.152] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:10:0)
[21:44:39.257] Build status: STARTED
[21:44:39.257] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:11:0) after 7m2s
[21:51:41.269] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:11:0)
[21:51:41.412] Build status: STARTED
[21:51:41.412] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:12:0) after 3m20s
[21:55:01.438] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:12:0)
[21:55:01.455] Build status: STARTED
[21:55:01.455] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:13:0) after 3m45s
[21:58:46.429] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:13:0)
[21:58:46.442] Build status: STARTED
[21:58:46.442] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:14:0) after 4m46s
[22:03:32.495] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:14:0)
[22:03:32.510] Build status: STARTED
[22:03:32.510] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:15:0) after 1m37s
[22:05:09.522] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:15:0)
[22:05:09.541] Build status: STARTED
[22:05:09.541] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:16:0) after 1m34s
[22:06:43.546] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:16:0)
[22:06:43.583] Build status: STARTED
[22:06:43.583] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:17:0) after 6m54s
[22:13:37.594] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:17:0)
[22:13:37.681] Build status: STARTED
[22:13:37.681] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:18:0) after 6m6s
[22:19:43.724] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:18:0)
[22:19:43.743] Build status: STARTED
[22:19:43.743] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:19:0) after 7m48s
[22:27:31.813] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:19:0)
[22:27:31.844] Build status: STARTED
[22:27:31.844] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:20:0) after 9m34s
[22:37:05.858] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:20:0)
[22:37:05.930] Build status: STARTED
[22:37:05.930] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:21:0) after 1m17s
[22:38:22.942] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:21:0)
[22:38:22.968] Build status: STARTED
[22:38:22.968] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:22:0) after 6m16s
[22:44:38.962] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:22:0)
[22:44:38.978] Build status: STARTED
[22:44:38.978] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:23:0) after 1m52s
[22:46:31.330] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:23:0)
[22:46:31.351] Build status: STARTED
[22:46:31.351] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:24:0) after 6m13s
[22:52:44.366] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:24:0)
[22:52:44.380] Build status: STARTED
[22:52:44.380] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:25:0) after 6m48s
[22:59:32.396] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:25:0)
[22:59:32.597] Build status: STARTED
[22:59:32.597] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:26:0) after 3m39s
[23:03:11.612] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:26:0)
[23:03:11.650] Build status: STARTED
[23:03:11.650] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:27:0) after 2m0s
[23:05:11.667] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:27:0)
[23:05:11.684] Build status: STARTED
[23:05:11.684] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:28:0) after 9m28s
[23:14:39.739] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:28:0)
[23:14:39.757] Build status: STARTED
[23:14:39.757] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:29:0) after 1m7s
[23:15:46.818] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:29:0)
[23:15:46.833] Build status: STARTED
[23:15:46.833] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:30:0) after 5m35s
[23:21:21.888] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:30:0)
[23:21:21.903] Build status: STARTED
[23:21:21.903] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:31:0) after 3m31s
[23:24:52.969] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:31:0)
[23:24:53.044] Build status: STARTED
[23:24:53.044] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:32:0) after 8m46s
[23:33:39.060] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:32:0)
[23:33:39.121] Build status: STARTED
[23:33:39.121] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:33:0) after 1m38s
[23:35:17.138] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:33:0)
[23:35:17.163] Build status: STARTED
[23:35:17.164] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:34:0) after 6m51s
[23:42:08.182] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:34:0)
[23:42:08.208] Build status: STARTED
[23:42:08.209] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:35:0) after 7m53s
[23:50:01.221] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:35:0)
[23:50:01.235] Build status: STARTED
[23:50:01.235] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:36:0) after 9m14s
[23:59:15.267] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:36:0)
[23:59:15.282] Build status: STARTED
[23:59:15.283] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:37:0) after 3m52s
[00:03:07.425] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:37:0)
[00:03:07.445] Build status: STARTED
[00:03:07.445] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:38:0) after 5m37s
[00:08:44.510] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:38:0)
[00:08:44.528] Build status: STARTED
[00:08:44.528] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:39:0) after 5m33s
[00:14:17.659] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:39:0)
[00:14:17.740] Build status: STARTED
[00:14:17.741] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:40:0) after 8m27s
[00:22:44.757] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:40:0)
[00:22:44.778] Build status: STARTED
[00:22:44.778] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:41:0) after 6m47s
[00:29:31.786] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:41:0)
[00:29:31.802] Build status: STARTED
[00:29:31.802] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:42:0) after 7m51s
[00:37:22.864] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:42:0)
[00:37:22.897] Build status: STARTED
[00:37:22.897] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:43:0) after 7m2s
[00:44:25.211] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:43:0)
[00:44:25.236] Build status: STARTED
[00:44:25.236] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:44:0) after 7m7s
[00:51:32.372] Handling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:44:0)
[00:51:32.577] Build status: STARTED
[00:51:32.577] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win32-official:8861447550525736448:45:0) after 8m54s
[00:52:02.979] Received PubSub notification, asking Buildbucket for the build status
[00:52:03.027] Build:
{
"id": "8696017488513728465",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "win32-official"
},
"number": 256,
"createdBy": "project:chromium-m143",
"createTime": "2025-12-08T21:14:48.392371875Z",
"startTime": "2025-12-08T21:14:52.519556906Z",
"endTime": "2025-12-09T00:52:02.750386090Z",
"updateTime": "2025-12-09T00:52:02.750386090Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "71a0dbd6672e2ccb6d1008376cbb7acd315cb8d6",
"ref": "refs/branch-heads/7499"
}
}
}
[00:52:03.027] Invocation finished in 3h37m15.936703782s with status SUCCEEDED