[04:15:01.335] New invocation is queued and will start shortly
[04:15:02.413] Starting the invocation (attempt 1)
[04:15:02.446] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e14fef87a382fe6b7cac6803b6f2149170ad9fbe"
[04:15:02.446] Popped gitiles commit info from properties and tags
[04:15:02.446] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:15:02.446] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:15:02.447] Buildbucket request:
{
"requestId": "8857797232823368432",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8857797232823368432",
"job": "chromium-m144/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "612f6be010edf55941d58fc0901dbf6ca9ccbb2c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@612f6be010edf55941d58fc0901dbf6ca9ccbb2c",
"title": "612f6be010edf55941d58fc0901dbf6ca9ccbb2c",
"url": "https://chromium.googlesource.com/chromium/src/+/612f6be010edf55941d58fc0901dbf6ca9ccbb2c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3a2a37a8ab276310e18b1a7383a6429900941c86"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@3a2a37a8ab276310e18b1a7383a6429900941c86",
"title": "3a2a37a8ab276310e18b1a7383a6429900941c86",
"url": "https://chromium.googlesource.com/chromium/src/+/3a2a37a8ab276310e18b1a7383a6429900941c86"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "39057fdfa0b33a5b80f54d14490f0be7c2739b40"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@39057fdfa0b33a5b80f54d14490f0be7c2739b40",
"title": "39057fdfa0b33a5b80f54d14490f0be7c2739b40",
"url": "https://chromium.googlesource.com/chromium/src/+/39057fdfa0b33a5b80f54d14490f0be7c2739b40"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6532a96038784fe3934b95e522c62b4fd2b0becc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@6532a96038784fe3934b95e522c62b4fd2b0becc",
"title": "6532a96038784fe3934b95e522c62b4fd2b0becc",
"url": "https://chromium.googlesource.com/chromium/src/+/6532a96038784fe3934b95e522c62b4fd2b0becc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "912de653ee9bacafff0f69ce299a27ff1a098e11"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@912de653ee9bacafff0f69ce299a27ff1a098e11",
"title": "912de653ee9bacafff0f69ce299a27ff1a098e11",
"url": "https://chromium.googlesource.com/chromium/src/+/912de653ee9bacafff0f69ce299a27ff1a098e11"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e14fef87a382fe6b7cac6803b6f2149170ad9fbe"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@e14fef87a382fe6b7cac6803b6f2149170ad9fbe",
"title": "e14fef87a382fe6b7cac6803b6f2149170ad9fbe",
"url": "https://chromium.googlesource.com/chromium/src/+/e14fef87a382fe6b7cac6803b6f2149170ad9fbe"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e14fef87a382fe6b7cac6803b6f2149170ad9fbe",
"ref": "refs/branch-heads/7559"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8857797232823368432"
},
{
"key": "scheduler_job_id",
"value": "chromium-m144/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:15:02.880] Scheduled build:
{
"id": "8692367170942408417",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "mac-official"
},
"number": 313,
"createdBy": "project:chromium-m144",
"createTime": "2026-01-18T04:15:02.507776582Z",
"updateTime": "2026-01-18T04:15:02.507776582Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e14fef87a382fe6b7cac6803b6f2149170ad9fbe",
"ref": "refs/branch-heads/7559"
}
}
}
[04:15:02.880] Task URL: https://cr-buildbucket.appspot.com/build/8692367170942408417
[04:15:02.880] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:2:0) after 5m36s
[04:15:03.903] Received PubSub notification, asking Buildbucket for the build status
[04:15:03.929] Build status: SCHEDULED
[04:16:13.475] Received PubSub notification, asking Buildbucket for the build status
[04:16:13.492] Build status: STARTED
[04:20:38.925] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:2:0)
[04:20:38.939] Build status: STARTED
[04:20:38.939] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:5:0) after 2m29s
[04:23:08.171] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:5:0)
[04:23:08.196] Build status: STARTED
[04:23:08.196] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:6:0) after 6m40s
[04:29:48.249] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:6:0)
[04:29:48.264] Build status: STARTED
[04:29:48.264] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:7:0) after 9m52s
[04:39:40.276] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:7:0)
[04:39:40.296] Build status: STARTED
[04:39:40.296] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:8:0) after 6m15s
[04:45:55.325] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:8:0)
[04:45:55.353] Build status: STARTED
[04:45:55.353] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:9:0) after 8m20s
[04:54:15.366] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:9:0)
[04:54:15.382] Build status: STARTED
[04:54:15.382] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:10:0) after 6m21s
[05:00:36.398] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:10:0)
[05:00:36.414] Build status: STARTED
[05:00:36.414] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:11:0) after 2m31s
[05:03:07.413] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:11:0)
[05:03:07.432] Build status: STARTED
[05:03:07.432] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:12:0) after 6m11s
[05:09:18.469] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:12:0)
[05:09:18.495] Build status: STARTED
[05:09:18.495] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:13:0) after 2m52s
[05:12:10.660] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:13:0)
[05:12:10.687] Build status: STARTED
[05:12:10.687] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:14:0) after 6m31s
[05:18:41.671] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:14:0)
[05:18:41.687] Build status: STARTED
[05:18:41.687] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:15:0) after 9m37s
[05:28:19.195] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:15:0)
[05:28:19.299] Build status: STARTED
[05:28:19.300] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:16:0) after 1m54s
[05:30:13.440] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:16:0)
[05:30:13.456] Build status: STARTED
[05:30:13.456] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:17:0) after 6m41s
[05:36:54.765] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:17:0)
[05:36:54.785] Build status: STARTED
[05:36:54.785] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:18:0) after 5m57s
[05:42:51.797] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:18:0)
[05:42:51.810] Build status: STARTED
[05:42:51.810] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:19:0) after 7m38s
[05:50:29.831] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:19:0)
[05:50:29.848] Build status: STARTED
[05:50:29.848] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:20:0) after 1m55s
[05:52:24.888] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:20:0)
[05:52:24.902] Build status: STARTED
[05:52:24.903] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:21:0) after 9m5s
[06:01:29.916] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:21:0)
[06:01:30.141] Build status: STARTED
[06:01:30.141] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:22:0) after 2m3s
[06:03:33.154] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:22:0)
[06:03:33.168] Build status: STARTED
[06:03:33.168] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:23:0) after 8m22s
[06:11:55.187] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:23:0)
[06:11:55.205] Build status: STARTED
[06:11:55.206] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:24:0) after 3m53s
[06:15:48.402] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:24:0)
[06:15:48.417] Build status: STARTED
[06:15:48.417] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:25:0) after 1m47s
[06:17:35.660] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:25:0)
[06:17:35.675] Build status: STARTED
[06:17:35.675] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:26:0) after 4m57s
[06:22:32.712] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:26:0)
[06:22:32.728] Build status: STARTED
[06:22:32.728] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:27:0) after 9m3s
[06:31:35.842] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:27:0)
[06:31:35.859] Build status: STARTED
[06:31:35.859] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:28:0) after 6m36s
[06:38:12.026] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:28:0)
[06:38:12.043] Build status: STARTED
[06:38:12.043] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:29:0) after 1m14s
[06:39:26.055] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:29:0)
[06:39:26.072] Build status: STARTED
[06:39:26.072] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:30:0) after 5m13s
[06:44:39.237] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:30:0)
[06:44:39.257] Build status: STARTED
[06:44:39.257] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:31:0) after 7m2s
[06:51:41.472] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:31:0)
[06:51:41.486] Build status: STARTED
[06:51:41.486] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:32:0) after 4m32s
[06:56:13.502] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:32:0)
[06:56:13.521] Build status: STARTED
[06:56:13.521] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:33:0) after 6m2s
[07:02:15.573] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:33:0)
[07:02:15.587] Build status: STARTED
[07:02:15.587] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:34:0) after 6m4s
[07:08:19.599] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:34:0)
[07:08:19.631] Build status: STARTED
[07:08:19.631] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:35:0) after 2m27s
[07:10:46.757] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:35:0)
[07:10:46.790] Build status: STARTED
[07:10:46.790] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:36:0) after 3m50s
[07:14:36.857] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:36:0)
[07:14:36.897] Build status: STARTED
[07:14:36.897] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:37:0) after 9m30s
[07:24:07.064] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:37:0)
[07:24:07.088] Build status: STARTED
[07:24:07.088] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:38:0) after 7m1s
[07:31:08.143] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:38:0)
[07:31:08.195] Build status: STARTED
[07:31:08.195] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:39:0) after 1m57s
[07:33:05.324] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:39:0)
[07:33:05.338] Build status: STARTED
[07:33:05.338] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:40:0) after 9m24s
[07:42:29.354] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:40:0)
[07:42:29.369] Build status: STARTED
[07:42:29.369] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8857797232823368432:41:0) after 2m59s
[07:43:45.554] Received PubSub notification, asking Buildbucket for the build status
[07:43:45.570] Build:
{
"id": "8692367170942408417",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "mac-official"
},
"number": 313,
"createdBy": "project:chromium-m144",
"createTime": "2026-01-18T04:15:02.507776582Z",
"startTime": "2026-01-18T04:16:13.312375890Z",
"endTime": "2026-01-18T07:43:38.888841397Z",
"updateTime": "2026-01-18T07:43:38.888841397Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e14fef87a382fe6b7cac6803b6f2149170ad9fbe",
"ref": "refs/branch-heads/7559"
}
}
}
[07:43:45.570] Invocation finished in 3h28m44.239860243s with status SUCCEEDED