Status: SUCCEEDED
Triggered by: -
Duration: 5 hours
Actions:

Definition (rev 5ef313b610068c2546b37fe9e5221e7f42819a45)

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

Incoming triggers

Incoming properties

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

Incoming tags

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

Debug log (UTC)

[23:02:57.237] New invocation is queued and will start shortly
[23:02:58.312] Starting the invocation (attempt 1)
[23:02:58.358] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/c5e421f211bbc2dfb2bf2f9431f442af9fcaf89b"
[23:02:58.358] Popped gitiles commit info from properties and tags
[23:02:58.358] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:02:58.358] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:02:58.358] Buildbucket request:
{
  "requestId": "9003224997546201984",
  "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": "0193e8456a5bda89468d156108b17031b6798a05"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@0193e8456a5bda89468d156108b17031b6798a05",
                        "title": "0193e8456a5bda89468d156108b17031b6798a05",
                        "url": "https://r8.googlesource.com/r8/+/0193e8456a5bda89468d156108b17031b6798a05"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://r8.googlesource.com/r8",
                              "revision": "a1f2fb64547ccd4da1fb090952771dbbea8148f5"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@a1f2fb64547ccd4da1fb090952771dbbea8148f5",
                        "title": "a1f2fb64547ccd4da1fb090952771dbbea8148f5",
                        "url": "https://r8.googlesource.com/r8/+/a1f2fb64547ccd4da1fb090952771dbbea8148f5"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://r8.googlesource.com/r8",
                              "revision": "a2f11283005b6464fe818cf2484791169376717b"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@a2f11283005b6464fe818cf2484791169376717b",
                        "title": "a2f11283005b6464fe818cf2484791169376717b",
                        "url": "https://r8.googlesource.com/r8/+/a2f11283005b6464fe818cf2484791169376717b"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://r8.googlesource.com/r8",
                              "revision": "387e73d38d58f73020f2d2dc7bf52143b7655093"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@387e73d38d58f73020f2d2dc7bf52143b7655093",
                        "title": "387e73d38d58f73020f2d2dc7bf52143b7655093",
                        "url": "https://r8.googlesource.com/r8/+/387e73d38d58f73020f2d2dc7bf52143b7655093"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://r8.googlesource.com/r8",
                              "revision": "3b872685abcfc48266f881e1d36cfa15c2c7c816"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@3b872685abcfc48266f881e1d36cfa15c2c7c816",
                        "title": "3b872685abcfc48266f881e1d36cfa15c2c7c816",
                        "url": "https://r8.googlesource.com/r8/+/3b872685abcfc48266f881e1d36cfa15c2c7c816"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://r8.googlesource.com/r8",
                              "revision": "ff09af60a5e5dbe4fdf20c394dfc68c1047f341a"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@ff09af60a5e5dbe4fdf20c394dfc68c1047f341a",
                        "title": "ff09af60a5e5dbe4fdf20c394dfc68c1047f341a",
                        "url": "https://r8.googlesource.com/r8/+/ff09af60a5e5dbe4fdf20c394dfc68c1047f341a"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://r8.googlesource.com/r8",
                              "revision": "96514bde91d601be403e4c1dbcc024ad8184017d"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@96514bde91d601be403e4c1dbcc024ad8184017d",
                        "title": "96514bde91d601be403e4c1dbcc024ad8184017d",
                        "url": "https://r8.googlesource.com/r8/+/96514bde91d601be403e4c1dbcc024ad8184017d"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://r8.googlesource.com/r8",
                              "revision": "7b238284bce003cdb5b88076c05eb422bf4151ed"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@7b238284bce003cdb5b88076c05eb422bf4151ed",
                        "title": "7b238284bce003cdb5b88076c05eb422bf4151ed",
                        "url": "https://r8.googlesource.com/r8/+/7b238284bce003cdb5b88076c05eb422bf4151ed"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://r8.googlesource.com/r8",
                              "revision": "c5e421f211bbc2dfb2bf2f9431f442af9fcaf89b"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@c5e421f211bbc2dfb2bf2f9431f442af9fcaf89b",
                        "title": "c5e421f211bbc2dfb2bf2f9431f442af9fcaf89b",
                        "url": "https://r8.googlesource.com/r8/+/c5e421f211bbc2dfb2bf2f9431f442af9fcaf89b"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "r8.googlesource.com",
    "project": "r8",
    "id": "c5e421f211bbc2dfb2bf2f9431f442af9fcaf89b",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "9003224997546201984"
    },
    {
      "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"
  }
}
[23:02:58.947] Scheduled build:
{
  "id": "8837794935588367873",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "linux-internal"
  },
  "number": 1996,
  "createdBy": "project:r8",
  "createTime": "2021-08-26T23:02:58.472769187Z",
  "updateTime": "2021-08-26T23:02:58.693685592Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "r8.googlesource.com",
      "project": "r8",
      "id": "c5e421f211bbc2dfb2bf2f9431f442af9fcaf89b",
      "ref": "refs/heads/main"
    }
  }
}
[23:02:58.947] Task URL: https://cr-buildbucket.appspot.com/build/8837794935588367873
[23:02:58.947] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:2:0) after 2m51s
[23:05:50.124] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:2:0)
[23:05:50.174] Build status: SCHEDULED
[23:05:50.174] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:3:0) after 7m24s
[23:10:29.131] Received PubSub notification, asking Buildbucket for the build status
[23:10:29.170] Build status: STARTED
[23:13:14.293] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:3:0)
[23:13:14.316] Build status: STARTED
[23:13:14.316] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:5:0) after 5m44s
[23:18:58.349] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:5:0)
[23:18:58.381] Build status: STARTED
[23:18:58.381] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:6:0) after 1m39s
[23:20:37.441] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:6:0)
[23:20:37.484] Build status: STARTED
[23:20:37.484] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:7:0) after 1m4s
[23:21:41.477] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:7:0)
[23:21:41.505] Build status: STARTED
[23:21:41.505] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:8:0) after 2m18s
[23:23:59.759] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:8:0)
[23:23:59.787] Build status: STARTED
[23:23:59.787] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:9:0) after 4m1s
[23:28:00.884] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:9:0)
[23:28:00.918] Build status: STARTED
[23:28:00.918] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:10:0) after 1m52s
[23:29:52.978] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:10:0)
[23:29:53.009] Build status: STARTED
[23:29:53.009] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:11:0) after 3m30s
[23:33:23.173] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:11:0)
[23:33:23.202] Build status: STARTED
[23:33:23.202] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:12:0) after 5m57s
[23:39:20.220] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:12:0)
[23:39:20.259] Build status: STARTED
[23:39:20.259] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:13:0) after 3m23s
[23:42:43.307] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:13:0)
[23:42:43.342] Build status: STARTED
[23:42:43.342] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:14:0) after 1m11s
[23:43:54.744] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:14:0)
[23:43:54.779] Build status: STARTED
[23:43:54.779] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:15:0) after 3m45s
[23:47:39.855] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:15:0)
[23:47:39.885] Build status: STARTED
[23:47:39.885] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:16:0) after 2m29s
[23:50:08.906] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:16:0)
[23:50:09.041] Build status: STARTED
[23:50:09.041] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:17:0) after 6m58s
[23:57:07.242] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:17:0)
[23:57:07.283] Build status: STARTED
[23:57:07.283] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:18:0) after 8m37s
[00:05:44.346] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:18:0)
[00:05:44.372] Build status: STARTED
[00:05:44.372] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:19:0) after 2m35s
[00:08:19.399] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:19:0)
[00:08:19.482] Build status: STARTED
[00:08:19.482] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:20:0) after 5m56s
[00:14:15.540] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:20:0)
[00:14:15.775] Build status: STARTED
[00:14:15.775] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:21:0) after 6m41s
[00:20:57.006] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:21:0)
[00:20:57.039] Build status: STARTED
[00:20:57.039] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:22:0) after 8m58s
[00:29:55.162] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:22:0)
[00:29:55.294] Build status: STARTED
[00:29:55.294] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:23:0) after 9m42s
[00:39:37.473] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:23:0)
[00:39:37.511] Build status: STARTED
[00:39:37.511] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:24:0) after 6m15s
[00:45:52.529] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:24:0)
[00:45:52.562] Build status: STARTED
[00:45:52.562] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:25:0) after 4m24s
[00:50:16.567] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:25:0)
[00:50:16.611] Build status: STARTED
[00:50:16.611] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:26:0) after 5m31s
[00:55:47.819] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:26:0)
[00:55:47.857] Build status: STARTED
[00:55:47.857] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:27:0) after 9m35s
[01:05:23.052] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:27:0)
[01:05:23.176] Build status: STARTED
[01:05:23.176] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:28:0) after 5m48s
[01:11:11.201] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:28:0)
[01:11:11.232] Build status: STARTED
[01:11:11.232] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:29:0) after 4m4s
[01:15:15.383] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:29:0)
[01:15:15.575] Build status: STARTED
[01:15:15.575] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:30:0) after 8m25s
[01:23:40.767] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:30:0)
[01:23:40.800] Build status: STARTED
[01:23:40.800] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:31:0) after 4m21s
[01:28:01.838] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:31:0)
[01:28:01.881] Build status: STARTED
[01:28:01.881] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:32:0) after 4m41s
[01:32:43.349] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:32:0)
[01:32:43.394] Build status: STARTED
[01:32:43.394] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:33:0) after 2m21s
[01:35:04.464] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:33:0)
[01:35:04.496] Build status: STARTED
[01:35:04.496] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:34:0) after 5m13s
[01:40:17.738] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:34:0)
[01:40:17.788] Build status: STARTED
[01:40:17.788] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:35:0) after 5m31s
[01:45:48.826] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:35:0)
[01:45:48.858] Build status: STARTED
[01:45:48.858] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:36:0) after 5m48s
[01:51:36.995] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:36:0)
[01:51:37.028] Build status: STARTED
[01:51:37.028] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:37:0) after 1m30s
[01:53:07.165] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:37:0)
[01:53:07.192] Build status: STARTED
[01:53:07.192] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:38:0) after 7m43s
[02:00:50.215] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:38:0)
[02:00:50.243] Build status: STARTED
[02:00:50.243] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:39:0) after 4m28s
[02:05:18.311] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:39:0)
[02:05:18.343] Build status: STARTED
[02:05:18.343] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:40:0) after 8m4s
[02:13:22.489] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:40:0)
[02:13:22.516] Build status: STARTED
[02:13:22.517] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:41:0) after 6m40s
[02:20:02.554] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:41:0)
[02:20:02.594] Build status: STARTED
[02:20:02.594] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:42:0) after 4m22s
[02:24:24.883] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:42:0)
[02:24:24.924] Build status: STARTED
[02:24:24.924] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:43:0) after 5m26s
[02:29:51.042] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:43:0)
[02:29:51.073] Build status: STARTED
[02:29:51.073] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:44:0) after 9m39s
[02:39:30.139] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:44:0)
[02:39:30.190] Build status: STARTED
[02:39:30.190] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:45:0) after 5m37s
[02:45:07.369] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:45:0)
[02:45:07.413] Build status: STARTED
[02:45:07.413] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:46:0) after 7m24s
[02:52:31.470] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:46:0)
[02:52:31.507] Build status: STARTED
[02:52:31.507] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:47:0) after 5m57s
[02:58:28.573] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:47:0)
[02:58:28.612] Build status: STARTED
[02:58:28.612] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:48:0) after 3m45s
[03:02:13.629] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:48:0)
[03:02:13.684] Build status: STARTED
[03:02:13.684] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:49:0) after 7m22s
[03:09:35.835] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:49:0)
[03:09:35.875] Build status: STARTED
[03:09:35.875] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:50:0) after 5m29s
[03:15:05.103] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:50:0)
[03:15:05.136] Build status: STARTED
[03:15:05.136] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:51:0) after 4m0s
[03:19:05.540] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:51:0)
[03:19:05.568] Build status: STARTED
[03:19:05.568] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:52:0) after 5m55s
[03:25:01.385] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:52:0)
[03:25:02.416] Build status: STARTED
[03:25:02.416] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:53:0) after 5m26s
[03:30:28.511] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:53:0)
[03:30:28.549] Build status: STARTED
[03:30:28.549] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:54:0) after 5m13s
[03:35:41.531] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:54:0)
[03:35:41.561] Build status: STARTED
[03:35:41.562] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:55:0) after 8m23s
[03:44:04.586] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:55:0)
[03:44:04.623] Build status: STARTED
[03:44:04.623] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:56:0) after 9m31s
[03:53:35.812] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:56:0)
[03:53:35.841] Build status: STARTED
[03:53:35.841] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:57:0) after 2m11s
[03:55:46.914] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:57:0)
[03:55:46.939] Build status: STARTED
[03:55:46.939] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:58:0) after 5m35s
[04:01:22.047] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:58:0)
[04:01:22.082] Build status: STARTED
[04:01:22.082] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:59:0) after 7m51s
[04:09:13.104] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:59:0)
[04:09:13.151] Build status: STARTED
[04:09:13.151] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:60:0) after 1m33s
[04:10:46.136] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:60:0)
[04:10:46.171] Build status: STARTED
[04:10:46.171] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:61:0) after 7m37s
[04:18:23.257] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:61:0)
[04:18:23.296] Build status: STARTED
[04:18:23.296] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:62:0) after 7m7s
[04:25:30.373] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:62:0)
[04:25:30.402] Build status: STARTED
[04:25:30.402] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:63:0) after 4m43s
[04:30:13.427] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:63:0)
[04:30:13.494] Build status: STARTED
[04:30:13.494] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:64:0) after 2m6s
[04:32:19.609] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:64:0)
[04:32:19.662] Build status: STARTED
[04:32:19.662] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:65:0) after 6m45s
[04:39:04.826] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:65:0)
[04:39:04.874] Build status: STARTED
[04:39:04.874] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9003224997546201984:66:0) after 6m58s
[04:41:11.610] Received PubSub notification, asking Buildbucket for the build status
[04:41:11.641] Build:
{
  "id": "8837794935588367873",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "linux-internal"
  },
  "number": 1996,
  "createdBy": "project:r8",
  "createTime": "2021-08-26T23:02:58.472769187Z",
  "startTime": "2021-08-26T23:10:27.160015Z",
  "endTime": "2021-08-27T04:41:09.536559Z",
  "updateTime": "2021-08-27T04:41:10.278360Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "r8.googlesource.com",
      "project": "r8",
      "id": "c5e421f211bbc2dfb2bf2f9431f442af9fcaf89b",
      "ref": "refs/heads/main"
    }
  }
}
[04:41:11.641] Invocation finished in 5h38m14.419746322s with status SUCCEEDED