[03:19:02.730] New invocation is queued and will start shortly
[03:19:03.888] Starting the invocation (attempt 1)
[03:19:03.909] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4b7bc8f4f4bd89e822610488901f58122b42ce38"
[03:19:03.909] Popped gitiles commit info from properties and tags
[03:19:03.909] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:19:03.909] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:19:03.910] Buildbucket request:
{
"requestId": "8858706724247195408",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8858706724247195408",
"job": "chromium-m143/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7d03abf716f65cdb6d8c68ac3a8c03212193cadd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@7d03abf716f65cdb6d8c68ac3a8c03212193cadd",
"title": "7d03abf716f65cdb6d8c68ac3a8c03212193cadd",
"url": "https://chromium.googlesource.com/chromium/src/+/7d03abf716f65cdb6d8c68ac3a8c03212193cadd"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "eaf23b9d51815a004a132028173c6864c1498217"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@eaf23b9d51815a004a132028173c6864c1498217",
"title": "eaf23b9d51815a004a132028173c6864c1498217",
"url": "https://chromium.googlesource.com/chromium/src/+/eaf23b9d51815a004a132028173c6864c1498217"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "10a4eca87dc44980cf18d411a76416e36d502b26"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@10a4eca87dc44980cf18d411a76416e36d502b26",
"title": "10a4eca87dc44980cf18d411a76416e36d502b26",
"url": "https://chromium.googlesource.com/chromium/src/+/10a4eca87dc44980cf18d411a76416e36d502b26"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "44ee5e75edc2d43d6ce977ecc58d0b50e62fb0ab"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@44ee5e75edc2d43d6ce977ecc58d0b50e62fb0ab",
"title": "44ee5e75edc2d43d6ce977ecc58d0b50e62fb0ab",
"url": "https://chromium.googlesource.com/chromium/src/+/44ee5e75edc2d43d6ce977ecc58d0b50e62fb0ab"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "22d6c0b4765c96292bd097f90842eb197525de21"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@22d6c0b4765c96292bd097f90842eb197525de21",
"title": "22d6c0b4765c96292bd097f90842eb197525de21",
"url": "https://chromium.googlesource.com/chromium/src/+/22d6c0b4765c96292bd097f90842eb197525de21"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4eb76e79326f533dd98fcebf4e34ed945cdc3a76"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@4eb76e79326f533dd98fcebf4e34ed945cdc3a76",
"title": "4eb76e79326f533dd98fcebf4e34ed945cdc3a76",
"url": "https://chromium.googlesource.com/chromium/src/+/4eb76e79326f533dd98fcebf4e34ed945cdc3a76"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3f1e2b12ef84f52c2a013456a8dc8c22a4f1fa89"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@3f1e2b12ef84f52c2a013456a8dc8c22a4f1fa89",
"title": "3f1e2b12ef84f52c2a013456a8dc8c22a4f1fa89",
"url": "https://chromium.googlesource.com/chromium/src/+/3f1e2b12ef84f52c2a013456a8dc8c22a4f1fa89"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4b7bc8f4f4bd89e822610488901f58122b42ce38"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@4b7bc8f4f4bd89e822610488901f58122b42ce38",
"title": "4b7bc8f4f4bd89e822610488901f58122b42ce38",
"url": "https://chromium.googlesource.com/chromium/src/+/4b7bc8f4f4bd89e822610488901f58122b42ce38"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4b7bc8f4f4bd89e822610488901f58122b42ce38",
"ref": "refs/branch-heads/7499"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8858706724247195408"
},
{
"key": "scheduler_job_id",
"value": "chromium-m143/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:19:04.495] Scheduled build:
{
"id": "8693276662300120577",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "mac-official"
},
"number": 524,
"createdBy": "project:chromium-m143",
"createTime": "2026-01-08T03:19:03.958031069Z",
"updateTime": "2026-01-08T03:19:03.958031069Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4b7bc8f4f4bd89e822610488901f58122b42ce38",
"ref": "refs/branch-heads/7499"
}
}
}
[03:19:04.495] Task URL: https://cr-buildbucket.appspot.com/build/8693276662300120577
[03:19:04.495] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:2:0) after 5m28s
[03:19:05.503] Received PubSub notification, asking Buildbucket for the build status
[03:19:05.527] Build status: SCHEDULED
[03:19:07.914] Received PubSub notification, asking Buildbucket for the build status
[03:19:07.948] Build status: STARTED
[03:24:32.664] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:2:0)
[03:24:32.751] Build status: STARTED
[03:24:32.751] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:5:0) after 8m0s
[03:32:32.762] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:5:0)
[03:32:32.779] Build status: STARTED
[03:32:32.779] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:6:0) after 9m57s
[03:42:29.795] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:6:0)
[03:42:29.812] Build status: STARTED
[03:42:29.812] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:7:0) after 6m14s
[03:48:43.920] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:7:0)
[03:48:43.950] Build status: STARTED
[03:48:43.951] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:8:0) after 1m20s
[03:50:03.962] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:8:0)
[03:50:03.980] Build status: STARTED
[03:50:03.980] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:9:0) after 5m30s
[03:55:34.069] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:9:0)
[03:55:34.199] Build status: STARTED
[03:55:34.199] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:10:0) after 6m13s
[04:01:47.323] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:10:0)
[04:01:47.410] Build status: STARTED
[04:01:47.410] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:11:0) after 5m47s
[04:07:34.431] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:11:0)
[04:07:34.454] Build status: STARTED
[04:07:34.454] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:12:0) after 6m40s
[04:14:14.566] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:12:0)
[04:14:14.630] Build status: STARTED
[04:14:14.630] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:13:0) after 2m0s
[04:16:14.643] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:13:0)
[04:16:14.665] Build status: STARTED
[04:16:14.665] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:14:0) after 9m4s
[04:25:18.725] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:14:0)
[04:25:18.750] Build status: STARTED
[04:25:18.750] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:15:0) after 2m19s
[04:27:37.744] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:15:0)
[04:27:37.829] Build status: STARTED
[04:27:37.829] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:16:0) after 5m3s
[04:32:40.849] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:16:0)
[04:32:40.914] Build status: STARTED
[04:32:40.914] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:17:0) after 2m38s
[04:35:18.932] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:17:0)
[04:35:18.950] Build status: STARTED
[04:35:18.950] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:18:0) after 8m46s
[04:44:05.025] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:18:0)
[04:44:05.039] Build status: STARTED
[04:44:05.039] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:19:0) after 5m31s
[04:49:36.076] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:19:0)
[04:49:36.099] Build status: STARTED
[04:49:36.099] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:20:0) after 2m21s
[04:51:57.356] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:20:0)
[04:51:57.376] Build status: STARTED
[04:51:57.376] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:21:0) after 4m50s
[04:56:48.332] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:21:0)
[04:56:48.349] Build status: STARTED
[04:56:48.349] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:22:0) after 4m35s
[05:01:23.459] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:22:0)
[05:01:23.477] Build status: STARTED
[05:01:23.477] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:23:0) after 5m53s
[05:07:16.497] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:23:0)
[05:07:16.544] Build status: STARTED
[05:07:16.544] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:24:0) after 8m33s
[05:15:49.559] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:24:0)
[05:15:49.572] Build status: STARTED
[05:15:49.572] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:25:0) after 7m3s
[05:22:52.765] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:25:0)
[05:22:52.785] Build status: STARTED
[05:22:52.785] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:26:0) after 7m42s
[05:30:34.851] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:26:0)
[05:30:34.868] Build status: STARTED
[05:30:34.868] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:27:0) after 3m9s
[05:33:43.936] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:27:0)
[05:33:43.959] Build status: STARTED
[05:33:43.959] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:28:0) after 3m35s
[05:37:19.108] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:28:0)
[05:37:19.122] Build status: STARTED
[05:37:19.122] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:29:0) after 1m33s
[05:38:52.135] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:29:0)
[05:38:52.149] Build status: STARTED
[05:38:52.149] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:30:0) after 3m59s
[05:42:51.164] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:30:0)
[05:42:51.179] Build status: STARTED
[05:42:51.179] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:31:0) after 3m42s
[05:46:33.194] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:31:0)
[05:46:33.214] Build status: STARTED
[05:46:33.214] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:32:0) after 1m24s
[05:47:57.283] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:32:0)
[05:47:57.300] Build status: STARTED
[05:47:57.300] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:33:0) after 1m21s
[05:49:18.402] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:33:0)
[05:49:18.418] Build status: STARTED
[05:49:18.418] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:34:0) after 9m39s
[05:58:57.553] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:34:0)
[05:58:57.596] Build status: STARTED
[05:58:57.596] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:35:0) after 9m41s
[06:08:38.671] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:35:0)
[06:08:38.693] Build status: STARTED
[06:08:38.693] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:36:0) after 9m55s
[06:18:33.795] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:36:0)
[06:18:33.810] Build status: STARTED
[06:18:33.810] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:37:0) after 5m5s
[06:23:38.826] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:37:0)
[06:23:38.908] Build status: STARTED
[06:23:38.908] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:38:0) after 8m52s
[06:32:30.936] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:38:0)
[06:32:30.953] Build status: STARTED
[06:32:30.953] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8858706724247195408:39:0) after 2m31s
[06:33:28.830] Received PubSub notification, asking Buildbucket for the build status
[06:33:28.856] Build:
{
"id": "8693276662300120577",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "mac-official"
},
"number": 524,
"createdBy": "project:chromium-m143",
"createTime": "2026-01-08T03:19:03.958031069Z",
"startTime": "2026-01-08T03:19:07.769114987Z",
"endTime": "2026-01-08T06:33:28.573708476Z",
"updateTime": "2026-01-08T06:33:28.573708476Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4b7bc8f4f4bd89e822610488901f58122b42ce38",
"ref": "refs/branch-heads/7499"
}
}
}
[06:33:28.856] Invocation finished in 3h14m26.138225407s with status SUCCEEDED