[03:20:09.140] New invocation is queued and will start shortly
[03:20:10.258] Starting the invocation (attempt 1)
[03:20:10.283] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5d90b15d0ca3c5314f081281dda37f97f9f3262b"
[03:20:10.283] Popped gitiles commit info from properties and tags
[03:20:10.283] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:20:10.283] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:20:10.284] Buildbucket request:
{
"requestId": "8857528894039570496",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8857528894039570496",
"job": "chromium-m144/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a8c256cc991bd32856caaccdcfa33a3b3c1a7027"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@a8c256cc991bd32856caaccdcfa33a3b3c1a7027",
"title": "a8c256cc991bd32856caaccdcfa33a3b3c1a7027",
"url": "https://chromium.googlesource.com/chromium/src/+/a8c256cc991bd32856caaccdcfa33a3b3c1a7027"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "37cea1c5b209b5ba63b6910090308a3a8019d25e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@37cea1c5b209b5ba63b6910090308a3a8019d25e",
"title": "37cea1c5b209b5ba63b6910090308a3a8019d25e",
"url": "https://chromium.googlesource.com/chromium/src/+/37cea1c5b209b5ba63b6910090308a3a8019d25e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "67c3665082547427208ec3c3da9d5a2ece7cd2fb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@67c3665082547427208ec3c3da9d5a2ece7cd2fb",
"title": "67c3665082547427208ec3c3da9d5a2ece7cd2fb",
"url": "https://chromium.googlesource.com/chromium/src/+/67c3665082547427208ec3c3da9d5a2ece7cd2fb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ae685cbb8fda6686107ac0fedc9d44a9d40c27ee"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@ae685cbb8fda6686107ac0fedc9d44a9d40c27ee",
"title": "ae685cbb8fda6686107ac0fedc9d44a9d40c27ee",
"url": "https://chromium.googlesource.com/chromium/src/+/ae685cbb8fda6686107ac0fedc9d44a9d40c27ee"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2eb1b2935d7c1a0070236cb3dbd776393819f363"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@2eb1b2935d7c1a0070236cb3dbd776393819f363",
"title": "2eb1b2935d7c1a0070236cb3dbd776393819f363",
"url": "https://chromium.googlesource.com/chromium/src/+/2eb1b2935d7c1a0070236cb3dbd776393819f363"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1d47879b8939bca665621d68d6cccb078189fc1a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@1d47879b8939bca665621d68d6cccb078189fc1a",
"title": "1d47879b8939bca665621d68d6cccb078189fc1a",
"url": "https://chromium.googlesource.com/chromium/src/+/1d47879b8939bca665621d68d6cccb078189fc1a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d5933c86452dbffe81e6eb4b289b0dd00d9b8f76"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@d5933c86452dbffe81e6eb4b289b0dd00d9b8f76",
"title": "d5933c86452dbffe81e6eb4b289b0dd00d9b8f76",
"url": "https://chromium.googlesource.com/chromium/src/+/d5933c86452dbffe81e6eb4b289b0dd00d9b8f76"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c298fde5008bd4af2dfe57121396e205d47cfa3f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@c298fde5008bd4af2dfe57121396e205d47cfa3f",
"title": "c298fde5008bd4af2dfe57121396e205d47cfa3f",
"url": "https://chromium.googlesource.com/chromium/src/+/c298fde5008bd4af2dfe57121396e205d47cfa3f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c907a5c67f0ba9459d837684f40db13f62440723"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@c907a5c67f0ba9459d837684f40db13f62440723",
"title": "c907a5c67f0ba9459d837684f40db13f62440723",
"url": "https://chromium.googlesource.com/chromium/src/+/c907a5c67f0ba9459d837684f40db13f62440723"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5d90b15d0ca3c5314f081281dda37f97f9f3262b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@5d90b15d0ca3c5314f081281dda37f97f9f3262b",
"title": "5d90b15d0ca3c5314f081281dda37f97f9f3262b",
"url": "https://chromium.googlesource.com/chromium/src/+/5d90b15d0ca3c5314f081281dda37f97f9f3262b"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5d90b15d0ca3c5314f081281dda37f97f9f3262b",
"ref": "refs/branch-heads/7559"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8857528894039570496"
},
{
"key": "scheduler_job_id",
"value": "chromium-m144/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:20:10.833] Scheduled build:
{
"id": "8692098832125073729",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "mac-official"
},
"number": 331,
"createdBy": "project:chromium-m144",
"createTime": "2026-01-21T03:20:10.345614124Z",
"updateTime": "2026-01-21T03:20:10.345614124Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5d90b15d0ca3c5314f081281dda37f97f9f3262b",
"ref": "refs/branch-heads/7559"
}
}
}
[03:20:10.833] Task URL: https://cr-buildbucket.appspot.com/build/8692098832125073729
[03:20:10.833] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:2:0) after 3m55s
[03:20:11.810] Received PubSub notification, asking Buildbucket for the build status
[03:20:11.833] Build status: SCHEDULED
[03:20:15.763] Received PubSub notification, asking Buildbucket for the build status
[03:20:15.777] Build status: STARTED
[03:24:05.891] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:2:0)
[03:24:05.913] Build status: STARTED
[03:24:05.913] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:5:0) after 3m18s
[03:27:24.015] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:5:0)
[03:27:24.066] Build status: STARTED
[03:27:24.066] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:6:0) after 8m27s
[03:35:51.084] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:6:0)
[03:35:51.119] Build status: STARTED
[03:35:51.119] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:7:0) after 3m8s
[03:38:59.137] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:7:0)
[03:38:59.162] Build status: STARTED
[03:38:59.162] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:8:0) after 8m20s
[03:47:19.199] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:8:0)
[03:47:19.219] Build status: STARTED
[03:47:19.219] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:9:0) after 2m41s
[03:50:00.282] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:9:0)
[03:50:00.299] Build status: STARTED
[03:50:00.299] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:10:0) after 4m50s
[03:54:50.316] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:10:0)
[03:54:50.333] Build status: STARTED
[03:54:50.333] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:11:0) after 7m21s
[04:02:11.456] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:11:0)
[04:02:11.479] Build status: STARTED
[04:02:11.479] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:12:0) after 1m24s
[04:03:35.509] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:12:0)
[04:03:35.535] Build status: STARTED
[04:03:35.535] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:13:0) after 9m7s
[04:12:42.547] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:13:0)
[04:12:42.573] Build status: STARTED
[04:12:42.573] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:14:0) after 9m42s
[04:22:24.788] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:14:0)
[04:22:24.800] Build status: STARTED
[04:22:24.800] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:15:0) after 6m27s
[04:28:51.793] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:15:0)
[04:28:51.810] Build status: STARTED
[04:28:51.810] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:16:0) after 8m20s
[04:37:12.015] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:16:0)
[04:37:12.032] Build status: STARTED
[04:37:12.032] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:17:0) after 8m5s
[04:45:17.056] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:17:0)
[04:45:17.075] Build status: STARTED
[04:45:17.075] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:18:0) after 7m48s
[04:53:05.089] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:18:0)
[04:53:05.106] Build status: STARTED
[04:53:05.106] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:19:0) after 4m28s
[04:57:33.126] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:19:0)
[04:57:33.177] Build status: STARTED
[04:57:33.177] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:20:0) after 2m52s
[05:00:25.231] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:20:0)
[05:00:25.270] Build status: STARTED
[05:00:25.270] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:21:0) after 5m40s
[05:06:05.272] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:21:0)
[05:06:05.289] Build status: STARTED
[05:06:05.290] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:22:0) after 6m3s
[05:12:08.445] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:22:0)
[05:12:08.465] Build status: STARTED
[05:12:08.465] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:23:0) after 2m57s
[05:15:05.478] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:23:0)
[05:15:05.504] Build status: STARTED
[05:15:05.504] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:24:0) after 5m6s
[05:20:12.059] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:24:0)
[05:20:12.081] Build status: STARTED
[05:20:12.081] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:25:0) after 5m38s
[05:25:50.150] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:25:0)
[05:25:50.171] Build status: STARTED
[05:25:50.171] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:26:0) after 9m21s
[05:35:11.197] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:26:0)
[05:35:11.225] Build status: STARTED
[05:35:11.225] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:27:0) after 1m36s
[05:36:48.024] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:27:0)
[05:36:48.049] Build status: STARTED
[05:36:48.049] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:28:0) after 4m41s
[05:41:29.065] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:28:0)
[05:41:29.085] Build status: STARTED
[05:41:29.085] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:29:0) after 6m32s
[05:48:01.140] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:29:0)
[05:48:01.155] Build status: STARTED
[05:48:01.155] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:30:0) after 4m18s
[05:52:19.175] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:30:0)
[05:52:19.207] Build status: STARTED
[05:52:19.207] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:31:0) after 3m52s
[05:56:11.270] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:31:0)
[05:56:11.286] Build status: STARTED
[05:56:11.286] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:32:0) after 4m33s
[06:00:44.355] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:32:0)
[06:00:44.368] Build status: STARTED
[06:00:44.369] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:33:0) after 9m8s
[06:09:52.388] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:33:0)
[06:09:52.405] Build status: STARTED
[06:09:52.405] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:34:0) after 7m29s
[06:17:21.424] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:34:0)
[06:17:21.439] Build status: STARTED
[06:17:21.439] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:35:0) after 1m31s
[06:18:52.453] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:35:0)
[06:18:52.498] Build status: STARTED
[06:18:52.498] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:36:0) after 7m58s
[06:26:50.490] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:36:0)
[06:26:50.508] Build status: STARTED
[06:26:50.508] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:37:0) after 4m21s
[06:31:11.697] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:37:0)
[06:31:11.717] Build status: STARTED
[06:31:11.717] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:38:0) after 5m18s
[06:36:29.735] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:38:0)
[06:36:29.752] Build status: STARTED
[06:36:29.752] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:39:0) after 4m17s
[06:40:46.772] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:39:0)
[06:40:46.789] Build status: STARTED
[06:40:46.789] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:40:0) after 7m32s
[06:48:18.823] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:40:0)
[06:48:18.842] Build status: STARTED
[06:48:18.843] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:41:0) after 9m17s
[06:57:35.865] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:41:0)
[06:57:35.895] Build status: STARTED
[06:57:35.895] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:42:0) after 1m53s
[06:59:28.910] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:42:0)
[06:59:28.927] Build status: STARTED
[06:59:28.927] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857528894039570496:43:0) after 1m50s
[06:59:50.161] Received PubSub notification, asking Buildbucket for the build status
[06:59:50.179] Build:
{
"id": "8692098832125073729",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "mac-official"
},
"number": 331,
"createdBy": "project:chromium-m144",
"createTime": "2026-01-21T03:20:10.345614124Z",
"startTime": "2026-01-21T03:20:15.611169187Z",
"endTime": "2026-01-21T06:59:49.898477984Z",
"updateTime": "2026-01-21T06:59:49.898477984Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5d90b15d0ca3c5314f081281dda37f97f9f3262b",
"ref": "refs/branch-heads/7559"
}
}
}
[06:59:50.179] Invocation finished in 3h39m41.043081648s with status SUCCEEDED