[04:11:14.340] New invocation is queued and will start shortly
[04:11:15.461] Starting the invocation (attempt 1)
[04:11:15.489] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cabca1efc8f705750fe6438ce05ea7b01d076a84"
[04:11:15.489] Popped gitiles commit info from properties and tags
[04:11:15.489] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:11:15.489] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:11:15.489] Buildbucket request:
{
"requestId": "8838953301832881680",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cabca1efc8f705750fe6438ce05ea7b01d076a84",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8838953301832881680"
},
{
"key": "scheduler_job_id",
"value": "chromium/Linux CFI"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:11:15.967] Scheduled build:
{
"id": "8673523239894266673",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 32598,
"createdBy": "project:chromium",
"createTime": "2026-08-14T04:11:15.568678987Z",
"updateTime": "2026-08-14T04:11:15.568678987Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cabca1efc8f705750fe6438ce05ea7b01d076a84",
"ref": "refs/heads/main"
}
}
}
[04:11:15.967] Task URL: https://cr-buildbucket.appspot.com/build/8673523239894266673
[04:11:15.967] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:2:0) after 2m9s
[04:11:16.991] Received PubSub notification, asking Buildbucket for the build status
[04:11:17.014] Build status: SCHEDULED
[04:13:25.175] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:2:0)
[04:13:25.215] Build status: SCHEDULED
[04:13:25.215] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:4:0) after 1m37s
[04:15:02.371] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:4:0)
[04:15:02.390] Build status: SCHEDULED
[04:15:02.390] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:5:0) after 6m54s
[04:21:56.464] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:5:0)
[04:21:56.488] Build status: SCHEDULED
[04:21:56.488] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:6:0) after 8m10s
[04:30:06.504] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:6:0)
[04:30:06.539] Build status: SCHEDULED
[04:30:06.539] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:7:0) after 9m7s
[04:38:36.292] Received PubSub notification, asking Buildbucket for the build status
[04:38:36.317] Build status: STARTED
[04:39:13.547] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:7:0)
[04:39:13.568] Build status: STARTED
[04:39:13.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:9:0) after 1m3s
[04:40:16.640] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:9:0)
[04:40:16.657] Build status: STARTED
[04:40:16.658] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:10:0) after 9m4s
[04:49:20.749] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:10:0)
[04:49:20.775] Build status: STARTED
[04:49:20.775] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:11:0) after 7m51s
[04:57:11.801] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:11:0)
[04:57:11.876] Build status: STARTED
[04:57:11.876] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:12:0) after 4m12s
[05:01:23.962] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:12:0)
[05:01:23.995] Build status: STARTED
[05:01:23.995] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:13:0) after 5m1s
[05:06:25.009] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:13:0)
[05:06:25.039] Build status: STARTED
[05:06:25.039] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:14:0) after 1m27s
[05:07:52.155] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:14:0)
[05:07:52.175] Build status: STARTED
[05:07:52.175] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:15:0) after 2m45s
[05:10:37.262] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:15:0)
[05:10:37.282] Build status: STARTED
[05:10:37.282] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:16:0) after 3m41s
[05:14:18.302] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:16:0)
[05:14:18.362] Build status: STARTED
[05:14:18.362] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:17:0) after 8m35s
[05:22:53.373] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:17:0)
[05:22:53.413] Build status: STARTED
[05:22:53.413] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:18:0) after 6m21s
[05:29:14.466] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:18:0)
[05:29:14.495] Build status: STARTED
[05:29:14.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:19:0) after 4m18s
[05:33:32.512] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:19:0)
[05:33:32.535] Build status: STARTED
[05:33:32.535] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:20:0) after 2m44s
[05:36:16.550] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:20:0)
[05:36:16.573] Build status: STARTED
[05:36:16.573] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:21:0) after 9m19s
[05:45:35.550] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:21:0)
[05:45:35.579] Build status: STARTED
[05:45:35.579] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:22:0) after 6m49s
[05:52:24.919] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:22:0)
[05:52:24.997] Build status: STARTED
[05:52:24.998] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:23:0) after 8m51s
[06:01:16.070] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:23:0)
[06:01:16.478] Build status: STARTED
[06:01:16.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:24:0) after 1m10s
[06:02:26.572] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:24:0)
[06:02:26.916] Build status: STARTED
[06:02:26.916] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:25:0) after 7m13s
[06:09:40.113] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:25:0)
[06:09:40.134] Build status: STARTED
[06:09:40.134] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:26:0) after 4m15s
[06:13:55.264] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:26:0)
[06:13:55.329] Build status: STARTED
[06:13:55.329] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:27:0) after 9m46s
[06:23:41.419] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:27:0)
[06:23:41.507] Build status: STARTED
[06:23:41.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:28:0) after 1m16s
[06:24:57.655] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:28:0)
[06:24:57.676] Build status: STARTED
[06:24:57.676] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:29:0) after 3m57s
[06:28:54.723] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:29:0)
[06:28:54.752] Build status: STARTED
[06:28:54.752] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:30:0) after 4m4s
[06:32:58.785] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:30:0)
[06:32:58.843] Build status: STARTED
[06:32:58.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:31:0) after 6m28s
[06:39:26.861] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:31:0)
[06:39:26.890] Build status: STARTED
[06:39:26.890] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:32:0) after 1m54s
[06:41:20.907] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:32:0)
[06:41:20.923] Build status: STARTED
[06:41:20.923] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:33:0) after 7m9s
[06:48:30.003] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:33:0)
[06:48:30.024] Build status: STARTED
[06:48:30.024] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:34:0) after 2m30s
[06:51:00.047] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:34:0)
[06:51:00.069] Build status: STARTED
[06:51:00.069] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:35:0) after 7m27s
[06:58:27.136] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:35:0)
[06:58:27.172] Build status: STARTED
[06:58:27.172] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:36:0) after 9m3s
[07:07:30.234] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:36:0)
[07:07:30.255] Build status: STARTED
[07:07:30.255] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:37:0) after 8m14s
[07:15:44.273] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:37:0)
[07:15:44.293] Build status: STARTED
[07:15:44.293] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:38:0) after 1m5s
[07:16:49.395] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:38:0)
[07:16:49.416] Build status: STARTED
[07:16:49.416] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:39:0) after 5m54s
[07:22:43.510] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:39:0)
[07:22:43.536] Build status: STARTED
[07:22:43.536] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:40:0) after 5m45s
[07:28:28.599] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:40:0)
[07:28:28.619] Build status: STARTED
[07:28:28.619] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:41:0) after 9m14s
[07:37:42.642] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:41:0)
[07:37:42.669] Build status: STARTED
[07:37:42.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:42:0) after 3m2s
[07:40:44.804] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:42:0)
[07:40:44.831] Build status: STARTED
[07:40:44.831] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:43:0) after 3m56s
[07:44:41.000] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:43:0)
[07:44:41.018] Build status: STARTED
[07:44:41.019] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:44:0) after 9m34s
[07:54:15.039] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:44:0)
[07:54:15.130] Build status: STARTED
[07:54:15.131] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:45:0) after 8m6s
[08:02:21.302] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:45:0)
[08:02:21.322] Build status: STARTED
[08:02:21.323] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:46:0) after 5m47s
[08:08:08.347] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:46:0)
[08:08:08.466] Build status: STARTED
[08:08:08.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:47:0) after 1m57s
[08:10:05.583] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:47:0)
[08:10:05.603] Build status: STARTED
[08:10:05.603] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:48:0) after 1m57s
[08:12:02.687] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:48:0)
[08:12:02.704] Build status: STARTED
[08:12:02.705] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:49:0) after 8m49s
[08:20:51.733] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:49:0)
[08:20:51.763] Build status: STARTED
[08:20:51.763] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:50:0) after 1m40s
[08:22:31.880] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:50:0)
[08:22:31.913] Build status: STARTED
[08:22:31.913] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:51:0) after 2m3s
[08:24:34.980] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:51:0)
[08:24:34.997] Build status: STARTED
[08:24:34.997] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:52:0) after 5m50s
[08:30:25.129] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:52:0)
[08:30:25.175] Build status: STARTED
[08:30:25.175] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:53:0) after 8m4s
[08:38:29.208] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:53:0)
[08:38:29.224] Build status: STARTED
[08:38:29.224] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:54:0) after 4m39s
[08:43:08.356] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:54:0)
[08:43:08.376] Build status: STARTED
[08:43:08.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:55:0) after 5m3s
[08:48:11.404] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:55:0)
[08:48:11.453] Build status: STARTED
[08:48:11.453] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:56:0) after 5m35s
[08:53:46.495] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:56:0)
[08:53:46.519] Build status: STARTED
[08:53:46.519] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:57:0) after 8m56s
[09:02:42.589] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:57:0)
[09:02:42.606] Build status: STARTED
[09:02:42.606] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:58:0) after 3m8s
[09:05:50.677] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:58:0)
[09:05:50.701] Build status: STARTED
[09:05:50.701] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8838953301832881680:59:0) after 5m18s
[09:10:01.820] Received PubSub notification, asking Buildbucket for the build status
[09:10:01.836] Build:
{
"id": "8673523239894266673",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 32598,
"createdBy": "project:chromium",
"createTime": "2026-08-14T04:11:15.568678987Z",
"startTime": "2026-08-14T04:38:36.006531067Z",
"endTime": "2026-08-14T09:10:01.057175226Z",
"updateTime": "2026-08-14T09:10:01.057175226Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cabca1efc8f705750fe6438ce05ea7b01d076a84",
"ref": "refs/heads/main"
}
}
}
[09:10:01.836] Invocation finished in 4h58m47.501148836s with status SUCCEEDED