LUCI Scheduler :: r8 :: win :: 8910281229754225856

Status: FAILED

Triggered by: -

Duration: 15 hours

Actions:

Definition (rev 87e0f3400b0aac78b81573f56efb8257c68089b2)

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

Incoming triggers

7b94946db716ac9da037c0fd9f3c0ed70d70ba42 (8 months ago)

Incoming properties

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

Incoming tags

buildset:commit/gitiles/r8.googlesource.com/r8/+/7b94946db716ac9da037c0fd9f3c0ed70d70ba42
gitiles_ref:refs/heads/main

Debug log (UTC)

[20:44:21.447] New invocation is queued and will start shortly
[20:44:22.809] Starting the invocation (attempt 1)
[20:44:22.834] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/7b94946db716ac9da037c0fd9f3c0ed70d70ba42"
[20:44:22.834] Popped gitiles commit info from properties and tags
[20:44:22.834] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:44:22.834] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:44:22.834] Buildbucket request:
{
  "requestId": "8910281229754225856",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "win"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8910281229754225856",
            "job": "r8/win",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://r8.googlesource.com/r8",
                              "revision": "7b94946db716ac9da037c0fd9f3c0ed70d70ba42"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@7b94946db716ac9da037c0fd9f3c0ed70d70ba42",
                        "title": "7b94946db716ac9da037c0fd9f3c0ed70d70ba42",
                        "url": "https://r8.googlesource.com/r8/+/7b94946db716ac9da037c0fd9f3c0ed70d70ba42"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "r8.googlesource.com",
    "project": "r8",
    "id": "7b94946db716ac9da037c0fd9f3c0ed70d70ba42",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8910281229754225856"
    },
    {
      "key": "scheduler_job_id",
      "value": "r8/win"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[20:44:23.452] Scheduled build:
{
  "id": "8744851167565444129",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "win"
  },
  "number": 43,
  "createdBy": "project:r8",
  "createTime": "2024-06-17T20:44:22.905291309Z",
  "updateTime": "2024-06-17T20:44:22.905291309Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "r8.googlesource.com",
      "project": "r8",
      "id": "7b94946db716ac9da037c0fd9f3c0ed70d70ba42",
      "ref": "refs/heads/main"
    }
  }
}
[20:44:23.452] Task URL: https://cr-buildbucket.appspot.com/build/8744851167565444129
[20:44:23.452] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:2:0) after 4m13s
[20:44:24.358] Received PubSub notification, asking Buildbucket for the build status
[20:44:24.378] Build status: SCHEDULED
[20:48:36.469] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:2:0)
[20:48:36.502] Build status: SCHEDULED
[20:48:36.502] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:4:0) after 1m53s
[20:50:29.528] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:4:0)
[20:50:29.548] Build status: SCHEDULED
[20:50:29.549] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:5:0) after 2m38s
[20:53:07.675] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:5:0)
[20:53:07.700] Build status: SCHEDULED
[20:53:07.700] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:6:0) after 6m28s
[20:59:35.725] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:6:0)
[20:59:35.766] Build status: SCHEDULED
[20:59:35.766] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:7:0) after 4m9s
[21:03:44.811] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:7:0)
[21:03:44.843] Build status: SCHEDULED
[21:03:44.843] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:8:0) after 1m13s
[21:04:57.894] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:8:0)
[21:04:57.914] Build status: SCHEDULED
[21:04:57.914] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:9:0) after 3m12s
[21:08:09.937] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:9:0)
[21:08:09.972] Build status: SCHEDULED
[21:08:09.972] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:10:0) after 3m22s
[21:11:32.034] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:10:0)
[21:11:32.075] Build status: SCHEDULED
[21:11:32.075] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:11:0) after 4m50s
[21:16:22.189] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:11:0)
[21:16:22.207] Build status: SCHEDULED
[21:16:22.207] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:12:0) after 6m33s
[21:22:55.280] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:12:0)
[21:22:55.302] Build status: SCHEDULED
[21:22:55.303] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:13:0) after 1m42s
[21:24:37.341] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:13:0)
[21:24:37.380] Build status: SCHEDULED
[21:24:37.381] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:14:0) after 4m29s
[21:29:06.377] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:14:0)
[21:29:06.394] Build status: SCHEDULED
[21:29:06.394] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:15:0) after 7m16s
[21:36:22.411] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:15:0)
[21:36:22.433] Build status: SCHEDULED
[21:36:22.433] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:16:0) after 6m8s
[21:42:30.461] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:16:0)
[21:42:30.480] Build status: SCHEDULED
[21:42:30.480] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:17:0) after 5m16s
[21:47:46.454] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:17:0)
[21:47:46.480] Build status: SCHEDULED
[21:47:46.480] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:18:0) after 2m28s
[21:50:14.506] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:18:0)
[21:50:14.525] Build status: SCHEDULED
[21:50:14.525] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:19:0) after 5m3s
[21:55:17.627] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:19:0)
[21:55:17.678] Build status: SCHEDULED
[21:55:17.678] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:20:0) after 1m33s
[21:56:50.692] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:20:0)
[21:56:50.742] Build status: SCHEDULED
[21:56:50.742] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:21:0) after 6m44s
[22:03:34.786] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:21:0)
[22:03:34.805] Build status: SCHEDULED
[22:03:34.805] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:22:0) after 4m25s
[22:07:59.883] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:22:0)
[22:07:59.956] Build status: SCHEDULED
[22:07:59.956] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:23:0) after 1m26s
[22:09:26.027] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:23:0)
[22:09:26.051] Build status: SCHEDULED
[22:09:26.051] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:24:0) after 1m8s
[22:10:34.078] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:24:0)
[22:10:34.110] Build status: SCHEDULED
[22:10:34.110] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:25:0) after 7m38s
[22:18:12.127] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:25:0)
[22:18:12.148] Build status: SCHEDULED
[22:18:12.148] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:26:0) after 5m18s
[22:23:30.286] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:26:0)
[22:23:30.454] Build status: SCHEDULED
[22:23:30.454] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:27:0) after 7m6s
[22:30:36.473] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:27:0)
[22:30:36.499] Build status: SCHEDULED
[22:30:36.499] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:28:0) after 2m57s
[22:33:33.511] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:28:0)
[22:33:33.529] Build status: SCHEDULED
[22:33:33.529] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:29:0) after 9m27s
[22:43:00.533] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:29:0)
[22:43:00.550] Build status: SCHEDULED
[22:43:00.550] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:30:0) after 2m55s
[22:45:55.571] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:30:0)
[22:45:55.589] Build status: SCHEDULED
[22:45:55.589] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:31:0) after 7m2s
[22:52:57.604] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:31:0)
[22:52:57.625] Build status: SCHEDULED
[22:52:57.626] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:32:0) after 1m58s
[22:54:55.608] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:32:0)
[22:54:55.736] Build status: SCHEDULED
[22:54:55.736] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:33:0) after 8m39s
[23:03:34.761] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:33:0)
[23:03:34.854] Build status: SCHEDULED
[23:03:34.854] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:34:0) after 1m4s
[23:04:38.882] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:34:0)
[23:04:38.948] Build status: SCHEDULED
[23:04:38.948] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:35:0) after 2m54s
[23:07:32.926] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:35:0)
[23:07:32.960] Build status: SCHEDULED
[23:07:32.960] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:36:0) after 3m59s
[23:11:31.986] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:36:0)
[23:11:32.006] Build status: SCHEDULED
[23:11:32.006] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:37:0) after 7m13s
[23:18:45.148] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:37:0)
[23:18:45.213] Build status: SCHEDULED
[23:18:45.213] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:38:0) after 3m5s
[23:21:50.240] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:38:0)
[23:21:50.274] Build status: SCHEDULED
[23:21:50.274] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:39:0) after 4m52s
[23:26:42.290] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:39:0)
[23:26:42.312] Build status: SCHEDULED
[23:26:42.312] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:40:0) after 6m20s
[23:33:02.373] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:40:0)
[23:33:02.405] Build status: SCHEDULED
[23:33:02.405] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:41:0) after 1m55s
[23:34:57.534] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:41:0)
[23:34:57.554] Build status: SCHEDULED
[23:34:57.555] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:42:0) after 6m28s
[23:41:25.571] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:42:0)
[23:41:25.695] Build status: SCHEDULED
[23:41:25.695] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:43:0) after 6m51s
[23:48:16.783] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:43:0)
[23:48:16.801] Build status: SCHEDULED
[23:48:16.801] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:44:0) after 4m49s
[23:53:05.817] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:44:0)
[23:53:05.835] Build status: SCHEDULED
[23:53:05.835] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:45:0) after 7m38s
[00:00:44.064] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:45:0)
[00:00:44.084] Build status: SCHEDULED
[00:00:44.084] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:46:0) after 5m21s
[00:06:05.211] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:46:0)
[00:06:05.306] Build status: SCHEDULED
[00:06:05.306] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:47:0) after 4m29s
[00:10:34.439] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:47:0)
[00:10:34.464] Build status: SCHEDULED
[00:10:34.464] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:48:0) after 5m33s
[00:16:07.698] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:48:0)
[00:16:07.782] Build status: SCHEDULED
[00:16:07.782] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:49:0) after 8m18s
[00:24:25.851] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:49:0)
[00:24:25.871] Build status: SCHEDULED
[00:24:25.871] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:50:0) after 6m53s
[00:31:19.051] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:50:0)
[00:31:19.079] Build status: SCHEDULED
[00:31:19.079] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:51:0) after 6m26s
[00:37:45.096] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:51:0)
[00:37:45.126] Build status: SCHEDULED
[00:37:45.126] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:52:0) after 3m10s
[00:40:55.193] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:52:0)
[00:40:55.225] Build status: SCHEDULED
[00:40:55.225] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:53:0) after 8m37s
[00:49:32.244] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:53:0)
[00:49:32.263] Build status: SCHEDULED
[00:49:32.263] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:54:0) after 2m1s
[00:51:33.278] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:54:0)
[00:51:33.403] Build status: SCHEDULED
[00:51:33.403] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:55:0) after 7m2s
[00:58:35.802] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:55:0)
[00:58:35.822] Build status: SCHEDULED
[00:58:35.822] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:56:0) after 8m40s
[01:07:15.841] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:56:0)
[01:07:15.865] Build status: SCHEDULED
[01:07:15.865] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:57:0) after 1m37s
[01:08:53.315] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:57:0)
[01:08:53.334] Build status: SCHEDULED
[01:08:53.334] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:58:0) after 5m55s
[01:14:48.470] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:58:0)
[01:14:48.486] Build status: SCHEDULED
[01:14:48.486] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:59:0) after 6m29s
[01:21:17.555] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:59:0)
[01:21:17.601] Build status: SCHEDULED
[01:21:17.601] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:60:0) after 5m45s
[01:27:02.762] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:60:0)
[01:27:02.841] Build status: SCHEDULED
[01:27:02.841] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:61:0) after 4m0s
[01:31:02.943] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:61:0)
[01:31:02.961] Build status: SCHEDULED
[01:31:02.961] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:62:0) after 1m40s
[01:32:42.979] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:62:0)
[01:32:43.007] Build status: SCHEDULED
[01:32:43.007] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:63:0) after 7m3s
[01:39:46.016] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:63:0)
[01:39:46.038] Build status: SCHEDULED
[01:39:46.038] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:64:0) after 7m27s
[01:47:13.051] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:64:0)
[01:47:13.073] Build status: SCHEDULED
[01:47:13.073] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:65:0) after 3m37s
[01:50:50.108] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:65:0)
[01:50:50.129] Build status: SCHEDULED
[01:50:50.129] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:66:0) after 7m35s
[01:58:25.145] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:66:0)
[01:58:25.206] Build status: SCHEDULED
[01:58:25.206] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:67:0) after 1m40s
[02:00:05.231] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:67:0)
[02:00:05.253] Build status: SCHEDULED
[02:00:05.253] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:68:0) after 6m53s
[02:06:58.271] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:68:0)
[02:06:58.436] Build status: SCHEDULED
[02:06:58.436] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:69:0) after 9m25s
[02:16:23.519] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:69:0)
[02:16:23.646] Build status: SCHEDULED
[02:16:23.646] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:70:0) after 4m26s
[02:20:49.738] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:70:0)
[02:20:49.762] Build status: SCHEDULED
[02:20:49.763] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:71:0) after 7m21s
[02:28:10.804] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:71:0)
[02:28:10.821] Build status: SCHEDULED
[02:28:10.821] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:72:0) after 7m52s
[02:36:02.938] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:72:0)
[02:36:02.959] Build status: SCHEDULED
[02:36:02.959] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:73:0) after 2m41s
[02:38:43.974] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:73:0)
[02:38:43.993] Build status: SCHEDULED
[02:38:43.993] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:74:0) after 4m6s
[02:42:50.088] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:74:0)
[02:42:50.106] Build status: SCHEDULED
[02:42:50.106] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:75:0) after 6m38s
[02:49:28.125] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:75:0)
[02:49:28.151] Build status: SCHEDULED
[02:49:28.151] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:76:0) after 5m53s
[02:55:21.171] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:76:0)
[02:55:21.192] Build status: SCHEDULED
[02:55:21.192] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:77:0) after 8m39s
[03:04:00.298] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:77:0)
[03:04:00.316] Build status: SCHEDULED
[03:04:00.316] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:78:0) after 3m28s
[03:07:28.341] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:78:0)
[03:07:28.361] Build status: SCHEDULED
[03:07:28.361] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:79:0) after 3m30s
[03:10:58.428] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:79:0)
[03:10:58.512] Build status: SCHEDULED
[03:10:58.512] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:80:0) after 6m11s
[03:17:09.664] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:80:0)
[03:17:09.686] Build status: SCHEDULED
[03:17:09.686] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:81:0) after 7m13s
[03:24:22.780] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:81:0)
[03:24:22.802] Build status: SCHEDULED
[03:24:22.802] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:82:0) after 4m55s
[03:29:17.932] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:82:0)
[03:29:17.950] Build status: SCHEDULED
[03:29:17.950] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:83:0) after 3m48s
[03:33:06.131] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:83:0)
[03:33:06.149] Build status: SCHEDULED
[03:33:06.149] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:84:0) after 7m8s
[03:40:14.419] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:84:0)
[03:40:14.438] Build status: SCHEDULED
[03:40:14.438] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:85:0) after 7m35s
[03:47:49.537] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:85:0)
[03:47:49.555] Build status: SCHEDULED
[03:47:49.555] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:86:0) after 2m39s
[03:50:28.843] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:86:0)
[03:50:28.885] Build status: SCHEDULED
[03:50:28.885] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:87:0) after 2m43s
[03:53:12.040] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:87:0)
[03:53:12.061] Build status: SCHEDULED
[03:53:12.061] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:88:0) after 9m11s
[04:02:23.128] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:88:0)
[04:02:23.156] Build status: SCHEDULED
[04:02:23.156] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:89:0) after 6m11s
[04:08:34.253] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:89:0)
[04:08:34.271] Build status: SCHEDULED
[04:08:34.271] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:90:0) after 5m41s
[04:14:15.306] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:90:0)
[04:14:15.339] Build status: SCHEDULED
[04:14:15.339] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:91:0) after 4m13s
[04:18:28.337] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:91:0)
[04:18:28.354] Build status: SCHEDULED
[04:18:28.354] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:92:0) after 3m31s
[04:21:59.442] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:92:0)
[04:21:59.469] Build status: SCHEDULED
[04:21:59.469] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:93:0) after 2m3s
[04:24:02.575] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:93:0)
[04:24:02.595] Build status: SCHEDULED
[04:24:02.595] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:94:0) after 1m49s
[04:25:51.706] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:94:0)
[04:25:51.748] Build status: SCHEDULED
[04:25:51.748] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:95:0) after 9m36s
[04:35:27.765] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:95:0)
[04:35:27.792] Build status: SCHEDULED
[04:35:27.792] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:96:0) after 3m48s
[04:39:15.817] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:96:0)
[04:39:15.845] Build status: SCHEDULED
[04:39:15.845] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:97:0) after 7m41s
[04:46:57.229] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:97:0)
[04:46:57.246] Build status: SCHEDULED
[04:46:57.246] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:98:0) after 5m24s
[04:52:21.458] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:98:0)
[04:52:21.483] Build status: SCHEDULED
[04:52:21.483] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:99:0) after 3m24s
[04:55:45.704] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:99:0)
[04:55:45.757] Build status: SCHEDULED
[04:55:45.757] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:100:0) after 4m38s
[05:00:23.907] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:100:0)
[05:00:24.099] Build status: SCHEDULED
[05:00:24.099] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:101:0) after 2m30s
[05:02:54.126] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:101:0)
[05:02:54.158] Build status: SCHEDULED
[05:02:54.158] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:102:0) after 1m54s
[05:04:35.636] Received PubSub notification, asking Buildbucket for the build status
[05:04:35.659] Build status: STARTED
[05:04:48.195] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:102:0)
[05:04:48.226] Build status: STARTED
[05:04:48.226] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:104:0) after 9m45s
[05:14:33.377] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:104:0)
[05:14:33.404] Build status: STARTED
[05:14:33.404] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:105:0) after 1m59s
[05:16:32.699] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:105:0)
[05:16:32.739] Build status: STARTED
[05:16:32.739] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:106:0) after 2m4s
[05:18:37.388] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:106:0)
[05:18:37.406] Build status: STARTED
[05:18:37.406] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:107:0) after 7m53s
[05:26:30.842] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:107:0)
[05:26:30.860] Build status: STARTED
[05:26:30.860] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:108:0) after 8m58s
[05:35:28.876] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:108:0)
[05:35:28.893] Build status: STARTED
[05:35:28.893] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:109:0) after 2m55s
[05:38:23.911] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:109:0)
[05:38:23.931] Build status: STARTED
[05:38:23.931] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:110:0) after 2m21s
[05:40:45.090] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:110:0)
[05:40:45.138] Build status: STARTED
[05:40:45.138] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:111:0) after 4m53s
[05:45:38.178] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:111:0)
[05:45:38.267] Build status: STARTED
[05:45:38.267] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:112:0) after 6m32s
[05:52:10.494] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:112:0)
[05:52:10.542] Build status: STARTED
[05:52:10.542] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:113:0) after 8m45s
[06:00:55.581] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:113:0)
[06:00:55.608] Build status: STARTED
[06:00:55.608] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:114:0) after 7m33s
[06:08:28.665] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:114:0)
[06:08:28.698] Build status: STARTED
[06:08:28.698] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:115:0) after 8m16s
[06:16:44.715] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:115:0)
[06:16:44.745] Build status: STARTED
[06:16:44.745] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:116:0) after 8m47s
[06:25:31.766] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:116:0)
[06:25:31.786] Build status: STARTED
[06:25:31.786] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:117:0) after 9m45s
[06:35:16.812] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:117:0)
[06:35:16.838] Build status: STARTED
[06:35:16.838] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:118:0) after 9m24s
[06:44:40.895] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:118:0)
[06:44:40.968] Build status: STARTED
[06:44:40.969] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:119:0) after 9m44s
[06:54:25.203] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:119:0)
[06:54:25.222] Build status: STARTED
[06:54:25.223] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:120:0) after 6m24s
[07:00:49.398] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:120:0)
[07:00:49.420] Build status: STARTED
[07:00:49.420] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:121:0) after 1m28s
[07:02:17.528] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:121:0)
[07:02:17.554] Build status: STARTED
[07:02:17.554] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:122:0) after 4m41s
[07:06:58.922] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:122:0)
[07:06:59.010] Build status: STARTED
[07:06:59.010] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:123:0) after 5m50s
[07:12:49.228] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:123:0)
[07:12:49.251] Build status: STARTED
[07:12:49.251] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:124:0) after 9m37s
[07:22:26.272] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:124:0)
[07:22:26.303] Build status: STARTED
[07:22:26.303] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:125:0) after 3m22s
[07:25:48.442] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:125:0)
[07:25:48.464] Build status: STARTED
[07:25:48.464] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:126:0) after 9m0s
[07:34:48.490] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:126:0)
[07:34:48.983] Build status: STARTED
[07:34:48.983] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:127:0) after 9m30s
[07:44:19.001] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:127:0)
[07:44:19.019] Build status: STARTED
[07:44:19.019] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:128:0) after 6m27s
[07:50:46.382] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:128:0)
[07:50:46.400] Build status: STARTED
[07:50:46.400] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:129:0) after 5m12s
[07:55:58.520] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:129:0)
[07:55:58.550] Build status: STARTED
[07:55:58.550] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:130:0) after 6m45s
[08:02:43.622] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:130:0)
[08:02:43.649] Build status: STARTED
[08:02:43.649] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:131:0) after 1m17s
[08:04:00.722] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:131:0)
[08:04:00.797] Build status: STARTED
[08:04:00.797] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:132:0) after 8m49s
[08:12:49.956] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:132:0)
[08:12:49.977] Build status: STARTED
[08:12:49.977] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:133:0) after 2m56s
[08:15:46.017] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:133:0)
[08:15:46.058] Build status: STARTED
[08:15:46.059] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:134:0) after 7m27s
[08:23:13.836] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:134:0)
[08:23:13.856] Build status: STARTED
[08:23:13.856] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:135:0) after 1m42s
[08:24:56.081] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:135:0)
[08:24:56.102] Build status: STARTED
[08:24:56.102] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:136:0) after 7m23s
[08:32:19.153] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:136:0)
[08:32:19.180] Build status: STARTED
[08:32:19.180] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:137:0) after 3m57s
[08:36:16.201] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:137:0)
[08:36:16.234] Build status: STARTED
[08:36:16.234] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:138:0) after 9m6s
[08:45:22.759] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:138:0)
[08:45:22.781] Build status: STARTED
[08:45:22.781] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:139:0) after 2m43s
[08:48:05.987] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:139:0)
[08:48:06.037] Build status: STARTED
[08:48:06.037] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:140:0) after 4m1s
[08:52:07.472] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:140:0)
[08:52:07.492] Build status: STARTED
[08:52:07.492] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:141:0) after 1m14s
[08:53:21.706] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:141:0)
[08:53:21.728] Build status: STARTED
[08:53:21.728] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:142:0) after 6m24s
[08:59:45.759] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:142:0)
[08:59:45.778] Build status: STARTED
[08:59:45.778] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:143:0) after 2m59s
[09:02:45.244] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:143:0)
[09:02:45.407] Build status: STARTED
[09:02:45.407] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:144:0) after 8m17s
[09:11:02.425] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:144:0)
[09:11:02.447] Build status: STARTED
[09:11:02.447] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:145:0) after 6m5s
[09:17:07.484] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:145:0)
[09:17:07.503] Build status: STARTED
[09:17:07.503] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:146:0) after 2m24s
[09:19:31.953] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:146:0)
[09:19:32.028] Build status: STARTED
[09:19:32.028] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:147:0) after 3m55s
[09:23:27.324] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:147:0)
[09:23:27.341] Build status: STARTED
[09:23:27.341] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:148:0) after 6m5s
[09:29:32.362] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:148:0)
[09:29:32.384] Build status: STARTED
[09:29:32.384] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:149:0) after 2m25s
[09:31:57.486] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:149:0)
[09:31:57.530] Build status: STARTED
[09:31:57.530] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:150:0) after 2m55s
[09:34:52.738] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:150:0)
[09:34:52.780] Build status: STARTED
[09:34:52.780] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:151:0) after 1m31s
[09:36:23.890] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:151:0)
[09:36:23.911] Build status: STARTED
[09:36:23.911] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:152:0) after 8m44s
[09:45:07.934] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:152:0)
[09:45:07.970] Build status: STARTED
[09:45:07.970] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:153:0) after 6m31s
[09:51:39.002] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:153:0)
[09:51:39.072] Build status: STARTED
[09:51:39.072] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:154:0) after 8m21s
[10:00:00.098] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:154:0)
[10:00:00.122] Build status: STARTED
[10:00:00.122] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:155:0) after 6m3s
[10:06:03.651] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:155:0)
[10:06:03.669] Build status: STARTED
[10:06:03.669] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:156:0) after 6m39s
[10:12:42.648] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:156:0)
[10:12:42.687] Build status: STARTED
[10:12:42.687] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:157:0) after 7m35s
[10:20:17.706] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:157:0)
[10:20:17.728] Build status: STARTED
[10:20:17.728] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:158:0) after 3m51s
[10:24:08.904] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:158:0)
[10:24:08.928] Build status: STARTED
[10:24:08.928] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:159:0) after 8m27s
[10:32:36.133] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:159:0)
[10:32:36.177] Build status: STARTED
[10:32:36.178] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:160:0) after 1m38s
[10:34:14.255] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:160:0)
[10:34:14.273] Build status: STARTED
[10:34:14.273] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:161:0) after 9m4s
[10:43:18.294] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:161:0)
[10:43:18.312] Build status: STARTED
[10:43:18.312] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:162:0) after 1m31s
[10:44:49.346] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:162:0)
[10:44:49.377] Build status: STARTED
[10:44:49.377] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:163:0) after 9m0s
[10:53:49.452] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:163:0)
[10:53:49.473] Build status: STARTED
[10:53:49.473] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:164:0) after 1m25s
[10:55:14.499] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:164:0)
[10:55:14.518] Build status: STARTED
[10:55:14.518] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:165:0) after 4m50s
[11:00:04.549] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:165:0)
[11:00:04.569] Build status: STARTED
[11:00:04.569] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:166:0) after 8m18s
[11:08:22.634] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:166:0)
[11:08:22.701] Build status: STARTED
[11:08:22.701] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:167:0) after 2m8s
[11:10:30.733] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:167:0)
[11:10:30.764] Build status: STARTED
[11:10:30.764] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:168:0) after 6m31s
[11:17:01.909] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:168:0)
[11:17:01.930] Build status: STARTED
[11:17:01.930] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:169:0) after 2m49s
[11:19:51.522] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:169:0)
[11:19:51.545] Build status: STARTED
[11:19:51.545] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:170:0) after 2m10s
[11:22:01.976] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:170:0)
[11:22:01.993] Build status: STARTED
[11:22:01.993] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:171:0) after 1m10s
[11:23:12.011] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:171:0)
[11:23:12.026] Build status: STARTED
[11:23:12.026] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:172:0) after 3m54s
[11:27:06.027] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:172:0)
[11:27:06.045] Build status: STARTED
[11:27:06.045] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:173:0) after 8m51s
[11:35:57.154] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:173:0)
[11:35:57.173] Build status: STARTED
[11:35:57.173] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:174:0) after 8m47s
[11:44:44.169] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:174:0)
[11:44:44.233] Build status: STARTED
[11:44:44.233] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:175:0) after 7m57s
[11:52:41.262] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:175:0)
[11:52:41.283] Build status: STARTED
[11:52:41.283] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:176:0) after 3m8s
[11:55:49.417] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:176:0)
[11:55:49.455] Build status: STARTED
[11:55:49.455] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:177:0) after 9m48s
[12:05:37.475] Handling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:177:0)
[12:05:37.495] Build status: STARTED
[12:05:37.495] Scheduling timer "check-buildbucket-build-status" (r8/win:8910281229754225856:178:0) after 9m57s
[12:09:50.956] Received PubSub notification, asking Buildbucket for the build status
[12:09:50.975] Build:
{
  "id": "8744851167565444129",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "win"
  },
  "number": 43,
  "createdBy": "project:r8",
  "createTime": "2024-06-17T20:44:22.905291309Z",
  "startTime": "2024-06-18T05:04:35.405365414Z",
  "endTime": "2024-06-18T12:09:38.055431321Z",
  "updateTime": "2024-06-18T12:09:38.055431321Z",
  "status": "FAILURE",
  "input": {
    "gitilesCommit": {
      "host": "r8.googlesource.com",
      "project": "r8",
      "id": "7b94946db716ac9da037c0fd9f3c0ed70d70ba42",
      "ref": "refs/heads/main"
    }
  }
}
[12:09:50.975] Invocation finished in 15h25m29.541479778s with status FAILED