[00:24:44.544] New invocation is queued and will start shortly
[00:24:45.692] Starting the invocation (attempt 1)
[00:24:45.719] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d93980e833ebabe7b660a9bf3e3f4beedff989d6"
[00:24:45.719] Popped gitiles commit info from properties and tags
[00:24:45.719] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:24:45.719] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:24:45.719] Buildbucket request:
{
"requestId": "8836521433676206640",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacPGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d93980e833ebabe7b660a9bf3e3f4beedff989d6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8836521433676206640"
},
{
"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"
}
}
[00:24:46.707] Scheduled build:
{
"id": "8671091371427170481",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacPGO"
},
"number": 4602,
"createdBy": "project:chromium",
"createTime": "2026-09-10T00:24:46.066915464Z",
"updateTime": "2026-09-10T00:24:46.066915464Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d93980e833ebabe7b660a9bf3e3f4beedff989d6",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:24:46.707] Task URL: https://cr-buildbucket.appspot.com/build/8671091371427170481
[00:24:46.707] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:2:0) after 1m15s
[00:24:47.563] Received PubSub notification, asking Buildbucket for the build status
[00:24:47.626] Build status: SCHEDULED
[00:24:50.582] Received PubSub notification, asking Buildbucket for the build status
[00:24:50.600] Build status: STARTED
[00:26:01.898] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:2:0)
[00:26:01.920] Build status: STARTED
[00:26:01.920] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:5:0) after 8m24s
[00:34:26.063] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:5:0)
[00:34:26.089] Build status: STARTED
[00:34:26.089] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:6:0) after 3m13s
[00:37:39.432] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:6:0)
[00:37:39.452] Build status: STARTED
[00:37:39.452] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:7:0) after 5m2s
[00:42:41.504] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:7:0)
[00:42:41.530] Build status: STARTED
[00:42:41.530] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:8:0) after 6m10s
[00:48:51.600] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:8:0)
[00:48:51.623] Build status: STARTED
[00:48:51.624] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:9:0) after 6m1s
[00:54:52.734] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:9:0)
[00:54:52.755] Build status: STARTED
[00:54:52.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:10:0) after 9m35s
[01:04:27.742] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:10:0)
[01:04:27.764] Build status: STARTED
[01:04:27.764] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:11:0) after 9m44s
[01:14:11.823] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:11:0)
[01:14:11.844] Build status: STARTED
[01:14:11.844] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:12:0) after 8m45s
[01:22:56.885] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:12:0)
[01:22:56.907] Build status: STARTED
[01:22:56.907] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:13:0) after 5m13s
[01:28:09.973] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:13:0)
[01:28:09.995] Build status: STARTED
[01:28:09.995] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:14:0) after 7m51s
[01:36:01.003] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:14:0)
[01:36:01.026] Build status: STARTED
[01:36:01.026] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:15:0) after 5m4s
[01:41:05.125] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:15:0)
[01:41:05.146] Build status: STARTED
[01:41:05.146] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:16:0) after 6m50s
[01:47:55.205] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:16:0)
[01:47:55.229] Build status: STARTED
[01:47:55.229] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:17:0) after 7m44s
[01:55:39.271] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:17:0)
[01:55:39.289] Build status: STARTED
[01:55:39.289] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:18:0) after 1m38s
[01:57:17.431] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:18:0)
[01:57:17.483] Build status: STARTED
[01:57:17.483] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:19:0) after 5m33s
[02:02:50.520] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:19:0)
[02:02:50.543] Build status: STARTED
[02:02:50.543] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:20:0) after 8m42s
[02:11:32.682] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:20:0)
[02:11:32.699] Build status: STARTED
[02:11:32.699] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:21:0) after 2m39s
[02:14:11.827] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:21:0)
[02:14:11.848] Build status: STARTED
[02:14:11.848] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:22:0) after 3m43s
[02:17:54.837] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:22:0)
[02:17:54.853] Build status: STARTED
[02:17:54.853] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:23:0) after 5m10s
[02:23:04.948] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:23:0)
[02:23:04.965] Build status: STARTED
[02:23:04.965] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:24:0) after 4m26s
[02:27:31.100] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:24:0)
[02:27:31.120] Build status: STARTED
[02:27:31.120] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:25:0) after 4m20s
[02:31:51.143] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:25:0)
[02:31:51.166] Build status: STARTED
[02:31:51.166] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:26:0) after 1m31s
[02:33:22.324] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:26:0)
[02:33:22.341] Build status: STARTED
[02:33:22.341] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:27:0) after 2m3s
[02:35:25.539] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:27:0)
[02:35:25.557] Build status: STARTED
[02:35:25.558] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:28:0) after 6m45s
[02:42:10.643] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:28:0)
[02:42:10.762] Build status: STARTED
[02:42:10.762] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:29:0) after 1m2s
[02:43:12.742] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:29:0)
[02:43:12.763] Build status: STARTED
[02:43:12.763] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:30:0) after 1m7s
[02:44:19.866] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:30:0)
[02:44:19.890] Build status: STARTED
[02:44:19.890] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:31:0) after 5m22s
[02:49:41.913] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:31:0)
[02:49:41.932] Build status: STARTED
[02:49:41.932] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:32:0) after 6m8s
[02:55:50.083] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:32:0)
[02:55:50.107] Build status: STARTED
[02:55:50.107] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:33:0) after 8m50s
[03:04:40.151] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:33:0)
[03:04:40.176] Build status: STARTED
[03:04:40.176] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:34:0) after 6m22s
[03:11:02.334] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:34:0)
[03:11:02.409] Build status: STARTED
[03:11:02.409] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:35:0) after 1m33s
[03:12:35.477] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:35:0)
[03:12:35.588] Build status: STARTED
[03:12:35.588] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:36:0) after 4m15s
[03:16:50.646] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:36:0)
[03:16:50.667] Build status: STARTED
[03:16:50.667] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:37:0) after 3m1s
[03:19:51.698] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:37:0)
[03:19:51.716] Build status: STARTED
[03:19:51.716] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:38:0) after 6m35s
[03:26:26.739] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:38:0)
[03:26:26.762] Build status: STARTED
[03:26:26.762] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:39:0) after 4m18s
[03:30:44.781] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:39:0)
[03:30:44.799] Build status: STARTED
[03:30:44.799] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:40:0) after 5m36s
[03:36:20.928] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:40:0)
[03:36:20.950] Build status: STARTED
[03:36:20.950] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:41:0) after 4m55s
[03:41:15.931] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:41:0)
[03:41:15.950] Build status: STARTED
[03:41:15.950] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:42:0) after 7m7s
[03:48:23.023] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:42:0)
[03:48:23.087] Build status: STARTED
[03:48:23.087] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:43:0) after 8m26s
[03:56:49.181] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:43:0)
[03:56:49.245] Build status: STARTED
[03:56:49.245] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:44:0) after 8m58s
[04:05:47.293] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:44:0)
[04:05:47.326] Build status: STARTED
[04:05:47.327] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:45:0) after 1m14s
[04:07:01.485] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:45:0)
[04:07:01.551] Build status: STARTED
[04:07:01.551] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:46:0) after 4m42s
[04:11:43.570] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:46:0)
[04:11:43.590] Build status: STARTED
[04:11:43.590] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:47:0) after 7m17s
[04:19:00.728] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:47:0)
[04:19:00.744] Build status: STARTED
[04:19:00.744] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8836521433676206640:48:0) after 8m46s
[04:20:01.491] Received PubSub notification, asking Buildbucket for the build status
[04:20:01.586] Build:
{
"id": "8671091371427170481",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacPGO"
},
"number": 4602,
"createdBy": "project:chromium",
"createTime": "2026-09-10T00:24:46.066915464Z",
"startTime": "2026-09-10T00:24:49.876653486Z",
"endTime": "2026-09-10T04:19:59.430653534Z",
"updateTime": "2026-09-10T04:19:59.430653534Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d93980e833ebabe7b660a9bf3e3f4beedff989d6",
"ref": "refs/heads/main"
}
},
"canary": true
}
[04:20:01.586] Invocation finished in 3h55m17.048829648s with status SUCCEEDED