[19:24:20.223] New invocation is queued and will start shortly
[19:24:20.223] Triggered by user:rerunner@pigweed-service-accounts.iam.gserviceaccount.com
[19:24:21.357] Starting the invocation (attempt 1)
[19:24:21.380] Ignoring gitiles_ref tag without the buildset tag
[19:24:21.380] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:24:21.380] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:24:21.380] Buildbucket request:
{
  "requestId": "8865621959773329728",
  "builder": {
    "project": "pigweed",
    "bucket": "examples.dev.ci",
    "builder": "examples-default-build-mac-arm"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8865621959773329728",
            "job": "pigweed/examples.dev.ci-examples-default-build-mac-arm",
            "triggers": [
                  {
                        "buildbucket": {
                              "tags": [
                                    "parent_buildername:rerunner",
                                    "user_agent:rerunner"
                                  ]
                            },
                        "id": "6753dce8-d1e8-4977-bee8-78cb5155447a",
                        "title": "rerunner/0",
                        "url": "https://cr-buildbucket.appspot.com/build/8700192189479395633"
                      }
                ]
          }
    },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8865621959773329728"
    },
    {
      "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"
  }
}
[19:24:21.762] Scheduled build:
{
  "id": "8700191897830620529",
  "builder": {
    "project": "pigweed",
    "bucket": "examples.dev.ci",
    "builder": "examples-default-build-mac-arm"
  },
  "createdBy": "project:pigweed",
  "createTime": "2025-10-23T19:24:21.441685527Z",
  "updateTime": "2025-10-23T19:24:21.441685527Z",
  "status": "SCHEDULED",
  "input": {
  }
}
[19:24:21.762] Task URL: https://cr-buildbucket.appspot.com/build/8700191897830620529
[19:24:21.762] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:2:0) after 2m15s
[19:24:21.992] Received PubSub notification, asking Buildbucket for the build status
[19:24:22.021] Build status: SCHEDULED
[19:26:36.773] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:2:0)
[19:26:36.808] Build status: SCHEDULED
[19:26:36.808] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:4:0) after 6m59s
[19:33:35.818] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:4:0)
[19:33:35.844] Build status: SCHEDULED
[19:33:35.844] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:5:0) after 9m2s
[19:42:37.859] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:5:0)
[19:42:37.925] Build status: SCHEDULED
[19:42:37.925] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:6:0) after 6m55s
[19:49:32.938] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:6:0)
[19:49:33.244] Build status: SCHEDULED
[19:49:33.244] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:7:0) after 3m5s
[19:52:38.317] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:7:0)
[19:52:38.343] Build status: SCHEDULED
[19:52:38.343] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:8:0) after 3m31s
[19:56:09.398] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:8:0)
[19:56:09.426] Build status: SCHEDULED
[19:56:09.426] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:9:0) after 5m38s
[20:01:47.504] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:9:0)
[20:01:47.524] Build status: SCHEDULED
[20:01:47.524] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:10:0) after 8m14s
[20:10:01.556] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:10:0)
[20:10:01.605] Build status: SCHEDULED
[20:10:01.605] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:11:0) after 1m6s
[20:11:07.619] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:11:0)
[20:11:07.635] Build status: SCHEDULED
[20:11:07.635] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:12:0) after 5m34s
[20:16:41.617] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:12:0)
[20:16:41.641] Build status: SCHEDULED
[20:16:41.642] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:13:0) after 4m52s
[20:21:33.661] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:13:0)
[20:21:33.677] Build status: SCHEDULED
[20:21:33.677] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:14:0) after 3m4s
[20:24:37.790] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:14:0)
[20:24:37.807] Build status: SCHEDULED
[20:24:37.807] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:15:0) after 9m8s
[20:33:45.996] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:15:0)
[20:33:46.010] Build status: SCHEDULED
[20:33:46.010] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:16:0) after 7m11s
[20:40:57.068] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:16:0)
[20:40:57.085] Build status: SCHEDULED
[20:40:57.085] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:17:0) after 8m28s
[20:49:25.100] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:17:0)
[20:49:25.123] Build status: SCHEDULED
[20:49:25.123] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:18:0) after 4m39s
[20:54:04.186] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:18:0)
[20:54:04.202] Build status: SCHEDULED
[20:54:04.202] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:19:0) after 5m49s
[20:59:53.213] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:19:0)
[20:59:53.278] Build status: SCHEDULED
[20:59:53.278] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:20:0) after 4m42s
[21:04:35.292] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:20:0)
[21:04:35.321] Build status: SCHEDULED
[21:04:35.321] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:21:0) after 2m5s
[21:06:40.320] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:21:0)
[21:06:40.336] Build status: SCHEDULED
[21:06:40.336] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:22:0) after 7m49s
[21:14:29.435] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:22:0)
[21:14:29.450] Build status: SCHEDULED
[21:14:29.450] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:23:0) after 1m57s
[21:16:26.485] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:23:0)
[21:16:26.503] Build status: SCHEDULED
[21:16:26.503] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:24:0) after 9m36s
[21:26:02.514] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:24:0)
[21:26:02.542] Build status: SCHEDULED
[21:26:02.543] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:25:0) after 7m38s
[21:33:40.511] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:25:0)
[21:33:40.536] Build status: SCHEDULED
[21:33:40.536] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:26:0) after 6m36s
[21:40:16.548] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:26:0)
[21:40:16.566] Build status: SCHEDULED
[21:40:16.566] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:27:0) after 4m42s
[21:44:58.663] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:27:0)
[21:44:58.687] Build status: SCHEDULED
[21:44:58.687] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:28:0) after 9m54s
[21:54:52.785] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:28:0)
[21:54:52.829] Build status: SCHEDULED
[21:54:52.829] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:29:0) after 7m37s
[22:02:29.844] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:29:0)
[22:02:29.858] Build status: SCHEDULED
[22:02:29.859] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:30:0) after 9m17s
[22:11:46.833] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:30:0)
[22:11:46.923] Build status: SCHEDULED
[22:11:46.923] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:31:0) after 8m39s
[22:20:25.983] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:31:0)
[22:20:26.050] Build status: SCHEDULED
[22:20:26.050] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:32:0) after 3m56s
[22:24:22.168] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:32:0)
[22:24:22.188] Build status: SCHEDULED
[22:24:22.189] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:33:0) after 8m38s
[22:33:00.153] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:33:0)
[22:33:00.231] Build status: SCHEDULED
[22:33:00.231] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:34:0) after 7m59s
[22:40:59.298] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:34:0)
[22:40:59.318] Build status: SCHEDULED
[22:40:59.319] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:35:0) after 5m47s
[22:46:46.293] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:35:0)
[22:46:46.551] Build status: SCHEDULED
[22:46:46.551] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:36:0) after 7m44s
[22:52:17.719] Received PubSub notification, asking Buildbucket for the build status
[22:52:17.738] Build status: STARTED
[22:54:30.625] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:36:0)
[22:54:30.701] Build status: STARTED
[22:54:30.701] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865621959773329728:38:0) after 5m10s
[22:57:48.625] Received PubSub notification, asking Buildbucket for the build status
[22:57:48.640] Build:
{
  "id": "8700191897830620529",
  "builder": {
    "project": "pigweed",
    "bucket": "examples.dev.ci",
    "builder": "examples-default-build-mac-arm"
  },
  "createdBy": "project:pigweed",
  "createTime": "2025-10-23T19:24:21.441685527Z",
  "startTime": "2025-10-23T22:52:17.587671670Z",
  "endTime": "2025-10-23T22:57:48.043438253Z",
  "updateTime": "2025-10-23T22:57:48.043438253Z",
  "status": "SUCCESS",
  "input": {
  }
}
[22:57:48.640] Invocation finished in 3h33m28.436078608s with status SUCCEEDED