[09:14:42.152] New invocation is queued and will start shortly
[09:14:43.238] Starting the invocation (attempt 1)
[09:14:43.266] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1ed1bef65d2844d42d9651ae6572ad7f18586a26"
[09:14:43.266] Popped gitiles commit info from properties and tags
[09:14:43.266] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:14:43.266] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:14:43.266] Buildbucket request:
{
"requestId": "8859046736097756992",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8859046736097756992",
"job": "chromium-m144/win-arm64-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8837282360501e4bb73eefc785a6b63bdfaa382a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@8837282360501e4bb73eefc785a6b63bdfaa382a",
"title": "8837282360501e4bb73eefc785a6b63bdfaa382a",
"url": "https://chromium.googlesource.com/chromium/src/+/8837282360501e4bb73eefc785a6b63bdfaa382a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "82b39890182b5b2f74d9336c90f6362d3f7b1c14"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@82b39890182b5b2f74d9336c90f6362d3f7b1c14",
"title": "82b39890182b5b2f74d9336c90f6362d3f7b1c14",
"url": "https://chromium.googlesource.com/chromium/src/+/82b39890182b5b2f74d9336c90f6362d3f7b1c14"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2fc8cd57ecb077061526bb1d025505737c5dc887"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@2fc8cd57ecb077061526bb1d025505737c5dc887",
"title": "2fc8cd57ecb077061526bb1d025505737c5dc887",
"url": "https://chromium.googlesource.com/chromium/src/+/2fc8cd57ecb077061526bb1d025505737c5dc887"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "730673d654c5fb304828216ca74b42ec5b0fc763"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@730673d654c5fb304828216ca74b42ec5b0fc763",
"title": "730673d654c5fb304828216ca74b42ec5b0fc763",
"url": "https://chromium.googlesource.com/chromium/src/+/730673d654c5fb304828216ca74b42ec5b0fc763"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "55ed51548d97b63c13117982fe39dd48a7f5cf01"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@55ed51548d97b63c13117982fe39dd48a7f5cf01",
"title": "55ed51548d97b63c13117982fe39dd48a7f5cf01",
"url": "https://chromium.googlesource.com/chromium/src/+/55ed51548d97b63c13117982fe39dd48a7f5cf01"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1ed1bef65d2844d42d9651ae6572ad7f18586a26"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@1ed1bef65d2844d42d9651ae6572ad7f18586a26",
"title": "1ed1bef65d2844d42d9651ae6572ad7f18586a26",
"url": "https://chromium.googlesource.com/chromium/src/+/1ed1bef65d2844d42d9651ae6572ad7f18586a26"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1ed1bef65d2844d42d9651ae6572ad7f18586a26",
"ref": "refs/branch-heads/7559"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8859046736097756992"
},
{
"key": "scheduler_job_id",
"value": "chromium-m144/win-arm64-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[09:14:43.940] Scheduled build:
{
"id": "8693616674183764161",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 327,
"createdBy": "project:chromium-m144",
"createTime": "2026-01-04T09:14:43.357643510Z",
"updateTime": "2026-01-04T09:14:43.357643510Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1ed1bef65d2844d42d9651ae6572ad7f18586a26",
"ref": "refs/branch-heads/7559"
}
}
}
[09:14:43.940] Task URL: https://cr-buildbucket.appspot.com/build/8693616674183764161
[09:14:43.940] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:2:0) after 1m51s
[09:14:52.687] Received PubSub notification, asking Buildbucket for the build status
[09:14:52.713] Build status: SCHEDULED
[09:15:16.328] Received PubSub notification, asking Buildbucket for the build status
[09:15:16.341] Build status: STARTED
[09:16:35.111] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:2:0)
[09:16:35.136] Build status: STARTED
[09:16:35.136] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:5:0) after 1m13s
[09:17:48.171] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:5:0)
[09:17:48.189] Build status: STARTED
[09:17:48.189] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:6:0) after 7m0s
[09:24:48.163] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:6:0)
[09:24:48.233] Build status: STARTED
[09:24:48.233] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:7:0) after 6m39s
[09:31:27.248] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:7:0)
[09:31:27.276] Build status: STARTED
[09:31:27.276] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:8:0) after 4m39s
[09:36:06.291] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:8:0)
[09:36:06.706] Build status: STARTED
[09:36:06.706] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:9:0) after 5m11s
[09:41:17.826] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:9:0)
[09:41:17.847] Build status: STARTED
[09:41:17.847] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:10:0) after 3m23s
[09:44:40.864] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:10:0)
[09:44:40.883] Build status: STARTED
[09:44:40.883] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:11:0) after 4m11s
[09:48:52.032] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:11:0)
[09:48:52.151] Build status: STARTED
[09:48:52.151] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:12:0) after 4m8s
[09:53:00.143] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:12:0)
[09:53:00.179] Build status: STARTED
[09:53:00.179] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:13:0) after 2m12s
[09:55:12.417] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:13:0)
[09:55:12.444] Build status: STARTED
[09:55:12.444] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:14:0) after 6m39s
[10:01:51.460] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:14:0)
[10:01:51.481] Build status: STARTED
[10:01:51.481] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:15:0) after 3m23s
[10:05:14.497] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:15:0)
[10:05:14.516] Build status: STARTED
[10:05:14.516] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:16:0) after 6m41s
[10:11:55.531] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:16:0)
[10:11:55.549] Build status: STARTED
[10:11:55.549] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:17:0) after 3m18s
[10:15:13.892] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:17:0)
[10:15:14.156] Build status: STARTED
[10:15:14.156] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:18:0) after 1m5s
[10:16:19.176] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:18:0)
[10:16:19.193] Build status: STARTED
[10:16:19.193] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:19:0) after 8m47s
[10:25:06.359] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:19:0)
[10:25:06.376] Build status: STARTED
[10:25:06.376] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:20:0) after 8m2s
[10:33:08.394] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:20:0)
[10:33:08.422] Build status: STARTED
[10:33:08.422] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:21:0) after 5m39s
[10:38:47.557] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:21:0)
[10:38:47.569] Build status: STARTED
[10:38:47.569] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:22:0) after 7m59s
[10:46:46.586] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:22:0)
[10:46:46.603] Build status: STARTED
[10:46:46.603] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:23:0) after 1m1s
[10:47:47.985] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:23:0)
[10:47:48.008] Build status: STARTED
[10:47:48.008] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:24:0) after 9m7s
[10:56:55.170] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:24:0)
[10:56:55.186] Build status: STARTED
[10:56:55.186] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:25:0) after 1m28s
[10:58:23.353] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:25:0)
[10:58:23.378] Build status: STARTED
[10:58:23.378] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:26:0) after 7m39s
[11:06:02.646] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:26:0)
[11:06:02.665] Build status: STARTED
[11:06:02.665] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:27:0) after 3m3s
[11:09:05.679] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:27:0)
[11:09:05.696] Build status: STARTED
[11:09:05.696] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:28:0) after 1m5s
[11:10:11.130] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:28:0)
[11:10:11.147] Build status: STARTED
[11:10:11.147] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:29:0) after 2m39s
[11:12:50.266] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:29:0)
[11:12:50.301] Build status: STARTED
[11:12:50.301] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:30:0) after 3m49s
[11:16:39.293] Handling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:30:0)
[11:16:39.316] Build status: STARTED
[11:16:39.317] Scheduling timer "check-buildbucket-build-status" (chromium-m144/win-arm64-rel:8859046736097756992:31:0) after 6m27s
[11:22:11.119] Received PubSub notification, asking Buildbucket for the build status
[11:22:11.137] Build:
{
"id": "8693616674183764161",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 327,
"createdBy": "project:chromium-m144",
"createTime": "2026-01-04T09:14:43.357643510Z",
"startTime": "2026-01-04T09:15:02.682986812Z",
"endTime": "2026-01-04T11:22:10.897553712Z",
"updateTime": "2026-01-04T11:22:10.897553712Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1ed1bef65d2844d42d9651ae6572ad7f18586a26",
"ref": "refs/branch-heads/7559"
}
}
}
[11:22:11.137] Invocation finished in 2h7m28.988778098s with status SUCCEEDED