[05:18:09.275] New invocation is queued and will start shortly
[05:18:10.356] Starting the invocation (attempt 1)
[05:18:10.382] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d1ae97eb72068b8eb98e157bd2685da7d985d91a"
[05:18:10.382] Popped gitiles commit info from properties and tags
[05:18:10.382] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:18:10.383] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:18:10.383] Buildbucket request:
{
"requestId": "8861779527400891552",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8861779527400891552",
"job": "chromium-m142/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "46a05dedac9daa2d7278f0d05272f80433ae391c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@46a05dedac9daa2d7278f0d05272f80433ae391c",
"title": "46a05dedac9daa2d7278f0d05272f80433ae391c",
"url": "https://chromium.googlesource.com/chromium/src/+/46a05dedac9daa2d7278f0d05272f80433ae391c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1a08ec5d94260d24eef69c61e9c830b8036ce6b7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@1a08ec5d94260d24eef69c61e9c830b8036ce6b7",
"title": "1a08ec5d94260d24eef69c61e9c830b8036ce6b7",
"url": "https://chromium.googlesource.com/chromium/src/+/1a08ec5d94260d24eef69c61e9c830b8036ce6b7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4c019c3ad75ff6973fa4abf0eb30623af543c80c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@4c019c3ad75ff6973fa4abf0eb30623af543c80c",
"title": "4c019c3ad75ff6973fa4abf0eb30623af543c80c",
"url": "https://chromium.googlesource.com/chromium/src/+/4c019c3ad75ff6973fa4abf0eb30623af543c80c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "540ded80115f17154a3dd5a8e490b3dd1674fc09"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@540ded80115f17154a3dd5a8e490b3dd1674fc09",
"title": "540ded80115f17154a3dd5a8e490b3dd1674fc09",
"url": "https://chromium.googlesource.com/chromium/src/+/540ded80115f17154a3dd5a8e490b3dd1674fc09"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f49f8e73052db03ccb935c3551689545f5e084a1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@f49f8e73052db03ccb935c3551689545f5e084a1",
"title": "f49f8e73052db03ccb935c3551689545f5e084a1",
"url": "https://chromium.googlesource.com/chromium/src/+/f49f8e73052db03ccb935c3551689545f5e084a1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f9ef3fa984e80d4e709f7cb51def4d2e127fb5ba"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@f9ef3fa984e80d4e709f7cb51def4d2e127fb5ba",
"title": "f9ef3fa984e80d4e709f7cb51def4d2e127fb5ba",
"url": "https://chromium.googlesource.com/chromium/src/+/f9ef3fa984e80d4e709f7cb51def4d2e127fb5ba"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "19c3fc241dc9c6d425ba278275af830e4caba1b8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@19c3fc241dc9c6d425ba278275af830e4caba1b8",
"title": "19c3fc241dc9c6d425ba278275af830e4caba1b8",
"url": "https://chromium.googlesource.com/chromium/src/+/19c3fc241dc9c6d425ba278275af830e4caba1b8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d1ae97eb72068b8eb98e157bd2685da7d985d91a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@d1ae97eb72068b8eb98e157bd2685da7d985d91a",
"title": "d1ae97eb72068b8eb98e157bd2685da7d985d91a",
"url": "https://chromium.googlesource.com/chromium/src/+/d1ae97eb72068b8eb98e157bd2685da7d985d91a"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d1ae97eb72068b8eb98e157bd2685da7d985d91a",
"ref": "refs/branch-heads/7444"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8861779527400891552"
},
{
"key": "scheduler_job_id",
"value": "chromium-m142/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:18:10.994] Scheduled build:
{
"id": "8696349465531678705",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "mac-official"
},
"number": 518,
"createdBy": "project:chromium-m142",
"createTime": "2025-12-05T05:18:10.437847415Z",
"updateTime": "2025-12-05T05:18:10.437847415Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d1ae97eb72068b8eb98e157bd2685da7d985d91a",
"ref": "refs/branch-heads/7444"
}
}
}
[05:18:10.994] Task URL: https://cr-buildbucket.appspot.com/build/8696349465531678705
[05:18:10.994] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:2:0) after 1m12s
[05:18:11.618] Received PubSub notification, asking Buildbucket for the build status
[05:18:11.637] Build status: SCHEDULED
[05:18:15.412] Received PubSub notification, asking Buildbucket for the build status
[05:18:15.438] Build status: STARTED
[05:19:23.000] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:2:0)
[05:19:23.021] Build status: STARTED
[05:19:23.021] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:5:0) after 4m37s
[05:23:59.997] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:5:0)
[05:24:00.246] Build status: STARTED
[05:24:00.246] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:6:0) after 9m29s
[05:33:29.275] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:6:0)
[05:33:29.326] Build status: STARTED
[05:33:29.326] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:7:0) after 4m18s
[05:37:47.301] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:7:0)
[05:37:47.317] Build status: STARTED
[05:37:47.317] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:8:0) after 1m1s
[05:38:48.377] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:8:0)
[05:38:48.406] Build status: STARTED
[05:38:48.407] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:9:0) after 5m0s
[05:43:48.439] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:9:0)
[05:43:48.459] Build status: STARTED
[05:43:48.459] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:10:0) after 7m49s
[05:51:37.473] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:10:0)
[05:51:37.496] Build status: STARTED
[05:51:37.496] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:11:0) after 3m6s
[05:54:43.517] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:11:0)
[05:54:43.534] Build status: STARTED
[05:54:43.534] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:12:0) after 9m43s
[06:04:26.516] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:12:0)
[06:04:26.530] Build status: STARTED
[06:04:26.530] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:13:0) after 8m49s
[06:13:15.545] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:13:0)
[06:13:15.574] Build status: STARTED
[06:13:15.574] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:14:0) after 3m36s
[06:16:51.586] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:14:0)
[06:16:51.606] Build status: STARTED
[06:16:51.606] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:15:0) after 5m55s
[06:22:46.822] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:15:0)
[06:22:46.838] Build status: STARTED
[06:22:46.838] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:16:0) after 9m33s
[06:32:19.910] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:16:0)
[06:32:19.925] Build status: STARTED
[06:32:19.925] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:17:0) after 5m21s
[06:37:40.998] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:17:0)
[06:37:41.016] Build status: STARTED
[06:37:41.016] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:18:0) after 9m57s
[06:47:38.031] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:18:0)
[06:47:38.072] Build status: STARTED
[06:47:38.072] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:19:0) after 4m0s
[06:51:38.044] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:19:0)
[06:51:38.058] Build status: STARTED
[06:51:38.058] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:20:0) after 7m52s
[06:59:30.074] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:20:0)
[06:59:30.174] Build status: STARTED
[06:59:30.174] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:21:0) after 6m47s
[07:06:17.190] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:21:0)
[07:06:17.219] Build status: STARTED
[07:06:17.219] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:22:0) after 6m3s
[07:12:20.233] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:22:0)
[07:12:20.289] Build status: STARTED
[07:12:20.289] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:23:0) after 4m23s
[07:16:43.306] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:23:0)
[07:16:43.323] Build status: STARTED
[07:16:43.323] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:24:0) after 4m58s
[07:21:41.404] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:24:0)
[07:21:41.424] Build status: STARTED
[07:21:41.424] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:25:0) after 3m9s
[07:24:50.471] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:25:0)
[07:24:50.500] Build status: STARTED
[07:24:50.500] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:26:0) after 1m18s
[07:26:08.528] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:26:0)
[07:26:08.568] Build status: STARTED
[07:26:08.568] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:27:0) after 1m21s
[07:27:29.543] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:27:0)
[07:27:29.602] Build status: STARTED
[07:27:29.602] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:28:0) after 1m47s
[07:29:16.614] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:28:0)
[07:29:16.742] Build status: STARTED
[07:29:16.742] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:29:0) after 3m57s
[07:33:13.756] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:29:0)
[07:33:13.801] Build status: STARTED
[07:33:13.801] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:30:0) after 6m28s
[07:39:41.840] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:30:0)
[07:39:41.855] Build status: STARTED
[07:39:41.855] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:31:0) after 2m20s
[07:42:01.824] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:31:0)
[07:42:01.850] Build status: STARTED
[07:42:01.850] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:32:0) after 2m8s
[07:44:09.919] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:32:0)
[07:44:09.986] Build status: STARTED
[07:44:09.986] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:33:0) after 4m13s
[07:48:23.000] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:33:0)
[07:48:23.017] Build status: STARTED
[07:48:23.017] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:34:0) after 2m6s
[07:50:29.035] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:34:0)
[07:50:29.049] Build status: STARTED
[07:50:29.049] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:35:0) after 3m0s
[07:53:29.066] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:35:0)
[07:53:29.083] Build status: STARTED
[07:53:29.083] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:36:0) after 6m44s
[08:00:13.118] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:36:0)
[08:00:13.142] Build status: STARTED
[08:00:13.142] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:37:0) after 9m57s
[08:10:10.209] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:37:0)
[08:10:10.308] Build status: STARTED
[08:10:10.308] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:38:0) after 3m26s
[08:13:36.332] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:38:0)
[08:13:36.360] Build status: STARTED
[08:13:36.360] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:39:0) after 4m33s
[08:18:09.398] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:39:0)
[08:18:09.423] Build status: STARTED
[08:18:09.423] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:40:0) after 7m38s
[08:25:47.414] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:40:0)
[08:25:47.428] Build status: STARTED
[08:25:47.428] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:41:0) after 7m59s
[08:33:46.444] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:41:0)
[08:33:46.477] Build status: STARTED
[08:33:46.477] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:42:0) after 2m21s
[08:36:07.521] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:42:0)
[08:36:07.537] Build status: STARTED
[08:36:07.537] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:43:0) after 8m13s
[08:44:20.571] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:43:0)
[08:44:20.592] Build status: STARTED
[08:44:20.592] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:44:0) after 5m57s
[08:50:17.661] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:44:0)
[08:50:17.675] Build status: STARTED
[08:50:17.675] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:45:0) after 3m4s
[08:53:21.690] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:45:0)
[08:53:21.707] Build status: STARTED
[08:53:21.707] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:46:0) after 6m17s
[08:59:38.730] Handling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:46:0)
[08:59:38.836] Build status: STARTED
[08:59:38.836] Scheduling timer "check-buildbucket-build-status" (chromium-m142/mac-official:8861779527400891552:47:0) after 7m4s
[09:01:06.715] Received PubSub notification, asking Buildbucket for the build status
[09:01:06.775] Build:
{
"id": "8696349465531678705",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "mac-official"
},
"number": 518,
"createdBy": "project:chromium-m142",
"createTime": "2025-12-05T05:18:10.437847415Z",
"startTime": "2025-12-05T05:18:15.216793542Z",
"endTime": "2025-12-05T09:00:57.110683642Z",
"updateTime": "2025-12-05T09:00:57.110683642Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d1ae97eb72068b8eb98e157bd2685da7d985d91a",
"ref": "refs/branch-heads/7444"
}
}
}
[09:01:06.775] Invocation finished in 3h42m57.503867885s with status SUCCEEDED