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

Definition (rev 87e0f3400b0aac78b81573f56efb8257c68089b2)

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

Incoming triggers

Incoming properties

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

Incoming tags

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

Debug log (UTC)

[13:02:39.055] New invocation is queued and will start shortly
[13:02:41.208] Starting the invocation (attempt 1)
[13:02:41.235] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/4b43e8a2636fbab67424d5e62a31719882c800a4"
[13:02:41.235] Popped gitiles commit info from properties and tags
[13:02:41.235] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:02:41.235] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:02:41.235] Buildbucket request:
{
  "requestId": "8905146250730956288",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "win"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8905146250730956288",
            "job": "r8/win",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://r8.googlesource.com/r8",
                              "revision": "e94e5b974554abd27cd1c89e1fcf04203e95f616"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@e94e5b974554abd27cd1c89e1fcf04203e95f616",
                        "title": "e94e5b974554abd27cd1c89e1fcf04203e95f616",
                        "url": "https://r8.googlesource.com/r8/+/e94e5b974554abd27cd1c89e1fcf04203e95f616"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://r8.googlesource.com/r8",
                              "revision": "5cfba788e3f1f9891f34d9f64e8db3fd8424a126"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@5cfba788e3f1f9891f34d9f64e8db3fd8424a126",
                        "title": "5cfba788e3f1f9891f34d9f64e8db3fd8424a126",
                        "url": "https://r8.googlesource.com/r8/+/5cfba788e3f1f9891f34d9f64e8db3fd8424a126"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://r8.googlesource.com/r8",
                              "revision": "4b43e8a2636fbab67424d5e62a31719882c800a4"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@4b43e8a2636fbab67424d5e62a31719882c800a4",
                        "title": "4b43e8a2636fbab67424d5e62a31719882c800a4",
                        "url": "https://r8.googlesource.com/r8/+/4b43e8a2636fbab67424d5e62a31719882c800a4"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "r8.googlesource.com",
    "project": "r8",
    "id": "4b43e8a2636fbab67424d5e62a31719882c800a4",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8905146250730956288"
    },
    {
      "key": "scheduler_job_id",
      "value": "r8/win"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[13:02:41.690] Scheduled build:
{
  "id": "8739716187717648593",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "win"
  },
  "number": 131,
  "createdBy": "project:r8",
  "createTime": "2024-08-13T13:02:41.301342649Z",
  "updateTime": "2024-08-13T13:02:41.301342649Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "r8.googlesource.com",
      "project": "r8",
      "id": "4b43e8a2636fbab67424d5e62a31719882c800a4",
      "ref": "refs/heads/main"
    }
  }
}
[13:02:41.690] Task URL: https://cr-buildbucket.appspot.com/build/8739716187717648593
[13:02:41.690] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:2:0) after 8m59s
[13:02:42.732] Received PubSub notification, asking Buildbucket for the build status
[13:02:42.758] Build status: SCHEDULED
[13:11:40.755] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:2:0)
[13:11:40.788] Build status: SCHEDULED
[13:11:40.788] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:4:0) after 3m40s
[13:15:20.819] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:4:0)
[13:15:20.838] Build status: SCHEDULED
[13:15:20.838] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:5:0) after 1m43s
[13:17:04.140] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:5:0)
[13:17:04.220] Build status: SCHEDULED
[13:17:04.220] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:6:0) after 4m25s
[13:21:29.290] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:6:0)
[13:21:29.322] Build status: SCHEDULED
[13:21:29.322] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:7:0) after 5m6s
[13:26:35.415] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:7:0)
[13:26:35.439] Build status: SCHEDULED
[13:26:35.439] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:8:0) after 4m8s
[13:30:43.527] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:8:0)
[13:30:43.623] Build status: SCHEDULED
[13:30:43.624] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:9:0) after 3m36s
[13:34:19.676] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:9:0)
[13:34:19.708] Build status: SCHEDULED
[13:34:19.708] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:10:0) after 7m6s
[13:41:25.745] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:10:0)
[13:41:25.767] Build status: SCHEDULED
[13:41:25.767] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:11:0) after 2m16s
[13:43:41.950] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:11:0)
[13:43:41.983] Build status: SCHEDULED
[13:43:41.983] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:12:0) after 8m51s
[13:52:33.099] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:12:0)
[13:52:33.145] Build status: SCHEDULED
[13:52:33.145] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:13:0) after 9m19s
[14:01:52.347] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:13:0)
[14:01:52.366] Build status: SCHEDULED
[14:01:52.367] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:14:0) after 8m52s
[14:10:44.386] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:14:0)
[14:10:44.429] Build status: SCHEDULED
[14:10:44.429] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:15:0) after 1m35s
[14:12:19.569] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:15:0)
[14:12:19.588] Build status: SCHEDULED
[14:12:19.588] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:16:0) after 4m14s
[14:16:33.680] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:16:0)
[14:16:33.710] Build status: SCHEDULED
[14:16:33.710] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:17:0) after 5m50s
[14:22:23.723] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:17:0)
[14:22:23.753] Build status: SCHEDULED
[14:22:23.753] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:18:0) after 1m31s
[14:23:54.769] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:18:0)
[14:23:54.800] Build status: SCHEDULED
[14:23:54.800] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:19:0) after 5m8s
[14:29:02.944] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:19:0)
[14:29:02.968] Build status: SCHEDULED
[14:29:02.968] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:20:0) after 6m18s
[14:35:20.982] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:20:0)
[14:35:21.041] Build status: SCHEDULED
[14:35:21.041] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:21:0) after 7m30s
[14:42:51.111] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:21:0)
[14:42:51.131] Build status: SCHEDULED
[14:42:51.131] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:22:0) after 3m57s
[14:46:48.148] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:22:0)
[14:46:48.169] Build status: SCHEDULED
[14:46:48.169] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:23:0) after 5m45s
[14:52:33.236] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:23:0)
[14:52:33.262] Build status: SCHEDULED
[14:52:33.263] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:24:0) after 4m36s
[14:57:09.351] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:24:0)
[14:57:09.372] Build status: SCHEDULED
[14:57:09.372] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:25:0) after 2m39s
[14:59:48.486] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:25:0)
[14:59:48.518] Build status: SCHEDULED
[14:59:48.518] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:26:0) after 4m52s
[15:04:40.559] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:26:0)
[15:04:40.581] Build status: SCHEDULED
[15:04:40.581] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:27:0) after 6m18s
[15:10:58.607] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:27:0)
[15:10:58.638] Build status: SCHEDULED
[15:10:58.639] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:28:0) after 9m10s
[15:20:08.665] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:28:0)
[15:20:08.684] Build status: SCHEDULED
[15:20:08.684] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:29:0) after 5m26s
[15:25:34.751] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:29:0)
[15:25:34.773] Build status: SCHEDULED
[15:25:34.773] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:30:0) after 4m44s
[15:30:18.793] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:30:0)
[15:30:18.856] Build status: SCHEDULED
[15:30:18.856] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:31:0) after 7m24s
[15:37:42.930] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:31:0)
[15:37:42.952] Build status: SCHEDULED
[15:37:42.952] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:32:0) after 1m2s
[15:38:44.967] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:32:0)
[15:38:44.990] Build status: SCHEDULED
[15:38:44.990] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:33:0) after 6m58s
[15:45:43.016] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:33:0)
[15:45:43.049] Build status: SCHEDULED
[15:45:43.049] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:34:0) after 5m59s
[15:51:42.072] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:34:0)
[15:51:42.154] Build status: SCHEDULED
[15:51:42.154] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:35:0) after 6m30s
[15:52:55.191] Received PubSub notification, asking Buildbucket for the build status
[15:52:55.212] Build status: STARTED
[15:58:12.179] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:35:0)
[15:58:12.209] Build status: STARTED
[15:58:12.210] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:37:0) after 8m30s
[16:06:42.341] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:37:0)
[16:06:42.368] Build status: STARTED
[16:06:42.368] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:38:0) after 3m44s
[16:10:26.447] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:38:0)
[16:10:26.467] Build status: STARTED
[16:10:26.467] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:39:0) after 4m25s
[16:14:51.496] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:39:0)
[16:14:51.517] Build status: STARTED
[16:14:51.517] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:40:0) after 9m19s
[16:24:10.537] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:40:0)
[16:24:10.564] Build status: STARTED
[16:24:10.564] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:41:0) after 8m18s
[16:32:28.593] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:41:0)
[16:32:28.627] Build status: STARTED
[16:32:28.627] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:42:0) after 3m29s
[16:35:57.652] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:42:0)
[16:35:57.674] Build status: STARTED
[16:35:57.674] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:43:0) after 8m43s
[16:44:40.810] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:43:0)
[16:44:40.829] Build status: STARTED
[16:44:40.829] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:44:0) after 5m16s
[16:49:56.831] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:44:0)
[16:49:56.851] Build status: STARTED
[16:49:56.851] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:45:0) after 1m45s
[16:51:41.997] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:45:0)
[16:51:42.022] Build status: STARTED
[16:51:42.022] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:46:0) after 9m17s
[17:00:59.047] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:46:0)
[17:00:59.079] Build status: STARTED
[17:00:59.079] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:47:0) after 8m19s
[17:09:18.187] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:47:0)
[17:09:18.256] Build status: STARTED
[17:09:18.256] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:48:0) after 7m36s
[17:16:54.343] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:48:0)
[17:16:54.364] Build status: STARTED
[17:16:54.364] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:49:0) after 4m48s
[17:21:42.454] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:49:0)
[17:21:42.471] Build status: STARTED
[17:21:42.471] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:50:0) after 1m38s
[17:23:20.511] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:50:0)
[17:23:20.553] Build status: STARTED
[17:23:20.553] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:51:0) after 9m26s
[17:32:46.576] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:51:0)
[17:32:46.595] Build status: STARTED
[17:32:46.595] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:52:0) after 2m14s
[17:35:00.754] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:52:0)
[17:35:00.772] Build status: STARTED
[17:35:00.772] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:53:0) after 5m49s
[17:40:49.746] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:53:0)
[17:40:49.787] Build status: STARTED
[17:40:49.787] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:54:0) after 1m0s
[17:41:49.807] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:54:0)
[17:41:49.830] Build status: STARTED
[17:41:49.830] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:55:0) after 7m3s
[17:48:52.867] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:55:0)
[17:48:52.924] Build status: STARTED
[17:48:52.924] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:56:0) after 2m20s
[17:51:12.954] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:56:0)
[17:51:12.977] Build status: STARTED
[17:51:12.977] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:57:0) after 4m27s
[17:55:40.046] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:57:0)
[17:55:40.072] Build status: STARTED
[17:55:40.072] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:58:0) after 8m53s
[18:04:33.119] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:58:0)
[18:04:33.166] Build status: STARTED
[18:04:33.166] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:59:0) after 1m56s
[18:06:29.247] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:59:0)
[18:06:29.269] Build status: STARTED
[18:06:29.269] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:60:0) after 1m36s
[18:08:05.286] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:60:0)
[18:08:05.305] Build status: STARTED
[18:08:05.305] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:61:0) after 5m2s
[18:13:07.330] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:61:0)
[18:13:07.371] Build status: STARTED
[18:13:07.371] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:62:0) after 4m10s
[18:17:17.386] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:62:0)
[18:17:17.406] Build status: STARTED
[18:17:17.406] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:63:0) after 4m24s
[18:21:41.392] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:63:0)
[18:21:41.431] Build status: STARTED
[18:21:41.431] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:64:0) after 7m51s
[18:29:32.437] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:64:0)
[18:29:32.457] Build status: STARTED
[18:29:32.457] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:65:0) after 6m3s
[18:35:35.486] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:65:0)
[18:35:35.505] Build status: STARTED
[18:35:35.505] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:66:0) after 5m54s
[18:41:29.572] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:66:0)
[18:41:29.597] Build status: STARTED
[18:41:29.597] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:67:0) after 6m39s
[18:48:10.843] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:67:0)
[18:48:10.861] Build status: STARTED
[18:48:10.861] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:68:0) after 1m11s
[18:49:21.883] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:68:0)
[18:49:21.947] Build status: STARTED
[18:49:21.947] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:69:0) after 9m30s
[18:58:51.969] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:69:0)
[18:58:52.038] Build status: STARTED
[18:58:52.038] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:70:0) after 3m33s
[19:02:25.113] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:70:0)
[19:02:25.131] Build status: STARTED
[19:02:25.131] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:71:0) after 5m28s
[19:07:53.203] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:71:0)
[19:07:53.239] Build status: STARTED
[19:07:53.239] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:72:0) after 7m54s
[19:15:47.338] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:72:0)
[19:15:47.370] Build status: STARTED
[19:15:47.371] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:73:0) after 8m29s
[19:24:16.440] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:73:0)
[19:24:16.458] Build status: STARTED
[19:24:16.458] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:74:0) after 2m58s
[19:27:14.545] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:74:0)
[19:27:14.566] Build status: STARTED
[19:27:14.567] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:75:0) after 1m46s
[19:29:00.575] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:75:0)
[19:29:00.595] Build status: STARTED
[19:29:00.595] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:76:0) after 5m14s
[19:34:14.661] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:76:0)
[19:34:14.692] Build status: STARTED
[19:34:14.692] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:77:0) after 8m45s
[19:42:59.760] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:77:0)
[19:42:59.793] Build status: STARTED
[19:42:59.793] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:78:0) after 7m44s
[19:50:43.796] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:78:0)
[19:50:43.832] Build status: STARTED
[19:50:43.832] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:79:0) after 5m30s
[19:56:13.918] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:79:0)
[19:56:13.956] Build status: STARTED
[19:56:13.956] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:80:0) after 4m59s
[20:01:12.989] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:80:0)
[20:01:13.018] Build status: STARTED
[20:01:13.018] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:81:0) after 8m31s
[20:09:44.046] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:81:0)
[20:09:44.070] Build status: STARTED
[20:09:44.070] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:82:0) after 1m54s
[20:11:38.098] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:82:0)
[20:11:38.119] Build status: STARTED
[20:11:38.119] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:83:0) after 4m16s
[20:15:54.187] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:83:0)
[20:15:54.204] Build status: STARTED
[20:15:54.204] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:84:0) after 2m40s
[20:18:34.230] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:84:0)
[20:18:34.266] Build status: STARTED
[20:18:34.266] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:85:0) after 8m14s
[20:26:48.286] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:85:0)
[20:26:48.325] Build status: STARTED
[20:26:48.325] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:86:0) after 1m44s
[20:28:32.350] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:86:0)
[20:28:32.402] Build status: STARTED
[20:28:32.402] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:87:0) after 6m37s
[20:35:09.458] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:87:0)
[20:35:09.517] Build status: STARTED
[20:35:09.517] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:88:0) after 1m57s
[20:37:06.550] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:88:0)
[20:37:06.577] Build status: STARTED
[20:37:06.577] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:89:0) after 8m1s
[20:45:07.593] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:89:0)
[20:45:07.609] Build status: STARTED
[20:45:07.609] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:90:0) after 6m45s
[20:51:52.629] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:90:0)
[20:51:52.645] Build status: STARTED
[20:51:52.645] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:91:0) after 2m49s
[20:54:41.665] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:91:0)
[20:54:41.691] Build status: STARTED
[20:54:41.691] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:92:0) after 3m59s
[20:58:40.697] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:92:0)
[20:58:40.720] Build status: STARTED
[20:58:40.720] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:93:0) after 4m48s
[21:03:28.745] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:93:0)
[21:03:28.824] Build status: STARTED
[21:03:28.824] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:94:0) after 1m4s
[21:04:32.847] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:94:0)
[21:04:32.884] Build status: STARTED
[21:04:32.884] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:95:0) after 2m19s
[21:06:51.916] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:95:0)
[21:06:51.944] Build status: STARTED
[21:06:51.944] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:96:0) after 2m56s
[21:09:48.013] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:96:0)
[21:09:48.042] Build status: STARTED
[21:09:48.042] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:97:0) after 6m4s
[21:15:52.099] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:97:0)
[21:15:52.118] Build status: STARTED
[21:15:52.118] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:98:0) after 2m38s
[21:18:30.187] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:98:0)
[21:18:30.232] Build status: STARTED
[21:18:30.232] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:99:0) after 3m23s
[21:21:53.459] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:99:0)
[21:21:53.478] Build status: STARTED
[21:21:53.478] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:100:0) after 3m38s
[21:25:31.502] Handling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:100:0)
[21:25:31.531] Build status: STARTED
[21:25:31.531] Scheduling timer "check-buildbucket-build-status" (r8/win:8905146250730956288:101:0) after 6m59s
[21:28:21.644] Received PubSub notification, asking Buildbucket for the build status
[21:28:21.696] Build:
{
  "id": "8739716187717648593",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "win"
  },
  "number": 131,
  "createdBy": "project:r8",
  "createTime": "2024-08-13T13:02:41.301342649Z",
  "startTime": "2024-08-13T15:52:54.618822316Z",
  "endTime": "2024-08-13T21:28:21.348621974Z",
  "updateTime": "2024-08-13T21:28:21.348621974Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "r8.googlesource.com",
      "project": "r8",
      "id": "4b43e8a2636fbab67424d5e62a31719882c800a4",
      "ref": "refs/heads/main"
    }
  }
}
[21:28:21.696] Invocation finished in 8h25m42.652213932s with status SUCCEEDED