[11:09:15.304] New invocation is queued and will start shortly
[11:09:16.450] Starting the invocation (attempt 1)
[11:09:16.478] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/883a5d1d5f2b665c6347ee7e04757e3c0cffa8ab"
[11:09:16.478] Popped gitiles commit info from properties and tags
[11:09:16.478] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:09:16.478] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:09:16.478] Buildbucket request:
{
"requestId": "8862482213805887264",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8862482213805887264",
"job": "chromium-m142/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dd446a357ea3f7990051b065b24a949f9206a767"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@dd446a357ea3f7990051b065b24a949f9206a767",
"title": "dd446a357ea3f7990051b065b24a949f9206a767",
"url": "https://chromium.googlesource.com/chromium/src/+/dd446a357ea3f7990051b065b24a949f9206a767"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "167f4658058666a9e53ba85acc015e3a8665b353"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@167f4658058666a9e53ba85acc015e3a8665b353",
"title": "167f4658058666a9e53ba85acc015e3a8665b353",
"url": "https://chromium.googlesource.com/chromium/src/+/167f4658058666a9e53ba85acc015e3a8665b353"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4b9d89186aea8f4c9946244eb22e5d7e95e8cb60"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@4b9d89186aea8f4c9946244eb22e5d7e95e8cb60",
"title": "4b9d89186aea8f4c9946244eb22e5d7e95e8cb60",
"url": "https://chromium.googlesource.com/chromium/src/+/4b9d89186aea8f4c9946244eb22e5d7e95e8cb60"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0a3780cfd7cbcf4154790b936834a9b8b21e39d7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@0a3780cfd7cbcf4154790b936834a9b8b21e39d7",
"title": "0a3780cfd7cbcf4154790b936834a9b8b21e39d7",
"url": "https://chromium.googlesource.com/chromium/src/+/0a3780cfd7cbcf4154790b936834a9b8b21e39d7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2a5703d660e5abe5a720b34c95b54344d4ebaf4c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@2a5703d660e5abe5a720b34c95b54344d4ebaf4c",
"title": "2a5703d660e5abe5a720b34c95b54344d4ebaf4c",
"url": "https://chromium.googlesource.com/chromium/src/+/2a5703d660e5abe5a720b34c95b54344d4ebaf4c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "883a5d1d5f2b665c6347ee7e04757e3c0cffa8ab"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@883a5d1d5f2b665c6347ee7e04757e3c0cffa8ab",
"title": "883a5d1d5f2b665c6347ee7e04757e3c0cffa8ab",
"url": "https://chromium.googlesource.com/chromium/src/+/883a5d1d5f2b665c6347ee7e04757e3c0cffa8ab"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "883a5d1d5f2b665c6347ee7e04757e3c0cffa8ab",
"ref": "refs/branch-heads/7444"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8862482213805887264"
},
{
"key": "scheduler_job_id",
"value": "chromium-m142/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:09:16.924] Scheduled build:
{
"id": "8697052151858859793",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "mac-official"
},
"number": 458,
"createdBy": "project:chromium-m142",
"createTime": "2025-11-27T11:09:16.534853959Z",
"updateTime": "2025-11-27T11:09:16.534853959Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "883a5d1d5f2b665c6347ee7e04757e3c0cffa8ab",
"ref": "refs/branch-heads/7444"
}
}
}
[11:09:16.924] Task URL: https://cr-buildbucket.appspot.com/build/8697052151858859793
[11:09:16.924] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:2:0) after 9m21s
[11:09:17.981] Received PubSub notification, asking Buildbucket for the build status
[11:09:17.997] Build status: SCHEDULED
[11:09:20.953] Received PubSub notification, asking Buildbucket for the build status
[11:09:20.965] Build status: STARTED
[11:18:37.965] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:2:0)
[11:18:37.981] Build status: STARTED
[11:18:37.981] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:5:0) after 6m37s
[11:25:14.992] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:5:0)
[11:25:15.043] Build status: STARTED
[11:25:15.043] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:6:0) after 9m21s
[11:34:36.038] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:6:0)
[11:34:36.065] Build status: STARTED
[11:34:36.066] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:7:0) after 1m43s
[11:36:19.079] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:7:0)
[11:36:19.102] Build status: STARTED
[11:36:19.102] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:8:0) after 3m12s
[11:39:31.067] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:8:0)
[11:39:31.096] Build status: STARTED
[11:39:31.096] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:9:0) after 7m21s
[11:46:52.128] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:9:0)
[11:46:52.151] Build status: STARTED
[11:46:52.151] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:10:0) after 6m27s
[11:53:19.132] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:10:0)
[11:53:19.155] Build status: STARTED
[11:53:19.155] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:11:0) after 7m5s
[12:00:24.168] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:11:0)
[12:00:24.187] Build status: STARTED
[12:00:24.187] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:12:0) after 2m11s
[12:02:35.198] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:12:0)
[12:02:35.216] Build status: STARTED
[12:02:35.216] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:13:0) after 5m27s
[12:08:02.293] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:13:0)
[12:08:02.315] Build status: STARTED
[12:08:02.315] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:14:0) after 3m10s
[12:11:12.330] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:14:0)
[12:11:12.348] Build status: STARTED
[12:11:12.348] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:15:0) after 1m43s
[12:12:55.412] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:15:0)
[12:12:55.427] Build status: STARTED
[12:12:55.427] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:16:0) after 3m21s
[12:16:16.444] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:16:0)
[12:16:16.459] Build status: STARTED
[12:16:16.459] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:17:0) after 9m32s
[12:25:48.454] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:17:0)
[12:25:48.476] Build status: STARTED
[12:25:48.476] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:18:0) after 9m59s
[12:35:47.550] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:18:0)
[12:35:47.568] Build status: STARTED
[12:35:47.568] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:19:0) after 7m18s
[12:43:05.583] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:19:0)
[12:43:05.601] Build status: STARTED
[12:43:05.601] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:20:0) after 6m13s
[12:49:18.664] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:20:0)
[12:49:18.833] Build status: STARTED
[12:49:18.833] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:21:0) after 4m55s
[12:54:13.830] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:21:0)
[12:54:13.849] Build status: STARTED
[12:54:13.849] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:22:0) after 4m30s
[12:58:43.953] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:22:0)
[12:58:43.971] Build status: STARTED
[12:58:43.971] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:23:0) after 8m10s
[13:06:53.985] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:23:0)
[13:06:54.003] Build status: STARTED
[13:06:54.003] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:24:0) after 1m55s
[13:08:49.017] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:24:0)
[13:08:49.073] Build status: STARTED
[13:08:49.073] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:25:0) after 6m9s
[13:14:58.137] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:25:0)
[13:14:58.173] Build status: STARTED
[13:14:58.173] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:26:0) after 7m35s
[13:22:33.180] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:26:0)
[13:22:33.207] Build status: STARTED
[13:22:33.207] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:27:0) after 3m28s
[13:26:01.223] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:27:0)
[13:26:01.241] Build status: STARTED
[13:26:01.241] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:28:0) after 7m25s
[13:33:26.258] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:28:0)
[13:33:26.322] Build status: STARTED
[13:33:26.322] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:29:0) after 8m49s
[13:42:15.337] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:29:0)
[13:42:15.355] Build status: STARTED
[13:42:15.355] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:30:0) after 1m41s
[13:43:56.376] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:30:0)
[13:43:56.397] Build status: STARTED
[13:43:56.397] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:31:0) after 8m29s
[13:52:25.486] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:31:0)
[13:52:25.504] Build status: STARTED
[13:52:25.504] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:32:0) after 4m31s
[13:56:56.554] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:32:0)
[13:56:56.572] Build status: STARTED
[13:56:56.572] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:33:0) after 5m27s
[14:02:23.600] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:33:0)
[14:02:23.622] Build status: STARTED
[14:02:23.622] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:34:0) after 8m51s
[14:11:14.639] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:34:0)
[14:11:14.663] Build status: STARTED
[14:11:14.663] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8862482213805887264:35:0) after 8m8s
[14:14:30.518] Received PubSub notification, asking Buildbucket for the build status
[14:14:30.531] Build:
{
"id": "8697052151858859793",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "mac-official"
},
"number": 458,
"createdBy": "project:chromium-m142",
"createTime": "2025-11-27T11:09:16.534853959Z",
"startTime": "2025-11-27T11:09:20.476603818Z",
"endTime": "2025-11-27T14:14:30.004700504Z",
"updateTime": "2025-11-27T14:14:30.004700504Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "883a5d1d5f2b665c6347ee7e04757e3c0cffa8ab",
"ref": "refs/branch-heads/7444"
}
}
}
[14:14:30.531] Invocation finished in 3h5m15.236676957s with status SUCCEEDED