[01:29:35.375] New invocation is queued and will start shortly
[01:29:36.968] Starting the invocation (attempt 1)
[01:29:36.998] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/04eb9724ffbe90abe80d9c3a5a5a330554ad3595"
[01:29:36.998] Popped gitiles commit info from properties and tags
[01:29:36.998] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:29:36.998] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:29:36.998] Buildbucket request:
{
"requestId": "8835973772046422672",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacPGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "04eb9724ffbe90abe80d9c3a5a5a330554ad3595",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8835973772046422672"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTMacPGO"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:29:37.727] Scheduled build:
{
"id": "8670543709567738241",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacPGO"
},
"number": 4650,
"createdBy": "project:chromium",
"createTime": "2026-09-16T01:29:37.115664156Z",
"updateTime": "2026-09-16T01:29:37.115664156Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "04eb9724ffbe90abe80d9c3a5a5a330554ad3595",
"ref": "refs/heads/main"
}
}
}
[01:29:37.727] Task URL: https://cr-buildbucket.appspot.com/build/8670543709567738241
[01:29:37.727] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:2:0) after 4m24s
[01:29:41.382] Received PubSub notification, asking Buildbucket for the build status
[01:29:41.403] Build status: SCHEDULED
[01:29:46.392] Received PubSub notification, asking Buildbucket for the build status
[01:29:46.414] Build status: STARTED
[01:34:01.751] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:2:0)
[01:34:01.777] Build status: STARTED
[01:34:01.777] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:5:0) after 7m26s
[01:41:27.807] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:5:0)
[01:41:27.827] Build status: STARTED
[01:41:27.827] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:6:0) after 9m55s
[01:51:22.822] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:6:0)
[01:51:22.898] Build status: STARTED
[01:51:22.898] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:7:0) after 8m26s
[01:59:48.921] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:7:0)
[01:59:49.015] Build status: STARTED
[01:59:49.016] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:8:0) after 5m48s
[02:05:37.129] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:8:0)
[02:05:37.148] Build status: STARTED
[02:05:37.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:9:0) after 5m36s
[02:11:13.169] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:9:0)
[02:11:13.186] Build status: STARTED
[02:11:13.186] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:10:0) after 5m40s
[02:16:53.395] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:10:0)
[02:16:53.447] Build status: STARTED
[02:16:53.447] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:11:0) after 9m0s
[02:25:53.518] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:11:0)
[02:25:53.550] Build status: STARTED
[02:25:53.550] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:12:0) after 4m36s
[02:30:29.572] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:12:0)
[02:30:29.605] Build status: STARTED
[02:30:29.605] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:13:0) after 1m6s
[02:31:35.624] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:13:0)
[02:31:35.644] Build status: STARTED
[02:31:35.644] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:14:0) after 1m41s
[02:33:16.680] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:14:0)
[02:33:16.698] Build status: STARTED
[02:33:16.699] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:15:0) after 9m22s
[02:42:38.741] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:15:0)
[02:42:38.819] Build status: STARTED
[02:42:38.819] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:16:0) after 9m48s
[02:52:26.901] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:16:0)
[02:52:26.966] Build status: STARTED
[02:52:26.966] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:17:0) after 5m15s
[02:57:42.020] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:17:0)
[02:57:42.040] Build status: STARTED
[02:57:42.040] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:18:0) after 3m11s
[03:00:53.164] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:18:0)
[03:00:53.252] Build status: STARTED
[03:00:53.252] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:19:0) after 5m39s
[03:06:32.271] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:19:0)
[03:06:32.293] Build status: STARTED
[03:06:32.293] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:20:0) after 4m22s
[03:10:54.381] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:20:0)
[03:10:54.402] Build status: STARTED
[03:10:54.402] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:21:0) after 2m43s
[03:13:37.530] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:21:0)
[03:13:37.549] Build status: STARTED
[03:13:37.549] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:22:0) after 1m32s
[03:15:09.568] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:22:0)
[03:15:09.587] Build status: STARTED
[03:15:09.587] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:23:0) after 3m2s
[03:18:11.627] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:23:0)
[03:18:11.657] Build status: STARTED
[03:18:11.657] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:24:0) after 2m7s
[03:20:18.814] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:24:0)
[03:20:18.831] Build status: STARTED
[03:20:18.831] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:25:0) after 3m25s
[03:23:43.994] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:25:0)
[03:23:44.015] Build status: STARTED
[03:23:44.015] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:26:0) after 9m16s
[03:33:00.099] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:26:0)
[03:33:00.117] Build status: STARTED
[03:33:00.117] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:27:0) after 7m21s
[03:40:21.139] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:27:0)
[03:40:21.159] Build status: STARTED
[03:40:21.159] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:28:0) after 6m19s
[03:46:40.227] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:28:0)
[03:46:40.247] Build status: STARTED
[03:46:40.247] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:29:0) after 4m52s
[03:51:32.314] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:29:0)
[03:51:32.335] Build status: STARTED
[03:51:32.335] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:30:0) after 9m15s
[04:00:47.398] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:30:0)
[04:00:47.418] Build status: STARTED
[04:00:47.418] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:31:0) after 1m19s
[04:02:06.490] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:31:0)
[04:02:06.512] Build status: STARTED
[04:02:06.512] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:32:0) after 2m32s
[04:04:38.601] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:32:0)
[04:04:38.619] Build status: STARTED
[04:04:38.619] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:33:0) after 9m51s
[04:14:29.638] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:33:0)
[04:14:29.659] Build status: STARTED
[04:14:29.659] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:34:0) after 7m35s
[04:22:04.679] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:34:0)
[04:22:04.702] Build status: STARTED
[04:22:04.702] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:35:0) after 5m3s
[04:27:07.729] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:35:0)
[04:27:07.831] Build status: STARTED
[04:27:07.831] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:36:0) after 9m36s
[04:36:43.949] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:36:0)
[04:36:43.974] Build status: STARTED
[04:36:43.974] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:37:0) after 2m26s
[04:39:10.108] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:37:0)
[04:39:10.127] Build status: STARTED
[04:39:10.127] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:38:0) after 8m11s
[04:47:21.195] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:38:0)
[04:47:21.213] Build status: STARTED
[04:47:21.213] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:39:0) after 3m35s
[04:50:56.233] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:39:0)
[04:50:56.317] Build status: STARTED
[04:50:56.317] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:40:0) after 9m28s
[05:00:24.387] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:40:0)
[05:00:24.409] Build status: STARTED
[05:00:24.409] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:41:0) after 4m53s
[05:05:17.480] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:41:0)
[05:05:17.551] Build status: STARTED
[05:05:17.551] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:42:0) after 4m14s
[05:09:31.592] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:42:0)
[05:09:31.610] Build status: STARTED
[05:09:31.610] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:43:0) after 5m11s
[05:14:42.635] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:43:0)
[05:14:42.802] Build status: STARTED
[05:14:42.802] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:44:0) after 2m53s
[05:17:35.896] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:44:0)
[05:17:35.916] Build status: STARTED
[05:17:35.916] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8835973772046422672:45:0) after 5m18s
[05:21:03.489] Received PubSub notification, asking Buildbucket for the build status
[05:21:03.515] Build:
{
"id": "8670543709567738241",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacPGO"
},
"number": 4650,
"createdBy": "project:chromium",
"createTime": "2026-09-16T01:29:37.115664156Z",
"startTime": "2026-09-16T01:29:41.923268092Z",
"endTime": "2026-09-16T05:21:03.197987476Z",
"updateTime": "2026-09-16T05:21:03.197987476Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "04eb9724ffbe90abe80d9c3a5a5a330554ad3595",
"ref": "refs/heads/main"
}
}
}
[05:21:03.515] Invocation finished in 3h51m28.14705419s with status SUCCEEDED