LUCI Scheduler :: r8 :: perf :: 8835142444836400336

Status: SUCCEEDED

Triggered by: -

Duration: 13 hours

Actions:

Definition (rev c50208f5f1a1a5046149c608c11b6df22a8d85ac)

buildbucket: <
  server: "cr-buildbucket.appspot.com"
  bucket: "ci"
  builder: "perf"
>

Incoming triggers

61e86fccaac612690a2a5ee2df9a979af8be277e (2 weeks ago)

Incoming properties

{
  "branch": "refs/heads/main",
  "repository": "https://r8.googlesource.com/r8",
  "revision": "61e86fccaac612690a2a5ee2df9a979af8be277e"
}

Incoming tags

buildset:commit/gitiles/r8.googlesource.com/r8/+/61e86fccaac612690a2a5ee2df9a979af8be277e
gitiles_ref:refs/heads/main

Debug log (UTC)

[05:43:10.782] New invocation is queued and will start shortly
[05:43:13.785] Starting the invocation (attempt 1)
[05:43:13.815] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/61e86fccaac612690a2a5ee2df9a979af8be277e"
[05:43:13.815] Popped gitiles commit info from properties and tags
[05:43:13.815] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:43:13.815] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:43:13.815] Buildbucket request:
{
  "requestId": "8835142444836400336",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "perf"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8835142444836400336",
            "job": "r8/perf",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://r8.googlesource.com/r8",
                              "revision": "61e86fccaac612690a2a5ee2df9a979af8be277e"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@61e86fccaac612690a2a5ee2df9a979af8be277e",
                        "title": "61e86fccaac612690a2a5ee2df9a979af8be277e",
                        "url": "https://r8.googlesource.com/r8/+/61e86fccaac612690a2a5ee2df9a979af8be277e"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "r8.googlesource.com",
    "project": "r8",
    "id": "61e86fccaac612690a2a5ee2df9a979af8be277e",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8835142444836400336"
    },
    {
      "key": "scheduler_job_id",
      "value": "r8/perf"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[05:43:14.446] Scheduled build:
{
  "id": "8669712380876110257",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "perf"
  },
  "number": 3590,
  "createdBy": "project:r8",
  "createTime": "2026-09-25T05:43:13.937732787Z",
  "updateTime": "2026-09-25T05:43:13.937732787Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "r8.googlesource.com",
      "project": "r8",
      "id": "61e86fccaac612690a2a5ee2df9a979af8be277e",
      "ref": "refs/heads/main"
    }
  }
}
[05:43:14.446] Task URL: https://cr-buildbucket.appspot.com/build/8669712380876110257
[05:43:14.446] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:2:0) after 6m17s
[05:43:15.177] Received PubSub notification, asking Buildbucket for the build status
[05:43:15.197] Build status: SCHEDULED
[05:49:31.469] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:2:0)
[05:49:31.488] Build status: SCHEDULED
[05:49:31.488] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:4:0) after 9m12s
[05:58:43.507] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:4:0)
[05:58:43.538] Build status: SCHEDULED
[05:58:43.538] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:5:0) after 7m53s
[06:07:00.024] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:5:0)
[06:07:00.111] Build status: SCHEDULED
[06:07:00.111] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:6:0) after 9m33s
[06:16:33.138] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:6:0)
[06:16:33.159] Build status: SCHEDULED
[06:16:33.159] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:7:0) after 4m2s
[06:20:35.175] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:7:0)
[06:20:35.199] Build status: SCHEDULED
[06:20:35.199] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:8:0) after 4m48s
[06:25:23.214] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:8:0)
[06:25:23.232] Build status: SCHEDULED
[06:25:23.232] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:9:0) after 2m55s
[06:28:18.288] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:9:0)
[06:28:18.307] Build status: SCHEDULED
[06:28:18.307] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:10:0) after 4m1s
[06:32:19.358] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:10:0)
[06:32:19.378] Build status: SCHEDULED
[06:32:19.378] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:11:0) after 1m24s
[06:33:43.392] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:11:0)
[06:33:43.412] Build status: SCHEDULED
[06:33:43.412] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:12:0) after 1m36s
[06:35:19.428] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:12:0)
[06:35:19.446] Build status: SCHEDULED
[06:35:19.446] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:13:0) after 4m41s
[06:40:00.420] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:13:0)
[06:40:00.461] Build status: SCHEDULED
[06:40:00.461] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:14:0) after 4m42s
[06:44:42.492] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:14:0)
[06:44:42.563] Build status: SCHEDULED
[06:44:42.563] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:15:0) after 7m59s
[06:52:41.843] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:15:0)
[06:52:41.863] Build status: SCHEDULED
[06:52:41.863] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:16:0) after 7m53s
[07:00:34.882] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:16:0)
[07:00:34.912] Build status: SCHEDULED
[07:00:34.912] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:17:0) after 9m1s
[07:09:36.005] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:17:0)
[07:09:36.030] Build status: SCHEDULED
[07:09:36.030] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:18:0) after 1m45s
[07:11:21.226] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:18:0)
[07:11:21.246] Build status: SCHEDULED
[07:11:21.246] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:19:0) after 5m0s
[07:16:22.462] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:19:0)
[07:16:22.484] Build status: SCHEDULED
[07:16:22.484] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:20:0) after 8m17s
[07:24:39.498] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:20:0)
[07:24:39.521] Build status: SCHEDULED
[07:24:39.521] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:21:0) after 8m38s
[07:33:17.648] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:21:0)
[07:33:17.667] Build status: SCHEDULED
[07:33:17.667] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:22:0) after 9m58s
[07:43:15.677] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:22:0)
[07:43:15.756] Build status: SCHEDULED
[07:43:15.756] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:23:0) after 7m32s
[07:50:47.779] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:23:0)
[07:50:47.797] Build status: SCHEDULED
[07:50:47.797] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:24:0) after 8m34s
[07:59:21.812] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:24:0)
[07:59:21.833] Build status: SCHEDULED
[07:59:21.833] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:25:0) after 2m26s
[08:01:47.810] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:25:0)
[08:01:47.833] Build status: SCHEDULED
[08:01:47.833] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:26:0) after 7m3s
[08:08:50.837] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:26:0)
[08:08:50.916] Build status: SCHEDULED
[08:08:50.916] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:27:0) after 2m4s
[08:10:54.933] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:27:0)
[08:10:54.951] Build status: SCHEDULED
[08:10:54.951] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:28:0) after 7m54s
[08:18:48.989] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:28:0)
[08:18:49.144] Build status: SCHEDULED
[08:18:49.144] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:29:0) after 7m45s
[08:26:34.230] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:29:0)
[08:26:34.278] Build status: SCHEDULED
[08:26:34.278] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:30:0) after 9m42s
[08:36:16.371] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:30:0)
[08:36:16.390] Build status: SCHEDULED
[08:36:16.390] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:31:0) after 2m22s
[08:38:38.460] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:31:0)
[08:38:38.489] Build status: SCHEDULED
[08:38:38.489] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:32:0) after 1m27s
[08:40:05.614] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:32:0)
[08:40:05.639] Build status: SCHEDULED
[08:40:05.639] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:33:0) after 7m15s
[08:47:20.658] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:33:0)
[08:47:20.681] Build status: SCHEDULED
[08:47:20.681] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:34:0) after 3m36s
[08:50:56.700] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:34:0)
[08:50:56.736] Build status: SCHEDULED
[08:50:56.736] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:35:0) after 2m10s
[08:53:06.802] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:35:0)
[08:53:06.830] Build status: SCHEDULED
[08:53:06.830] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:36:0) after 6m48s
[08:59:54.897] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:36:0)
[08:59:54.956] Build status: SCHEDULED
[08:59:54.956] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:37:0) after 3m1s
[09:02:55.973] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:37:0)
[09:02:56.016] Build status: SCHEDULED
[09:02:56.016] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:38:0) after 7m40s
[09:10:36.033] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:38:0)
[09:10:36.058] Build status: SCHEDULED
[09:10:36.058] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:39:0) after 7m1s
[09:17:37.165] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:39:0)
[09:17:37.192] Build status: SCHEDULED
[09:17:37.192] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:40:0) after 8m50s
[09:26:27.209] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:40:0)
[09:26:27.227] Build status: SCHEDULED
[09:26:27.227] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:41:0) after 6m50s
[09:33:17.288] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:41:0)
[09:33:17.310] Build status: SCHEDULED
[09:33:17.310] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:42:0) after 9m50s
[09:43:07.366] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:42:0)
[09:43:07.390] Build status: SCHEDULED
[09:43:07.390] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:43:0) after 4m17s
[09:47:24.445] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:43:0)
[09:47:24.472] Build status: SCHEDULED
[09:47:24.472] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:44:0) after 9m4s
[09:56:28.532] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:44:0)
[09:56:28.603] Build status: SCHEDULED
[09:56:28.603] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:45:0) after 8m16s
[10:04:44.660] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:45:0)
[10:04:44.736] Build status: SCHEDULED
[10:04:44.736] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:46:0) after 1m4s
[10:05:48.754] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:46:0)
[10:05:48.775] Build status: SCHEDULED
[10:05:48.775] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:47:0) after 4m48s
[10:10:36.841] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:47:0)
[10:10:36.873] Build status: SCHEDULED
[10:10:36.873] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:48:0) after 2m55s
[10:13:31.892] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:48:0)
[10:13:31.922] Build status: SCHEDULED
[10:13:31.922] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:49:0) after 9m47s
[10:23:19.017] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:49:0)
[10:23:19.037] Build status: SCHEDULED
[10:23:19.037] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:50:0) after 9m21s
[10:32:40.083] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:50:0)
[10:32:40.105] Build status: SCHEDULED
[10:32:40.105] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:51:0) after 1m15s
[10:33:55.221] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:51:0)
[10:33:55.273] Build status: SCHEDULED
[10:33:55.273] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:52:0) after 6m41s
[10:40:36.417] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:52:0)
[10:40:36.542] Build status: SCHEDULED
[10:40:36.542] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:53:0) after 8m10s
[10:48:46.609] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:53:0)
[10:48:46.632] Build status: SCHEDULED
[10:48:46.632] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:54:0) after 7m30s
[10:56:16.802] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:54:0)
[10:56:16.829] Build status: SCHEDULED
[10:56:16.829] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:55:0) after 3m21s
[10:59:37.847] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:55:0)
[10:59:37.865] Build status: SCHEDULED
[10:59:37.865] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:56:0) after 3m5s
[11:02:42.961] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:56:0)
[11:02:42.983] Build status: SCHEDULED
[11:02:42.983] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:57:0) after 1m47s
[11:04:29.971] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:57:0)
[11:04:30.014] Build status: SCHEDULED
[11:04:30.014] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:58:0) after 9m42s
[11:14:12.105] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:58:0)
[11:14:12.129] Build status: SCHEDULED
[11:14:12.129] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:59:0) after 8m46s
[11:22:58.175] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:59:0)
[11:22:58.207] Build status: SCHEDULED
[11:22:58.207] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:60:0) after 9m40s
[11:32:38.201] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:60:0)
[11:32:38.248] Build status: SCHEDULED
[11:32:38.249] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:61:0) after 3m2s
[11:35:40.275] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:61:0)
[11:35:40.297] Build status: SCHEDULED
[11:35:40.297] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:62:0) after 6m6s
[11:41:46.332] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:62:0)
[11:41:46.355] Build status: SCHEDULED
[11:41:46.355] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:63:0) after 8m50s
[11:50:36.372] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:63:0)
[11:50:36.406] Build status: SCHEDULED
[11:50:36.406] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:64:0) after 5m46s
[11:56:22.431] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:64:0)
[11:56:22.452] Build status: SCHEDULED
[11:56:22.452] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:65:0) after 9m28s
[12:05:50.470] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:65:0)
[12:05:50.489] Build status: SCHEDULED
[12:05:50.489] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:66:0) after 6m33s
[12:12:23.515] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:66:0)
[12:12:23.543] Build status: SCHEDULED
[12:12:23.543] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:67:0) after 5m3s
[12:17:26.652] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:67:0)
[12:17:26.710] Build status: SCHEDULED
[12:17:26.710] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:68:0) after 8m42s
[12:26:08.730] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:68:0)
[12:26:08.751] Build status: SCHEDULED
[12:26:08.751] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:69:0) after 4m23s
[12:30:31.770] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:69:0)
[12:30:31.800] Build status: SCHEDULED
[12:30:31.800] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:70:0) after 5m32s
[12:36:03.862] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:70:0)
[12:36:03.883] Build status: SCHEDULED
[12:36:03.883] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:71:0) after 6m59s
[12:43:03.003] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:71:0)
[12:43:03.025] Build status: SCHEDULED
[12:43:03.025] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:72:0) after 6m57s
[12:50:00.094] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:72:0)
[12:50:00.114] Build status: SCHEDULED
[12:50:00.114] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:73:0) after 4m34s
[12:54:34.134] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:73:0)
[12:54:34.163] Build status: SCHEDULED
[12:54:34.163] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:74:0) after 3m11s
[12:57:45.142] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:74:0)
[12:57:45.166] Build status: SCHEDULED
[12:57:45.166] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:75:0) after 7m2s
[13:04:47.336] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:75:0)
[13:04:47.355] Build status: SCHEDULED
[13:04:47.355] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:76:0) after 6m10s
[13:10:57.853] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:76:0)
[13:10:57.874] Build status: SCHEDULED
[13:10:57.874] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:77:0) after 7m37s
[13:18:35.131] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:77:0)
[13:18:35.156] Build status: SCHEDULED
[13:18:35.156] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:78:0) after 1m59s
[13:20:34.290] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:78:0)
[13:20:34.327] Build status: SCHEDULED
[13:20:34.327] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:79:0) after 1m10s
[13:21:44.525] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:79:0)
[13:21:44.551] Build status: SCHEDULED
[13:21:44.552] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:80:0) after 6m45s
[13:28:29.571] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:80:0)
[13:28:29.592] Build status: SCHEDULED
[13:28:29.592] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:81:0) after 4m45s
[13:33:14.661] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:81:0)
[13:33:14.688] Build status: SCHEDULED
[13:33:14.688] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:82:0) after 9m57s
[13:43:11.709] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:82:0)
[13:43:11.731] Build status: SCHEDULED
[13:43:11.731] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:83:0) after 2m32s
[13:45:43.774] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:83:0)
[13:45:43.806] Build status: SCHEDULED
[13:45:43.806] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:84:0) after 3m57s
[13:49:40.827] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:84:0)
[13:49:40.857] Build status: SCHEDULED
[13:49:40.858] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:85:0) after 3m6s
[13:52:46.874] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:85:0)
[13:52:46.891] Build status: SCHEDULED
[13:52:46.891] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:86:0) after 9m27s
[14:02:14.048] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:86:0)
[14:02:14.070] Build status: SCHEDULED
[14:02:14.070] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:87:0) after 2m29s
[14:04:43.105] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:87:0)
[14:04:43.135] Build status: SCHEDULED
[14:04:43.135] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:88:0) after 5m45s
[14:10:28.154] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:88:0)
[14:10:28.173] Build status: SCHEDULED
[14:10:28.173] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:89:0) after 4m30s
[14:14:58.212] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:89:0)
[14:14:58.231] Build status: SCHEDULED
[14:14:58.231] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:90:0) after 7m13s
[14:22:11.330] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:90:0)
[14:22:11.392] Build status: SCHEDULED
[14:22:11.392] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:91:0) after 4m39s
[14:26:50.410] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:91:0)
[14:26:50.454] Build status: SCHEDULED
[14:26:50.454] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:92:0) after 5m2s
[14:31:52.464] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:92:0)
[14:31:52.487] Build status: SCHEDULED
[14:31:52.487] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:93:0) after 5m27s
[14:37:19.556] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:93:0)
[14:37:19.590] Build status: SCHEDULED
[14:37:19.590] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:94:0) after 1m2s
[14:38:21.603] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:94:0)
[14:38:21.625] Build status: SCHEDULED
[14:38:21.625] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:95:0) after 6m52s
[14:45:13.691] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:95:0)
[14:45:13.729] Build status: SCHEDULED
[14:45:13.729] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:96:0) after 4m51s
[14:50:04.747] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:96:0)
[14:50:04.776] Build status: SCHEDULED
[14:50:04.776] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:97:0) after 8m8s
[14:58:12.788] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:97:0)
[14:58:12.811] Build status: SCHEDULED
[14:58:12.811] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:98:0) after 4m44s
[15:02:56.906] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:98:0)
[15:02:56.930] Build status: SCHEDULED
[15:02:56.930] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:99:0) after 6m55s
[15:09:52.094] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:99:0)
[15:09:52.169] Build status: SCHEDULED
[15:09:52.169] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:100:0) after 6m27s
[15:16:19.186] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:100:0)
[15:16:19.202] Build status: SCHEDULED
[15:16:19.202] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:101:0) after 3m25s
[15:19:44.225] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:101:0)
[15:19:44.270] Build status: SCHEDULED
[15:19:44.270] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:102:0) after 9m57s
[15:29:41.269] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:102:0)
[15:29:41.291] Build status: SCHEDULED
[15:29:41.291] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:103:0) after 7m12s
[15:36:53.307] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:103:0)
[15:36:53.325] Build status: SCHEDULED
[15:36:53.325] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:104:0) after 9m44s
[15:46:37.375] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:104:0)
[15:46:37.584] Build status: SCHEDULED
[15:46:37.584] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:105:0) after 5m45s
[15:52:22.617] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:105:0)
[15:52:22.642] Build status: SCHEDULED
[15:52:22.643] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:106:0) after 2m21s
[15:54:43.727] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:106:0)
[15:54:43.748] Build status: SCHEDULED
[15:54:43.748] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:107:0) after 9m54s
[16:04:37.768] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:107:0)
[16:04:37.787] Build status: SCHEDULED
[16:04:37.787] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:108:0) after 1m25s
[16:06:02.885] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:108:0)
[16:06:02.919] Build status: SCHEDULED
[16:06:02.919] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:109:0) after 1m23s
[16:07:26.016] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:109:0)
[16:07:26.035] Build status: SCHEDULED
[16:07:26.035] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:110:0) after 8m55s
[16:16:21.056] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:110:0)
[16:16:21.095] Build status: SCHEDULED
[16:16:21.095] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:111:0) after 4m25s
[16:20:46.160] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:111:0)
[16:20:46.181] Build status: SCHEDULED
[16:20:46.181] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:112:0) after 3m33s
[16:24:19.199] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:112:0)
[16:24:19.219] Build status: SCHEDULED
[16:24:19.219] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:113:0) after 2m53s
[16:27:12.292] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:113:0)
[16:27:12.316] Build status: SCHEDULED
[16:27:12.316] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:114:0) after 8m34s
[16:35:46.512] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:114:0)
[16:35:46.598] Build status: SCHEDULED
[16:35:46.598] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:115:0) after 6m30s
[16:42:16.622] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:115:0)
[16:42:16.649] Build status: SCHEDULED
[16:42:16.649] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:116:0) after 7m26s
[16:49:42.671] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:116:0)
[16:49:42.694] Build status: SCHEDULED
[16:49:42.694] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:117:0) after 7m8s
[16:56:50.728] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:117:0)
[16:56:50.762] Build status: SCHEDULED
[16:56:50.762] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:118:0) after 6m16s
[17:03:06.792] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:118:0)
[17:03:06.823] Build status: SCHEDULED
[17:03:06.823] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:119:0) after 7m58s
[17:11:04.883] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:119:0)
[17:11:04.921] Build status: SCHEDULED
[17:11:04.921] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:120:0) after 6m20s
[17:17:25.014] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:120:0)
[17:17:25.059] Build status: SCHEDULED
[17:17:25.060] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:121:0) after 1m23s
[17:18:48.151] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:121:0)
[17:18:48.201] Build status: SCHEDULED
[17:18:48.201] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:122:0) after 6m10s
[17:24:58.315] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:122:0)
[17:24:58.346] Build status: SCHEDULED
[17:24:58.346] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:123:0) after 1m44s
[17:26:42.364] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:123:0)
[17:26:42.380] Build status: SCHEDULED
[17:26:42.380] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:124:0) after 9m4s
[17:35:46.410] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:124:0)
[17:35:46.457] Build status: SCHEDULED
[17:35:46.457] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:125:0) after 3m40s
[17:39:26.531] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:125:0)
[17:39:26.551] Build status: SCHEDULED
[17:39:26.551] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:126:0) after 6m0s
[17:45:26.595] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:126:0)
[17:45:26.618] Build status: SCHEDULED
[17:45:26.618] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:127:0) after 7m37s
[17:53:03.688] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:127:0)
[17:53:03.706] Build status: SCHEDULED
[17:53:03.706] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:128:0) after 2m56s
[17:55:59.706] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:128:0)
[17:55:59.727] Build status: SCHEDULED
[17:55:59.727] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:129:0) after 9m58s
[18:05:57.802] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:129:0)
[18:05:57.866] Build status: SCHEDULED
[18:05:57.866] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:130:0) after 4m40s
[18:10:15.181] Received PubSub notification, asking Buildbucket for the build status
[18:10:15.200] Build status: STARTED
[18:10:37.925] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:130:0)
[18:10:37.947] Build status: STARTED
[18:10:37.947] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:132:0) after 5m55s
[18:16:33.105] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:132:0)
[18:16:33.142] Build status: STARTED
[18:16:33.142] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:133:0) after 9m22s
[18:25:55.203] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:133:0)
[18:25:55.228] Build status: STARTED
[18:25:55.228] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:134:0) after 1m58s
[18:27:53.271] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:134:0)
[18:27:53.309] Build status: STARTED
[18:27:53.309] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:135:0) after 5m13s
[18:33:06.293] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:135:0)
[18:33:06.314] Build status: STARTED
[18:33:06.314] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:136:0) after 1m5s
[18:34:11.335] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:136:0)
[18:34:11.357] Build status: STARTED
[18:34:11.357] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:137:0) after 1m13s
[18:35:24.428] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:137:0)
[18:35:24.449] Build status: STARTED
[18:35:24.449] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:138:0) after 9m25s
[18:44:49.557] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:138:0)
[18:44:49.574] Build status: STARTED
[18:44:49.574] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:139:0) after 8m9s
[18:52:58.634] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:139:0)
[18:52:58.706] Build status: STARTED
[18:52:58.706] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:140:0) after 4m52s
[18:57:50.849] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:140:0)
[18:57:50.878] Build status: STARTED
[18:57:50.879] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:141:0) after 9m15s
[19:07:05.886] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:141:0)
[19:07:05.931] Build status: STARTED
[19:07:05.931] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:142:0) after 9m29s
[19:16:34.991] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:142:0)
[19:16:35.015] Build status: STARTED
[19:16:35.015] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:143:0) after 7m16s
[19:23:50.999] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:143:0)
[19:23:51.046] Build status: STARTED
[19:23:51.046] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:144:0) after 4m15s
[19:28:06.143] Handling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:144:0)
[19:28:06.182] Build status: STARTED
[19:28:06.182] Scheduling timer "check-buildbucket-build-status" (r8/perf:8835142444836400336:145:0) after 4m46s
[19:28:12.344] Received PubSub notification, asking Buildbucket for the build status
[19:28:12.363] Build:
{
  "id": "8669712380876110257",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "perf"
  },
  "number": 3590,
  "createdBy": "project:r8",
  "createTime": "2026-09-25T05:43:13.937732787Z",
  "startTime": "2026-09-25T18:10:14.946782420Z",
  "endTime": "2026-09-25T19:28:06.279326823Z",
  "updateTime": "2026-09-25T19:28:06.279326823Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "r8.googlesource.com",
      "project": "r8",
      "id": "61e86fccaac612690a2a5ee2df9a979af8be277e",
      "ref": "refs/heads/main"
    }
  }
}
[19:28:12.363] Invocation finished in 13h45m1.587060777s with status SUCCEEDED