[05:14:10.684] New invocation is queued and will start shortly
[05:14:11.791] Starting the invocation (attempt 1)
[05:14:11.813] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8c4a78175daec9a649c8a32255fc828bcf3a06ee"
[05:14:11.813] Popped gitiles commit info from properties and tags
[05:14:11.813] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:14:11.814] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:14:11.814] Buildbucket request:
{
"requestId": "8861507986682227696",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8861507986682227696",
"job": "chromium-m144/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "92e7c8141bfe9132d25a4f6528ad3a314dd71343"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@92e7c8141bfe9132d25a4f6528ad3a314dd71343",
"title": "92e7c8141bfe9132d25a4f6528ad3a314dd71343",
"url": "https://chromium.googlesource.com/chromium/src/+/92e7c8141bfe9132d25a4f6528ad3a314dd71343"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "df7b19d877d19326ee49ddee8a2560b608b975cf"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@df7b19d877d19326ee49ddee8a2560b608b975cf",
"title": "df7b19d877d19326ee49ddee8a2560b608b975cf",
"url": "https://chromium.googlesource.com/chromium/src/+/df7b19d877d19326ee49ddee8a2560b608b975cf"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "335a66c6021124e9cc6057d61f0744a020911af5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@335a66c6021124e9cc6057d61f0744a020911af5",
"title": "335a66c6021124e9cc6057d61f0744a020911af5",
"url": "https://chromium.googlesource.com/chromium/src/+/335a66c6021124e9cc6057d61f0744a020911af5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7ff0c293695903023ae992217f8bf4769859d215"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@7ff0c293695903023ae992217f8bf4769859d215",
"title": "7ff0c293695903023ae992217f8bf4769859d215",
"url": "https://chromium.googlesource.com/chromium/src/+/7ff0c293695903023ae992217f8bf4769859d215"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "01e090106102b597fb007e89cc5901745eb3df15"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@01e090106102b597fb007e89cc5901745eb3df15",
"title": "01e090106102b597fb007e89cc5901745eb3df15",
"url": "https://chromium.googlesource.com/chromium/src/+/01e090106102b597fb007e89cc5901745eb3df15"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6739090650ca83ddc734cf4313c1a73fd78f7b04"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@6739090650ca83ddc734cf4313c1a73fd78f7b04",
"title": "6739090650ca83ddc734cf4313c1a73fd78f7b04",
"url": "https://chromium.googlesource.com/chromium/src/+/6739090650ca83ddc734cf4313c1a73fd78f7b04"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d1c77b6f5f3c2c4fbd908c73cb5946dcf6b95358"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@d1c77b6f5f3c2c4fbd908c73cb5946dcf6b95358",
"title": "d1c77b6f5f3c2c4fbd908c73cb5946dcf6b95358",
"url": "https://chromium.googlesource.com/chromium/src/+/d1c77b6f5f3c2c4fbd908c73cb5946dcf6b95358"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "650756a388ce8664702871a519ebd1531e3ed799"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@650756a388ce8664702871a519ebd1531e3ed799",
"title": "650756a388ce8664702871a519ebd1531e3ed799",
"url": "https://chromium.googlesource.com/chromium/src/+/650756a388ce8664702871a519ebd1531e3ed799"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7e926e27c9c39d0142a6ced4a2383a8062d02fc5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@7e926e27c9c39d0142a6ced4a2383a8062d02fc5",
"title": "7e926e27c9c39d0142a6ced4a2383a8062d02fc5",
"url": "https://chromium.googlesource.com/chromium/src/+/7e926e27c9c39d0142a6ced4a2383a8062d02fc5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8c4a78175daec9a649c8a32255fc828bcf3a06ee"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@8c4a78175daec9a649c8a32255fc828bcf3a06ee",
"title": "8c4a78175daec9a649c8a32255fc828bcf3a06ee",
"url": "https://chromium.googlesource.com/chromium/src/+/8c4a78175daec9a649c8a32255fc828bcf3a06ee"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8c4a78175daec9a649c8a32255fc828bcf3a06ee",
"ref": "refs/branch-heads/7559"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8861507986682227696"
},
{
"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"
}
}
[05:14:12.258] Scheduled build:
{
"id": "8696077924782215377",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "mac-official"
},
"number": 40,
"createdBy": "project:chromium-m144",
"createTime": "2025-12-08T05:14:11.875888891Z",
"updateTime": "2025-12-08T05:14:11.875888891Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8c4a78175daec9a649c8a32255fc828bcf3a06ee",
"ref": "refs/branch-heads/7559"
}
}
}
[05:14:12.258] Task URL: https://cr-buildbucket.appspot.com/build/8696077924782215377
[05:14:12.258] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:2:0) after 9m29s
[05:14:12.663] Received PubSub notification, asking Buildbucket for the build status
[05:14:12.696] Build status: SCHEDULED
[05:15:25.041] Received PubSub notification, asking Buildbucket for the build status
[05:15:25.108] Build status: STARTED
[05:23:41.378] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:2:0)
[05:23:41.452] Build status: STARTED
[05:23:41.452] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:5:0) after 4m44s
[05:28:25.627] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:5:0)
[05:28:25.646] Build status: STARTED
[05:28:25.646] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:6:0) after 2m44s
[05:31:09.693] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:6:0)
[05:31:09.746] Build status: STARTED
[05:31:09.746] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:7:0) after 8m46s
[05:39:55.766] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:7:0)
[05:39:55.839] Build status: STARTED
[05:39:55.839] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:8:0) after 2m19s
[05:42:14.947] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:8:0)
[05:42:15.009] Build status: STARTED
[05:42:15.009] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:9:0) after 8m32s
[05:50:47.123] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:9:0)
[05:50:47.149] Build status: STARTED
[05:50:47.149] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:10:0) after 6m3s
[05:56:50.355] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:10:0)
[05:56:50.377] Build status: STARTED
[05:56:50.377] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:11:0) after 2m36s
[05:59:26.504] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:11:0)
[05:59:26.519] Build status: STARTED
[05:59:26.519] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:12:0) after 6m54s
[06:06:21.093] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:12:0)
[06:06:21.120] Build status: STARTED
[06:06:21.120] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:13:0) after 2m57s
[06:09:18.208] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:13:0)
[06:09:18.229] Build status: STARTED
[06:09:18.229] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:14:0) after 4m52s
[06:14:10.389] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:14:0)
[06:14:10.459] Build status: STARTED
[06:14:10.459] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:15:0) after 3m0s
[06:17:10.522] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:15:0)
[06:17:10.571] Build status: STARTED
[06:17:10.571] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:16:0) after 7m13s
[06:24:23.629] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:16:0)
[06:24:23.679] Build status: STARTED
[06:24:23.679] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:17:0) after 2m22s
[06:26:45.784] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:17:0)
[06:26:45.811] Build status: STARTED
[06:26:45.811] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:18:0) after 1m50s
[06:28:36.002] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:18:0)
[06:28:36.017] Build status: STARTED
[06:28:36.017] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:19:0) after 2m33s
[06:31:09.069] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:19:0)
[06:31:09.091] Build status: STARTED
[06:31:09.091] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:20:0) after 1m36s
[06:32:45.446] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:20:0)
[06:32:45.459] Build status: STARTED
[06:32:45.459] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:21:0) after 6m29s
[06:39:14.635] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:21:0)
[06:39:14.662] Build status: STARTED
[06:39:14.662] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:22:0) after 8m39s
[06:47:53.674] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:22:0)
[06:47:53.730] Build status: STARTED
[06:47:53.731] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:23:0) after 7m11s
[06:55:04.918] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:23:0)
[06:55:04.935] Build status: STARTED
[06:55:04.935] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:24:0) after 5m51s
[07:00:55.962] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:24:0)
[07:00:56.019] Build status: STARTED
[07:00:56.019] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:25:0) after 1m12s
[07:02:08.039] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:25:0)
[07:02:08.057] Build status: STARTED
[07:02:08.057] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:26:0) after 4m32s
[07:06:40.579] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:26:0)
[07:06:40.592] Build status: STARTED
[07:06:40.592] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:27:0) after 3m51s
[07:10:31.606] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:27:0)
[07:10:31.623] Build status: STARTED
[07:10:31.623] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:28:0) after 4m1s
[07:14:32.636] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:28:0)
[07:14:32.650] Build status: STARTED
[07:14:32.650] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:29:0) after 6m25s
[07:20:58.039] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:29:0)
[07:20:58.070] Build status: STARTED
[07:20:58.071] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:30:0) after 6m43s
[07:27:41.377] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:30:0)
[07:27:41.389] Build status: STARTED
[07:27:41.389] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:31:0) after 7m49s
[07:35:30.458] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:31:0)
[07:35:30.480] Build status: STARTED
[07:35:30.480] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:32:0) after 7m37s
[07:43:07.505] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:32:0)
[07:43:07.526] Build status: STARTED
[07:43:07.526] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:33:0) after 1m5s
[07:44:12.908] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:33:0)
[07:44:12.942] Build status: STARTED
[07:44:12.942] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:34:0) after 2m19s
[07:46:31.963] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:34:0)
[07:46:32.000] Build status: STARTED
[07:46:32.000] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:35:0) after 5m42s
[07:52:14.113] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:35:0)
[07:52:14.128] Build status: STARTED
[07:52:14.128] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:36:0) after 8m28s
[08:00:42.178] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:36:0)
[08:00:42.193] Build status: STARTED
[08:00:42.194] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:37:0) after 7m57s
[08:08:39.260] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:37:0)
[08:08:39.439] Build status: STARTED
[08:08:39.439] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:38:0) after 6m58s
[08:15:37.605] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:38:0)
[08:15:37.668] Build status: STARTED
[08:15:37.668] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:39:0) after 1m35s
[08:17:12.701] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:39:0)
[08:17:12.788] Build status: STARTED
[08:17:12.788] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:40:0) after 8m35s
[08:25:47.852] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:40:0)
[08:25:47.875] Build status: STARTED
[08:25:47.875] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:41:0) after 1m19s
[08:27:07.145] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:41:0)
[08:27:07.160] Build status: STARTED
[08:27:07.160] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:42:0) after 7m36s
[08:34:43.245] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:42:0)
[08:34:43.262] Build status: STARTED
[08:34:43.262] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:43:0) after 5m1s
[08:39:44.277] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:43:0)
[08:39:44.291] Build status: STARTED
[08:39:44.291] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:44:0) after 3m51s
[08:43:35.392] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:44:0)
[08:43:35.406] Build status: STARTED
[08:43:35.406] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:45:0) after 5m45s
[08:49:20.512] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:45:0)
[08:49:20.526] Build status: STARTED
[08:49:20.526] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:46:0) after 5m17s
[08:54:38.253] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:46:0)
[08:54:38.266] Build status: STARTED
[08:54:38.266] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:47:0) after 1m50s
[08:56:28.522] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:47:0)
[08:56:28.552] Build status: STARTED
[08:56:28.552] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:48:0) after 4m22s
[09:00:51.006] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:48:0)
[09:00:51.025] Build status: STARTED
[09:00:51.025] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8861507986682227696:49:0) after 9m53s
[09:07:47.018] Received PubSub notification, asking Buildbucket for the build status
[09:07:47.034] Build:
{
"id": "8696077924782215377",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "mac-official"
},
"number": 40,
"createdBy": "project:chromium-m144",
"createTime": "2025-12-08T05:14:11.875888891Z",
"startTime": "2025-12-08T05:15:24.889563361Z",
"endTime": "2025-12-08T09:07:46.781602547Z",
"updateTime": "2025-12-08T09:07:46.781602547Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8c4a78175daec9a649c8a32255fc828bcf3a06ee",
"ref": "refs/branch-heads/7559"
}
}
}
[09:07:47.034] Invocation finished in 3h53m36.354360677s with status SUCCEEDED