[14:27:40.489] New invocation is queued and will start shortly
[14:27:41.555] Starting the invocation (attempt 1)
[14:27:41.582] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/f2c3b60e64e819cbdb086600bc78bd35706f6073"
[14:27:41.582] Popped gitiles commit info from properties and tags
[14:27:41.582] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:27:41.582] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:27:41.583] Buildbucket request:
{
"requestId": "8835290640393641024",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "build_perf"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8835290640393641024",
"job": "r8/build_perf",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "f2c3b60e64e819cbdb086600bc78bd35706f6073"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@f2c3b60e64e819cbdb086600bc78bd35706f6073",
"title": "f2c3b60e64e819cbdb086600bc78bd35706f6073",
"url": "https://r8.googlesource.com/r8/+/f2c3b60e64e819cbdb086600bc78bd35706f6073"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "f2c3b60e64e819cbdb086600bc78bd35706f6073",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8835290640393641024"
},
{
"key": "scheduler_job_id",
"value": "r8/build_perf"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:27:42.261] Scheduled build:
{
"id": "8669860578482535185",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "build_perf"
},
"number": 1020,
"createdBy": "project:r8",
"createTime": "2026-09-23T14:27:41.686542654Z",
"updateTime": "2026-09-23T14:27:41.686542654Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "f2c3b60e64e819cbdb086600bc78bd35706f6073",
"ref": "refs/heads/main"
}
}
}
[14:27:42.261] Task URL: https://cr-buildbucket.appspot.com/build/8669860578482535185
[14:27:42.261] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:2:0) after 7m31s
[14:27:42.863] Received PubSub notification, asking Buildbucket for the build status
[14:27:42.888] Build status: SCHEDULED
[14:35:13.330] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:2:0)
[14:35:13.355] Build status: SCHEDULED
[14:35:13.355] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:4:0) after 8m46s
[14:43:59.396] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:4:0)
[14:43:59.492] Build status: SCHEDULED
[14:43:59.492] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:5:0) after 8m31s
[14:52:30.595] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:5:0)
[14:52:30.636] Build status: SCHEDULED
[14:52:30.636] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:6:0) after 1m55s
[14:54:25.699] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:6:0)
[14:54:25.723] Build status: SCHEDULED
[14:54:25.723] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:7:0) after 9m35s
[15:04:00.742] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:7:0)
[15:04:00.763] Build status: SCHEDULED
[15:04:00.763] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:8:0) after 9m45s
[15:13:45.828] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:8:0)
[15:13:45.848] Build status: SCHEDULED
[15:13:45.849] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:9:0) after 5m1s
[15:18:46.839] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:9:0)
[15:18:46.874] Build status: SCHEDULED
[15:18:46.874] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:10:0) after 1m42s
[15:20:29.074] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:10:0)
[15:20:29.097] Build status: SCHEDULED
[15:20:29.097] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:11:0) after 6m48s
[15:27:17.282] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:11:0)
[15:27:17.302] Build status: SCHEDULED
[15:27:17.302] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:12:0) after 3m15s
[15:30:32.315] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:12:0)
[15:30:32.377] Build status: SCHEDULED
[15:30:32.377] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:13:0) after 3m49s
[15:34:21.396] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:13:0)
[15:34:21.447] Build status: SCHEDULED
[15:34:21.447] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:14:0) after 1m16s
[15:35:37.495] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:14:0)
[15:35:37.515] Build status: SCHEDULED
[15:35:37.515] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:15:0) after 9m53s
[15:45:30.532] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:15:0)
[15:45:30.551] Build status: SCHEDULED
[15:45:30.551] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:16:0) after 5m17s
[15:50:47.666] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:16:0)
[15:50:47.686] Build status: SCHEDULED
[15:50:47.686] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:17:0) after 4m35s
[15:55:22.878] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:17:0)
[15:55:22.897] Build status: SCHEDULED
[15:55:22.897] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:18:0) after 5m32s
[16:00:54.963] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:18:0)
[16:00:54.985] Build status: SCHEDULED
[16:00:54.985] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:19:0) after 6m11s
[16:07:06.330] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:19:0)
[16:07:06.355] Build status: SCHEDULED
[16:07:06.355] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:20:0) after 4m3s
[16:11:09.369] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:20:0)
[16:11:09.392] Build status: SCHEDULED
[16:11:09.392] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:21:0) after 8m32s
[16:19:41.510] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:21:0)
[16:19:41.532] Build status: SCHEDULED
[16:19:41.532] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:22:0) after 8m0s
[16:27:41.572] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:22:0)
[16:27:41.601] Build status: SCHEDULED
[16:27:41.601] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:23:0) after 9m3s
[16:36:44.616] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:23:0)
[16:36:44.638] Build status: SCHEDULED
[16:36:44.638] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:24:0) after 4m18s
[16:41:02.733] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:24:0)
[16:41:02.816] Build status: SCHEDULED
[16:41:02.816] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:25:0) after 5m23s
[16:46:25.840] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:25:0)
[16:46:25.871] Build status: SCHEDULED
[16:46:25.871] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:26:0) after 9m1s
[16:55:26.929] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:26:0)
[16:55:26.957] Build status: SCHEDULED
[16:55:26.957] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:27:0) after 8m17s
[17:03:43.973] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:27:0)
[17:03:43.993] Build status: SCHEDULED
[17:03:43.993] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:28:0) after 9m9s
[17:12:53.109] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:28:0)
[17:12:53.134] Build status: SCHEDULED
[17:12:53.134] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:29:0) after 8m51s
[17:21:44.144] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:29:0)
[17:21:44.180] Build status: SCHEDULED
[17:21:44.180] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:30:0) after 1m16s
[17:23:00.281] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:30:0)
[17:23:00.302] Build status: SCHEDULED
[17:23:00.302] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:31:0) after 7m27s
[17:30:27.288] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:31:0)
[17:30:27.310] Build status: SCHEDULED
[17:30:27.310] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:32:0) after 6m25s
[17:36:52.393] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:32:0)
[17:36:52.437] Build status: SCHEDULED
[17:36:52.437] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:33:0) after 8m54s
[17:45:46.455] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:33:0)
[17:45:46.484] Build status: SCHEDULED
[17:45:46.484] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:34:0) after 8m15s
[17:54:01.499] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:34:0)
[17:54:01.540] Build status: SCHEDULED
[17:54:01.540] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:35:0) after 6m5s
[18:00:07.064] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:35:0)
[18:00:07.092] Build status: SCHEDULED
[18:00:07.093] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:36:0) after 2m16s
[18:02:23.183] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:36:0)
[18:02:23.206] Build status: SCHEDULED
[18:02:23.206] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:37:0) after 5m36s
[18:07:59.297] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:37:0)
[18:07:59.320] Build status: SCHEDULED
[18:07:59.320] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:38:0) after 1m58s
[18:09:57.368] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:38:0)
[18:09:57.427] Build status: SCHEDULED
[18:09:57.427] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:39:0) after 3m47s
[18:13:44.451] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:39:0)
[18:13:44.475] Build status: SCHEDULED
[18:13:44.475] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:40:0) after 2m15s
[18:15:59.491] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:40:0)
[18:15:59.523] Build status: SCHEDULED
[18:15:59.523] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:41:0) after 3m29s
[18:19:28.628] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:41:0)
[18:19:28.652] Build status: SCHEDULED
[18:19:28.652] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:42:0) after 9m27s
[18:28:55.766] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:42:0)
[18:28:55.791] Build status: SCHEDULED
[18:28:55.791] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:43:0) after 1m36s
[18:30:32.048] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:43:0)
[18:30:32.070] Build status: SCHEDULED
[18:30:32.070] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:44:0) after 2m31s
[18:33:03.176] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:44:0)
[18:33:03.198] Build status: SCHEDULED
[18:33:03.198] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:45:0) after 8m36s
[18:41:39.200] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:45:0)
[18:41:39.227] Build status: SCHEDULED
[18:41:39.227] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:46:0) after 7m57s
[18:49:36.244] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:46:0)
[18:49:36.265] Build status: SCHEDULED
[18:49:36.265] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:47:0) after 1m35s
[18:51:11.371] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:47:0)
[18:51:11.410] Build status: SCHEDULED
[18:51:11.410] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:48:0) after 1m21s
[18:52:32.419] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:48:0)
[18:52:32.439] Build status: SCHEDULED
[18:52:32.439] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:49:0) after 2m53s
[18:55:25.413] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:49:0)
[18:55:25.432] Build status: SCHEDULED
[18:55:25.432] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:50:0) after 5m54s
[19:01:19.546] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:50:0)
[19:01:19.585] Build status: SCHEDULED
[19:01:19.585] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:51:0) after 6m43s
[19:08:02.651] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:51:0)
[19:08:02.708] Build status: SCHEDULED
[19:08:02.708] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:52:0) after 4m30s
[19:12:32.698] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:52:0)
[19:12:32.720] Build status: SCHEDULED
[19:12:32.720] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:53:0) after 3m7s
[19:15:39.735] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:53:0)
[19:15:39.762] Build status: SCHEDULED
[19:15:39.762] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:54:0) after 3m16s
[19:18:55.781] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:54:0)
[19:18:55.811] Build status: SCHEDULED
[19:18:55.811] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:55:0) after 4m18s
[19:23:13.891] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:55:0)
[19:23:13.912] Build status: SCHEDULED
[19:23:13.912] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:56:0) after 6m34s
[19:29:47.954] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:56:0)
[19:29:48.012] Build status: SCHEDULED
[19:29:48.012] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:57:0) after 4m13s
[19:34:01.072] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:57:0)
[19:34:01.128] Build status: SCHEDULED
[19:34:01.128] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:58:0) after 9m9s
[19:43:10.184] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:58:0)
[19:43:10.200] Build status: SCHEDULED
[19:43:10.200] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:59:0) after 5m55s
[19:49:05.262] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:59:0)
[19:49:05.320] Build status: SCHEDULED
[19:49:05.320] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:60:0) after 8m57s
[19:58:02.387] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:60:0)
[19:58:02.410] Build status: SCHEDULED
[19:58:02.410] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:61:0) after 8m23s
[20:06:25.431] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:61:0)
[20:06:25.453] Build status: SCHEDULED
[20:06:25.453] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:62:0) after 4m15s
[20:10:40.716] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:62:0)
[20:10:40.737] Build status: SCHEDULED
[20:10:40.737] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:63:0) after 8m33s
[20:19:13.757] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:63:0)
[20:19:13.781] Build status: SCHEDULED
[20:19:13.781] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:64:0) after 1m14s
[20:20:27.812] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:64:0)
[20:20:27.833] Build status: SCHEDULED
[20:20:27.833] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:65:0) after 8m56s
[20:29:23.901] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:65:0)
[20:29:23.921] Build status: SCHEDULED
[20:29:23.922] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:66:0) after 9m34s
[20:38:57.943] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:66:0)
[20:38:57.964] Build status: SCHEDULED
[20:38:57.964] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:67:0) after 1m26s
[20:40:24.043] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:67:0)
[20:40:24.061] Build status: SCHEDULED
[20:40:24.061] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:68:0) after 3m25s
[20:43:49.141] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:68:0)
[20:43:49.216] Build status: SCHEDULED
[20:43:49.216] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:69:0) after 6m32s
[20:50:21.493] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:69:0)
[20:50:21.536] Build status: SCHEDULED
[20:50:21.536] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:70:0) after 1m40s
[20:52:01.553] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:70:0)
[20:52:01.574] Build status: SCHEDULED
[20:52:01.574] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:71:0) after 2m12s
[20:54:13.593] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:71:0)
[20:54:13.650] Build status: SCHEDULED
[20:54:13.650] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:72:0) after 4m29s
[20:58:42.704] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:72:0)
[20:58:42.786] Build status: SCHEDULED
[20:58:42.787] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:73:0) after 1m50s
[21:00:32.803] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:73:0)
[21:00:32.838] Build status: SCHEDULED
[21:00:32.838] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:74:0) after 2m43s
[21:03:15.896] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:74:0)
[21:03:15.916] Build status: SCHEDULED
[21:03:15.916] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:75:0) after 6m34s
[21:09:49.935] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:75:0)
[21:09:49.956] Build status: SCHEDULED
[21:09:49.956] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:76:0) after 7m28s
[21:17:18.052] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:76:0)
[21:17:18.075] Build status: SCHEDULED
[21:17:18.075] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:77:0) after 8m41s
[21:25:59.271] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:77:0)
[21:25:59.291] Build status: SCHEDULED
[21:25:59.291] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:78:0) after 7m1s
[21:33:00.358] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:78:0)
[21:33:00.382] Build status: SCHEDULED
[21:33:00.382] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:79:0) after 1m50s
[21:34:50.423] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:79:0)
[21:34:50.456] Build status: SCHEDULED
[21:34:50.456] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:80:0) after 3m20s
[21:38:10.677] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:80:0)
[21:38:10.704] Build status: SCHEDULED
[21:38:10.704] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:81:0) after 4m31s
[21:42:41.885] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:81:0)
[21:42:41.907] Build status: SCHEDULED
[21:42:41.907] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:82:0) after 3m17s
[21:45:58.995] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:82:0)
[21:45:59.088] Build status: SCHEDULED
[21:45:59.088] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:83:0) after 9m35s
[21:55:34.106] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:83:0)
[21:55:34.189] Build status: SCHEDULED
[21:55:34.189] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:84:0) after 8m23s
[22:03:57.214] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:84:0)
[22:03:57.239] Build status: SCHEDULED
[22:03:57.239] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:85:0) after 2m28s
[22:06:25.260] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:85:0)
[22:06:25.314] Build status: SCHEDULED
[22:06:25.314] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:86:0) after 4m30s
[22:10:55.570] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:86:0)
[22:10:55.594] Build status: SCHEDULED
[22:10:55.594] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:87:0) after 3m45s
[22:14:40.621] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:87:0)
[22:14:40.640] Build status: SCHEDULED
[22:14:40.640] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:88:0) after 5m50s
[22:20:30.742] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:88:0)
[22:20:30.762] Build status: SCHEDULED
[22:20:30.762] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:89:0) after 5m57s
[22:26:27.830] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:89:0)
[22:26:27.868] Build status: SCHEDULED
[22:26:27.869] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:90:0) after 3m34s
[22:30:01.991] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:90:0)
[22:30:02.046] Build status: SCHEDULED
[22:30:02.046] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:91:0) after 2m17s
[22:32:19.110] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:91:0)
[22:32:19.128] Build status: SCHEDULED
[22:32:19.128] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:92:0) after 9m34s
[22:41:53.146] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:92:0)
[22:41:53.170] Build status: SCHEDULED
[22:41:53.170] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:93:0) after 1m45s
[22:43:38.255] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:93:0)
[22:43:38.274] Build status: SCHEDULED
[22:43:38.274] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:94:0) after 8m51s
[22:52:29.547] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:94:0)
[22:52:29.568] Build status: SCHEDULED
[22:52:29.568] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:95:0) after 5m3s
[22:57:32.596] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:95:0)
[22:57:32.648] Build status: SCHEDULED
[22:57:32.648] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:96:0) after 3m57s
[23:01:29.666] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:96:0)
[23:01:29.687] Build status: SCHEDULED
[23:01:29.687] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:97:0) after 2m59s
[23:04:28.794] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:97:0)
[23:04:28.816] Build status: SCHEDULED
[23:04:28.816] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:98:0) after 8m28s
[23:12:56.883] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:98:0)
[23:12:56.961] Build status: SCHEDULED
[23:12:56.961] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:99:0) after 7m44s
[23:20:41.048] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:99:0)
[23:20:41.125] Build status: SCHEDULED
[23:20:41.125] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:100:0) after 1m11s
[23:21:52.142] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:100:0)
[23:21:52.179] Build status: SCHEDULED
[23:21:52.179] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:101:0) after 7m37s
[23:29:29.197] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:101:0)
[23:29:29.217] Build status: SCHEDULED
[23:29:29.217] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:102:0) after 8m29s
[23:37:58.282] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:102:0)
[23:37:58.302] Build status: SCHEDULED
[23:37:58.302] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:103:0) after 4m5s
[23:42:03.421] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:103:0)
[23:42:03.440] Build status: SCHEDULED
[23:42:03.440] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:104:0) after 7m2s
[23:49:05.534] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:104:0)
[23:49:05.553] Build status: SCHEDULED
[23:49:05.554] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:105:0) after 3m19s
[23:52:24.554] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:105:0)
[23:52:24.604] Build status: SCHEDULED
[23:52:24.604] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:106:0) after 4m55s
[23:57:19.622] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:106:0)
[23:57:19.650] Build status: SCHEDULED
[23:57:19.650] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:107:0) after 9m16s
[00:06:35.722] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:107:0)
[00:06:35.745] Build status: SCHEDULED
[00:06:35.745] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:108:0) after 4m43s
[00:11:18.899] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:108:0)
[00:11:18.925] Build status: SCHEDULED
[00:11:18.925] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:109:0) after 5m55s
[00:17:14.005] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:109:0)
[00:17:14.049] Build status: SCHEDULED
[00:17:14.049] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:110:0) after 8m27s
[00:25:41.208] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:110:0)
[00:25:41.225] Build status: SCHEDULED
[00:25:41.225] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:111:0) after 8m34s
[00:34:15.353] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:111:0)
[00:34:15.369] Build status: SCHEDULED
[00:34:15.369] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:112:0) after 8m49s
[00:43:04.384] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:112:0)
[00:43:04.399] Build status: SCHEDULED
[00:43:04.399] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:113:0) after 5m50s
[00:48:54.551] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:113:0)
[00:48:54.579] Build status: SCHEDULED
[00:48:54.579] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:114:0) after 8m32s
[00:57:26.634] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:114:0)
[00:57:26.653] Build status: SCHEDULED
[00:57:26.653] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:115:0) after 8m0s
[01:05:26.933] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:115:0)
[01:05:26.963] Build status: SCHEDULED
[01:05:26.963] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:116:0) after 7m26s
[01:12:53.126] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:116:0)
[01:12:53.185] Build status: SCHEDULED
[01:12:53.185] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:117:0) after 4m13s
[01:17:06.231] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:117:0)
[01:17:06.257] Build status: SCHEDULED
[01:17:06.257] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:118:0) after 2m45s
[01:19:51.352] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:118:0)
[01:19:51.372] Build status: SCHEDULED
[01:19:51.372] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:119:0) after 9m0s
[01:28:51.496] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:119:0)
[01:28:51.515] Build status: SCHEDULED
[01:28:51.515] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:120:0) after 5m52s
[01:34:43.531] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:120:0)
[01:34:43.549] Build status: SCHEDULED
[01:34:43.549] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:121:0) after 9m59s
[01:44:42.524] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:121:0)
[01:44:42.544] Build status: SCHEDULED
[01:44:42.544] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:122:0) after 4m8s
[01:48:50.563] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:122:0)
[01:48:50.601] Build status: SCHEDULED
[01:48:50.601] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:123:0) after 6m47s
[01:55:37.688] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:123:0)
[01:55:37.705] Build status: SCHEDULED
[01:55:37.705] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:124:0) after 2m30s
[01:58:07.728] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:124:0)
[01:58:07.754] Build status: SCHEDULED
[01:58:07.754] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:125:0) after 5m26s
[02:03:33.791] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:125:0)
[02:03:33.812] Build status: SCHEDULED
[02:03:33.812] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:126:0) after 3m31s
[02:07:04.972] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:126:0)
[02:07:04.991] Build status: SCHEDULED
[02:07:04.991] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:127:0) after 6m33s
[02:13:38.061] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:127:0)
[02:13:38.094] Build status: SCHEDULED
[02:13:38.094] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:128:0) after 2m29s
[02:16:07.116] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:128:0)
[02:16:07.139] Build status: SCHEDULED
[02:16:07.139] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:129:0) after 5m4s
[02:21:11.243] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:129:0)
[02:21:11.260] Build status: SCHEDULED
[02:21:11.260] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:130:0) after 7m18s
[02:28:29.344] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:130:0)
[02:28:29.365] Build status: SCHEDULED
[02:28:29.365] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:131:0) after 2m15s
[02:30:44.420] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:131:0)
[02:30:44.443] Build status: SCHEDULED
[02:30:44.443] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:132:0) after 5m29s
[02:33:28.758] Received PubSub notification, asking Buildbucket for the build status
[02:33:28.791] Build status: STARTED
[02:36:13.484] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:132:0)
[02:36:13.506] Build status: STARTED
[02:36:13.506] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:134:0) after 4m10s
[02:40:23.522] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:134:0)
[02:40:23.543] Build status: STARTED
[02:40:23.543] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:135:0) after 7m39s
[02:48:02.563] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:135:0)
[02:48:02.631] Build status: STARTED
[02:48:02.631] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:136:0) after 9m24s
[02:57:26.651] Handling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:136:0)
[02:57:26.698] Build status: STARTED
[02:57:26.699] Scheduling timer "check-buildbucket-build-status" (r8/build_perf:8835290640393641024:137:0) after 8m54s
[02:57:42.742] Received PubSub notification, asking Buildbucket for the build status
[02:57:42.768] Build:
{
"id": "8669860578482535185",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "build_perf"
},
"number": 1020,
"createdBy": "project:r8",
"createTime": "2026-09-23T14:27:41.686542654Z",
"startTime": "2026-09-24T02:33:28.590948816Z",
"endTime": "2026-09-24T02:57:38.234932523Z",
"updateTime": "2026-09-24T02:57:38.234932523Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "f2c3b60e64e819cbdb086600bc78bd35706f6073",
"ref": "refs/heads/main"
}
}
}
[02:57:42.768] Invocation finished in 12h30m2.287648591s with status SUCCEEDED