[23:13:41.621] New invocation is queued and will start shortly
[23:13:43.010] Starting the invocation (attempt 1)
[23:13:43.046] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/18b5e5884c772d054e42893c2b158a9c0da38531"
[23:13:43.046] Popped gitiles commit info from properties and tags
[23:13:43.046] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:13:43.046] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:13:43.046] Buildbucket request:
{
"requestId": "8866513499560869280",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacPGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "18b5e5884c772d054e42893c2b158a9c0da38531",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8866513499560869280"
},
{
"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"
}
}
[23:13:43.535] Scheduled build:
{
"id": "8701083437327137041",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacPGO"
},
"number": 69,
"createdBy": "project:chromium",
"createTime": "2025-10-13T23:13:43.121071532Z",
"updateTime": "2025-10-13T23:13:43.121071532Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "18b5e5884c772d054e42893c2b158a9c0da38531",
"ref": "refs/heads/main"
}
}
}
[23:13:43.535] Task URL: https://cr-buildbucket.appspot.com/build/8701083437327137041
[23:13:43.535] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:2:0) after 5m16s
[23:13:44.587] Received PubSub notification, asking Buildbucket for the build status
[23:13:44.612] Build status: SCHEDULED
[23:13:46.873] Received PubSub notification, asking Buildbucket for the build status
[23:13:46.910] Build status: STARTED
[23:18:59.551] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:2:0)
[23:18:59.567] Build status: STARTED
[23:18:59.567] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:5:0) after 4m15s
[23:23:14.632] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:5:0)
[23:23:14.653] Build status: STARTED
[23:23:14.653] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:6:0) after 6m59s
[23:30:13.793] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:6:0)
[23:30:13.824] Build status: STARTED
[23:30:13.824] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:7:0) after 2m19s
[23:32:32.844] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:7:0)
[23:32:32.908] Build status: STARTED
[23:32:32.908] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:8:0) after 3m12s
[23:35:45.035] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:8:0)
[23:35:45.079] Build status: STARTED
[23:35:45.079] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:9:0) after 4m15s
[23:40:00.123] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:9:0)
[23:40:00.221] Build status: STARTED
[23:40:00.221] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:10:0) after 3m37s
[23:43:37.287] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:10:0)
[23:43:37.313] Build status: STARTED
[23:43:37.313] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:11:0) after 6m13s
[23:49:50.301] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:11:0)
[23:49:50.321] Build status: STARTED
[23:49:50.321] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:12:0) after 9m55s
[23:59:45.392] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:12:0)
[23:59:45.407] Build status: STARTED
[23:59:45.407] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:13:0) after 8m10s
[00:07:55.476] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:13:0)
[00:07:55.491] Build status: STARTED
[00:07:55.491] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:14:0) after 5m11s
[00:13:06.537] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:14:0)
[00:13:06.557] Build status: STARTED
[00:13:06.557] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:15:0) after 7m42s
[00:20:48.700] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:15:0)
[00:20:48.715] Build status: STARTED
[00:20:48.715] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:16:0) after 9m44s
[00:30:32.767] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:16:0)
[00:30:32.786] Build status: STARTED
[00:30:32.786] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:17:0) after 9m31s
[00:40:03.830] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:17:0)
[00:40:03.844] Build status: STARTED
[00:40:03.844] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:18:0) after 1m13s
[00:41:16.883] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:18:0)
[00:41:16.903] Build status: STARTED
[00:41:16.903] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:19:0) after 9m5s
[00:50:21.929] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:19:0)
[00:50:21.945] Build status: STARTED
[00:50:21.945] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:20:0) after 7m26s
[00:57:47.989] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:20:0)
[00:57:48.005] Build status: STARTED
[00:57:48.005] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:21:0) after 2m10s
[00:59:58.039] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:21:0)
[00:59:58.116] Build status: STARTED
[00:59:58.117] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:22:0) after 6m4s
[01:06:02.137] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:22:0)
[01:06:02.159] Build status: STARTED
[01:06:02.159] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:23:0) after 2m16s
[01:08:18.177] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:23:0)
[01:08:18.196] Build status: STARTED
[01:08:18.196] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:24:0) after 9m27s
[01:17:45.241] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:24:0)
[01:17:45.270] Build status: STARTED
[01:17:45.270] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:25:0) after 9m57s
[01:27:42.291] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:25:0)
[01:27:42.306] Build status: STARTED
[01:27:42.306] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:26:0) after 9m40s
[01:37:22.315] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:26:0)
[01:37:22.393] Build status: STARTED
[01:37:22.393] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:27:0) after 7m56s
[01:45:18.385] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:27:0)
[01:45:18.437] Build status: STARTED
[01:45:18.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:28:0) after 6m24s
[01:51:42.629] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:28:0)
[01:51:42.648] Build status: STARTED
[01:51:42.648] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:29:0) after 2m49s
[01:54:31.669] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:29:0)
[01:54:31.709] Build status: STARTED
[01:54:31.709] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:30:0) after 1m13s
[01:55:44.782] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:30:0)
[01:55:44.797] Build status: STARTED
[01:55:44.797] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:31:0) after 3m26s
[01:59:10.872] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:31:0)
[01:59:10.892] Build status: STARTED
[01:59:10.892] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:32:0) after 5m12s
[02:04:22.992] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:32:0)
[02:04:23.005] Build status: STARTED
[02:04:23.005] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:33:0) after 7m30s
[02:11:53.225] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:33:0)
[02:11:53.240] Build status: STARTED
[02:11:53.240] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:34:0) after 5m35s
[02:17:28.367] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:34:0)
[02:17:28.432] Build status: STARTED
[02:17:28.432] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:35:0) after 5m41s
[02:23:09.520] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:35:0)
[02:23:09.539] Build status: STARTED
[02:23:09.539] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:36:0) after 3m24s
[02:26:33.560] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:36:0)
[02:26:33.575] Build status: STARTED
[02:26:33.575] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:37:0) after 2m13s
[02:28:46.658] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:37:0)
[02:28:46.750] Build status: STARTED
[02:28:46.750] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:38:0) after 2m19s
[02:31:05.767] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:38:0)
[02:31:05.793] Build status: STARTED
[02:31:05.793] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:39:0) after 1m48s
[02:32:53.807] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:39:0)
[02:32:53.823] Build status: STARTED
[02:32:53.823] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:40:0) after 4m14s
[02:37:07.844] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:40:0)
[02:37:07.866] Build status: STARTED
[02:37:07.866] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:41:0) after 6m43s
[02:43:50.886] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:41:0)
[02:43:50.900] Build status: STARTED
[02:43:50.900] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:42:0) after 8m14s
[02:52:04.978] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:42:0)
[02:52:04.993] Build status: STARTED
[02:52:04.993] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:43:0) after 7m51s
[02:59:56.139] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:43:0)
[02:59:56.156] Build status: STARTED
[02:59:56.156] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:44:0) after 4m11s
[03:04:07.221] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:44:0)
[03:04:07.238] Build status: STARTED
[03:04:07.238] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:45:0) after 4m45s
[03:08:52.267] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:45:0)
[03:08:52.283] Build status: STARTED
[03:08:52.283] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:46:0) after 3m15s
[03:12:07.310] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:46:0)
[03:12:07.338] Build status: STARTED
[03:12:07.339] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:47:0) after 5m23s
[03:17:30.411] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:47:0)
[03:17:30.426] Build status: STARTED
[03:17:30.426] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:48:0) after 2m49s
[03:20:19.446] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:48:0)
[03:20:19.464] Build status: STARTED
[03:20:19.464] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:49:0) after 3m20s
[03:23:39.464] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:49:0)
[03:23:39.483] Build status: STARTED
[03:23:39.483] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:50:0) after 8m32s
[03:32:11.505] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:50:0)
[03:32:11.520] Build status: STARTED
[03:32:11.520] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:51:0) after 9m59s
[03:42:10.625] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:51:0)
[03:42:10.696] Build status: STARTED
[03:42:10.696] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:52:0) after 3m40s
[03:45:50.722] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:52:0)
[03:45:50.739] Build status: STARTED
[03:45:50.739] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:53:0) after 6m39s
[03:52:29.817] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:53:0)
[03:52:29.831] Build status: STARTED
[03:52:29.831] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:54:0) after 8m50s
[04:01:19.861] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:54:0)
[04:01:19.882] Build status: STARTED
[04:01:19.882] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:55:0) after 3m7s
[04:04:26.924] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:55:0)
[04:04:26.938] Build status: STARTED
[04:04:26.938] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:56:0) after 5m7s
[04:09:33.949] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:56:0)
[04:09:33.964] Build status: STARTED
[04:09:33.964] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:57:0) after 9m39s
[04:19:13.024] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:57:0)
[04:19:13.057] Build status: STARTED
[04:19:13.057] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:58:0) after 4m2s
[04:23:15.078] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:58:0)
[04:23:15.094] Build status: STARTED
[04:23:15.094] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8866513499560869280:59:0) after 4m34s
[04:26:23.441] Received PubSub notification, asking Buildbucket for the build status
[04:26:23.460] Build:
{
"id": "8701083437327137041",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacPGO"
},
"number": 69,
"createdBy": "project:chromium",
"createTime": "2025-10-13T23:13:43.121071532Z",
"startTime": "2025-10-13T23:13:46.643966865Z",
"endTime": "2025-10-14T04:26:23.081527429Z",
"updateTime": "2025-10-14T04:26:23.081527429Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "18b5e5884c772d054e42893c2b158a9c0da38531",
"ref": "refs/heads/main"
}
}
}
[04:26:23.460] Invocation finished in 5h12m41.852915663s with status SUCCEEDED