[03:41:18.067] New invocation is queued and will start shortly
[03:41:19.181] Starting the invocation (attempt 1)
[03:41:19.208] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/396687b51e9c453d7babd0788f79f4315000b83c"
[03:41:19.208] Popped gitiles commit info from properties and tags
[03:41:19.208] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:41:19.208] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:41:19.208] Buildbucket request:
{
"requestId": "8864231738985215232",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8864231738985215232",
"job": "chromium-m143/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "117f25b4d0c1adb494c35aa1fe4c2025859ca386"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@117f25b4d0c1adb494c35aa1fe4c2025859ca386",
"title": "117f25b4d0c1adb494c35aa1fe4c2025859ca386",
"url": "https://chromium.googlesource.com/chromium/src/+/117f25b4d0c1adb494c35aa1fe4c2025859ca386"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "029274d17848828401cd70b9fe998bc69ea581a8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@029274d17848828401cd70b9fe998bc69ea581a8",
"title": "029274d17848828401cd70b9fe998bc69ea581a8",
"url": "https://chromium.googlesource.com/chromium/src/+/029274d17848828401cd70b9fe998bc69ea581a8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cfc077b189c80fb7648ce6cd9840d54c9c6ba2bd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@cfc077b189c80fb7648ce6cd9840d54c9c6ba2bd",
"title": "cfc077b189c80fb7648ce6cd9840d54c9c6ba2bd",
"url": "https://chromium.googlesource.com/chromium/src/+/cfc077b189c80fb7648ce6cd9840d54c9c6ba2bd"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a75608e55dac73d2ebd418164aaac69e3f59ef01"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@a75608e55dac73d2ebd418164aaac69e3f59ef01",
"title": "a75608e55dac73d2ebd418164aaac69e3f59ef01",
"url": "https://chromium.googlesource.com/chromium/src/+/a75608e55dac73d2ebd418164aaac69e3f59ef01"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f0630e5d86c3ace69416882bc264270af842bb3e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@f0630e5d86c3ace69416882bc264270af842bb3e",
"title": "f0630e5d86c3ace69416882bc264270af842bb3e",
"url": "https://chromium.googlesource.com/chromium/src/+/f0630e5d86c3ace69416882bc264270af842bb3e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "505fd8d96f708a703c99849ee42f110aeed560ce"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@505fd8d96f708a703c99849ee42f110aeed560ce",
"title": "505fd8d96f708a703c99849ee42f110aeed560ce",
"url": "https://chromium.googlesource.com/chromium/src/+/505fd8d96f708a703c99849ee42f110aeed560ce"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7fbbe557ce4c6f20a19c979799e1f24af51718ce"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@7fbbe557ce4c6f20a19c979799e1f24af51718ce",
"title": "7fbbe557ce4c6f20a19c979799e1f24af51718ce",
"url": "https://chromium.googlesource.com/chromium/src/+/7fbbe557ce4c6f20a19c979799e1f24af51718ce"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f1b99e3d731ee443a00191bc6cc22f2e347919cc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@f1b99e3d731ee443a00191bc6cc22f2e347919cc",
"title": "f1b99e3d731ee443a00191bc6cc22f2e347919cc",
"url": "https://chromium.googlesource.com/chromium/src/+/f1b99e3d731ee443a00191bc6cc22f2e347919cc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e4da3bd400f63d48779a595ef7f8588e45f98017"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@e4da3bd400f63d48779a595ef7f8588e45f98017",
"title": "e4da3bd400f63d48779a595ef7f8588e45f98017",
"url": "https://chromium.googlesource.com/chromium/src/+/e4da3bd400f63d48779a595ef7f8588e45f98017"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b1fd231758ce06bc9ebf51ffab20b231f331f96d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@b1fd231758ce06bc9ebf51ffab20b231f331f96d",
"title": "b1fd231758ce06bc9ebf51ffab20b231f331f96d",
"url": "https://chromium.googlesource.com/chromium/src/+/b1fd231758ce06bc9ebf51ffab20b231f331f96d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "358dba54b808e6da07b9092e59a5204a5517e9cf"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@358dba54b808e6da07b9092e59a5204a5517e9cf",
"title": "358dba54b808e6da07b9092e59a5204a5517e9cf",
"url": "https://chromium.googlesource.com/chromium/src/+/358dba54b808e6da07b9092e59a5204a5517e9cf"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "396687b51e9c453d7babd0788f79f4315000b83c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@396687b51e9c453d7babd0788f79f4315000b83c",
"title": "396687b51e9c453d7babd0788f79f4315000b83c",
"url": "https://chromium.googlesource.com/chromium/src/+/396687b51e9c453d7babd0788f79f4315000b83c"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "396687b51e9c453d7babd0788f79f4315000b83c",
"ref": "refs/branch-heads/7499"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8864231738985215232"
},
{
"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:41:19.684] Scheduled build:
{
"id": "8698801677086656273",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "mac-official"
},
"number": 82,
"createdBy": "project:chromium-m143",
"createTime": "2025-11-08T03:41:19.258336699Z",
"updateTime": "2025-11-08T03:41:19.258336699Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "396687b51e9c453d7babd0788f79f4315000b83c",
"ref": "refs/branch-heads/7499"
}
}
}
[03:41:19.684] Task URL: https://cr-buildbucket.appspot.com/build/8698801677086656273
[03:41:19.684] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:2:0) after 9m46s
[03:41:20.639] Received PubSub notification, asking Buildbucket for the build status
[03:41:20.678] Build status: SCHEDULED
[03:41:23.405] Received PubSub notification, asking Buildbucket for the build status
[03:41:23.427] Build status: STARTED
[03:51:05.792] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:2:0)
[03:51:05.844] Build status: STARTED
[03:51:05.845] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:5:0) after 1m58s
[03:53:03.914] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:5:0)
[03:53:03.930] Build status: STARTED
[03:53:03.930] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:6:0) after 1m56s
[03:55:00.046] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:6:0)
[03:55:00.065] Build status: STARTED
[03:55:00.065] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:7:0) after 6m22s
[04:01:22.488] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:7:0)
[04:01:22.513] Build status: STARTED
[04:01:22.513] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:8:0) after 2m6s
[04:03:28.530] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:8:0)
[04:03:28.548] Build status: STARTED
[04:03:28.549] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:9:0) after 7m49s
[04:11:17.576] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:9:0)
[04:11:17.595] Build status: STARTED
[04:11:17.595] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:10:0) after 5m11s
[04:16:28.804] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:10:0)
[04:16:28.821] Build status: STARTED
[04:16:28.821] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:11:0) after 7m56s
[04:24:25.213] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:11:0)
[04:24:25.235] Build status: STARTED
[04:24:25.235] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:12:0) after 4m19s
[04:28:44.310] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:12:0)
[04:28:44.378] Build status: STARTED
[04:28:44.378] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:13:0) after 3m10s
[04:31:54.478] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:13:0)
[04:31:54.493] Build status: STARTED
[04:31:54.493] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:14:0) after 4m53s
[04:36:47.595] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:14:0)
[04:36:47.649] Build status: STARTED
[04:36:47.649] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:15:0) after 8m18s
[04:45:05.667] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:15:0)
[04:45:05.685] Build status: STARTED
[04:45:05.685] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:16:0) after 5m36s
[04:50:41.704] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:16:0)
[04:50:41.722] Build status: STARTED
[04:50:41.722] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:17:0) after 1m43s
[04:52:24.772] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:17:0)
[04:52:24.792] Build status: STARTED
[04:52:24.793] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:18:0) after 9m50s
[05:02:15.026] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:18:0)
[05:02:15.040] Build status: STARTED
[05:02:15.040] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:19:0) after 4m11s
[05:06:26.388] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:19:0)
[05:06:26.413] Build status: STARTED
[05:06:26.413] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:20:0) after 5m57s
[05:12:23.498] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:20:0)
[05:12:23.520] Build status: STARTED
[05:12:23.520] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:21:0) after 1m33s
[05:13:56.731] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:21:0)
[05:13:56.745] Build status: STARTED
[05:13:56.745] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:22:0) after 8m45s
[05:22:41.774] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:22:0)
[05:22:41.789] Build status: STARTED
[05:22:41.789] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:23:0) after 2m27s
[05:25:08.765] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:23:0)
[05:25:08.808] Build status: STARTED
[05:25:08.808] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:24:0) after 2m53s
[05:28:01.822] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:24:0)
[05:28:01.877] Build status: STARTED
[05:28:01.877] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:25:0) after 7m40s
[05:35:41.944] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:25:0)
[05:35:41.998] Build status: STARTED
[05:35:41.998] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:26:0) after 4m39s
[05:40:21.121] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:26:0)
[05:40:21.210] Build status: STARTED
[05:40:21.210] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:27:0) after 8m25s
[05:48:46.229] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:27:0)
[05:48:46.248] Build status: STARTED
[05:48:46.249] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:28:0) after 4m26s
[05:53:12.234] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:28:0)
[05:53:12.249] Build status: STARTED
[05:53:12.249] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:29:0) after 4m30s
[05:57:42.719] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:29:0)
[05:57:42.734] Build status: STARTED
[05:57:42.734] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:30:0) after 2m1s
[05:59:43.749] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:30:0)
[05:59:43.766] Build status: STARTED
[05:59:43.766] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:31:0) after 9m48s
[06:09:31.866] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:31:0)
[06:09:31.881] Build status: STARTED
[06:09:31.881] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:32:0) after 9m25s
[06:18:57.108] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:32:0)
[06:18:57.125] Build status: STARTED
[06:18:57.125] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:33:0) after 3m49s
[06:22:46.238] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:33:0)
[06:22:46.252] Build status: STARTED
[06:22:46.252] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:34:0) after 1m2s
[06:23:48.910] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:34:0)
[06:23:48.925] Build status: STARTED
[06:23:48.925] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:35:0) after 1m53s
[06:25:41.939] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:35:0)
[06:25:41.958] Build status: STARTED
[06:25:41.958] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:36:0) after 5m31s
[06:31:12.973] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:36:0)
[06:31:12.995] Build status: STARTED
[06:31:12.995] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:37:0) after 9m32s
[06:40:45.599] Handling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:37:0)
[06:40:45.642] Build status: STARTED
[06:40:45.643] Scheduling timer "check-buildbucket-build-status" (chromium-m143/mac-official:8864231738985215232:38:0) after 8m11s
[06:48:18.898] Received PubSub notification, asking Buildbucket for the build status
[06:48:18.914] Build:
{
"id": "8698801677086656273",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "mac-official"
},
"number": 82,
"createdBy": "project:chromium-m143",
"createTime": "2025-11-08T03:41:19.258336699Z",
"startTime": "2025-11-08T03:41:23.228806926Z",
"endTime": "2025-11-08T06:47:59.168678295Z",
"updateTime": "2025-11-08T06:47:59.168678295Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "396687b51e9c453d7babd0788f79f4315000b83c",
"ref": "refs/branch-heads/7499"
}
}
}
[06:48:18.914] Invocation finished in 3h7m0.850310699s with status SUCCEEDED