[18:50:06.534] New invocation is queued and will start shortly
[18:50:07.846] Starting the invocation (attempt 1)
[18:50:07.873] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a5ef13fdb0138d4718cc0010b5798ec7fc753001"
[18:50:07.873] Popped gitiles commit info from properties and tags
[18:50:07.873] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:50:07.873] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:50:07.874] Buildbucket request:
{
"requestId": "8874683809859945472",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a5ef13fdb0138d4718cc0010b5798ec7fc753001",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8874683809859945472"
},
{
"key": "scheduler_job_id",
"value": "chromium/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:50:08.370] Scheduled build:
{
"id": "8709253747730362801",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"number": 12142,
"createdBy": "project:chromium",
"createTime": "2025-07-15T18:50:07.932363900Z",
"updateTime": "2025-07-15T18:50:07.932363900Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a5ef13fdb0138d4718cc0010b5798ec7fc753001",
"ref": "refs/heads/main"
}
}
}
[18:50:08.370] Task URL: https://cr-buildbucket.appspot.com/build/8709253747730362801
[18:50:08.370] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:2:0) after 7m54s
[18:50:09.362] Received PubSub notification, asking Buildbucket for the build status
[18:50:09.393] Build status: SCHEDULED
[18:50:13.182] Received PubSub notification, asking Buildbucket for the build status
[18:50:13.201] Build status: STARTED
[18:58:02.396] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:2:0)
[18:58:02.429] Build status: STARTED
[18:58:02.429] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:5:0) after 8m36s
[19:06:38.460] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:5:0)
[19:06:38.481] Build status: STARTED
[19:06:38.481] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:6:0) after 4m16s
[19:10:54.655] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:6:0)
[19:10:54.681] Build status: STARTED
[19:10:54.681] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:7:0) after 8m34s
[19:19:28.702] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:7:0)
[19:19:28.735] Build status: STARTED
[19:19:28.735] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:8:0) after 4m55s
[19:24:23.757] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:8:0)
[19:24:23.773] Build status: STARTED
[19:24:23.773] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:9:0) after 5m3s
[19:29:26.846] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:9:0)
[19:29:26.876] Build status: STARTED
[19:29:26.876] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:10:0) after 7m48s
[19:37:14.973] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:10:0)
[19:37:14.991] Build status: STARTED
[19:37:14.991] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:11:0) after 5m58s
[19:43:13.109] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:11:0)
[19:43:13.146] Build status: STARTED
[19:43:13.147] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:12:0) after 4m39s
[19:47:52.199] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:12:0)
[19:47:52.226] Build status: STARTED
[19:47:52.226] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:13:0) after 2m33s
[19:50:25.297] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:13:0)
[19:50:25.324] Build status: STARTED
[19:50:25.324] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:14:0) after 5m33s
[19:55:58.333] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:14:0)
[19:55:58.367] Build status: STARTED
[19:55:58.367] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:15:0) after 2m3s
[19:58:01.348] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:15:0)
[19:58:01.379] Build status: STARTED
[19:58:01.379] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:16:0) after 6m37s
[20:04:38.410] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:16:0)
[20:04:38.428] Build status: STARTED
[20:04:38.428] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:17:0) after 6m2s
[20:10:40.465] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:17:0)
[20:10:40.482] Build status: STARTED
[20:10:40.482] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:18:0) after 1m8s
[20:11:48.865] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:18:0)
[20:11:48.883] Build status: STARTED
[20:11:48.883] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:19:0) after 1m21s
[20:13:09.895] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:19:0)
[20:13:09.915] Build status: STARTED
[20:13:09.915] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:20:0) after 9m21s
[20:22:31.015] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:20:0)
[20:22:31.177] Build status: STARTED
[20:22:31.177] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:21:0) after 6m4s
[20:28:35.247] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:21:0)
[20:28:35.264] Build status: STARTED
[20:28:35.264] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:22:0) after 2m25s
[20:31:00.287] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:22:0)
[20:31:00.318] Build status: STARTED
[20:31:00.318] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:23:0) after 1m18s
[20:32:18.484] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:23:0)
[20:32:18.510] Build status: STARTED
[20:32:18.510] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:24:0) after 6m57s
[20:39:15.592] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:24:0)
[20:39:15.607] Build status: STARTED
[20:39:15.607] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:25:0) after 1m31s
[20:40:46.634] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:25:0)
[20:40:46.652] Build status: STARTED
[20:40:46.652] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:26:0) after 3m12s
[20:43:58.704] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:26:0)
[20:43:58.725] Build status: STARTED
[20:43:58.725] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:27:0) after 2m5s
[20:46:03.756] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:27:0)
[20:46:03.836] Build status: STARTED
[20:46:03.836] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:28:0) after 5m11s
[20:51:14.895] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:28:0)
[20:51:14.936] Build status: STARTED
[20:51:14.936] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:29:0) after 1m54s
[20:53:08.987] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:29:0)
[20:53:09.005] Build status: STARTED
[20:53:09.005] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:30:0) after 3m11s
[20:56:20.046] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:30:0)
[20:56:20.090] Build status: STARTED
[20:56:20.090] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:31:0) after 3m18s
[20:59:38.095] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:31:0)
[20:59:38.114] Build status: STARTED
[20:59:38.114] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:32:0) after 1m11s
[21:00:49.155] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:32:0)
[21:00:49.197] Build status: STARTED
[21:00:49.197] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:33:0) after 1m29s
[21:02:18.188] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:33:0)
[21:02:18.219] Build status: STARTED
[21:02:18.219] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:34:0) after 5m57s
[21:08:15.238] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:34:0)
[21:08:15.275] Build status: STARTED
[21:08:15.275] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:35:0) after 6m1s
[21:14:16.300] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:35:0)
[21:14:16.325] Build status: STARTED
[21:14:16.325] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:36:0) after 5m5s
[21:19:21.385] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:36:0)
[21:19:21.496] Build status: STARTED
[21:19:21.496] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:37:0) after 8m39s
[21:28:00.525] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:37:0)
[21:28:00.544] Build status: STARTED
[21:28:00.544] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:38:0) after 3m54s
[21:31:54.625] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:38:0)
[21:31:54.652] Build status: STARTED
[21:31:54.652] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8874683809859945472:39:0) after 9m30s
[21:33:25.491] Received PubSub notification, asking Buildbucket for the build status
[21:33:25.516] Build:
{
"id": "8709253747730362801",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"number": 12142,
"createdBy": "project:chromium",
"createTime": "2025-07-15T18:50:07.932363900Z",
"startTime": "2025-07-15T18:50:12.973051176Z",
"endTime": "2025-07-15T21:33:25.050259015Z",
"updateTime": "2025-07-15T21:33:25.050259015Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a5ef13fdb0138d4718cc0010b5798ec7fc753001",
"ref": "refs/heads/main"
}
}
}
[21:33:25.516] Invocation finished in 2h43m18.997718079s with status SUCCEEDED