[16:52:50.466] New invocation is queued and will start shortly
[16:52:52.797] Starting the invocation (attempt 1)
[16:52:52.824] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/22c6691de5cb6261d8e8439a98c4d5fbf5936370"
[16:52:52.824] Popped gitiles commit info from properties and tags
[16:52:52.824] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:52:52.825] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:52:52.825] Buildbucket request:
{
"requestId": "8865540894104624288",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMac"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "22c6691de5cb6261d8e8439a98c4d5fbf5936370",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8865540894104624288"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTMac"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:52:53.577] Scheduled build:
{
"id": "8700110830866632033",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMac"
},
"number": 38193,
"createdBy": "project:chromium",
"createTime": "2025-10-24T16:52:52.922085443Z",
"updateTime": "2025-10-24T16:52:52.922085443Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "22c6691de5cb6261d8e8439a98c4d5fbf5936370",
"ref": "refs/heads/main"
}
},
"canary": true
}
[16:52:53.577] Task URL: https://cr-buildbucket.appspot.com/build/8700110830866632033
[16:52:53.577] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:2:0) after 1m40s
[16:52:54.535] Received PubSub notification, asking Buildbucket for the build status
[16:52:54.591] Build status: SCHEDULED
[16:52:56.549] Received PubSub notification, asking Buildbucket for the build status
[16:52:56.564] Build status: STARTED
[16:54:33.653] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:2:0)
[16:54:33.704] Build status: STARTED
[16:54:33.704] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:5:0) after 6m55s
[17:01:28.813] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:5:0)
[17:01:28.873] Build status: STARTED
[17:01:28.873] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:6:0) after 1m30s
[17:02:58.892] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:6:0)
[17:02:58.921] Build status: STARTED
[17:02:58.921] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:7:0) after 5m36s
[17:08:34.937] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:7:0)
[17:08:35.070] Build status: STARTED
[17:08:35.071] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:8:0) after 9m29s
[17:18:04.087] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:8:0)
[17:18:04.145] Build status: STARTED
[17:18:04.145] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:9:0) after 3m48s
[17:21:52.203] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:9:0)
[17:21:52.221] Build status: STARTED
[17:21:52.221] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:10:0) after 5m26s
[17:27:18.287] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:10:0)
[17:27:18.308] Build status: STARTED
[17:27:18.308] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:11:0) after 5m7s
[17:32:25.332] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:11:0)
[17:32:25.383] Build status: STARTED
[17:32:25.383] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:12:0) after 1m22s
[17:33:47.448] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:12:0)
[17:33:47.478] Build status: STARTED
[17:33:47.478] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:13:0) after 5m32s
[17:39:19.514] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:13:0)
[17:39:19.533] Build status: STARTED
[17:39:19.533] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:14:0) after 9m55s
[17:49:14.575] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:14:0)
[17:49:14.595] Build status: STARTED
[17:49:14.595] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:15:0) after 4m36s
[17:53:50.751] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:15:0)
[17:53:50.839] Build status: STARTED
[17:53:50.840] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:16:0) after 1m43s
[17:55:33.941] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:16:0)
[17:55:33.962] Build status: STARTED
[17:55:33.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:17:0) after 9m44s
[18:05:17.998] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:17:0)
[18:05:18.018] Build status: STARTED
[18:05:18.018] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:18:0) after 9m33s
[18:14:51.087] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:18:0)
[18:14:51.127] Build status: STARTED
[18:14:51.127] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:19:0) after 6m46s
[18:21:37.236] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:19:0)
[18:21:37.253] Build status: STARTED
[18:21:37.253] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:20:0) after 2m42s
[18:24:19.359] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:20:0)
[18:24:19.374] Build status: STARTED
[18:24:19.374] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:21:0) after 3m44s
[18:28:03.404] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:21:0)
[18:28:03.420] Build status: STARTED
[18:28:03.420] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:22:0) after 7m15s
[18:35:18.400] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:22:0)
[18:35:18.415] Build status: STARTED
[18:35:18.415] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:23:0) after 9m35s
[18:44:53.446] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:23:0)
[18:44:53.504] Build status: STARTED
[18:44:53.504] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:24:0) after 3m23s
[18:48:16.529] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:24:0)
[18:48:16.545] Build status: STARTED
[18:48:16.545] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:25:0) after 7m33s
[18:55:49.763] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:25:0)
[18:55:49.784] Build status: STARTED
[18:55:49.784] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:26:0) after 2m20s
[18:58:09.853] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:26:0)
[18:58:09.867] Build status: STARTED
[18:58:09.868] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:27:0) after 8m10s
[19:06:19.983] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:27:0)
[19:06:20.047] Build status: STARTED
[19:06:20.047] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:28:0) after 6m33s
[19:12:53.124] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:28:0)
[19:12:53.151] Build status: STARTED
[19:12:53.151] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:29:0) after 2m20s
[19:15:13.181] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:29:0)
[19:15:13.208] Build status: STARTED
[19:15:13.208] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:30:0) after 6m11s
[19:21:24.323] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:30:0)
[19:21:24.345] Build status: STARTED
[19:21:24.345] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:31:0) after 8m17s
[19:29:41.395] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:31:0)
[19:29:41.444] Build status: STARTED
[19:29:41.444] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:32:0) after 8m7s
[19:37:48.459] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:32:0)
[19:37:48.502] Build status: STARTED
[19:37:48.502] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:33:0) after 3m30s
[19:41:18.559] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:33:0)
[19:41:18.571] Build status: STARTED
[19:41:18.571] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:34:0) after 3m5s
[19:44:23.545] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:34:0)
[19:44:23.564] Build status: STARTED
[19:44:23.564] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:35:0) after 3m33s
[19:47:56.597] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:35:0)
[19:47:56.613] Build status: STARTED
[19:47:56.613] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:36:0) after 9m10s
[19:57:06.647] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:36:0)
[19:57:06.696] Build status: STARTED
[19:57:06.696] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:37:0) after 7m1s
[20:04:07.730] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:37:0)
[20:04:07.784] Build status: STARTED
[20:04:07.784] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:38:0) after 2m42s
[20:06:49.801] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:38:0)
[20:06:49.834] Build status: STARTED
[20:06:49.834] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:39:0) after 5m12s
[20:12:01.830] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:39:0)
[20:12:01.845] Build status: STARTED
[20:12:01.845] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:40:0) after 9m26s
[20:21:27.863] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:40:0)
[20:21:27.883] Build status: STARTED
[20:21:27.883] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:41:0) after 9m18s
[20:30:45.974] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:41:0)
[20:30:45.993] Build status: STARTED
[20:30:45.993] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:42:0) after 3m9s
[20:33:54.997] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:42:0)
[20:33:55.046] Build status: STARTED
[20:33:55.046] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:43:0) after 5m54s
[20:39:49.065] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:43:0)
[20:39:49.227] Build status: STARTED
[20:39:49.227] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:44:0) after 2m41s
[20:42:30.245] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:44:0)
[20:42:30.260] Build status: STARTED
[20:42:30.260] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:45:0) after 3m22s
[20:45:52.281] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:45:0)
[20:45:52.306] Build status: STARTED
[20:45:52.306] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:46:0) after 7m17s
[20:53:09.374] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:46:0)
[20:53:09.397] Build status: STARTED
[20:53:09.397] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:47:0) after 1m58s
[20:55:07.556] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:47:0)
[20:55:07.573] Build status: STARTED
[20:55:07.573] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:48:0) after 5m24s
[21:00:31.594] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:48:0)
[21:00:31.609] Build status: STARTED
[21:00:31.609] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:49:0) after 5m54s
[21:06:25.690] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:49:0)
[21:06:25.721] Build status: STARTED
[21:06:25.721] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:50:0) after 1m37s
[21:08:02.742] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:50:0)
[21:08:02.760] Build status: STARTED
[21:08:02.760] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:51:0) after 4m22s
[21:12:24.778] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:51:0)
[21:12:24.795] Build status: STARTED
[21:12:24.795] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:52:0) after 3m52s
[21:16:16.838] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:52:0)
[21:16:16.854] Build status: STARTED
[21:16:16.854] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:53:0) after 2m53s
[21:19:09.914] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:53:0)
[21:19:09.927] Build status: STARTED
[21:19:09.927] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:54:0) after 8m56s
[21:28:06.050] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:54:0)
[21:28:06.064] Build status: STARTED
[21:28:06.064] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:55:0) after 3m58s
[21:32:04.082] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:55:0)
[21:32:04.098] Build status: STARTED
[21:32:04.098] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:56:0) after 7m42s
[21:39:46.226] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:56:0)
[21:39:46.248] Build status: STARTED
[21:39:46.248] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:57:0) after 2m9s
[21:41:55.489] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:57:0)
[21:41:55.506] Build status: STARTED
[21:41:55.506] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:58:0) after 8m51s
[21:50:46.537] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:58:0)
[21:50:46.552] Build status: STARTED
[21:50:46.552] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:59:0) after 5m6s
[21:55:52.599] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:59:0)
[21:55:52.617] Build status: STARTED
[21:55:52.617] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:60:0) after 8m33s
[22:04:25.632] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:60:0)
[22:04:25.647] Build status: STARTED
[22:04:25.647] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:61:0) after 4m42s
[22:09:07.681] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:61:0)
[22:09:07.721] Build status: STARTED
[22:09:07.721] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:62:0) after 9m2s
[22:18:09.787] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:62:0)
[22:18:09.811] Build status: STARTED
[22:18:09.811] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:63:0) after 6m16s
[22:24:25.860] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:63:0)
[22:24:25.873] Build status: STARTED
[22:24:25.873] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:64:0) after 7m47s
[22:32:12.888] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:64:0)
[22:32:12.915] Build status: STARTED
[22:32:12.915] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:65:0) after 9m37s
[22:41:49.986] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:65:0)
[22:41:50.003] Build status: STARTED
[22:41:50.003] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:66:0) after 9m44s
[22:51:34.058] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:66:0)
[22:51:34.081] Build status: STARTED
[22:51:34.081] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:67:0) after 4m23s
[22:55:57.102] Handling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:67:0)
[22:55:57.148] Build status: STARTED
[22:55:57.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac:8865540894104624288:68:0) after 5m57s
[23:01:44.819] Received PubSub notification, asking Buildbucket for the build status
[23:01:44.835] Build:
{
"id": "8700110830866632033",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMac"
},
"number": 38193,
"createdBy": "project:chromium",
"createTime": "2025-10-24T16:52:52.922085443Z",
"startTime": "2025-10-24T16:52:56.387156016Z",
"endTime": "2025-10-24T23:01:43.634226989Z",
"updateTime": "2025-10-24T23:01:43.634226989Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "22c6691de5cb6261d8e8439a98c4d5fbf5936370",
"ref": "refs/heads/main"
}
},
"canary": true
}
[23:01:44.835] Invocation finished in 6h8m54.38362624s with status SUCCEEDED