[01:40:41.168] New invocation is queued and will start shortly
[01:40:41.169] Triggered by user:rerunner@pigweed-service-accounts.iam.gserviceaccount.com
[01:40:42.258] Starting the invocation (attempt 1)
[01:40:42.279] Ignoring gitiles_ref tag without the buildset tag
[01:40:42.279] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:40:42.279] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:40:42.280] Buildbucket request:
{
  "requestId": "8864873506194362432",
  "builder": {
    "project": "pigweed",
    "bucket": "examples.dev.ci",
    "builder": "examples-default-build-mac-arm"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8864873506194362432",
            "job": "pigweed/examples.dev.ci-examples-default-build-mac-arm",
            "triggers": [
                  {
                        "buildbucket": {
                              "tags": [
                                    "parent_buildername:rerunner",
                                    "user_agent:rerunner"
                                  ]
                            },
                        "id": "92b53be4-c77d-4585-bae4-55ad1009d9de",
                        "title": "rerunner/0",
                        "url": "https://cr-buildbucket.appspot.com/build/8699443663496637681"
                      }
                ]
          }
    },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8864873506194362432"
    },
    {
      "key": "scheduler_job_id",
      "value": "pigweed/examples.dev.ci-examples-default-build-mac-arm"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    },
    {
      "key": "parent_buildername",
      "value": "rerunner"
    },
    {
      "key": "user_agent",
      "value": "rerunner"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[01:40:42.752] Scheduled build:
{
  "id": "8699443444273048305",
  "builder": {
    "project": "pigweed",
    "bucket": "examples.dev.ci",
    "builder": "examples-default-build-mac-arm"
  },
  "createdBy": "project:pigweed",
  "createTime": "2025-11-01T01:40:42.376958132Z",
  "updateTime": "2025-11-01T01:40:42.376958132Z",
  "status": "SCHEDULED",
  "input": {
  }
}
[01:40:42.752] Task URL: https://cr-buildbucket.appspot.com/build/8699443444273048305
[01:40:42.753] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:2:0) after 8m13s
[01:40:43.669] Received PubSub notification, asking Buildbucket for the build status
[01:40:43.688] Build status: SCHEDULED
[01:48:55.779] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:2:0)
[01:48:55.794] Build status: SCHEDULED
[01:48:55.794] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:4:0) after 2m45s
[01:51:40.850] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:4:0)
[01:51:40.886] Build status: SCHEDULED
[01:51:40.886] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:5:0) after 1m0s
[01:52:40.938] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:5:0)
[01:52:40.967] Build status: SCHEDULED
[01:52:40.967] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:6:0) after 1m50s
[01:54:31.112] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:6:0)
[01:54:31.162] Build status: SCHEDULED
[01:54:31.162] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:7:0) after 8m41s
[02:03:12.218] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:7:0)
[02:03:12.247] Build status: SCHEDULED
[02:03:12.247] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:8:0) after 3m5s
[02:06:17.258] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:8:0)
[02:06:17.292] Build status: SCHEDULED
[02:06:17.292] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:9:0) after 5m41s
[02:11:58.406] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:9:0)
[02:11:58.471] Build status: SCHEDULED
[02:11:58.472] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:10:0) after 2m20s
[02:14:18.652] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:10:0)
[02:14:18.674] Build status: SCHEDULED
[02:14:18.674] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:11:0) after 2m25s
[02:16:43.750] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:11:0)
[02:16:43.779] Build status: SCHEDULED
[02:16:43.779] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:12:0) after 6m40s
[02:23:23.843] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:12:0)
[02:23:23.888] Build status: SCHEDULED
[02:23:23.888] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:13:0) after 1m3s
[02:24:26.899] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:13:0)
[02:24:26.921] Build status: SCHEDULED
[02:24:26.921] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:14:0) after 5m33s
[02:29:59.933] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:14:0)
[02:29:59.950] Build status: SCHEDULED
[02:29:59.950] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:15:0) after 9m35s
[02:39:35.452] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:15:0)
[02:39:35.484] Build status: SCHEDULED
[02:39:35.484] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:16:0) after 4m19s
[02:43:54.829] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:16:0)
[02:43:54.869] Build status: SCHEDULED
[02:43:54.869] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:17:0) after 7m28s
[02:51:22.893] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:17:0)
[02:51:22.914] Build status: SCHEDULED
[02:51:22.914] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:18:0) after 8m20s
[02:59:42.984] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:18:0)
[02:59:43.003] Build status: SCHEDULED
[02:59:43.003] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:19:0) after 2m12s
[03:01:55.251] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:19:0)
[03:01:55.272] Build status: SCHEDULED
[03:01:55.272] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:20:0) after 2m11s
[03:04:06.656] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:20:0)
[03:04:06.673] Build status: SCHEDULED
[03:04:06.673] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:21:0) after 9m31s
[03:13:38.564] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:21:0)
[03:13:38.596] Build status: SCHEDULED
[03:13:38.596] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:22:0) after 9m30s
[03:23:08.724] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:22:0)
[03:23:08.744] Build status: SCHEDULED
[03:23:08.744] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:23:0) after 7m56s
[03:31:04.760] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:23:0)
[03:31:04.776] Build status: SCHEDULED
[03:31:04.777] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:24:0) after 2m3s
[03:33:07.761] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:24:0)
[03:33:07.788] Build status: SCHEDULED
[03:33:07.788] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:25:0) after 3m0s
[03:36:07.826] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:25:0)
[03:36:07.905] Build status: SCHEDULED
[03:36:07.905] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:26:0) after 4m7s
[03:40:14.940] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:26:0)
[03:40:15.022] Build status: SCHEDULED
[03:40:15.022] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:27:0) after 6m59s
[03:47:14.136] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:27:0)
[03:47:14.205] Build status: SCHEDULED
[03:47:14.205] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:28:0) after 2m37s
[03:49:51.356] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:28:0)
[03:49:51.373] Build status: SCHEDULED
[03:49:51.373] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:29:0) after 2m0s
[03:51:51.419] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:29:0)
[03:51:51.448] Build status: SCHEDULED
[03:51:51.448] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:30:0) after 3m10s
[03:55:01.740] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:30:0)
[03:55:01.757] Build status: SCHEDULED
[03:55:01.757] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:31:0) after 8m27s
[04:03:28.945] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:31:0)
[04:03:28.986] Build status: SCHEDULED
[04:03:28.986] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:32:0) after 6m50s
[04:10:19.208] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:32:0)
[04:10:19.230] Build status: SCHEDULED
[04:10:19.230] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:33:0) after 6m58s
[04:17:17.246] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:33:0)
[04:17:17.273] Build status: SCHEDULED
[04:17:17.273] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:34:0) after 1m49s
[04:19:06.358] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:34:0)
[04:19:06.428] Build status: SCHEDULED
[04:19:06.428] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:35:0) after 4m25s
[04:23:31.442] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:35:0)
[04:23:31.461] Build status: SCHEDULED
[04:23:31.461] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:36:0) after 9m47s
[04:33:18.512] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:36:0)
[04:33:18.531] Build status: SCHEDULED
[04:33:18.531] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:37:0) after 1m47s
[04:35:05.728] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:37:0)
[04:35:05.789] Build status: SCHEDULED
[04:35:05.789] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:38:0) after 3m42s
[04:38:47.909] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:38:0)
[04:38:47.933] Build status: SCHEDULED
[04:38:47.933] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:39:0) after 9m54s
[04:48:42.043] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:39:0)
[04:48:42.062] Build status: SCHEDULED
[04:48:42.062] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:40:0) after 4m2s
[04:52:44.125] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:40:0)
[04:52:44.146] Build status: SCHEDULED
[04:52:44.146] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:41:0) after 6m28s
[04:59:12.162] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:41:0)
[04:59:12.272] Build status: SCHEDULED
[04:59:12.272] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8864873506194362432:42:0) after 8m47s
[05:00:48.159] Received PubSub notification, asking Buildbucket for the build status
[05:00:48.235] Build status: STARTED
[05:06:15.262] Received PubSub notification, asking Buildbucket for the build status
[05:06:15.284] Build:
{
  "id": "8699443444273048305",
  "builder": {
    "project": "pigweed",
    "bucket": "examples.dev.ci",
    "builder": "examples-default-build-mac-arm"
  },
  "createdBy": "project:pigweed",
  "createTime": "2025-11-01T01:40:42.376958132Z",
  "startTime": "2025-11-01T05:00:47.945990944Z",
  "endTime": "2025-11-01T05:06:14.856950989Z",
  "updateTime": "2025-11-01T05:06:14.856950989Z",
  "status": "SUCCESS",
  "input": {
  }
}
[05:06:15.284] Invocation finished in 3h25m34.124106526s with status SUCCEEDED