[08:13:33.033] New invocation is queued and will start shortly
[08:13:34.214] Starting the invocation (attempt 1)
[08:13:34.240] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/examples/+/81ae0d2b4da73a535381087ec34519caeb95e1dd"
[08:13:34.241] Popped gitiles commit info from properties and tags
[08:13:34.241] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:13:34.241] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:13:34.241] Buildbucket request:
{
  "requestId": "8865211177170882688",
  "builder": {
    "project": "pigweed",
    "bucket": "examples.dev.ci",
    "builder": "examples-default-build-mac-arm"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8865211177170882688",
            "job": "pigweed/examples.dev.ci-examples-default-build-mac-arm",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/examples",
                              "revision": "81ae0d2b4da73a535381087ec34519caeb95e1dd"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/examples/+/refs/heads/main@81ae0d2b4da73a535381087ec34519caeb95e1dd",
                        "title": "81ae0d2b4da73a535381087ec34519caeb95e1dd",
                        "url": "https://pigweed.googlesource.com/pigweed/examples/+/81ae0d2b4da73a535381087ec34519caeb95e1dd"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "pigweed.googlesource.com",
    "project": "pigweed/examples",
    "id": "81ae0d2b4da73a535381087ec34519caeb95e1dd",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8865211177170882688"
    },
    {
      "key": "scheduler_job_id",
      "value": "pigweed/examples.dev.ci-examples-default-build-mac-arm"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[08:13:34.619] Scheduled build:
{
  "id": "8699781115192914337",
  "builder": {
    "project": "pigweed",
    "bucket": "examples.dev.ci",
    "builder": "examples-default-build-mac-arm"
  },
  "createdBy": "project:pigweed",
  "createTime": "2025-10-28T08:13:34.292010517Z",
  "updateTime": "2025-10-28T08:13:34.292010517Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "pigweed.googlesource.com",
      "project": "pigweed/examples",
      "id": "81ae0d2b4da73a535381087ec34519caeb95e1dd",
      "ref": "refs/heads/main"
    }
  }
}
[08:13:34.620] Task URL: https://cr-buildbucket.appspot.com/build/8699781115192914337
[08:13:34.620] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:2:0) after 4m5s
[08:13:35.609] Received PubSub notification, asking Buildbucket for the build status
[08:13:35.777] Build status: SCHEDULED
[08:17:39.777] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:2:0)
[08:17:39.809] Build status: SCHEDULED
[08:17:39.809] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:4:0) after 7m7s
[08:24:46.829] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:4:0)
[08:24:46.902] Build status: SCHEDULED
[08:24:46.902] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:5:0) after 5m39s
[08:30:25.913] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:5:0)
[08:30:25.941] Build status: SCHEDULED
[08:30:25.941] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:6:0) after 2m44s
[08:33:09.959] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:6:0)
[08:33:09.980] Build status: SCHEDULED
[08:33:09.980] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:7:0) after 1m50s
[08:34:59.996] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:7:0)
[08:35:00.139] Build status: SCHEDULED
[08:35:00.139] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:8:0) after 3m46s
[08:38:46.151] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:8:0)
[08:38:46.338] Build status: SCHEDULED
[08:38:46.338] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:9:0) after 8m8s
[08:46:54.333] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:9:0)
[08:46:54.348] Build status: SCHEDULED
[08:46:54.348] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:10:0) after 5m50s
[08:52:44.542] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:10:0)
[08:52:44.558] Build status: SCHEDULED
[08:52:44.558] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:11:0) after 4m30s
[08:57:14.574] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:11:0)
[08:57:14.598] Build status: SCHEDULED
[08:57:14.598] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:12:0) after 7m41s
[09:04:55.757] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:12:0)
[09:04:55.780] Build status: SCHEDULED
[09:04:55.780] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:13:0) after 4m50s
[09:09:45.815] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:13:0)
[09:09:45.846] Build status: SCHEDULED
[09:09:45.846] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:14:0) after 1m4s
[09:10:49.884] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:14:0)
[09:10:49.941] Build status: SCHEDULED
[09:10:49.941] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:15:0) after 5m44s
[09:16:34.016] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:15:0)
[09:16:34.055] Build status: SCHEDULED
[09:16:34.055] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:16:0) after 6m37s
[09:23:11.193] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:16:0)
[09:23:11.524] Build status: SCHEDULED
[09:23:11.524] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:17:0) after 9m25s
[09:32:36.567] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:17:0)
[09:32:36.589] Build status: SCHEDULED
[09:32:36.589] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:18:0) after 4m12s
[09:36:48.647] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:18:0)
[09:36:48.661] Build status: SCHEDULED
[09:36:48.661] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:19:0) after 9m2s
[09:45:50.675] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:19:0)
[09:45:50.692] Build status: SCHEDULED
[09:45:50.692] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:20:0) after 9m25s
[09:55:15.769] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:20:0)
[09:55:15.783] Build status: SCHEDULED
[09:55:15.783] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:21:0) after 3m0s
[09:58:15.798] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:21:0)
[09:58:15.815] Build status: SCHEDULED
[09:58:15.816] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:22:0) after 2m29s
[10:00:44.880] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:22:0)
[10:00:44.899] Build status: SCHEDULED
[10:00:44.899] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:23:0) after 7m37s
[10:08:21.946] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:23:0)
[10:08:22.026] Build status: SCHEDULED
[10:08:22.026] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:24:0) after 3m1s
[10:11:23.038] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:24:0)
[10:11:23.061] Build status: SCHEDULED
[10:11:23.061] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:25:0) after 2m21s
[10:13:44.175] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:25:0)
[10:13:44.198] Build status: SCHEDULED
[10:13:44.199] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:26:0) after 5m18s
[10:19:02.216] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:26:0)
[10:19:02.234] Build status: SCHEDULED
[10:19:02.234] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:27:0) after 4m28s
[10:23:30.349] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:27:0)
[10:23:30.373] Build status: SCHEDULED
[10:23:30.373] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:28:0) after 2m41s
[10:26:11.386] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:28:0)
[10:26:11.406] Build status: SCHEDULED
[10:26:11.406] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:29:0) after 2m44s
[10:28:55.423] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:29:0)
[10:28:55.444] Build status: SCHEDULED
[10:28:55.444] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:30:0) after 1m16s
[10:30:11.528] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:30:0)
[10:30:11.549] Build status: SCHEDULED
[10:30:11.549] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:31:0) after 4m42s
[10:34:53.589] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:31:0)
[10:34:53.606] Build status: SCHEDULED
[10:34:53.606] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:32:0) after 7m21s
[10:42:14.673] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:32:0)
[10:42:14.720] Build status: SCHEDULED
[10:42:14.720] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:33:0) after 4m39s
[10:46:53.735] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:33:0)
[10:46:53.833] Build status: SCHEDULED
[10:46:53.833] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:34:0) after 9m23s
[10:56:16.851] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:34:0)
[10:56:16.880] Build status: SCHEDULED
[10:56:16.881] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:35:0) after 2m21s
[10:58:37.941] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:35:0)
[10:58:38.025] Build status: SCHEDULED
[10:58:38.025] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:36:0) after 2m39s
[11:01:17.059] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:36:0)
[11:01:17.109] Build status: SCHEDULED
[11:01:17.109] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:37:0) after 3m58s
[11:05:15.123] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:37:0)
[11:05:15.194] Build status: SCHEDULED
[11:05:15.194] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-default-build-mac-arm:8865211177170882688:38:0) after 5m55s
[11:06:04.504] Received PubSub notification, asking Buildbucket for the build status
[11:06:04.599] Build status: STARTED
[11:06:52.881] Received PubSub notification, asking Buildbucket for the build status
[11:06:52.903] Build:
{
  "id": "8699781115192914337",
  "builder": {
    "project": "pigweed",
    "bucket": "examples.dev.ci",
    "builder": "examples-default-build-mac-arm"
  },
  "createdBy": "project:pigweed",
  "createTime": "2025-10-28T08:13:34.292010517Z",
  "startTime": "2025-10-28T11:06:04.208566036Z",
  "endTime": "2025-10-28T11:06:52.576986122Z",
  "updateTime": "2025-10-28T11:06:52.576986122Z",
  "status": "INFRA_FAILURE",
  "input": {
    "gitilesCommit": {
      "host": "pigweed.googlesource.com",
      "project": "pigweed/examples",
      "id": "81ae0d2b4da73a535381087ec34519caeb95e1dd",
      "ref": "refs/heads/main"
    }
  }
}
[11:06:52.904] Invocation finished in 2h53m19.882049949s with status FAILED