[13:03:28.935] New invocation is queued and will start shortly
[13:03:29.988] Starting the invocation (attempt 1)
[13:03:30.011] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/aa50695db2a9afe7346473b5b6503b765a63b5ad"
[13:03:30.011] Popped gitiles commit info from properties and tags
[13:03:30.011] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:03:30.012] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:03:30.012] Buildbucket request:
{
"requestId": "8845714588465440736",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8845714588465440736",
"job": "chromium-m148/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "525db639db92b6e9270078407a3db6112f9f8ece"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@525db639db92b6e9270078407a3db6112f9f8ece",
"title": "525db639db92b6e9270078407a3db6112f9f8ece",
"url": "https://chromium.googlesource.com/chromium/src/+/525db639db92b6e9270078407a3db6112f9f8ece"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7f48a188bff8c80aff2fbdc22a96ddb9fbb19965"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@7f48a188bff8c80aff2fbdc22a96ddb9fbb19965",
"title": "7f48a188bff8c80aff2fbdc22a96ddb9fbb19965",
"url": "https://chromium.googlesource.com/chromium/src/+/7f48a188bff8c80aff2fbdc22a96ddb9fbb19965"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ff36a738913f8693c36aba7c02987d5d94d4d826"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@ff36a738913f8693c36aba7c02987d5d94d4d826",
"title": "ff36a738913f8693c36aba7c02987d5d94d4d826",
"url": "https://chromium.googlesource.com/chromium/src/+/ff36a738913f8693c36aba7c02987d5d94d4d826"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1ad39224295edb942d31014ce1e17ea6ea3d67e7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@1ad39224295edb942d31014ce1e17ea6ea3d67e7",
"title": "1ad39224295edb942d31014ce1e17ea6ea3d67e7",
"url": "https://chromium.googlesource.com/chromium/src/+/1ad39224295edb942d31014ce1e17ea6ea3d67e7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0d9c68e39610c3e999c2dfaa1f9970436e9b9167"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@0d9c68e39610c3e999c2dfaa1f9970436e9b9167",
"title": "0d9c68e39610c3e999c2dfaa1f9970436e9b9167",
"url": "https://chromium.googlesource.com/chromium/src/+/0d9c68e39610c3e999c2dfaa1f9970436e9b9167"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "aa50695db2a9afe7346473b5b6503b765a63b5ad"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@aa50695db2a9afe7346473b5b6503b765a63b5ad",
"title": "aa50695db2a9afe7346473b5b6503b765a63b5ad",
"url": "https://chromium.googlesource.com/chromium/src/+/aa50695db2a9afe7346473b5b6503b765a63b5ad"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "aa50695db2a9afe7346473b5b6503b765a63b5ad",
"ref": "refs/branch-heads/7778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8845714588465440736"
},
{
"key": "scheduler_job_id",
"value": "chromium-m148/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:03:30.647] Scheduled build:
{
"id": "8680284526618290161",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "mac-official"
},
"number": 319,
"createdBy": "project:chromium-m148",
"createTime": "2026-05-31T13:03:30.073236029Z",
"updateTime": "2026-05-31T13:03:30.073236029Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "aa50695db2a9afe7346473b5b6503b765a63b5ad",
"ref": "refs/branch-heads/7778"
}
}
}
[13:03:30.647] Task URL: https://cr-buildbucket.appspot.com/build/8680284526618290161
[13:03:30.647] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:2:0) after 3m53s
[13:03:31.453] Received PubSub notification, asking Buildbucket for the build status
[13:03:31.479] Build status: SCHEDULED
[13:03:34.956] Received PubSub notification, asking Buildbucket for the build status
[13:03:34.975] Build status: STARTED
[13:07:23.659] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:2:0)
[13:07:23.673] Build status: STARTED
[13:07:23.673] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:5:0) after 4m6s
[13:11:29.837] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:5:0)
[13:11:29.854] Build status: STARTED
[13:11:29.854] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:6:0) after 6m9s
[13:17:39.098] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:6:0)
[13:17:39.170] Build status: STARTED
[13:17:39.170] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:7:0) after 8m37s
[13:26:16.152] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:7:0)
[13:26:16.172] Build status: STARTED
[13:26:16.172] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:8:0) after 6m43s
[13:32:59.187] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:8:0)
[13:32:59.205] Build status: STARTED
[13:32:59.205] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:9:0) after 7m58s
[13:40:57.227] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:9:0)
[13:40:57.244] Build status: STARTED
[13:40:57.244] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:10:0) after 1m52s
[13:42:49.254] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:10:0)
[13:42:49.309] Build status: STARTED
[13:42:49.309] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:11:0) after 3m10s
[13:45:59.427] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:11:0)
[13:45:59.444] Build status: STARTED
[13:45:59.444] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:12:0) after 3m38s
[13:49:37.765] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:12:0)
[13:49:37.780] Build status: STARTED
[13:49:37.780] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:13:0) after 1m23s
[13:51:00.792] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:13:0)
[13:51:00.809] Build status: STARTED
[13:51:00.809] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:14:0) after 8m53s
[13:59:53.823] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:14:0)
[13:59:53.837] Build status: STARTED
[13:59:53.837] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:15:0) after 8m26s
[14:08:19.857] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:15:0)
[14:08:19.881] Build status: STARTED
[14:08:19.881] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:16:0) after 3m0s
[14:11:19.899] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:16:0)
[14:11:19.974] Build status: STARTED
[14:11:19.974] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:17:0) after 7m10s
[14:18:30.486] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:17:0)
[14:18:30.518] Build status: STARTED
[14:18:30.518] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:18:0) after 8m47s
[14:27:17.513] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:18:0)
[14:27:17.541] Build status: STARTED
[14:27:17.541] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:19:0) after 4m48s
[14:32:05.554] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:19:0)
[14:32:05.579] Build status: STARTED
[14:32:05.579] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:20:0) after 1m45s
[14:33:50.592] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:20:0)
[14:33:50.612] Build status: STARTED
[14:33:50.612] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:21:0) after 7m0s
[14:40:50.627] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:21:0)
[14:40:50.645] Build status: STARTED
[14:40:50.645] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:22:0) after 8m42s
[14:49:32.748] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:22:0)
[14:49:32.765] Build status: STARTED
[14:49:32.765] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:23:0) after 5m52s
[14:55:24.796] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:23:0)
[14:55:24.961] Build status: STARTED
[14:55:24.961] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:24:0) after 2m59s
[14:58:23.949] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:24:0)
[14:58:24.023] Build status: STARTED
[14:58:24.023] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:25:0) after 1m31s
[14:59:55.160] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:25:0)
[14:59:55.179] Build status: STARTED
[14:59:55.179] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:26:0) after 7m49s
[15:07:44.196] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:26:0)
[15:07:44.216] Build status: STARTED
[15:07:44.216] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:27:0) after 3m16s
[15:11:00.293] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:27:0)
[15:11:00.309] Build status: STARTED
[15:11:00.310] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:28:0) after 4m23s
[15:15:23.322] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:28:0)
[15:15:23.342] Build status: STARTED
[15:15:23.342] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:29:0) after 4m2s
[15:19:25.497] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:29:0)
[15:19:25.515] Build status: STARTED
[15:19:25.515] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:30:0) after 8m43s
[15:28:08.530] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:30:0)
[15:28:08.547] Build status: STARTED
[15:28:08.547] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:31:0) after 6m20s
[15:34:28.664] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:31:0)
[15:34:28.679] Build status: STARTED
[15:34:28.679] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:32:0) after 6m21s
[15:40:49.765] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:32:0)
[15:40:49.784] Build status: STARTED
[15:40:49.784] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:33:0) after 6m54s
[15:47:43.900] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:33:0)
[15:47:43.919] Build status: STARTED
[15:47:43.919] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:34:0) after 2m30s
[15:50:14.035] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:34:0)
[15:50:14.055] Build status: STARTED
[15:50:14.055] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:35:0) after 4m30s
[15:54:44.239] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:35:0)
[15:54:44.261] Build status: STARTED
[15:54:44.261] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:36:0) after 5m54s
[16:00:38.278] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:36:0)
[16:00:38.436] Build status: STARTED
[16:00:38.436] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:37:0) after 9m48s
[16:10:26.535] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:37:0)
[16:10:26.551] Build status: STARTED
[16:10:26.551] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:38:0) after 9m28s
[16:19:54.569] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:38:0)
[16:19:54.586] Build status: STARTED
[16:19:54.586] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:39:0) after 2m30s
[16:22:24.600] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:39:0)
[16:22:24.615] Build status: STARTED
[16:22:24.615] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:40:0) after 9m16s
[16:31:40.629] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:40:0)
[16:31:40.646] Build status: STARTED
[16:31:40.646] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:41:0) after 6m22s
[16:38:02.660] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:41:0)
[16:38:02.688] Build status: STARTED
[16:38:02.688] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:42:0) after 1m5s
[16:39:07.738] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:42:0)
[16:39:07.752] Build status: STARTED
[16:39:07.752] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:43:0) after 4m15s
[16:43:22.783] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:43:0)
[16:43:22.801] Build status: STARTED
[16:43:22.801] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:44:0) after 6m6s
[16:49:28.922] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:44:0)
[16:49:28.953] Build status: STARTED
[16:49:28.953] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:45:0) after 5m42s
[16:55:10.972] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:45:0)
[16:55:11.008] Build status: STARTED
[16:55:11.008] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:46:0) after 2m9s
[16:57:20.115] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:46:0)
[16:57:20.135] Build status: STARTED
[16:57:20.135] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8845714588465440736:47:0) after 3m12s
[16:57:48.438] Received PubSub notification, asking Buildbucket for the build status
[16:57:48.466] Build:
{
"id": "8680284526618290161",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "mac-official"
},
"number": 319,
"createdBy": "project:chromium-m148",
"createTime": "2026-05-31T13:03:30.073236029Z",
"startTime": "2026-05-31T13:03:34.792881398Z",
"endTime": "2026-05-31T16:57:48.205357723Z",
"updateTime": "2026-05-31T16:57:48.205357723Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "aa50695db2a9afe7346473b5b6503b765a63b5ad",
"ref": "refs/branch-heads/7778"
}
}
}
[16:57:48.466] Invocation finished in 3h54m19.537648902s with status SUCCEEDED