[20:20:09.679] New invocation is queued and will start shortly
[20:20:10.947] Starting the invocation (attempt 1)
[20:20:10.978] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/20305e7ddc0d5cf5e6db3636de39d9f28db87d3a"
[20:20:10.978] Popped gitiles commit info from properties and tags
[20:20:10.978] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:20:10.979] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:20:10.979] Buildbucket request:
{
"requestId": "8855471587366525968",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacPGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "20305e7ddc0d5cf5e6db3636de39d9f28db87d3a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8855471587366525968"
},
{
"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"
}
}
[20:20:11.545] Scheduled build:
{
"id": "8690041525280137169",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacPGO"
},
"number": 1357,
"createdBy": "project:chromium",
"createTime": "2026-02-12T20:20:11.044885708Z",
"updateTime": "2026-02-12T20:20:11.044885708Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "20305e7ddc0d5cf5e6db3636de39d9f28db87d3a",
"ref": "refs/heads/main"
}
},
"canary": true
}
[20:20:11.545] Task URL: https://cr-buildbucket.appspot.com/build/8690041525280137169
[20:20:11.545] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:2:0) after 9m53s
[20:20:12.473] Received PubSub notification, asking Buildbucket for the build status
[20:20:12.642] Build status: SCHEDULED
[20:21:19.252] Received PubSub notification, asking Buildbucket for the build status
[20:21:19.329] Build status: STARTED
[20:30:04.620] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:2:0)
[20:30:04.669] Build status: STARTED
[20:30:04.669] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:5:0) after 9m47s
[20:39:51.693] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:5:0)
[20:39:51.761] Build status: STARTED
[20:39:51.761] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:6:0) after 4m1s
[20:43:52.744] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:6:0)
[20:43:52.766] Build status: STARTED
[20:43:52.766] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:7:0) after 8m26s
[20:52:18.783] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:7:0)
[20:52:18.853] Build status: STARTED
[20:52:18.853] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:8:0) after 1m32s
[20:53:50.921] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:8:0)
[20:53:50.939] Build status: STARTED
[20:53:50.939] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:9:0) after 9m47s
[21:03:38.010] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:9:0)
[21:03:38.096] Build status: STARTED
[21:03:38.096] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:10:0) after 9m43s
[21:13:21.082] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:10:0)
[21:13:21.135] Build status: STARTED
[21:13:21.135] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:11:0) after 1m6s
[21:14:27.187] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:11:0)
[21:14:27.251] Build status: STARTED
[21:14:27.251] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:12:0) after 9m59s
[21:24:26.280] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:12:0)
[21:24:26.300] Build status: STARTED
[21:24:26.300] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:13:0) after 7m20s
[21:31:46.380] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:13:0)
[21:31:46.394] Build status: STARTED
[21:31:46.394] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:14:0) after 3m27s
[21:35:13.486] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:14:0)
[21:35:13.507] Build status: STARTED
[21:35:13.507] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:15:0) after 7m36s
[21:42:49.532] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:15:0)
[21:42:49.564] Build status: STARTED
[21:42:49.564] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:16:0) after 4m53s
[21:47:42.591] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:16:0)
[21:47:42.612] Build status: STARTED
[21:47:42.612] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:17:0) after 8m42s
[21:56:24.637] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:17:0)
[21:56:24.703] Build status: STARTED
[21:56:24.704] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:18:0) after 6m45s
[22:03:09.725] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:18:0)
[22:03:09.745] Build status: STARTED
[22:03:09.745] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:19:0) after 8m58s
[22:12:07.812] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:19:0)
[22:12:07.888] Build status: STARTED
[22:12:07.888] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:20:0) after 5m22s
[22:17:29.889] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:20:0)
[22:17:29.966] Build status: STARTED
[22:17:29.966] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:21:0) after 9m25s
[22:26:54.987] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:21:0)
[22:26:55.007] Build status: STARTED
[22:26:55.007] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:22:0) after 3m48s
[22:30:43.169] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:22:0)
[22:30:43.186] Build status: STARTED
[22:30:43.186] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:23:0) after 2m17s
[22:33:00.215] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:23:0)
[22:33:00.229] Build status: STARTED
[22:33:00.229] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:24:0) after 7m54s
[22:40:54.262] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:24:0)
[22:40:54.279] Build status: STARTED
[22:40:54.279] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:25:0) after 1m38s
[22:42:32.299] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:25:0)
[22:42:32.323] Build status: STARTED
[22:42:32.323] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:26:0) after 7m9s
[22:49:41.382] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:26:0)
[22:49:41.396] Build status: STARTED
[22:49:41.396] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:27:0) after 6m13s
[22:55:54.630] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:27:0)
[22:55:54.653] Build status: STARTED
[22:55:54.653] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:28:0) after 1m14s
[22:57:08.722] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:28:0)
[22:57:08.741] Build status: STARTED
[22:57:08.742] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:29:0) after 1m12s
[22:58:20.775] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:29:0)
[22:58:20.811] Build status: STARTED
[22:58:20.811] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:30:0) after 5m54s
[23:04:14.912] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:30:0)
[23:04:14.931] Build status: STARTED
[23:04:14.931] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:31:0) after 5m12s
[23:09:27.033] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:31:0)
[23:09:27.051] Build status: STARTED
[23:09:27.051] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:32:0) after 1m9s
[23:10:36.125] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:32:0)
[23:10:36.145] Build status: STARTED
[23:10:36.145] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:33:0) after 8m43s
[23:19:19.216] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:33:0)
[23:19:19.241] Build status: STARTED
[23:19:19.241] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:34:0) after 3m42s
[23:23:01.302] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:34:0)
[23:23:01.315] Build status: STARTED
[23:23:01.315] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:35:0) after 3m49s
[23:26:50.384] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:35:0)
[23:26:50.398] Build status: STARTED
[23:26:50.398] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:36:0) after 3m16s
[23:30:06.570] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:36:0)
[23:30:06.600] Build status: STARTED
[23:30:06.600] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:37:0) after 1m9s
[23:31:15.691] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:37:0)
[23:31:15.703] Build status: STARTED
[23:31:15.703] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:38:0) after 7m57s
[23:39:12.816] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:38:0)
[23:39:12.865] Build status: STARTED
[23:39:12.865] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:39:0) after 5m34s
[23:44:47.023] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:39:0)
[23:44:47.038] Build status: STARTED
[23:44:47.038] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:40:0) after 9m36s
[23:54:23.066] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:40:0)
[23:54:23.087] Build status: STARTED
[23:54:23.087] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:41:0) after 5m31s
[23:59:54.259] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:41:0)
[23:59:54.277] Build status: STARTED
[23:59:54.277] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:42:0) after 3m7s
[00:03:01.327] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:42:0)
[00:03:01.355] Build status: STARTED
[00:03:01.356] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:43:0) after 8m36s
[00:11:37.364] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:43:0)
[00:11:37.387] Build status: STARTED
[00:11:37.387] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:44:0) after 6m57s
[00:18:34.408] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:44:0)
[00:18:34.425] Build status: STARTED
[00:18:34.425] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:45:0) after 5m56s
[00:24:30.425] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:45:0)
[00:24:30.446] Build status: STARTED
[00:24:30.446] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:46:0) after 5m53s
[00:30:23.542] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:46:0)
[00:30:23.605] Build status: STARTED
[00:30:23.605] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:47:0) after 7m24s
[00:37:47.623] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:47:0)
[00:37:47.646] Build status: STARTED
[00:37:47.646] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:48:0) after 1m57s
[00:39:44.716] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:48:0)
[00:39:44.749] Build status: STARTED
[00:39:44.749] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:49:0) after 2m39s
[00:42:23.780] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:49:0)
[00:42:23.797] Build status: STARTED
[00:42:23.797] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:50:0) after 6m32s
[00:48:55.887] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:50:0)
[00:48:55.938] Build status: STARTED
[00:48:55.938] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:51:0) after 6m0s
[00:54:56.011] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:51:0)
[00:54:56.029] Build status: STARTED
[00:54:56.029] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:52:0) after 8m53s
[01:03:49.148] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:52:0)
[01:03:49.166] Build status: STARTED
[01:03:49.167] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:53:0) after 6m19s
[01:10:08.233] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:53:0)
[01:10:08.324] Build status: STARTED
[01:10:08.324] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:54:0) after 7m9s
[01:17:17.347] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:54:0)
[01:17:17.384] Build status: STARTED
[01:17:17.384] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:55:0) after 1m7s
[01:18:24.393] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:55:0)
[01:18:24.414] Build status: STARTED
[01:18:24.414] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:56:0) after 9m19s
[01:27:43.459] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:56:0)
[01:27:43.515] Build status: STARTED
[01:27:43.515] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:57:0) after 7m29s
[01:35:12.584] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:57:0)
[01:35:12.603] Build status: STARTED
[01:35:12.603] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:58:0) after 9m12s
[01:44:24.609] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:58:0)
[01:44:24.632] Build status: STARTED
[01:44:24.632] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:59:0) after 2m48s
[01:47:12.712] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:59:0)
[01:47:12.741] Build status: STARTED
[01:47:12.741] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:60:0) after 3m39s
[01:50:51.774] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:60:0)
[01:50:51.787] Build status: STARTED
[01:50:51.787] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:61:0) after 2m6s
[01:52:57.830] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:61:0)
[01:52:57.844] Build status: STARTED
[01:52:57.844] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:62:0) after 4m42s
[01:57:39.875] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:62:0)
[01:57:39.935] Build status: STARTED
[01:57:39.935] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:63:0) after 1m45s
[01:59:25.019] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:63:0)
[01:59:25.037] Build status: STARTED
[01:59:25.037] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8855471587366525968:64:0) after 5m26s
[02:00:47.076] Received PubSub notification, asking Buildbucket for the build status
[02:00:47.116] Build:
{
"id": "8690041525280137169",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacPGO"
},
"number": 1357,
"createdBy": "project:chromium",
"createTime": "2026-02-12T20:20:11.044885708Z",
"startTime": "2026-02-12T20:21:19.080608543Z",
"endTime": "2026-02-13T02:00:46.371965588Z",
"updateTime": "2026-02-13T02:00:46.371965588Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "20305e7ddc0d5cf5e6db3636de39d9f28db87d3a",
"ref": "refs/heads/main"
}
},
"canary": true
}
[02:00:47.116] Invocation finished in 5h40m37.445115339s with status SUCCEEDED