[13:43:44.749] New invocation is queued and will start shortly
[13:43:45.867] Starting the invocation (attempt 1)
[13:43:45.896] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/697e9ae24890a87cd871652889cd00217782f36f"
[13:43:45.896] Popped gitiles commit info from properties and tags
[13:43:45.896] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:43:45.896] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:43:45.897] Buildbucket request:
{
"requestId": "8837286537426310624",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacArm64"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "697e9ae24890a87cd871652889cd00217782f36f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8837286537426310624"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTMacArm64"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:43:46.584] Scheduled build:
{
"id": "8671856475477947425",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacArm64"
},
"number": 4885,
"createdBy": "project:chromium",
"createTime": "2026-09-01T13:43:45.983369602Z",
"updateTime": "2026-09-01T13:43:45.983369602Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "697e9ae24890a87cd871652889cd00217782f36f",
"ref": "refs/heads/main"
}
}
}
[13:43:46.584] Task URL: https://cr-buildbucket.appspot.com/build/8671856475477947425
[13:43:46.584] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:2:0) after 3m21s
[13:43:49.186] Received PubSub notification, asking Buildbucket for the build status
[13:43:49.247] Build status: SCHEDULED
[13:44:38.071] Received PubSub notification, asking Buildbucket for the build status
[13:44:38.090] Build status: STARTED
[13:47:07.600] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:2:0)
[13:47:07.618] Build status: STARTED
[13:47:07.618] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:5:0) after 4m46s
[13:51:53.763] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:5:0)
[13:51:53.790] Build status: STARTED
[13:51:53.790] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:6:0) after 1m29s
[13:53:22.860] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:6:0)
[13:53:22.900] Build status: STARTED
[13:53:22.900] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:7:0) after 8m58s
[14:02:20.948] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:7:0)
[14:02:20.972] Build status: STARTED
[14:02:20.972] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:8:0) after 1m2s
[14:03:23.005] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:8:0)
[14:03:23.090] Build status: STARTED
[14:03:23.091] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:9:0) after 1m13s
[14:04:36.240] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:9:0)
[14:04:36.262] Build status: STARTED
[14:04:36.263] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:10:0) after 3m44s
[14:08:20.280] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:10:0)
[14:08:20.301] Build status: STARTED
[14:08:20.301] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:11:0) after 7m4s
[14:15:24.344] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:11:0)
[14:15:24.361] Build status: STARTED
[14:15:24.361] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:12:0) after 5m20s
[14:20:44.390] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:12:0)
[14:20:44.420] Build status: STARTED
[14:20:44.420] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:13:0) after 2m0s
[14:22:44.480] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:13:0)
[14:22:44.537] Build status: STARTED
[14:22:44.537] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:14:0) after 7m2s
[14:29:46.555] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:14:0)
[14:29:46.581] Build status: STARTED
[14:29:46.581] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:15:0) after 4m57s
[14:34:43.615] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:15:0)
[14:34:43.643] Build status: STARTED
[14:34:43.643] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:16:0) after 1m10s
[14:35:53.722] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:16:0)
[14:35:53.743] Build status: STARTED
[14:35:53.743] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:17:0) after 6m44s
[14:42:37.772] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:17:0)
[14:42:37.792] Build status: STARTED
[14:42:37.792] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:18:0) after 3m51s
[14:46:28.849] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:18:0)
[14:46:28.869] Build status: STARTED
[14:46:28.869] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:19:0) after 6m58s
[14:53:26.913] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:19:0)
[14:53:26.932] Build status: STARTED
[14:53:26.932] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:20:0) after 3m30s
[14:56:56.927] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:20:0)
[14:56:56.958] Build status: STARTED
[14:56:56.958] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:21:0) after 5m36s
[15:02:32.973] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:21:0)
[15:02:33.005] Build status: STARTED
[15:02:33.005] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:22:0) after 3m39s
[15:06:12.790] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:22:0)
[15:06:12.869] Build status: STARTED
[15:06:12.870] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:23:0) after 6m58s
[15:13:10.886] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:23:0)
[15:13:10.907] Build status: STARTED
[15:13:10.907] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:24:0) after 2m33s
[15:15:44.102] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:24:0)
[15:15:44.160] Build status: STARTED
[15:15:44.160] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:25:0) after 3m56s
[15:19:40.190] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:25:0)
[15:19:40.247] Build status: STARTED
[15:19:40.247] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:26:0) after 3m20s
[15:23:02.496] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:26:0)
[15:23:02.553] Build status: STARTED
[15:23:02.553] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:27:0) after 1m40s
[15:24:42.597] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:27:0)
[15:24:42.681] Build status: STARTED
[15:24:42.681] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:28:0) after 9m10s
[15:33:52.716] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:28:0)
[15:33:52.737] Build status: STARTED
[15:33:52.737] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:29:0) after 8m9s
[15:42:01.753] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:29:0)
[15:42:01.782] Build status: STARTED
[15:42:01.782] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:30:0) after 4m58s
[15:46:59.893] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:30:0)
[15:46:59.918] Build status: STARTED
[15:46:59.918] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:31:0) after 8m13s
[15:55:13.015] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:31:0)
[15:55:13.040] Build status: STARTED
[15:55:13.040] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:32:0) after 7m1s
[16:02:14.057] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:32:0)
[16:02:14.146] Build status: STARTED
[16:02:14.146] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:33:0) after 3m28s
[16:05:42.165] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:33:0)
[16:05:42.187] Build status: STARTED
[16:05:42.187] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:34:0) after 3m36s
[16:09:18.204] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:34:0)
[16:09:18.225] Build status: STARTED
[16:09:18.226] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:35:0) after 1m30s
[16:10:48.308] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:35:0)
[16:10:48.426] Build status: STARTED
[16:10:48.426] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:36:0) after 4m22s
[16:15:10.477] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:36:0)
[16:15:10.539] Build status: STARTED
[16:15:10.539] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:37:0) after 5m40s
[16:20:50.565] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:37:0)
[16:20:50.587] Build status: STARTED
[16:20:50.587] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:38:0) after 1m44s
[16:22:34.711] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:38:0)
[16:22:34.760] Build status: STARTED
[16:22:34.761] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:39:0) after 3m24s
[16:25:58.837] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:39:0)
[16:25:58.975] Build status: STARTED
[16:25:58.975] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:40:0) after 7m15s
[16:33:13.994] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:40:0)
[16:33:14.013] Build status: STARTED
[16:33:14.013] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:41:0) after 2m14s
[16:35:28.030] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:41:0)
[16:35:28.072] Build status: STARTED
[16:35:28.072] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:42:0) after 6m50s
[16:42:18.096] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:42:0)
[16:42:18.118] Build status: STARTED
[16:42:18.118] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:43:0) after 1m4s
[16:43:22.106] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:43:0)
[16:43:22.166] Build status: STARTED
[16:43:22.166] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:44:0) after 3m43s
[16:47:05.189] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:44:0)
[16:47:05.218] Build status: STARTED
[16:47:05.218] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:45:0) after 7m47s
[16:54:52.302] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:45:0)
[16:54:52.410] Build status: STARTED
[16:54:52.410] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:46:0) after 7m22s
[17:02:14.471] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:46:0)
[17:02:14.521] Build status: STARTED
[17:02:14.521] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:47:0) after 7m17s
[17:09:31.535] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:47:0)
[17:09:31.575] Build status: STARTED
[17:09:31.575] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:48:0) after 6m31s
[17:16:02.700] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:48:0)
[17:16:02.717] Build status: STARTED
[17:16:02.717] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:49:0) after 8m33s
[17:24:35.847] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:49:0)
[17:24:35.916] Build status: STARTED
[17:24:35.916] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:50:0) after 3m49s
[17:28:24.931] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:50:0)
[17:28:24.971] Build status: STARTED
[17:28:24.971] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:51:0) after 9m13s
[17:37:38.023] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:51:0)
[17:37:38.048] Build status: STARTED
[17:37:38.048] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:52:0) after 2m40s
[17:40:18.067] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:52:0)
[17:40:18.087] Build status: STARTED
[17:40:18.087] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:53:0) after 1m6s
[17:41:24.200] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:53:0)
[17:41:24.321] Build status: STARTED
[17:41:24.321] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:54:0) after 1m0s
[17:42:24.397] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:54:0)
[17:42:24.417] Build status: STARTED
[17:42:24.417] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:55:0) after 2m44s
[17:45:08.436] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:55:0)
[17:45:08.461] Build status: STARTED
[17:45:08.462] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:56:0) after 1m1s
[17:46:09.553] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:56:0)
[17:46:09.575] Build status: STARTED
[17:46:09.575] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:57:0) after 1m39s
[17:47:48.707] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:57:0)
[17:47:48.729] Build status: STARTED
[17:47:48.729] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:58:0) after 6m13s
[17:54:01.811] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:58:0)
[17:54:01.944] Build status: STARTED
[17:54:01.944] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:59:0) after 2m29s
[17:56:30.978] Handling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:59:0)
[17:56:31.000] Build status: STARTED
[17:56:31.000] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacArm64:8837286537426310624:60:0) after 7m20s
[18:00:24.015] Received PubSub notification, asking Buildbucket for the build status
[18:00:24.067] Build:
{
"id": "8671856475477947425",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacArm64"
},
"number": 4885,
"createdBy": "project:chromium",
"createTime": "2026-09-01T13:43:45.983369602Z",
"startTime": "2026-09-01T13:44:31.488219916Z",
"endTime": "2026-09-01T18:00:16.429062366Z",
"updateTime": "2026-09-01T18:00:16.429062366Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "697e9ae24890a87cd871652889cd00217782f36f",
"ref": "refs/heads/main"
}
}
}
[18:00:24.067] Invocation finished in 4h16m39.325987288s with status SUCCEEDED