LUCI Scheduler :: r8 :: linux-internal

Status: SUCCEEDED

Triggered by: -

Duration: 10 hours

Actions:

Definition (rev f340a0e7fd42f658022a052c8e96c9e3492df823)

buildbucket: <
  server: "cr-buildbucket.appspot.com"
  bucket: "luci.r8.ci"
  builder: "linux-internal"
>

Incoming triggers

efaa1e59ac3783260a5e62f62b6856bd3585fe8a (3 years ago)
8470bf53e47d6b42b89b7ef31015002869a283e5 (3 years ago)

Incoming properties

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

Incoming tags

buildset:commit/gitiles/r8.googlesource.com/r8/+/8470bf53e47d6b42b89b7ef31015002869a283e5
gitiles_ref:refs/heads/main

Debug log (UTC)

[18:16:24.000] New invocation is queued and will start shortly
[18:16:25.083] Starting the invocation (attempt 1)
[18:16:25.131] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/8470bf53e47d6b42b89b7ef31015002869a283e5"
[18:16:25.131] Popped gitiles commit info from properties and tags
[18:16:25.131] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:16:25.131] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:16:25.132] Buildbucket request:
{
  "requestId": "8993639747522685616",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "linux-internal"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://r8.googlesource.com/r8",
                              "revision": "efaa1e59ac3783260a5e62f62b6856bd3585fe8a"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@efaa1e59ac3783260a5e62f62b6856bd3585fe8a",
                        "title": "efaa1e59ac3783260a5e62f62b6856bd3585fe8a",
                        "url": "https://r8.googlesource.com/r8/+/efaa1e59ac3783260a5e62f62b6856bd3585fe8a"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://r8.googlesource.com/r8",
                              "revision": "8470bf53e47d6b42b89b7ef31015002869a283e5"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@8470bf53e47d6b42b89b7ef31015002869a283e5",
                        "title": "8470bf53e47d6b42b89b7ef31015002869a283e5",
                        "url": "https://r8.googlesource.com/r8/+/8470bf53e47d6b42b89b7ef31015002869a283e5"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "r8.googlesource.com",
    "project": "r8",
    "id": "8470bf53e47d6b42b89b7ef31015002869a283e5",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8993639747522685616"
    },
    {
      "key": "scheduler_job_id",
      "value": "r8/linux-internal"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[18:16:25.853] Scheduled build:
{
  "id": "8828209685546547441",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "linux-internal"
  },
  "number": 2256,
  "createdBy": "project:r8",
  "createTime": "2021-12-10T18:16:25.253420087Z",
  "updateTime": "2021-12-10T18:16:25.253420087Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "r8.googlesource.com",
      "project": "r8",
      "id": "8470bf53e47d6b42b89b7ef31015002869a283e5",
      "ref": "refs/heads/main"
    }
  },
  "canary": true
}
[18:16:25.853] Task URL: https://cr-buildbucket.appspot.com/build/8828209685546547441
[18:16:25.853] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:2:0) after 2m6s
[18:18:31.925] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:2:0)
[18:18:31.971] Build status: SCHEDULED
[18:18:31.971] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:3:0) after 6m24s
[18:24:55.989] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:3:0)
[18:24:56.015] Build status: SCHEDULED
[18:24:56.015] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:4:0) after 1m21s
[18:26:17.030] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:4:0)
[18:26:17.069] Build status: SCHEDULED
[18:26:17.069] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:5:0) after 3m0s
[18:29:17.218] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:5:0)
[18:29:17.251] Build status: SCHEDULED
[18:29:17.251] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:6:0) after 5m51s
[18:35:08.278] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:6:0)
[18:35:08.362] Build status: SCHEDULED
[18:35:08.362] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:7:0) after 9m42s
[18:44:50.378] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:7:0)
[18:44:50.412] Build status: SCHEDULED
[18:44:50.412] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:8:0) after 5m19s
[18:50:09.419] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:8:0)
[18:50:09.466] Build status: SCHEDULED
[18:50:09.466] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:9:0) after 2m36s
[18:52:45.624] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:9:0)
[18:52:45.690] Build status: SCHEDULED
[18:52:45.690] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:10:0) after 6m43s
[18:59:28.709] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:10:0)
[18:59:28.741] Build status: SCHEDULED
[18:59:28.741] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:11:0) after 7m13s
[19:06:41.854] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:11:0)
[19:06:41.886] Build status: SCHEDULED
[19:06:41.886] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:12:0) after 3m15s
[19:09:56.996] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:12:0)
[19:09:57.024] Build status: SCHEDULED
[19:09:57.024] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:13:0) after 5m22s
[19:15:19.101] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:13:0)
[19:15:19.145] Build status: SCHEDULED
[19:15:19.145] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:14:0) after 2m11s
[19:17:30.275] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:14:0)
[19:17:30.307] Build status: SCHEDULED
[19:17:30.307] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:15:0) after 7m56s
[19:25:26.325] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:15:0)
[19:25:26.400] Build status: SCHEDULED
[19:25:26.400] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:16:0) after 9m57s
[19:35:23.545] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:16:0)
[19:35:23.577] Build status: SCHEDULED
[19:35:23.577] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:17:0) after 2m20s
[19:37:43.698] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:17:0)
[19:37:43.732] Build status: SCHEDULED
[19:37:43.732] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:18:0) after 5m11s
[19:42:54.995] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:18:0)
[19:42:55.026] Build status: SCHEDULED
[19:42:55.026] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:19:0) after 3m13s
[19:46:08.095] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:19:0)
[19:46:08.178] Build status: SCHEDULED
[19:46:08.178] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:20:0) after 7m29s
[19:53:37.293] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:20:0)
[19:53:37.328] Build status: SCHEDULED
[19:53:37.328] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:21:0) after 4m34s
[19:58:11.480] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:21:0)
[19:58:11.512] Build status: SCHEDULED
[19:58:11.512] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:22:0) after 9m49s
[20:08:00.559] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:22:0)
[20:08:00.595] Build status: SCHEDULED
[20:08:00.595] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:23:0) after 4m21s
[20:12:21.982] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:23:0)
[20:12:22.007] Build status: SCHEDULED
[20:12:22.007] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:24:0) after 5m14s
[20:17:36.003] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:24:0)
[20:17:36.033] Build status: SCHEDULED
[20:17:36.033] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:25:0) after 7m12s
[20:24:48.128] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:25:0)
[20:24:48.155] Build status: SCHEDULED
[20:24:48.155] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:26:0) after 2m45s
[20:27:33.179] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:26:0)
[20:27:33.216] Build status: SCHEDULED
[20:27:33.217] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:27:0) after 7m13s
[20:34:46.250] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:27:0)
[20:34:46.297] Build status: SCHEDULED
[20:34:46.297] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:28:0) after 2m51s
[20:37:37.638] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:28:0)
[20:37:37.666] Build status: SCHEDULED
[20:37:37.666] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:29:0) after 2m7s
[20:39:44.686] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:29:0)
[20:39:44.707] Build status: SCHEDULED
[20:39:44.707] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:30:0) after 7m57s
[20:47:42.003] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:30:0)
[20:47:42.034] Build status: SCHEDULED
[20:47:42.034] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:31:0) after 2m25s
[20:50:07.221] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:31:0)
[20:50:07.256] Build status: SCHEDULED
[20:50:07.256] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:32:0) after 7m7s
[20:57:14.276] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:32:0)
[20:57:14.326] Build status: SCHEDULED
[20:57:14.326] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:33:0) after 4m34s
[21:01:48.438] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:33:0)
[21:01:48.485] Build status: SCHEDULED
[21:01:48.485] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:34:0) after 2m4s
[21:03:52.723] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:34:0)
[21:03:52.753] Build status: SCHEDULED
[21:03:52.753] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:35:0) after 6m55s
[21:10:47.883] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:35:0)
[21:10:47.932] Build status: SCHEDULED
[21:10:47.932] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:36:0) after 1m42s
[21:12:30.104] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:36:0)
[21:12:30.134] Build status: SCHEDULED
[21:12:30.135] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:37:0) after 5m11s
[21:17:41.279] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:37:0)
[21:17:41.309] Build status: SCHEDULED
[21:17:41.309] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:38:0) after 2m6s
[21:19:47.703] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:38:0)
[21:19:47.743] Build status: SCHEDULED
[21:19:47.743] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:39:0) after 6m56s
[21:26:43.760] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:39:0)
[21:26:43.790] Build status: SCHEDULED
[21:26:43.790] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:40:0) after 4m31s
[21:31:14.809] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:40:0)
[21:31:14.848] Build status: SCHEDULED
[21:31:14.848] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:41:0) after 5m10s
[21:36:24.876] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:41:0)
[21:36:24.907] Build status: SCHEDULED
[21:36:24.907] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:42:0) after 8m34s
[21:44:59.072] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:42:0)
[21:44:59.093] Build status: SCHEDULED
[21:44:59.093] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:43:0) after 3m7s
[21:48:06.343] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:43:0)
[21:48:06.380] Build status: SCHEDULED
[21:48:06.380] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:44:0) after 2m33s
[21:50:39.585] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:44:0)
[21:50:39.631] Build status: SCHEDULED
[21:50:39.631] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:45:0) after 7m46s
[21:58:25.806] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:45:0)
[21:58:25.846] Build status: SCHEDULED
[21:58:25.846] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:46:0) after 5m48s
[22:01:51.542] Received PubSub notification, asking Buildbucket for the build status
[22:01:51.583] Build status: STARTED
[22:04:13.868] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:46:0)
[22:04:13.901] Build status: STARTED
[22:04:13.901] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:48:0) after 5m56s
[22:10:09.950] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:48:0)
[22:10:09.980] Build status: STARTED
[22:10:09.980] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:49:0) after 6m37s
[22:16:47.039] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:49:0)
[22:16:47.076] Build status: STARTED
[22:16:47.077] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:50:0) after 7m42s
[22:24:29.229] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:50:0)
[22:24:29.254] Build status: STARTED
[22:24:29.254] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:51:0) after 4m7s
[22:28:36.347] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:51:0)
[22:28:36.376] Build status: STARTED
[22:28:36.377] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:52:0) after 9m59s
[22:38:35.749] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:52:0)
[22:38:35.794] Build status: STARTED
[22:38:35.794] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:53:0) after 2m20s
[22:40:55.970] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:53:0)
[22:40:56.001] Build status: STARTED
[22:40:56.001] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:54:0) after 1m39s
[22:42:35.198] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:54:0)
[22:42:35.233] Build status: STARTED
[22:42:35.233] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:55:0) after 8m21s
[22:50:56.213] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:55:0)
[22:50:56.242] Build status: STARTED
[22:50:56.242] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:56:0) after 7m4s
[22:58:00.384] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:56:0)
[22:58:00.863] Build status: STARTED
[22:58:00.863] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:57:0) after 8m57s
[23:06:58.184] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:57:0)
[23:06:58.233] Build status: STARTED
[23:06:58.233] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:58:0) after 9m42s
[23:16:40.253] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:58:0)
[23:16:40.292] Build status: STARTED
[23:16:40.292] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:59:0) after 8m19s
[23:24:59.394] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:59:0)
[23:24:59.432] Build status: STARTED
[23:24:59.432] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:60:0) after 5m11s
[23:30:10.673] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:60:0)
[23:30:10.716] Build status: STARTED
[23:30:10.716] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:61:0) after 6m53s
[23:37:03.841] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:61:0)
[23:37:03.895] Build status: STARTED
[23:37:03.895] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:62:0) after 7m43s
[23:44:46.912] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:62:0)
[23:44:46.942] Build status: STARTED
[23:44:46.942] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:63:0) after 8m1s
[23:52:47.964] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:63:0)
[23:52:47.997] Build status: STARTED
[23:52:47.997] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:64:0) after 3m45s
[23:56:33.258] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:64:0)
[23:56:33.289] Build status: STARTED
[23:56:33.289] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:65:0) after 6m33s
[00:03:06.314] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:65:0)
[00:03:06.343] Build status: STARTED
[00:03:06.343] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:66:0) after 2m3s
[00:05:09.398] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:66:0)
[00:05:09.435] Build status: STARTED
[00:05:09.435] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:67:0) after 7m47s
[00:12:56.505] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:67:0)
[00:12:56.542] Build status: STARTED
[00:12:56.542] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:68:0) after 1m52s
[00:14:48.518] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:68:0)
[00:14:48.556] Build status: STARTED
[00:14:48.556] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:69:0) after 3m15s
[00:18:03.684] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:69:0)
[00:18:03.736] Build status: STARTED
[00:18:03.736] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:70:0) after 7m44s
[00:25:47.858] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:70:0)
[00:25:47.905] Build status: STARTED
[00:25:47.905] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:71:0) after 4m5s
[00:29:53.024] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:71:0)
[00:29:53.095] Build status: STARTED
[00:29:53.095] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:72:0) after 4m16s
[00:34:09.214] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:72:0)
[00:34:09.259] Build status: STARTED
[00:34:09.259] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:73:0) after 6m13s
[00:40:22.286] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:73:0)
[00:40:22.342] Build status: STARTED
[00:40:22.342] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:74:0) after 9m58s
[00:50:20.376] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:74:0)
[00:50:20.409] Build status: STARTED
[00:50:20.409] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:75:0) after 5m2s
[00:55:22.559] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:75:0)
[00:55:22.588] Build status: STARTED
[00:55:22.588] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:76:0) after 6m42s
[01:02:04.611] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:76:0)
[01:02:04.649] Build status: STARTED
[01:02:04.649] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:77:0) after 9m32s
[01:11:36.683] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:77:0)
[01:11:36.718] Build status: STARTED
[01:11:36.718] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:78:0) after 8m23s
[01:19:59.743] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:78:0)
[01:19:59.780] Build status: STARTED
[01:19:59.780] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:79:0) after 5m50s
[01:25:49.814] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:79:0)
[01:25:49.856] Build status: STARTED
[01:25:49.856] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:80:0) after 5m18s
[01:31:07.881] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:80:0)
[01:31:07.916] Build status: STARTED
[01:31:07.916] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:81:0) after 3m54s
[01:35:01.934] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:81:0)
[01:35:01.967] Build status: STARTED
[01:35:01.967] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:82:0) after 8m17s
[01:43:19.027] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:82:0)
[01:43:19.063] Build status: STARTED
[01:43:19.063] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:83:0) after 2m22s
[01:45:41.193] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:83:0)
[01:45:41.245] Build status: STARTED
[01:45:41.245] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:84:0) after 8m21s
[01:54:02.383] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:84:0)
[01:54:02.412] Build status: STARTED
[01:54:02.412] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:85:0) after 1m17s
[01:55:19.437] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:85:0)
[01:55:19.466] Build status: STARTED
[01:55:19.466] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:86:0) after 6m57s
[02:02:16.496] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:86:0)
[02:02:16.553] Build status: STARTED
[02:02:16.553] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:87:0) after 2m0s
[02:04:16.543] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:87:0)
[02:04:16.589] Build status: STARTED
[02:04:16.589] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:88:0) after 5m54s
[02:10:10.613] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:88:0)
[02:10:10.634] Build status: STARTED
[02:10:10.634] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:89:0) after 9m55s
[02:20:05.653] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:89:0)
[02:20:05.695] Build status: STARTED
[02:20:05.695] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:90:0) after 6m41s
[02:26:47.441] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:90:0)
[02:26:47.487] Build status: STARTED
[02:26:47.487] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:91:0) after 4m56s
[02:31:43.505] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:91:0)
[02:31:43.548] Build status: STARTED
[02:31:43.548] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:92:0) after 7m39s
[02:39:22.567] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:92:0)
[02:39:22.597] Build status: STARTED
[02:39:22.597] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:93:0) after 4m36s
[02:43:58.770] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:93:0)
[02:43:58.800] Build status: STARTED
[02:43:58.801] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:94:0) after 3m5s
[02:47:03.821] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:94:0)
[02:47:03.849] Build status: STARTED
[02:47:03.849] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:95:0) after 9m7s
[02:56:10.872] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:95:0)
[02:56:10.903] Build status: STARTED
[02:56:10.903] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:96:0) after 1m10s
[02:57:21.005] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:96:0)
[02:57:21.041] Build status: STARTED
[02:57:21.041] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:97:0) after 9m37s
[03:06:58.021] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:97:0)
[03:06:58.054] Build status: STARTED
[03:06:58.054] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:98:0) after 2m53s
[03:09:51.071] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:98:0)
[03:09:51.109] Build status: STARTED
[03:09:51.109] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:99:0) after 1m46s
[03:11:37.133] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:99:0)
[03:11:37.181] Build status: STARTED
[03:11:37.181] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:100:0) after 7m44s
[03:19:21.330] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:100:0)
[03:19:21.361] Build status: STARTED
[03:19:21.361] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:101:0) after 1m10s
[03:20:31.816] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:101:0)
[03:20:31.845] Build status: STARTED
[03:20:31.845] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:102:0) after 1m50s
[03:22:21.864] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:102:0)
[03:22:21.905] Build status: STARTED
[03:22:21.905] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:103:0) after 6m27s
[03:28:49.349] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:103:0)
[03:28:49.464] Build status: STARTED
[03:28:49.464] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:104:0) after 9m55s
[03:38:45.066] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:104:0)
[03:38:45.105] Build status: STARTED
[03:38:45.105] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:105:0) after 1m48s
[03:40:33.149] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:105:0)
[03:40:33.183] Build status: STARTED
[03:40:33.183] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:106:0) after 5m21s
[03:45:54.205] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:106:0)
[03:45:54.239] Build status: STARTED
[03:45:54.239] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:107:0) after 2m18s
[03:48:12.261] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:107:0)
[03:48:12.372] Build status: STARTED
[03:48:12.372] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:108:0) after 1m42s
[03:49:55.888] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:108:0)
[03:49:55.918] Build status: STARTED
[03:49:55.918] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:109:0) after 1m51s
[03:51:47.055] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:109:0)
[03:51:47.092] Build status: STARTED
[03:51:47.092] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:110:0) after 5m28s
[03:57:15.270] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:110:0)
[03:57:15.303] Build status: STARTED
[03:57:15.303] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:111:0) after 7m52s
[04:05:07.323] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:111:0)
[04:05:07.356] Build status: STARTED
[04:05:07.356] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:112:0) after 7m6s
[04:12:13.461] Handling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:112:0)
[04:12:13.561] Build status: STARTED
[04:12:13.561] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:8993639747522685616:113:0) after 5m46s
[04:17:12.068] Received PubSub notification, asking Buildbucket for the build status
[04:17:12.097] Build:
{
  "id": "8828209685546547441",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "linux-internal"
  },
  "number": 2256,
  "createdBy": "project:r8",
  "createTime": "2021-12-10T18:16:25.253420087Z",
  "startTime": "2021-12-10T22:01:50.552923Z",
  "endTime": "2021-12-11T04:17:11.062972589Z",
  "updateTime": "2021-12-11T04:17:11.062972589Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "r8.googlesource.com",
      "project": "r8",
      "id": "8470bf53e47d6b42b89b7ef31015002869a283e5",
      "ref": "refs/heads/main"
    }
  },
  "canary": true
}
[04:17:12.097] Invocation finished in 10h0m48.112184724s with status SUCCEEDED