[09:52:50.082] New invocation is queued and will start shortly
[09:52:51.159] Starting the invocation (attempt 1)
[09:52:51.196] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eaac02ca17732c2444358cb916a83d0283b4cbbf"
[09:52:51.196] Popped gitiles commit info from properties and tags
[09:52:51.196] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:52:51.196] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:52:51.196] Buildbucket request:
{
"requestId": "8856145234107779968",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacPGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "eaac02ca17732c2444358cb916a83d0283b4cbbf",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8856145234107779968"
},
{
"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"
}
}
[09:52:51.746] Scheduled build:
{
"id": "8690715172241628945",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacPGO"
},
"number": 1307,
"createdBy": "project:chromium",
"createTime": "2026-02-05T09:52:51.239228757Z",
"updateTime": "2026-02-05T09:52:51.239228757Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "eaac02ca17732c2444358cb916a83d0283b4cbbf",
"ref": "refs/heads/main"
}
}
}
[09:52:51.746] Task URL: https://cr-buildbucket.appspot.com/build/8690715172241628945
[09:52:51.746] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:2:0) after 6m9s
[09:52:52.648] Received PubSub notification, asking Buildbucket for the build status
[09:52:52.689] Build status: SCHEDULED
[09:52:54.836] Received PubSub notification, asking Buildbucket for the build status
[09:52:54.893] Build status: STARTED
[09:59:00.865] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:2:0)
[09:59:00.893] Build status: STARTED
[09:59:00.893] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:5:0) after 3m32s
[10:02:32.907] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:5:0)
[10:02:32.938] Build status: STARTED
[10:02:32.938] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:6:0) after 4m19s
[10:06:51.969] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:6:0)
[10:06:51.994] Build status: STARTED
[10:06:51.994] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:7:0) after 6m22s
[10:13:14.087] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:7:0)
[10:13:14.112] Build status: STARTED
[10:13:14.112] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:8:0) after 6m3s
[10:19:17.134] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:8:0)
[10:19:17.158] Build status: STARTED
[10:19:17.159] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:9:0) after 2m21s
[10:21:38.178] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:9:0)
[10:21:38.193] Build status: STARTED
[10:21:38.201] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:10:0) after 4m38s
[10:26:16.307] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:10:0)
[10:26:16.320] Build status: STARTED
[10:26:16.320] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:11:0) after 9m21s
[10:35:37.382] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:11:0)
[10:35:37.397] Build status: STARTED
[10:35:37.398] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:12:0) after 3m7s
[10:38:44.414] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:12:0)
[10:38:44.444] Build status: STARTED
[10:38:44.444] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:13:0) after 3m13s
[10:41:57.462] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:13:0)
[10:41:57.477] Build status: STARTED
[10:41:57.477] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:14:0) after 6m13s
[10:48:10.557] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:14:0)
[10:48:10.573] Build status: STARTED
[10:48:10.574] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:15:0) after 1m51s
[10:50:01.623] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:15:0)
[10:50:01.677] Build status: STARTED
[10:50:01.677] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:16:0) after 8m0s
[10:58:01.719] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:16:0)
[10:58:01.736] Build status: STARTED
[10:58:01.736] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:17:0) after 9m42s
[11:07:43.805] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:17:0)
[11:07:43.824] Build status: STARTED
[11:07:43.824] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:18:0) after 6m36s
[11:14:19.840] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:18:0)
[11:14:19.878] Build status: STARTED
[11:14:19.878] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:19:0) after 7m7s
[11:21:26.967] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:19:0)
[11:21:26.994] Build status: STARTED
[11:21:26.994] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:20:0) after 8m48s
[11:30:14.977] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:20:0)
[11:30:14.993] Build status: STARTED
[11:30:14.993] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:21:0) after 6m40s
[11:36:55.009] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:21:0)
[11:36:55.034] Build status: STARTED
[11:36:55.034] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:22:0) after 5m6s
[11:42:01.058] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:22:0)
[11:42:01.075] Build status: STARTED
[11:42:01.075] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:23:0) after 3m18s
[11:45:19.144] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:23:0)
[11:45:19.167] Build status: STARTED
[11:45:19.167] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:24:0) after 6m46s
[11:52:05.178] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:24:0)
[11:52:05.200] Build status: STARTED
[11:52:05.200] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:25:0) after 9m1s
[12:01:06.219] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:25:0)
[12:01:06.235] Build status: STARTED
[12:01:06.235] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:26:0) after 9m24s
[12:10:30.308] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:26:0)
[12:10:30.366] Build status: STARTED
[12:10:30.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:27:0) after 2m32s
[12:13:02.407] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:27:0)
[12:13:02.422] Build status: STARTED
[12:13:02.422] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:28:0) after 7m50s
[12:20:52.451] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:28:0)
[12:20:52.468] Build status: STARTED
[12:20:52.468] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:29:0) after 5m8s
[12:26:00.489] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:29:0)
[12:26:00.514] Build status: STARTED
[12:26:00.514] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:30:0) after 8m38s
[12:34:38.493] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:30:0)
[12:34:38.509] Build status: STARTED
[12:34:38.509] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:31:0) after 2m27s
[12:37:05.590] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:31:0)
[12:37:05.687] Build status: STARTED
[12:37:05.688] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:32:0) after 5m47s
[12:42:52.816] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:32:0)
[12:42:52.833] Build status: STARTED
[12:42:52.833] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:33:0) after 8m11s
[12:51:03.856] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:33:0)
[12:51:03.902] Build status: STARTED
[12:51:03.902] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:34:0) after 1m17s
[12:52:20.935] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:34:0)
[12:52:20.951] Build status: STARTED
[12:52:20.951] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:35:0) after 3m8s
[12:55:28.969] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:35:0)
[12:55:28.996] Build status: STARTED
[12:55:28.996] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:36:0) after 5m29s
[13:00:57.975] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:36:0)
[13:00:58.089] Build status: STARTED
[13:00:58.089] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:37:0) after 3m43s
[13:04:41.109] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:37:0)
[13:04:41.123] Build status: STARTED
[13:04:41.123] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:38:0) after 8m6s
[13:12:47.165] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:38:0)
[13:12:47.183] Build status: STARTED
[13:12:47.183] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:39:0) after 3m38s
[13:16:25.211] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:39:0)
[13:16:25.229] Build status: STARTED
[13:16:25.229] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:40:0) after 2m27s
[13:18:52.314] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:40:0)
[13:18:52.396] Build status: STARTED
[13:18:52.396] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:41:0) after 5m30s
[13:24:22.449] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:41:0)
[13:24:22.466] Build status: STARTED
[13:24:22.466] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:42:0) after 9m49s
[13:34:11.486] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:42:0)
[13:34:11.503] Build status: STARTED
[13:34:11.503] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:43:0) after 9m2s
[13:43:13.528] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:43:0)
[13:43:13.553] Build status: STARTED
[13:43:13.553] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:44:0) after 4m46s
[13:47:59.538] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:44:0)
[13:47:59.554] Build status: STARTED
[13:47:59.554] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:45:0) after 1m53s
[13:49:52.601] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:45:0)
[13:49:52.623] Build status: STARTED
[13:49:52.623] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:46:0) after 2m31s
[13:52:23.646] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:46:0)
[13:52:23.661] Build status: STARTED
[13:52:23.661] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:47:0) after 3m6s
[13:55:29.679] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:47:0)
[13:55:29.727] Build status: STARTED
[13:55:29.727] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:48:0) after 1m38s
[13:57:07.806] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:48:0)
[13:57:07.827] Build status: STARTED
[13:57:07.827] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:49:0) after 6m40s
[14:03:47.855] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:49:0)
[14:03:47.869] Build status: STARTED
[14:03:47.869] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:50:0) after 6m14s
[14:10:01.887] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:50:0)
[14:10:01.944] Build status: STARTED
[14:10:01.944] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:51:0) after 7m31s
[14:17:33.016] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:51:0)
[14:17:33.154] Build status: STARTED
[14:17:33.155] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:52:0) after 1m58s
[14:19:31.172] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:52:0)
[14:19:31.191] Build status: STARTED
[14:19:31.191] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:53:0) after 1m14s
[14:20:45.221] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:53:0)
[14:20:45.281] Build status: STARTED
[14:20:45.281] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:54:0) after 9m43s
[14:30:28.379] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:54:0)
[14:30:28.401] Build status: STARTED
[14:30:28.401] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:55:0) after 2m44s
[14:33:12.434] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:55:0)
[14:33:12.449] Build status: STARTED
[14:33:12.449] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:56:0) after 2m50s
[14:36:02.510] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:56:0)
[14:36:02.530] Build status: STARTED
[14:36:02.530] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:57:0) after 8m20s
[14:44:22.552] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:57:0)
[14:44:22.585] Build status: STARTED
[14:44:22.585] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:58:0) after 4m4s
[14:48:26.602] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:58:0)
[14:48:26.624] Build status: STARTED
[14:48:26.624] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:59:0) after 9m35s
[14:58:01.699] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:59:0)
[14:58:01.792] Build status: STARTED
[14:58:01.792] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:60:0) after 2m5s
[15:00:06.819] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:60:0)
[15:00:06.893] Build status: STARTED
[15:00:06.893] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:61:0) after 4m35s
[15:04:41.913] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:61:0)
[15:04:41.927] Build status: STARTED
[15:04:41.928] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:62:0) after 1m14s
[15:05:55.948] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:62:0)
[15:05:55.964] Build status: STARTED
[15:05:55.964] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:63:0) after 4m0s
[15:09:56.036] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:63:0)
[15:09:56.052] Build status: STARTED
[15:09:56.052] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:64:0) after 8m25s
[15:18:21.072] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:64:0)
[15:18:21.093] Build status: STARTED
[15:18:21.093] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:65:0) after 6m56s
[15:25:17.163] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:65:0)
[15:25:17.178] Build status: STARTED
[15:25:17.178] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:66:0) after 2m42s
[15:27:59.249] Handling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:66:0)
[15:27:59.274] Build status: STARTED
[15:27:59.274] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacPGO:8856145234107779968:67:0) after 4m41s
[15:29:51.020] Received PubSub notification, asking Buildbucket for the build status
[15:29:51.039] Build:
{
"id": "8690715172241628945",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacPGO"
},
"number": 1307,
"createdBy": "project:chromium",
"createTime": "2026-02-05T09:52:51.239228757Z",
"startTime": "2026-02-05T09:52:54.672508290Z",
"endTime": "2026-02-05T15:29:49.905090224Z",
"updateTime": "2026-02-05T15:29:49.905090224Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "eaac02ca17732c2444358cb916a83d0283b4cbbf",
"ref": "refs/heads/main"
}
}
}
[15:29:51.039] Invocation finished in 5h37m0.964351099s with status SUCCEEDED