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

• 021c3f09703d17fec5022a007125dbf3e06a285e (3 years ago)

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

• buildset:commit/gitiles/r8.googlesource.com/r8/+/021c3f09703d17fec5022a007125dbf3e06a285e
• gitiles_ref:refs/heads/main

[17:41:28.312] New invocation is queued and will start shortly
[17:41:29.438] Starting the invocation (attempt 1)
[17:41:29.497] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/021c3f09703d17fec5022a007125dbf3e06a285e"
[17:41:29.497] Popped gitiles commit info from properties and tags
[17:41:29.497] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:41:29.497] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:41:29.497] Buildbucket request:
{
  "requestId": "9004060596146660304",
  "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": "021c3f09703d17fec5022a007125dbf3e06a285e"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@021c3f09703d17fec5022a007125dbf3e06a285e",
                        "title": "021c3f09703d17fec5022a007125dbf3e06a285e",
                        "url": "https://r8.googlesource.com/r8/+/021c3f09703d17fec5022a007125dbf3e06a285e"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "r8.googlesource.com",
    "project": "r8",
    "id": "021c3f09703d17fec5022a007125dbf3e06a285e",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "9004060596146660304"
    },
    {
      "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"
  }
}
[17:41:30.619] Scheduled build:
{
  "id": "8838630533931798096",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "linux-internal"
  },
  "number": 1977,
  "createdBy": "project:r8",
  "createTime": "2021-08-17T17:41:29.793401Z",
  "updateTime": "2021-08-17T17:41:30.464367Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "r8.googlesource.com",
      "project": "r8",
      "id": "021c3f09703d17fec5022a007125dbf3e06a285e",
      "ref": "refs/heads/main"
    }
  }
}
[17:41:30.619] Task URL: https://cr-buildbucket.appspot.com/build/8838630533931798096
[17:41:30.619] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:2:0) after 2m41s
[17:44:11.656] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:2:0)
[17:44:11.693] Build status: SCHEDULED
[17:44:11.693] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:3:0) after 6m39s
[17:50:50.765] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:3:0)
[17:50:50.793] Build status: SCHEDULED
[17:50:50.793] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:4:0) after 4m57s
[17:55:47.881] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:4:0)
[17:55:47.916] Build status: SCHEDULED
[17:55:47.916] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:5:0) after 4m3s
[17:59:51.293] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:5:0)
[17:59:51.350] Build status: SCHEDULED
[17:59:51.350] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:6:0) after 5m17s
[18:05:08.373] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:6:0)
[18:05:08.415] Build status: SCHEDULED
[18:05:08.415] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:7:0) after 5m41s
[18:10:49.511] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:7:0)
[18:10:49.540] Build status: SCHEDULED
[18:10:49.540] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:8:0) after 6m55s
[18:17:44.536] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:8:0)
[18:17:44.585] Build status: SCHEDULED
[18:17:44.585] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:9:0) after 3m34s
[18:21:18.911] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:9:0)
[18:21:18.989] Build status: SCHEDULED
[18:21:18.989] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:10:0) after 8m43s
[18:30:02.016] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:10:0)
[18:30:02.049] Build status: SCHEDULED
[18:30:02.049] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:11:0) after 8m42s
[18:38:44.067] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:11:0)
[18:38:44.098] Build status: SCHEDULED
[18:38:44.098] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:12:0) after 7m37s
[18:46:21.182] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:12:0)
[18:46:21.222] Build status: SCHEDULED
[18:46:21.222] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:13:0) after 5m15s
[18:51:36.406] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:13:0)
[18:51:36.440] Build status: SCHEDULED
[18:51:36.440] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:14:0) after 8m46s
[19:00:22.479] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:14:0)
[19:00:22.677] Build status: SCHEDULED
[19:00:22.678] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:15:0) after 2m34s
[19:02:56.659] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:15:0)
[19:02:56.693] Build status: SCHEDULED
[19:02:56.693] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:16:0) after 7m46s
[19:10:42.974] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:16:0)
[19:10:43.044] Build status: SCHEDULED
[19:10:43.044] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:17:0) after 7m13s
[19:17:56.312] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:17:0)
[19:17:56.367] Build status: SCHEDULED
[19:17:56.367] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:18:0) after 8m31s
[19:26:27.641] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:18:0)
[19:26:27.690] Build status: SCHEDULED
[19:26:27.690] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:19:0) after 8m53s
[19:35:20.674] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:19:0)
[19:35:20.732] Build status: SCHEDULED
[19:35:20.732] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:20:0) after 5m31s
[19:40:51.719] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:20:0)
[19:40:51.751] Build status: SCHEDULED
[19:40:51.751] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:21:0) after 2m27s
[19:43:18.769] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:21:0)
[19:43:18.839] Build status: SCHEDULED
[19:43:18.839] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:22:0) after 1m5s
[19:44:23.953] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:22:0)
[19:44:23.978] Build status: SCHEDULED
[19:44:23.979] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:23:0) after 7m32s
[19:51:56.044] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:23:0)
[19:51:56.086] Build status: SCHEDULED
[19:51:56.086] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:24:0) after 7m7s
[19:59:03.228] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:24:0)
[19:59:03.267] Build status: SCHEDULED
[19:59:03.267] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:25:0) after 5m37s
[20:04:40.290] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:25:0)
[20:04:40.320] Build status: SCHEDULED
[20:04:40.320] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:26:0) after 8m49s
[20:13:29.711] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:26:0)
[20:13:29.782] Build status: SCHEDULED
[20:13:29.782] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:27:0) after 6m52s
[20:20:21.892] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:27:0)
[20:20:21.935] Build status: SCHEDULED
[20:20:21.935] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:28:0) after 5m2s
[20:25:23.955] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:28:0)
[20:25:24.016] Build status: SCHEDULED
[20:25:24.016] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:29:0) after 8m43s
[20:34:07.036] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:29:0)
[20:34:07.085] Build status: SCHEDULED
[20:34:07.085] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:30:0) after 3m11s
[20:37:18.128] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:30:0)
[20:37:18.173] Build status: SCHEDULED
[20:37:18.173] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:31:0) after 3m43s
[20:41:01.588] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:31:0)
[20:41:01.619] Build status: SCHEDULED
[20:41:01.619] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:32:0) after 3m58s
[20:44:59.686] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:32:0)
[20:44:59.714] Build status: SCHEDULED
[20:44:59.715] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:33:0) after 6m51s
[20:51:50.844] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:33:0)
[20:51:50.874] Build status: SCHEDULED
[20:51:50.874] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:34:0) after 8m26s
[21:00:17.440] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:34:0)
[21:00:17.478] Build status: SCHEDULED
[21:00:17.478] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:35:0) after 1m1s
[21:01:18.498] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:35:0)
[21:01:18.537] Build status: SCHEDULED
[21:01:18.537] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:36:0) after 5m46s
[21:07:04.871] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:36:0)
[21:07:04.906] Build status: SCHEDULED
[21:07:04.906] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:37:0) after 1m29s
[21:08:33.924] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:37:0)
[21:08:33.954] Build status: SCHEDULED
[21:08:33.954] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:38:0) after 4m46s
[21:13:20.111] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:38:0)
[21:13:20.142] Build status: SCHEDULED
[21:13:20.142] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:39:0) after 3m58s
[21:17:18.549] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:39:0)
[21:17:18.583] Build status: SCHEDULED
[21:17:18.583] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:40:0) after 1m47s
[21:19:05.730] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:40:0)
[21:19:05.843] Build status: SCHEDULED
[21:19:05.843] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:41:0) after 3m17s
[21:22:22.850] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:41:0)
[21:22:22.895] Build status: SCHEDULED
[21:22:22.895] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:42:0) after 6m15s
[21:28:37.971] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:42:0)
[21:28:38.006] Build status: SCHEDULED
[21:28:38.006] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:43:0) after 1m17s
[21:29:54.986] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:43:0)
[21:29:55.071] Build status: SCHEDULED
[21:29:55.071] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:44:0) after 4m49s
[21:34:44.124] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:44:0)
[21:34:44.161] Build status: SCHEDULED
[21:34:44.162] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:45:0) after 3m52s
[21:38:36.183] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:45:0)
[21:38:36.266] Build status: SCHEDULED
[21:38:36.266] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:46:0) after 2m27s
[21:41:03.297] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:46:0)
[21:41:03.329] Build status: SCHEDULED
[21:41:03.330] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:47:0) after 4m31s
[21:45:34.629] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:47:0)
[21:45:34.678] Build status: SCHEDULED
[21:45:34.678] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:48:0) after 8m28s
[21:54:02.701] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:48:0)
[21:54:02.732] Build status: SCHEDULED
[21:54:02.732] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:49:0) after 8m31s
[22:02:33.756] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:49:0)
[22:02:33.912] Build status: SCHEDULED
[22:02:33.912] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:50:0) after 9m10s
[22:11:43.977] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:50:0)
[22:11:44.022] Build status: SCHEDULED
[22:11:44.022] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:51:0) after 6m7s
[22:17:51.259] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:51:0)
[22:17:51.288] Build status: SCHEDULED
[22:17:51.288] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:52:0) after 4m17s
[22:22:08.457] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:52:0)
[22:22:08.550] Build status: SCHEDULED
[22:22:08.550] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:53:0) after 3m27s
[22:25:35.672] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:53:0)
[22:25:35.699] Build status: SCHEDULED
[22:25:35.699] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:54:0) after 6m39s
[22:32:14.898] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:54:0)
[22:32:14.924] Build status: SCHEDULED
[22:32:14.924] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:55:0) after 9m49s
[22:42:04.176] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:55:0)
[22:42:04.219] Build status: SCHEDULED
[22:42:04.219] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:56:0) after 5m48s
[22:47:52.248] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:56:0)
[22:47:52.287] Build status: SCHEDULED
[22:47:52.287] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:57:0) after 2m45s
[22:50:37.440] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:57:0)
[22:50:37.470] Build status: SCHEDULED
[22:50:37.470] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:58:0) after 3m25s
[22:54:02.888] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:58:0)
[22:54:02.927] Build status: SCHEDULED
[22:54:02.927] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:59:0) after 3m52s
[22:57:55.080] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:59:0)
[22:57:55.107] Build status: SCHEDULED
[22:57:55.107] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:60:0) after 9m12s
[23:07:07.151] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:60:0)
[23:07:07.178] Build status: SCHEDULED
[23:07:07.178] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:61:0) after 8m13s
[23:15:20.305] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:61:0)
[23:15:20.340] Build status: SCHEDULED
[23:15:20.340] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:62:0) after 1m49s
[23:17:09.562] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:62:0)
[23:17:09.596] Build status: SCHEDULED
[23:17:09.596] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:63:0) after 5m40s
[23:22:49.629] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:63:0)
[23:22:49.668] Build status: SCHEDULED
[23:22:49.668] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:64:0) after 5m22s
[23:25:37.011] Received PubSub notification, asking Buildbucket for the build status
[23:25:37.041] Build status: STARTED
[23:28:11.748] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:64:0)
[23:28:11.786] Build status: STARTED
[23:28:11.786] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:66:0) after 5m33s
[23:33:44.924] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:66:0)
[23:33:44.967] Build status: STARTED
[23:33:44.967] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:67:0) after 2m5s
[23:35:49.977] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:67:0)
[23:35:50.005] Build status: STARTED
[23:35:50.005] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:68:0) after 6m25s
[23:42:15.082] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:68:0)
[23:42:15.108] Build status: STARTED
[23:42:15.108] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:69:0) after 3m26s
[23:45:41.092] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:69:0)
[23:45:41.123] Build status: STARTED
[23:45:41.123] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:70:0) after 5m44s
[23:51:25.209] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:70:0)
[23:51:25.245] Build status: STARTED
[23:51:25.245] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:71:0) after 1m53s
[23:53:18.470] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:71:0)
[23:53:18.499] Build status: STARTED
[23:53:18.499] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:72:0) after 5m58s
[23:59:16.517] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:72:0)
[23:59:16.571] Build status: STARTED
[23:59:16.571] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:73:0) after 8m22s
[00:07:38.915] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:73:0)
[00:07:38.977] Build status: STARTED
[00:07:38.977] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:74:0) after 8m17s
[00:15:56.062] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:74:0)
[00:15:56.092] Build status: STARTED
[00:15:56.092] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:75:0) after 5m35s
[00:21:31.177] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:75:0)
[00:21:31.214] Build status: STARTED
[00:21:31.214] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:76:0) after 4m54s
[00:26:25.244] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:76:0)
[00:26:25.274] Build status: STARTED
[00:26:25.274] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:77:0) after 6m16s
[00:32:41.491] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:77:0)
[00:32:41.534] Build status: STARTED
[00:32:41.534] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:78:0) after 6m10s
[00:38:51.687] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:78:0)
[00:38:51.720] Build status: STARTED
[00:38:51.720] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:79:0) after 9m50s
[00:48:41.760] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:79:0)
[00:48:41.807] Build status: STARTED
[00:48:41.807] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:80:0) after 3m35s
[00:52:16.824] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:80:0)
[00:52:16.855] Build status: STARTED
[00:52:16.855] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:81:0) after 2m22s
[00:54:39.043] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:81:0)
[00:54:39.074] Build status: STARTED
[00:54:39.074] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:82:0) after 5m31s
[01:00:10.094] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:82:0)
[01:00:10.124] Build status: STARTED
[01:00:10.124] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:83:0) after 4m38s
[01:04:48.195] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:83:0)
[01:04:48.233] Build status: STARTED
[01:04:48.233] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:84:0) after 8m37s
[01:13:25.407] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:84:0)
[01:13:25.442] Build status: STARTED
[01:13:25.442] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:85:0) after 2m57s
[01:16:22.718] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:85:0)
[01:16:22.751] Build status: STARTED
[01:16:22.751] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:86:0) after 6m13s
[01:22:35.796] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:86:0)
[01:22:35.826] Build status: STARTED
[01:22:35.826] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:87:0) after 8m50s
[01:31:25.861] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:87:0)
[01:31:25.892] Build status: STARTED
[01:31:25.892] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:88:0) after 9m48s
[01:41:14.070] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:88:0)
[01:41:14.096] Build status: STARTED
[01:41:14.096] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:89:0) after 6m22s
[01:47:36.267] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:89:0)
[01:47:36.298] Build status: STARTED
[01:47:36.298] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:90:0) after 9m39s
[01:57:15.282] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:90:0)
[01:57:15.314] Build status: STARTED
[01:57:15.314] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:91:0) after 8m3s
[02:05:18.334] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:91:0)
[02:05:18.380] Build status: STARTED
[02:05:18.380] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:92:0) after 1m18s
[02:06:36.403] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:92:0)
[02:06:36.433] Build status: STARTED
[02:06:36.433] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:93:0) after 4m39s
[02:11:15.670] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:93:0)
[02:11:15.700] Build status: STARTED
[02:11:15.700] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:94:0) after 9m14s
[02:20:30.021] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:94:0)
[02:20:30.081] Build status: STARTED
[02:20:30.081] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:95:0) after 4m36s
[02:25:06.104] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:95:0)
[02:25:06.147] Build status: STARTED
[02:25:06.147] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:96:0) after 3m28s
[02:28:34.411] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:96:0)
[02:28:34.438] Build status: STARTED
[02:28:34.438] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:97:0) after 6m8s
[02:34:42.657] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:97:0)
[02:34:42.691] Build status: STARTED
[02:34:42.691] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:98:0) after 5m22s
[02:40:04.712] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:98:0)
[02:40:04.781] Build status: STARTED
[02:40:04.781] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:99:0) after 6m23s
[02:46:27.801] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:99:0)
[02:46:27.837] Build status: STARTED
[02:46:27.837] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:100:0) after 4m57s
[02:51:25.175] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:100:0)
[02:51:25.213] Build status: STARTED
[02:51:25.213] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:101:0) after 7m5s
[02:58:30.334] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:101:0)
[02:58:30.362] Build status: STARTED
[02:58:30.362] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:102:0) after 5m6s
[03:03:36.451] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:102:0)
[03:03:36.481] Build status: STARTED
[03:03:36.481] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:103:0) after 8m6s
[03:11:42.603] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:103:0)
[03:11:42.633] Build status: STARTED
[03:11:42.633] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:104:0) after 7m16s
[03:18:59.240] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:104:0)
[03:18:59.266] Build status: STARTED
[03:18:59.266] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:105:0) after 5m37s
[03:24:36.287] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:105:0)
[03:24:36.512] Build status: STARTED
[03:24:36.512] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:106:0) after 6m15s
[03:30:51.701] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:106:0)
[03:30:51.750] Build status: STARTED
[03:30:51.750] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:107:0) after 9m36s
[03:40:27.775] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:107:0)
[03:40:27.807] Build status: STARTED
[03:40:27.807] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:108:0) after 8m45s
[03:49:12.932] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:108:0)
[03:49:12.963] Build status: STARTED
[03:49:12.963] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:109:0) after 5m49s
[03:55:02.086] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:109:0)
[03:55:02.150] Build status: STARTED
[03:55:02.150] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:110:0) after 2m33s
[03:57:35.246] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:110:0)
[03:57:35.275] Build status: STARTED
[03:57:35.275] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9004060596146660304:111:0) after 9m54s
[04:04:22.287] Received PubSub notification, asking Buildbucket for the build status
[04:04:22.323] Build:
{
  "id": "8838630533931798096",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "linux-internal"
  },
  "number": 1977,
  "createdBy": "project:r8",
  "createTime": "2021-08-17T17:41:29.793401Z",
  "startTime": "2021-08-17T23:25:36.110409Z",
  "endTime": "2021-08-18T04:04:21.574675Z",
  "updateTime": "2021-08-18T04:04:22.057750Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "r8.googlesource.com",
      "project": "r8",
      "id": "021c3f09703d17fec5022a007125dbf3e06a285e",
      "ref": "refs/heads/main"
    }
  }
}
[04:04:22.323] Invocation finished in 10h22m54.025259364s with status SUCCEEDED