[07:15:25.046] New invocation is queued and will start shortly
[07:15:26.848] Starting the invocation (attempt 1)
[07:15:26.879] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5b4d3d22c113fe4914072c7af3c2e6ae97419e01"
[07:15:26.879] Popped gitiles commit info from properties and tags
[07:15:26.879] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:15:26.879] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:15:26.879] Buildbucket request:
{
"requestId": "8849813350297322640",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8849813350297322640",
"job": "chromium-m148/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "74334cd9fa6e09f93432b86026c25c024dbdcc1f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@74334cd9fa6e09f93432b86026c25c024dbdcc1f",
"title": "74334cd9fa6e09f93432b86026c25c024dbdcc1f",
"url": "https://chromium.googlesource.com/chromium/src/+/74334cd9fa6e09f93432b86026c25c024dbdcc1f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ce9d036828d9c6a291193be85e9555c07882e722"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@ce9d036828d9c6a291193be85e9555c07882e722",
"title": "ce9d036828d9c6a291193be85e9555c07882e722",
"url": "https://chromium.googlesource.com/chromium/src/+/ce9d036828d9c6a291193be85e9555c07882e722"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "69e61d6aeada98fbdeee567a842e2d6ab0b4a226"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@69e61d6aeada98fbdeee567a842e2d6ab0b4a226",
"title": "69e61d6aeada98fbdeee567a842e2d6ab0b4a226",
"url": "https://chromium.googlesource.com/chromium/src/+/69e61d6aeada98fbdeee567a842e2d6ab0b4a226"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "62878819bee06964fd6df67cd9347cb61873db24"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@62878819bee06964fd6df67cd9347cb61873db24",
"title": "62878819bee06964fd6df67cd9347cb61873db24",
"url": "https://chromium.googlesource.com/chromium/src/+/62878819bee06964fd6df67cd9347cb61873db24"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2df395c30057b3b7d0fd7241cb4db6dfa11a91f3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@2df395c30057b3b7d0fd7241cb4db6dfa11a91f3",
"title": "2df395c30057b3b7d0fd7241cb4db6dfa11a91f3",
"url": "https://chromium.googlesource.com/chromium/src/+/2df395c30057b3b7d0fd7241cb4db6dfa11a91f3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bbcac9f0db863dbc4048f08b413bb5a6aee7be1a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@bbcac9f0db863dbc4048f08b413bb5a6aee7be1a",
"title": "bbcac9f0db863dbc4048f08b413bb5a6aee7be1a",
"url": "https://chromium.googlesource.com/chromium/src/+/bbcac9f0db863dbc4048f08b413bb5a6aee7be1a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a6ff32bc586bd927ef52ccf87fb558c0ae4a5ce6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@a6ff32bc586bd927ef52ccf87fb558c0ae4a5ce6",
"title": "a6ff32bc586bd927ef52ccf87fb558c0ae4a5ce6",
"url": "https://chromium.googlesource.com/chromium/src/+/a6ff32bc586bd927ef52ccf87fb558c0ae4a5ce6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "296b5a8396522d9d288280396d2953880f3ebd73"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@296b5a8396522d9d288280396d2953880f3ebd73",
"title": "296b5a8396522d9d288280396d2953880f3ebd73",
"url": "https://chromium.googlesource.com/chromium/src/+/296b5a8396522d9d288280396d2953880f3ebd73"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3ef931fc5024d042633db67761f8abd1b0783b01"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@3ef931fc5024d042633db67761f8abd1b0783b01",
"title": "3ef931fc5024d042633db67761f8abd1b0783b01",
"url": "https://chromium.googlesource.com/chromium/src/+/3ef931fc5024d042633db67761f8abd1b0783b01"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3a330a217a3cc6f7409c0ad6bc175d7f80ba4d52"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@3a330a217a3cc6f7409c0ad6bc175d7f80ba4d52",
"title": "3a330a217a3cc6f7409c0ad6bc175d7f80ba4d52",
"url": "https://chromium.googlesource.com/chromium/src/+/3a330a217a3cc6f7409c0ad6bc175d7f80ba4d52"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5b4d3d22c113fe4914072c7af3c2e6ae97419e01"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@5b4d3d22c113fe4914072c7af3c2e6ae97419e01",
"title": "5b4d3d22c113fe4914072c7af3c2e6ae97419e01",
"url": "https://chromium.googlesource.com/chromium/src/+/5b4d3d22c113fe4914072c7af3c2e6ae97419e01"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5b4d3d22c113fe4914072c7af3c2e6ae97419e01",
"ref": "refs/branch-heads/7778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8849813350297322640"
},
{
"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"
}
}
[07:15:27.448] Scheduled build:
{
"id": "8684383287643987921",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "mac-official"
},
"number": 53,
"createdBy": "project:chromium-m148",
"createTime": "2026-04-16T07:15:26.954823840Z",
"updateTime": "2026-04-16T07:15:26.954823840Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5b4d3d22c113fe4914072c7af3c2e6ae97419e01",
"ref": "refs/branch-heads/7778"
}
}
}
[07:15:27.448] Task URL: https://cr-buildbucket.appspot.com/build/8684383287643987921
[07:15:27.448] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:2:0) after 9m58s
[07:15:28.449] Received PubSub notification, asking Buildbucket for the build status
[07:15:28.469] Build status: SCHEDULED
[07:16:17.402] Received PubSub notification, asking Buildbucket for the build status
[07:16:17.423] Build status: STARTED
[07:25:25.467] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:2:0)
[07:25:25.485] Build status: STARTED
[07:25:25.485] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:5:0) after 7m41s
[07:33:06.504] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:5:0)
[07:33:06.530] Build status: STARTED
[07:33:06.530] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:6:0) after 2m23s
[07:35:29.530] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:6:0)
[07:35:29.554] Build status: STARTED
[07:35:29.554] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:7:0) after 4m31s
[07:40:00.572] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:7:0)
[07:40:00.596] Build status: STARTED
[07:40:00.596] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:8:0) after 9m39s
[07:49:39.641] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:8:0)
[07:49:39.663] Build status: STARTED
[07:49:39.663] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:9:0) after 9m9s
[07:58:48.793] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:9:0)
[07:58:48.811] Build status: STARTED
[07:58:48.811] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:10:0) after 1m41s
[08:00:29.914] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:10:0)
[08:00:29.936] Build status: STARTED
[08:00:29.937] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:11:0) after 8m47s
[08:09:17.003] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:11:0)
[08:09:17.030] Build status: STARTED
[08:09:17.031] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:12:0) after 6m14s
[08:15:31.103] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:12:0)
[08:15:31.124] Build status: STARTED
[08:15:31.124] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:13:0) after 5m2s
[08:20:33.193] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:13:0)
[08:20:33.217] Build status: STARTED
[08:20:33.217] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:14:0) after 9m33s
[08:30:06.231] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:14:0)
[08:30:06.250] Build status: STARTED
[08:30:06.250] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:15:0) after 1m23s
[08:31:29.268] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:15:0)
[08:31:29.288] Build status: STARTED
[08:31:29.288] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:16:0) after 5m42s
[08:37:11.309] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:16:0)
[08:37:11.331] Build status: STARTED
[08:37:11.331] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:17:0) after 7m13s
[08:44:24.512] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:17:0)
[08:44:24.530] Build status: STARTED
[08:44:24.530] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:18:0) after 6m37s
[08:51:01.712] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:18:0)
[08:51:01.731] Build status: STARTED
[08:51:01.731] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:19:0) after 3m55s
[08:54:56.843] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:19:0)
[08:54:56.883] Build status: STARTED
[08:54:56.883] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:20:0) after 5m43s
[09:00:39.862] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:20:0)
[09:00:39.889] Build status: STARTED
[09:00:39.889] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:21:0) after 8m21s
[09:09:00.909] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:21:0)
[09:09:01.548] Build status: STARTED
[09:09:01.548] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:22:0) after 3m21s
[09:12:22.611] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:22:0)
[09:12:22.632] Build status: STARTED
[09:12:22.632] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:23:0) after 6m39s
[09:19:01.663] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:23:0)
[09:19:01.678] Build status: STARTED
[09:19:01.678] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:24:0) after 9m46s
[09:28:47.697] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:24:0)
[09:28:47.715] Build status: STARTED
[09:28:47.715] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:25:0) after 1m17s
[09:30:04.735] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:25:0)
[09:30:04.773] Build status: STARTED
[09:30:04.773] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:26:0) after 5m17s
[09:35:21.872] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:26:0)
[09:35:21.900] Build status: STARTED
[09:35:21.900] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:27:0) after 2m47s
[09:38:08.943] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:27:0)
[09:38:08.962] Build status: STARTED
[09:38:08.973] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:28:0) after 7m37s
[09:45:46.073] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:28:0)
[09:45:46.110] Build status: STARTED
[09:45:46.110] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:29:0) after 7m44s
[09:53:30.188] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:29:0)
[09:53:30.207] Build status: STARTED
[09:53:30.207] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:30:0) after 1m19s
[09:54:49.191] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:30:0)
[09:54:49.205] Build status: STARTED
[09:54:49.205] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:31:0) after 3m59s
[09:58:48.258] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:31:0)
[09:58:48.276] Build status: STARTED
[09:58:48.276] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:32:0) after 9m49s
[10:08:37.375] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:32:0)
[10:08:37.392] Build status: STARTED
[10:08:37.392] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:33:0) after 6m34s
[10:15:11.447] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:33:0)
[10:15:11.473] Build status: STARTED
[10:15:11.473] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:34:0) after 2m28s
[10:17:39.558] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:34:0)
[10:17:39.581] Build status: STARTED
[10:17:39.581] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:35:0) after 8m14s
[10:25:53.599] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:35:0)
[10:25:53.616] Build status: STARTED
[10:25:53.616] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:36:0) after 7m45s
[10:33:38.653] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:36:0)
[10:33:38.667] Build status: STARTED
[10:33:38.667] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:37:0) after 3m17s
[10:36:55.696] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:37:0)
[10:36:55.726] Build status: STARTED
[10:36:55.726] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:38:0) after 8m31s
[10:45:26.743] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:38:0)
[10:45:26.807] Build status: STARTED
[10:45:26.807] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:39:0) after 3m9s
[10:48:35.832] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:39:0)
[10:48:35.848] Build status: STARTED
[10:48:35.848] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:40:0) after 5m35s
[10:54:10.867] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:40:0)
[10:54:10.885] Build status: STARTED
[10:54:10.885] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:41:0) after 6m14s
[11:00:25.035] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:41:0)
[11:00:25.054] Build status: STARTED
[11:00:25.054] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:42:0) after 2m54s
[11:03:19.056] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:42:0)
[11:03:19.081] Build status: STARTED
[11:03:19.081] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:43:0) after 4m33s
[11:07:52.102] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:43:0)
[11:07:52.121] Build status: STARTED
[11:07:52.121] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:44:0) after 9m21s
[11:17:13.163] Handling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:44:0)
[11:17:13.180] Build status: STARTED
[11:17:13.180] Scheduling timer "check-buildbucket-build-status" (chromium-m148/mac-official:8849813350297322640:45:0) after 5m33s
[11:22:28.501] Received PubSub notification, asking Buildbucket for the build status
[11:22:28.528] Build:
{
"id": "8684383287643987921",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "mac-official"
},
"number": 53,
"createdBy": "project:chromium-m148",
"createTime": "2026-04-16T07:15:26.954823840Z",
"startTime": "2026-04-16T07:16:17.030360286Z",
"endTime": "2026-04-16T11:22:28.170546820Z",
"updateTime": "2026-04-16T11:22:28.170546820Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5b4d3d22c113fe4914072c7af3c2e6ae97419e01",
"ref": "refs/branch-heads/7778"
}
}
}
[11:22:28.528] Invocation finished in 4h7m3.488742183s with status SUCCEEDED