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

• 4cbf700c98a4e5d79d03633ff280f9338ec4935b (2 weeks ago)

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

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

[20:31:04.846] New invocation is queued and will start shortly
[20:31:06.069] Starting the invocation (attempt 1)
[20:31:06.103] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/4cbf700c98a4e5d79d03633ff280f9338ec4935b"
[20:31:06.103] Popped gitiles commit info from properties and tags
[20:31:06.103] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:31:06.103] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:31:06.103] Buildbucket request:
{
  "requestId": "8889354165814456144",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "win"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8889354165814456144",
            "job": "r8/win",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://r8.googlesource.com/r8",
                              "revision": "4cbf700c98a4e5d79d03633ff280f9338ec4935b"
                            },
                        "id": "https://r8.googlesource.com/r8/+/refs/heads/main@4cbf700c98a4e5d79d03633ff280f9338ec4935b",
                        "title": "4cbf700c98a4e5d79d03633ff280f9338ec4935b",
                        "url": "https://r8.googlesource.com/r8/+/4cbf700c98a4e5d79d03633ff280f9338ec4935b"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "r8.googlesource.com",
    "project": "r8",
    "id": "4cbf700c98a4e5d79d03633ff280f9338ec4935b",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8889354165814456144"
    },
    {
      "key": "scheduler_job_id",
      "value": "r8/win"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[20:31:06.707] Scheduled build:
{
  "id": "8723924103700331841",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "win"
  },
  "number": 539,
  "createdBy": "project:r8",
  "createTime": "2025-02-03T20:31:06.231063167Z",
  "updateTime": "2025-02-03T20:31:06.231063167Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "r8.googlesource.com",
      "project": "r8",
      "id": "4cbf700c98a4e5d79d03633ff280f9338ec4935b",
      "ref": "refs/heads/main"
    }
  }
}
[20:31:06.707] Task URL: https://cr-buildbucket.appspot.com/build/8723924103700331841
[20:31:06.707] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:2:0) after 4m11s
[20:31:07.691] Received PubSub notification, asking Buildbucket for the build status
[20:31:07.712] Build status: SCHEDULED
[20:35:17.705] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:2:0)
[20:35:17.741] Build status: SCHEDULED
[20:35:17.741] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:4:0) after 9m2s
[20:44:19.761] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:4:0)
[20:44:19.829] Build status: SCHEDULED
[20:44:19.829] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:5:0) after 4m58s
[20:49:17.850] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:5:0)
[20:49:17.876] Build status: SCHEDULED
[20:49:17.876] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:6:0) after 8m12s
[20:57:29.977] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:6:0)
[20:57:30.008] Build status: SCHEDULED
[20:57:30.008] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:7:0) after 7m8s
[21:04:38.073] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:7:0)
[21:04:38.110] Build status: SCHEDULED
[21:04:38.110] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:8:0) after 6m38s
[21:11:16.147] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:8:0)
[21:11:16.181] Build status: SCHEDULED
[21:11:16.181] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:9:0) after 3m42s
[21:14:58.242] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:9:0)
[21:14:58.270] Build status: SCHEDULED
[21:14:58.270] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:10:0) after 1m54s
[21:16:52.254] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:10:0)
[21:16:52.330] Build status: SCHEDULED
[21:16:52.330] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:11:0) after 9m17s
[21:26:09.456] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:11:0)
[21:26:09.484] Build status: SCHEDULED
[21:26:09.484] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:12:0) after 9m40s
[21:35:49.575] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:12:0)
[21:35:49.650] Build status: SCHEDULED
[21:35:49.650] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:13:0) after 6m35s
[21:42:24.760] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:13:0)
[21:42:24.793] Build status: SCHEDULED
[21:42:24.793] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:14:0) after 6m38s
[21:49:02.912] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:14:0)
[21:49:02.977] Build status: SCHEDULED
[21:49:02.977] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:15:0) after 3m2s
[21:52:05.023] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:15:0)
[21:52:05.113] Build status: SCHEDULED
[21:52:05.113] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:16:0) after 8m7s
[21:56:03.409] Received PubSub notification, asking Buildbucket for the build status
[21:56:03.430] Build status: STARTED
[22:00:12.145] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:16:0)
[22:00:12.167] Build status: STARTED
[22:00:12.167] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:18:0) after 4m23s
[22:04:35.199] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:18:0)
[22:04:35.625] Build status: STARTED
[22:04:35.625] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:19:0) after 1m23s
[22:05:58.647] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:19:0)
[22:05:58.669] Build status: STARTED
[22:05:58.669] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:20:0) after 7m14s
[22:13:12.684] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:20:0)
[22:13:12.712] Build status: STARTED
[22:13:12.712] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:21:0) after 9m49s
[22:23:01.733] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:21:0)
[22:23:01.778] Build status: STARTED
[22:23:01.778] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:22:0) after 6m4s
[22:29:05.848] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:22:0)
[22:29:05.871] Build status: STARTED
[22:29:05.871] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:23:0) after 4m42s
[22:33:47.896] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:23:0)
[22:33:47.920] Build status: STARTED
[22:33:47.920] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:24:0) after 5m2s
[22:38:49.945] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:24:0)
[22:38:49.961] Build status: STARTED
[22:38:49.961] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:25:0) after 5m18s
[22:44:07.986] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:25:0)
[22:44:08.009] Build status: STARTED
[22:44:08.009] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:26:0) after 2m47s
[22:46:55.036] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:26:0)
[22:46:55.066] Build status: STARTED
[22:46:55.067] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:27:0) after 1m11s
[22:48:06.086] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:27:0)
[22:48:06.117] Build status: STARTED
[22:48:06.117] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:28:0) after 4m49s
[22:52:55.126] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:28:0)
[22:52:55.150] Build status: STARTED
[22:52:55.150] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:29:0) after 1m9s
[22:54:04.165] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:29:0)
[22:54:04.200] Build status: STARTED
[22:54:04.200] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:30:0) after 8m54s
[23:02:58.323] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:30:0)
[23:02:58.347] Build status: STARTED
[23:02:58.347] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:31:0) after 4m8s
[23:07:06.364] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:31:0)
[23:07:06.424] Build status: STARTED
[23:07:06.424] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:32:0) after 7m49s
[23:14:55.466] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:32:0)
[23:14:55.485] Build status: STARTED
[23:14:55.485] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:33:0) after 4m56s
[23:19:51.482] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:33:0)
[23:19:51.516] Build status: STARTED
[23:19:51.516] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:34:0) after 2m57s
[23:22:48.533] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:34:0)
[23:22:48.560] Build status: STARTED
[23:22:48.560] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:35:0) after 6m34s
[23:29:22.654] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:35:0)
[23:29:22.685] Build status: STARTED
[23:29:22.685] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:36:0) after 3m6s
[23:32:28.700] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:36:0)
[23:32:28.740] Build status: STARTED
[23:32:28.740] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:37:0) after 3m20s
[23:35:48.762] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:37:0)
[23:35:48.859] Build status: STARTED
[23:35:48.859] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:38:0) after 2m52s
[23:38:40.876] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:38:0)
[23:38:40.961] Build status: STARTED
[23:38:40.961] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:39:0) after 8m35s
[23:47:16.068] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:39:0)
[23:47:16.101] Build status: STARTED
[23:47:16.101] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:40:0) after 3m27s
[23:50:43.150] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:40:0)
[23:50:43.188] Build status: STARTED
[23:50:43.188] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:41:0) after 3m8s
[23:53:51.320] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:41:0)
[23:53:51.357] Build status: STARTED
[23:53:51.357] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:42:0) after 6m27s
[00:00:18.423] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:42:0)
[00:00:18.540] Build status: STARTED
[00:00:18.540] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:43:0) after 8m2s
[00:08:20.565] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:43:0)
[00:08:20.591] Build status: STARTED
[00:08:20.591] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:44:0) after 7m30s
[00:15:50.604] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:44:0)
[00:15:50.640] Build status: STARTED
[00:15:50.640] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:45:0) after 1m4s
[00:16:54.662] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:45:0)
[00:16:54.693] Build status: STARTED
[00:16:54.693] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:46:0) after 4m4s
[00:20:58.710] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:46:0)
[00:20:58.744] Build status: STARTED
[00:20:58.745] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:47:0) after 3m29s
[00:24:27.771] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:47:0)
[00:24:27.795] Build status: STARTED
[00:24:27.795] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:48:0) after 5m9s
[00:29:36.784] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:48:0)
[00:29:36.811] Build status: STARTED
[00:29:36.811] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:49:0) after 6m22s
[00:35:58.839] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:49:0)
[00:35:58.860] Build status: STARTED
[00:35:58.860] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:50:0) after 9m55s
[00:45:53.987] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:50:0)
[00:45:54.007] Build status: STARTED
[00:45:54.007] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:51:0) after 5m28s
[00:51:22.027] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:51:0)
[00:51:22.058] Build status: STARTED
[00:51:22.058] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:52:0) after 8m21s
[00:59:43.144] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:52:0)
[00:59:43.176] Build status: STARTED
[00:59:43.176] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:53:0) after 8m8s
[01:07:51.452] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:53:0)
[01:07:51.481] Build status: STARTED
[01:07:51.481] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:54:0) after 5m51s
[01:13:42.528] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:54:0)
[01:13:42.579] Build status: STARTED
[01:13:42.579] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:55:0) after 8m47s
[01:22:29.607] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:55:0)
[01:22:29.633] Build status: STARTED
[01:22:29.633] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:56:0) after 9m38s
[01:32:07.742] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:56:0)
[01:32:07.812] Build status: STARTED
[01:32:07.812] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:57:0) after 3m30s
[01:35:37.907] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:57:0)
[01:35:37.928] Build status: STARTED
[01:35:37.928] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:58:0) after 7m55s
[01:43:33.003] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:58:0)
[01:43:33.029] Build status: STARTED
[01:43:33.029] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:59:0) after 6m28s
[01:50:01.052] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:59:0)
[01:50:01.080] Build status: STARTED
[01:50:01.080] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:60:0) after 6m6s
[01:56:07.135] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:60:0)
[01:56:07.158] Build status: STARTED
[01:56:07.158] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:61:0) after 2m42s
[01:58:49.178] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:61:0)
[01:58:49.201] Build status: STARTED
[01:58:49.201] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:62:0) after 5m28s
[02:04:17.221] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:62:0)
[02:04:17.252] Build status: STARTED
[02:04:17.252] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:63:0) after 5m50s
[02:10:07.279] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:63:0)
[02:10:07.353] Build status: STARTED
[02:10:07.353] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:64:0) after 9m6s
[02:19:13.373] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:64:0)
[02:19:13.407] Build status: STARTED
[02:19:13.407] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:65:0) after 4m44s
[02:23:57.431] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:65:0)
[02:23:57.469] Build status: STARTED
[02:23:57.469] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:66:0) after 3m23s
[02:27:20.493] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:66:0)
[02:27:20.528] Build status: STARTED
[02:27:20.529] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:67:0) after 2m36s
[02:29:56.551] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:67:0)
[02:29:56.585] Build status: STARTED
[02:29:56.585] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:68:0) after 6m33s
[02:36:29.605] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:68:0)
[02:36:29.628] Build status: STARTED
[02:36:29.629] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:69:0) after 4m15s
[02:40:44.611] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:69:0)
[02:40:44.630] Build status: STARTED
[02:40:44.630] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:70:0) after 6m9s
[02:46:53.616] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:70:0)
[02:46:53.647] Build status: STARTED
[02:46:53.647] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:71:0) after 3m29s
[02:50:22.790] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:71:0)
[02:50:22.807] Build status: STARTED
[02:50:22.807] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:72:0) after 2m9s
[02:52:31.791] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:72:0)
[02:52:31.825] Build status: STARTED
[02:52:31.825] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:73:0) after 7m38s
[03:00:09.870] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:73:0)
[03:00:09.889] Build status: STARTED
[03:00:09.889] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:74:0) after 8m41s
[03:08:50.989] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:74:0)
[03:08:51.046] Build status: STARTED
[03:08:51.046] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:75:0) after 7m22s
[03:16:13.074] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:75:0)
[03:16:13.155] Build status: STARTED
[03:16:13.155] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:76:0) after 6m30s
[03:22:43.231] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:76:0)
[03:22:43.258] Build status: STARTED
[03:22:43.258] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:77:0) after 2m48s
[03:25:31.286] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:77:0)
[03:25:31.304] Build status: STARTED
[03:25:31.305] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:78:0) after 5m16s
[03:30:47.366] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:78:0)
[03:30:47.382] Build status: STARTED
[03:30:47.382] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:79:0) after 2m30s
[03:33:17.400] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:79:0)
[03:33:17.434] Build status: STARTED
[03:33:17.434] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:80:0) after 6m2s
[03:39:19.454] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:80:0)
[03:39:19.483] Build status: STARTED
[03:39:19.484] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:81:0) after 1m59s
[03:41:18.628] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:81:0)
[03:41:18.646] Build status: STARTED
[03:41:18.646] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:82:0) after 7m27s
[03:48:45.815] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:82:0)
[03:48:45.842] Build status: STARTED
[03:48:45.842] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:83:0) after 2m27s
[03:51:12.875] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:83:0)
[03:51:12.921] Build status: STARTED
[03:51:12.921] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:84:0) after 7m35s
[03:58:47.958] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:84:0)
[03:58:47.992] Build status: STARTED
[03:58:47.992] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:85:0) after 2m46s
[04:01:34.014] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:85:0)
[04:01:34.058] Build status: STARTED
[04:01:34.058] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:86:0) after 9m14s
[04:10:48.086] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:86:0)
[04:10:48.103] Build status: STARTED
[04:10:48.103] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:87:0) after 8m13s
[04:19:01.113] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:87:0)
[04:19:01.311] Build status: STARTED
[04:19:01.311] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:88:0) after 1m47s
[04:20:48.320] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:88:0)
[04:20:48.340] Build status: STARTED
[04:20:48.340] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:89:0) after 9m6s
[04:29:54.409] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:89:0)
[04:29:54.440] Build status: STARTED
[04:29:54.440] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:90:0) after 6m10s
[04:36:04.471] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:90:0)
[04:36:04.591] Build status: STARTED
[04:36:04.591] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:91:0) after 7m23s
[04:43:27.623] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:91:0)
[04:43:27.661] Build status: STARTED
[04:43:27.661] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:92:0) after 1m13s
[04:44:40.713] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:92:0)
[04:44:40.743] Build status: STARTED
[04:44:40.743] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:93:0) after 1m41s
[04:46:21.773] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:93:0)
[04:46:21.793] Build status: STARTED
[04:46:21.794] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:94:0) after 1m7s
[04:47:28.782] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:94:0)
[04:47:28.820] Build status: STARTED
[04:47:28.820] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:95:0) after 7m44s
[04:55:12.802] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:95:0)
[04:55:12.820] Build status: STARTED
[04:55:12.820] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:96:0) after 8m5s
[05:03:18.131] Handling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:96:0)
[05:03:18.161] Build status: STARTED
[05:03:18.161] Scheduling timer "check-buildbucket-build-status" (r8/win:8889354165814456144:97:0) after 8m6s
[05:11:22.808] Received PubSub notification, asking Buildbucket for the build status
[05:11:22.831] Build:
{
  "id": "8723924103700331841",
  "builder": {
    "project": "r8",
    "bucket": "ci",
    "builder": "win"
  },
  "number": 539,
  "createdBy": "project:r8",
  "createTime": "2025-02-03T20:31:06.231063167Z",
  "startTime": "2025-02-03T21:56:03.237049289Z",
  "endTime": "2025-02-04T05:11:22.541217867Z",
  "updateTime": "2025-02-04T05:11:22.541217867Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "r8.googlesource.com",
      "project": "r8",
      "id": "4cbf700c98a4e5d79d03633ff280f9338ec4935b",
      "ref": "refs/heads/main"
    }
  }
}
[05:11:22.831] Invocation finished in 8h40m17.999478164s with status SUCCEEDED