[11:33:03.234] New invocation is queued and will start shortly
[11:33:04.299] Starting the invocation (attempt 1)
[11:33:04.321] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d6a9603388c9ee13c3f232fee0da72c57938be9f"
[11:33:04.321] Popped gitiles commit info from properties and tags
[11:33:04.321] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:33:04.321] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:33:04.339] Buildbucket request:
{
"requestId": "8857588480330815904",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8857588480330815904",
"job": "chromium-m145/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e4f355793f80ea9355ff0d419d60c6fa8f7b6540"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@e4f355793f80ea9355ff0d419d60c6fa8f7b6540",
"title": "e4f355793f80ea9355ff0d419d60c6fa8f7b6540",
"url": "https://chromium.googlesource.com/chromium/src/+/e4f355793f80ea9355ff0d419d60c6fa8f7b6540"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4b574aa58c3b4fbe022d3f256e382730f8836f48"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@4b574aa58c3b4fbe022d3f256e382730f8836f48",
"title": "4b574aa58c3b4fbe022d3f256e382730f8836f48",
"url": "https://chromium.googlesource.com/chromium/src/+/4b574aa58c3b4fbe022d3f256e382730f8836f48"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4528f49459fc336a24bd98f3d6ad6103d2927c0d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@4528f49459fc336a24bd98f3d6ad6103d2927c0d",
"title": "4528f49459fc336a24bd98f3d6ad6103d2927c0d",
"url": "https://chromium.googlesource.com/chromium/src/+/4528f49459fc336a24bd98f3d6ad6103d2927c0d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cdc029534e819e237bed45d03cd9d45a5ce0bd3b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@cdc029534e819e237bed45d03cd9d45a5ce0bd3b",
"title": "cdc029534e819e237bed45d03cd9d45a5ce0bd3b",
"url": "https://chromium.googlesource.com/chromium/src/+/cdc029534e819e237bed45d03cd9d45a5ce0bd3b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "233c225f5be0d491f7d4fe47306fc975dbbf1556"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@233c225f5be0d491f7d4fe47306fc975dbbf1556",
"title": "233c225f5be0d491f7d4fe47306fc975dbbf1556",
"url": "https://chromium.googlesource.com/chromium/src/+/233c225f5be0d491f7d4fe47306fc975dbbf1556"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3ab6288557d57ff4f1178bf91da161a7d5ea2e93"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@3ab6288557d57ff4f1178bf91da161a7d5ea2e93",
"title": "3ab6288557d57ff4f1178bf91da161a7d5ea2e93",
"url": "https://chromium.googlesource.com/chromium/src/+/3ab6288557d57ff4f1178bf91da161a7d5ea2e93"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bcd34841a4b7b8b5508061dd0e1fd13de4016779"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@bcd34841a4b7b8b5508061dd0e1fd13de4016779",
"title": "bcd34841a4b7b8b5508061dd0e1fd13de4016779",
"url": "https://chromium.googlesource.com/chromium/src/+/bcd34841a4b7b8b5508061dd0e1fd13de4016779"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "161f2e7c792cd9a60da23b7f9f1e76ac4a3b968b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@161f2e7c792cd9a60da23b7f9f1e76ac4a3b968b",
"title": "161f2e7c792cd9a60da23b7f9f1e76ac4a3b968b",
"url": "https://chromium.googlesource.com/chromium/src/+/161f2e7c792cd9a60da23b7f9f1e76ac4a3b968b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "56682366668d3c3049415c90ca76f0e2cc366398"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@56682366668d3c3049415c90ca76f0e2cc366398",
"title": "56682366668d3c3049415c90ca76f0e2cc366398",
"url": "https://chromium.googlesource.com/chromium/src/+/56682366668d3c3049415c90ca76f0e2cc366398"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5fe9dbbe16bf1baf7a52bb05f7c151ca52877367"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@5fe9dbbe16bf1baf7a52bb05f7c151ca52877367",
"title": "5fe9dbbe16bf1baf7a52bb05f7c151ca52877367",
"url": "https://chromium.googlesource.com/chromium/src/+/5fe9dbbe16bf1baf7a52bb05f7c151ca52877367"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d6a9603388c9ee13c3f232fee0da72c57938be9f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@d6a9603388c9ee13c3f232fee0da72c57938be9f",
"title": "d6a9603388c9ee13c3f232fee0da72c57938be9f",
"url": "https://chromium.googlesource.com/chromium/src/+/d6a9603388c9ee13c3f232fee0da72c57938be9f"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d6a9603388c9ee13c3f232fee0da72c57938be9f",
"ref": "refs/branch-heads/7632"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8857588480330815904"
},
{
"key": "scheduler_job_id",
"value": "chromium-m145/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:33:05.064] Scheduled build:
{
"id": "8692158418455429793",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "mac-official"
},
"number": 43,
"createdBy": "project:chromium-m145",
"createTime": "2026-01-20T11:33:04.392881795Z",
"updateTime": "2026-01-20T11:33:04.392881795Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d6a9603388c9ee13c3f232fee0da72c57938be9f",
"ref": "refs/branch-heads/7632"
}
},
"canary": true
}
[11:33:05.064] Task URL: https://cr-buildbucket.appspot.com/build/8692158418455429793
[11:33:05.064] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:2:0) after 2m27s
[11:33:23.639] Received PubSub notification, asking Buildbucket for the build status
[11:33:23.655] Build status: STARTED
[11:33:24.770] Received PubSub notification, asking Buildbucket for the build status
[11:33:24.810] Build status: STARTED
[11:35:32.084] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:2:0)
[11:35:32.105] Build status: STARTED
[11:35:32.105] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:5:0) after 8m19s
[11:43:51.347] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:5:0)
[11:43:51.359] Build status: STARTED
[11:43:51.359] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:6:0) after 4m7s
[11:47:58.372] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:6:0)
[11:47:58.392] Build status: STARTED
[11:47:58.392] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:7:0) after 1m9s
[11:49:07.408] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:7:0)
[11:49:07.430] Build status: STARTED
[11:49:07.430] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:8:0) after 6m17s
[11:55:24.595] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:8:0)
[11:55:24.621] Build status: STARTED
[11:55:24.621] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:9:0) after 9m6s
[12:04:30.801] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:9:0)
[12:04:30.929] Build status: STARTED
[12:04:30.929] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:10:0) after 2m12s
[12:06:42.986] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:10:0)
[12:06:43.002] Build status: STARTED
[12:06:43.002] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:11:0) after 4m37s
[12:11:20.114] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:11:0)
[12:11:20.132] Build status: STARTED
[12:11:20.132] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:12:0) after 1m14s
[12:12:34.141] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:12:0)
[12:12:34.156] Build status: STARTED
[12:12:34.156] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:13:0) after 9m19s
[12:21:53.143] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:13:0)
[12:21:53.159] Build status: STARTED
[12:21:53.159] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:14:0) after 9m1s
[12:30:54.406] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:14:0)
[12:30:54.516] Build status: STARTED
[12:30:54.516] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:15:0) after 5m47s
[12:36:41.536] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:15:0)
[12:36:41.551] Build status: STARTED
[12:36:41.551] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:16:0) after 9m53s
[12:46:34.656] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:16:0)
[12:46:34.676] Build status: STARTED
[12:46:34.676] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:17:0) after 3m45s
[12:50:19.660] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:17:0)
[12:50:19.722] Build status: STARTED
[12:50:19.722] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:18:0) after 9m45s
[13:00:04.736] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:18:0)
[13:00:04.755] Build status: STARTED
[13:00:04.755] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:19:0) after 7m30s
[13:07:34.884] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:19:0)
[13:07:34.899] Build status: STARTED
[13:07:34.899] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:20:0) after 5m27s
[13:13:01.977] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:20:0)
[13:13:01.993] Build status: STARTED
[13:13:01.994] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:21:0) after 3m8s
[13:16:10.013] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:21:0)
[13:16:10.026] Build status: STARTED
[13:16:10.026] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:22:0) after 5m56s
[13:22:06.071] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:22:0)
[13:22:06.088] Build status: STARTED
[13:22:06.088] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:23:0) after 9m57s
[13:32:03.125] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:23:0)
[13:32:03.139] Build status: STARTED
[13:32:03.139] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:24:0) after 5m44s
[13:37:47.179] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:24:0)
[13:37:47.216] Build status: STARTED
[13:37:47.216] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:25:0) after 8m10s
[13:45:57.282] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:25:0)
[13:45:57.299] Build status: STARTED
[13:45:57.299] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:26:0) after 7m26s
[13:53:23.362] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:26:0)
[13:53:23.378] Build status: STARTED
[13:53:23.378] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:27:0) after 1m30s
[13:54:53.398] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:27:0)
[13:54:53.413] Build status: STARTED
[13:54:53.413] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:28:0) after 5m56s
[14:00:49.430] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:28:0)
[14:00:49.496] Build status: STARTED
[14:00:49.496] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:29:0) after 7m34s
[14:08:23.515] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:29:0)
[14:08:23.586] Build status: STARTED
[14:08:23.586] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:30:0) after 6m30s
[14:14:53.606] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:30:0)
[14:14:53.640] Build status: STARTED
[14:14:53.640] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:31:0) after 7m15s
[14:22:08.709] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:31:0)
[14:22:08.722] Build status: STARTED
[14:22:08.722] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:32:0) after 7m19s
[14:29:27.756] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:32:0)
[14:29:27.782] Build status: STARTED
[14:29:27.782] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:33:0) after 4m27s
[14:33:54.904] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:33:0)
[14:33:54.922] Build status: STARTED
[14:33:54.922] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:34:0) after 8m28s
[14:42:22.946] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:34:0)
[14:42:22.970] Build status: STARTED
[14:42:22.970] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:35:0) after 9m8s
[14:51:31.008] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:35:0)
[14:51:31.024] Build status: STARTED
[14:51:31.024] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:36:0) after 4m2s
[14:55:33.049] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:36:0)
[14:55:33.065] Build status: STARTED
[14:55:33.065] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:37:0) after 1m53s
[14:57:26.081] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:37:0)
[14:57:26.101] Build status: STARTED
[14:57:26.101] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:38:0) after 4m6s
[15:01:32.151] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:38:0)
[15:01:32.167] Build status: STARTED
[15:01:32.167] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:39:0) after 2m55s
[15:04:27.188] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:39:0)
[15:04:27.207] Build status: STARTED
[15:04:27.207] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:40:0) after 3m12s
[15:07:39.345] Handling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:40:0)
[15:07:39.365] Build status: STARTED
[15:07:39.365] Scheduling timer "check-buildbucket-build-status" (chromium-m145/mac-official:8857588480330815904:41:0) after 6m49s
[15:09:54.746] Received PubSub notification, asking Buildbucket for the build status
[15:09:54.813] Build:
{
"id": "8692158418455429793",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "mac-official"
},
"number": 43,
"createdBy": "project:chromium-m145",
"createTime": "2026-01-20T11:33:04.392881795Z",
"startTime": "2026-01-20T11:33:08.225350711Z",
"endTime": "2026-01-20T15:09:54.511377243Z",
"updateTime": "2026-01-20T15:09:54.511377243Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d6a9603388c9ee13c3f232fee0da72c57938be9f",
"ref": "refs/branch-heads/7632"
}
},
"canary": true
}
[15:09:54.813] Invocation finished in 3h36m51.593128262s with status SUCCEEDED