[23:43:50.485] New invocation is queued and will start shortly
[23:43:51.789] Starting the invocation (attempt 1)
[23:43:51.816] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1aeef941744fb81447ed7c4770cc1e792b09c70f"
[23:43:51.816] Popped gitiles commit info from properties and tags
[23:43:51.816] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:43:51.816] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:43:51.816] Buildbucket request:
{
"requestId": "8847939224711687568",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8847939224711687568",
"job": "chromium-m149/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e924772c28526536ed546fff828192824a4f40f8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@e924772c28526536ed546fff828192824a4f40f8",
"title": "e924772c28526536ed546fff828192824a4f40f8",
"url": "https://chromium.googlesource.com/chromium/src/+/e924772c28526536ed546fff828192824a4f40f8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5b5ad43d1595d0d0a2b1c29e5881b7e749e5c690"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@5b5ad43d1595d0d0a2b1c29e5881b7e749e5c690",
"title": "5b5ad43d1595d0d0a2b1c29e5881b7e749e5c690",
"url": "https://chromium.googlesource.com/chromium/src/+/5b5ad43d1595d0d0a2b1c29e5881b7e749e5c690"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "06a9b2cc17e13734547cf72db7463cd5511f80bd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@06a9b2cc17e13734547cf72db7463cd5511f80bd",
"title": "06a9b2cc17e13734547cf72db7463cd5511f80bd",
"url": "https://chromium.googlesource.com/chromium/src/+/06a9b2cc17e13734547cf72db7463cd5511f80bd"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5d68e5c7045ebb3b566b2e400946d091ac5c14bb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@5d68e5c7045ebb3b566b2e400946d091ac5c14bb",
"title": "5d68e5c7045ebb3b566b2e400946d091ac5c14bb",
"url": "https://chromium.googlesource.com/chromium/src/+/5d68e5c7045ebb3b566b2e400946d091ac5c14bb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "294d9b975eabba1e515dcdab90cf57b1859348fd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@294d9b975eabba1e515dcdab90cf57b1859348fd",
"title": "294d9b975eabba1e515dcdab90cf57b1859348fd",
"url": "https://chromium.googlesource.com/chromium/src/+/294d9b975eabba1e515dcdab90cf57b1859348fd"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c5052960792e3c0f538ba837d05bb836e83a2957"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@c5052960792e3c0f538ba837d05bb836e83a2957",
"title": "c5052960792e3c0f538ba837d05bb836e83a2957",
"url": "https://chromium.googlesource.com/chromium/src/+/c5052960792e3c0f538ba837d05bb836e83a2957"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "49a5f7b40c828b5e191b1ccae1b3e3084677f736"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@49a5f7b40c828b5e191b1ccae1b3e3084677f736",
"title": "49a5f7b40c828b5e191b1ccae1b3e3084677f736",
"url": "https://chromium.googlesource.com/chromium/src/+/49a5f7b40c828b5e191b1ccae1b3e3084677f736"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "65157f80ee855f9471ea8ecc645bea6706b2333f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@65157f80ee855f9471ea8ecc645bea6706b2333f",
"title": "65157f80ee855f9471ea8ecc645bea6706b2333f",
"url": "https://chromium.googlesource.com/chromium/src/+/65157f80ee855f9471ea8ecc645bea6706b2333f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "609e16a72f8ae275966d5b1606650820fcf7fbd7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@609e16a72f8ae275966d5b1606650820fcf7fbd7",
"title": "609e16a72f8ae275966d5b1606650820fcf7fbd7",
"url": "https://chromium.googlesource.com/chromium/src/+/609e16a72f8ae275966d5b1606650820fcf7fbd7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d22c943af335f16a0d690b5bcbc15f7cdaea8245"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@d22c943af335f16a0d690b5bcbc15f7cdaea8245",
"title": "d22c943af335f16a0d690b5bcbc15f7cdaea8245",
"url": "https://chromium.googlesource.com/chromium/src/+/d22c943af335f16a0d690b5bcbc15f7cdaea8245"
},
{
"gitiles": {
"ref": "refs/branch-heads/7827",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1aeef941744fb81447ed7c4770cc1e792b09c70f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7827@1aeef941744fb81447ed7c4770cc1e792b09c70f",
"title": "1aeef941744fb81447ed7c4770cc1e792b09c70f",
"url": "https://chromium.googlesource.com/chromium/src/+/1aeef941744fb81447ed7c4770cc1e792b09c70f"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1aeef941744fb81447ed7c4770cc1e792b09c70f",
"ref": "refs/branch-heads/7827"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8847939224711687568"
},
{
"key": "scheduler_job_id",
"value": "chromium-m149/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:43:52.394] Scheduled build:
{
"id": "8682509162586800929",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win-official"
},
"number": 12,
"createdBy": "project:chromium-m149",
"createTime": "2026-05-06T23:43:51.887232279Z",
"updateTime": "2026-05-06T23:43:51.887232279Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1aeef941744fb81447ed7c4770cc1e792b09c70f",
"ref": "refs/branch-heads/7827"
}
}
}
[23:43:52.394] Task URL: https://cr-buildbucket.appspot.com/build/8682509162586800929
[23:43:52.394] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:2:0) after 5m49s
[23:43:52.839] Received PubSub notification, asking Buildbucket for the build status
[23:43:52.856] Build status: SCHEDULED
[23:43:58.099] Received PubSub notification, asking Buildbucket for the build status
[23:43:58.126] Build status: STARTED
[23:49:41.520] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:2:0)
[23:49:41.548] Build status: STARTED
[23:49:41.548] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:5:0) after 8m57s
[23:58:38.664] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:5:0)
[23:58:38.692] Build status: STARTED
[23:58:38.692] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:6:0) after 4m5s
[00:02:43.790] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:6:0)
[00:02:43.816] Build status: STARTED
[00:02:43.816] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:7:0) after 2m23s
[00:05:06.820] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:7:0)
[00:05:06.838] Build status: STARTED
[00:05:06.838] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:8:0) after 2m28s
[00:07:34.896] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:8:0)
[00:07:34.913] Build status: STARTED
[00:07:34.913] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:9:0) after 4m36s
[00:12:11.012] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:9:0)
[00:12:11.040] Build status: STARTED
[00:12:11.040] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:10:0) after 3m25s
[00:15:36.257] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:10:0)
[00:15:36.312] Build status: STARTED
[00:15:36.312] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:11:0) after 1m19s
[00:16:55.387] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:11:0)
[00:16:55.427] Build status: STARTED
[00:16:55.427] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:12:0) after 8m39s
[00:25:34.453] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:12:0)
[00:25:34.477] Build status: STARTED
[00:25:34.477] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:13:0) after 9m56s
[00:35:30.508] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:13:0)
[00:35:30.521] Build status: STARTED
[00:35:30.521] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:14:0) after 7m36s
[00:43:06.556] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:14:0)
[00:43:06.579] Build status: STARTED
[00:43:06.579] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:15:0) after 6m58s
[00:50:04.764] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:15:0)
[00:50:04.802] Build status: STARTED
[00:50:04.802] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:16:0) after 4m24s
[00:54:28.971] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:16:0)
[00:54:29.008] Build status: STARTED
[00:54:29.008] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:17:0) after 3m32s
[00:58:01.023] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:17:0)
[00:58:01.044] Build status: STARTED
[00:58:01.044] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:18:0) after 2m32s
[01:00:33.061] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:18:0)
[01:00:33.102] Build status: STARTED
[01:00:33.102] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:19:0) after 5m54s
[01:06:27.099] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:19:0)
[01:06:27.122] Build status: STARTED
[01:06:27.122] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:20:0) after 9m22s
[01:15:49.222] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:20:0)
[01:15:49.244] Build status: STARTED
[01:15:49.244] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:21:0) after 1m27s
[01:17:16.295] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:21:0)
[01:17:16.385] Build status: STARTED
[01:17:16.385] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:22:0) after 8m28s
[01:25:44.454] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:22:0)
[01:25:44.471] Build status: STARTED
[01:25:44.471] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:23:0) after 2m22s
[01:28:06.662] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:23:0)
[01:28:06.705] Build status: STARTED
[01:28:06.705] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:24:0) after 6m17s
[01:34:23.723] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:24:0)
[01:34:23.791] Build status: STARTED
[01:34:23.791] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:25:0) after 7m53s
[01:42:16.849] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:25:0)
[01:42:16.871] Build status: STARTED
[01:42:16.871] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:26:0) after 9m22s
[01:51:39.117] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:26:0)
[01:51:39.146] Build status: STARTED
[01:51:39.146] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:27:0) after 6m22s
[01:58:01.253] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:27:0)
[01:58:01.273] Build status: STARTED
[01:58:01.273] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:28:0) after 3m29s
[02:01:30.667] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:28:0)
[02:01:30.695] Build status: STARTED
[02:01:30.695] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:29:0) after 9m58s
[02:11:28.739] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:29:0)
[02:11:28.758] Build status: STARTED
[02:11:28.758] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:30:0) after 6m45s
[02:18:13.735] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:30:0)
[02:18:13.756] Build status: STARTED
[02:18:13.756] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:31:0) after 9m24s
[02:27:37.777] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:31:0)
[02:27:37.847] Build status: STARTED
[02:27:37.847] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:32:0) after 5m58s
[02:33:35.863] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:32:0)
[02:33:35.887] Build status: STARTED
[02:33:35.887] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:33:0) after 4m2s
[02:37:37.974] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:33:0)
[02:37:38.001] Build status: STARTED
[02:37:38.001] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:34:0) after 7m35s
[02:45:12.991] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:34:0)
[02:45:13.007] Build status: STARTED
[02:45:13.008] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:35:0) after 4m37s
[02:49:50.020] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:35:0)
[02:49:50.040] Build status: STARTED
[02:49:50.040] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:36:0) after 3m55s
[02:53:45.210] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:36:0)
[02:53:45.225] Build status: STARTED
[02:53:45.225] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:37:0) after 9m9s
[03:02:54.445] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:37:0)
[03:02:54.468] Build status: STARTED
[03:02:54.468] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:38:0) after 6m32s
[03:09:26.482] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:38:0)
[03:09:26.536] Build status: STARTED
[03:09:26.536] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:39:0) after 1m29s
[03:10:55.628] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:39:0)
[03:10:55.678] Build status: STARTED
[03:10:55.678] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:40:0) after 7m53s
[03:18:48.760] Handling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:40:0)
[03:18:48.800] Build status: STARTED
[03:18:48.800] Scheduling timer "check-buildbucket-build-status" (chromium-m149/win-official:8847939224711687568:41:0) after 5m34s
[03:19:38.391] Received PubSub notification, asking Buildbucket for the build status
[03:19:38.410] Build:
{
"id": "8682509162586800929",
"builder": {
"project": "chromium-m149",
"bucket": "ci",
"builder": "win-official"
},
"number": 12,
"createdBy": "project:chromium-m149",
"createTime": "2026-05-06T23:43:51.887232279Z",
"startTime": "2026-05-06T23:43:57.845361368Z",
"endTime": "2026-05-07T03:19:38.016845700Z",
"updateTime": "2026-05-07T03:19:38.016845700Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1aeef941744fb81447ed7c4770cc1e792b09c70f",
"ref": "refs/branch-heads/7827"
}
}
}
[03:19:38.410] Invocation finished in 3h35m47.932745768s with status SUCCEEDED