[19:00:54.197] New invocation is queued and will start shortly
[19:00:55.775] Starting the invocation (attempt 1)
[19:00:55.808] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/49c68f64c2ff1670c566aeb4f29e282e4315bcaa"
[19:00:55.808] Popped gitiles commit info from properties and tags
[19:00:55.808] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:00:55.808] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:00:55.809] Buildbucket request:
{
  "requestId": "8865532837124034384",
  "builder": {
    "project": "pigweed",
    "bucket": "pigweed.ci",
    "builder": "pigweed-mac-x86-gn-platform"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8865532837124034384",
            "job": "pigweed/pigweed.ci-pigweed-mac-x86-gn-platform",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "49c68f64c2ff1670c566aeb4f29e282e4315bcaa"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@49c68f64c2ff1670c566aeb4f29e282e4315bcaa",
                        "title": "49c68f64c2ff1670c566aeb4f29e282e4315bcaa",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/49c68f64c2ff1670c566aeb4f29e282e4315bcaa"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "pigweed.googlesource.com",
    "project": "pigweed/pigweed",
    "id": "49c68f64c2ff1670c566aeb4f29e282e4315bcaa",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8865532837124034384"
    },
    {
      "key": "scheduler_job_id",
      "value": "pigweed/pigweed.ci-pigweed-mac-x86-gn-platform"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[19:00:56.794] Scheduled build:
{
  "id": "8700102774707988769",
  "builder": {
    "project": "pigweed",
    "bucket": "pigweed.ci",
    "builder": "pigweed-mac-x86-gn-platform"
  },
  "createdBy": "project:pigweed",
  "createTime": "2025-10-24T19:00:55.874817773Z",
  "updateTime": "2025-10-24T19:00:55.874817773Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "pigweed.googlesource.com",
      "project": "pigweed/pigweed",
      "id": "49c68f64c2ff1670c566aeb4f29e282e4315bcaa",
      "ref": "refs/heads/main"
    }
  }
}
[19:00:56.794] Task URL: https://cr-buildbucket.appspot.com/build/8700102774707988769
[19:00:56.794] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:2:0) after 5m29s
[19:00:57.537] Received PubSub notification, asking Buildbucket for the build status
[19:00:57.566] Build status: SCHEDULED
[19:06:26.139] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:2:0)
[19:06:26.167] Build status: SCHEDULED
[19:06:26.167] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:4:0) after 7m31s
[19:13:57.226] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:4:0)
[19:13:57.312] Build status: SCHEDULED
[19:13:57.312] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:5:0) after 2m42s
[19:16:39.432] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:5:0)
[19:16:39.541] Build status: SCHEDULED
[19:16:39.541] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:6:0) after 9m6s
[19:25:45.574] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:6:0)
[19:25:45.602] Build status: SCHEDULED
[19:25:45.602] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:7:0) after 2m16s
[19:28:01.616] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:7:0)
[19:28:01.637] Build status: SCHEDULED
[19:28:01.637] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:8:0) after 9m34s
[19:37:35.676] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:8:0)
[19:37:35.696] Build status: SCHEDULED
[19:37:35.696] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:9:0) after 1m14s
[19:38:49.750] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:9:0)
[19:38:49.773] Build status: SCHEDULED
[19:38:49.773] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:10:0) after 4m41s
[19:43:30.788] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:10:0)
[19:43:30.811] Build status: SCHEDULED
[19:43:30.811] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:11:0) after 9m33s
[19:53:03.823] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:11:0)
[19:53:03.843] Build status: SCHEDULED
[19:53:03.843] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:12:0) after 2m22s
[19:55:25.857] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:12:0)
[19:55:25.927] Build status: SCHEDULED
[19:55:25.928] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:13:0) after 8m3s
[20:03:28.951] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:13:0)
[20:03:28.965] Build status: SCHEDULED
[20:03:28.965] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:14:0) after 2m3s
[20:05:32.017] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:14:0)
[20:05:32.037] Build status: SCHEDULED
[20:05:32.037] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:15:0) after 2m57s
[20:07:33.190] Received PubSub notification, asking Buildbucket for the build status
[20:07:33.208] Build status: STARTED
[20:08:29.126] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:15:0)
[20:08:29.143] Build status: STARTED
[20:08:29.143] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:17:0) after 6m43s
[20:15:12.154] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:17:0)
[20:15:12.169] Build status: STARTED
[20:15:12.169] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:18:0) after 8m55s
[20:24:07.192] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:18:0)
[20:24:07.216] Build status: STARTED
[20:24:07.216] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:19:0) after 5m53s
[20:30:00.720] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:19:0)
[20:30:00.747] Build status: STARTED
[20:30:00.747] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:20:0) after 7m56s
[20:37:56.809] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:20:0)
[20:37:56.827] Build status: STARTED
[20:37:56.827] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:21:0) after 4m4s
[20:42:00.934] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:21:0)
[20:42:00.953] Build status: STARTED
[20:42:00.953] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:22:0) after 6m23s
[20:48:23.967] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:22:0)
[20:48:23.986] Build status: STARTED
[20:48:23.986] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865532837124034384:23:0) after 4m36s
[20:51:37.975] Received PubSub notification, asking Buildbucket for the build status
[20:51:37.991] Build:
{
  "id": "8700102774707988769",
  "builder": {
    "project": "pigweed",
    "bucket": "pigweed.ci",
    "builder": "pigweed-mac-x86-gn-platform"
  },
  "createdBy": "project:pigweed",
  "createTime": "2025-10-24T19:00:55.874817773Z",
  "startTime": "2025-10-24T20:07:32.937027470Z",
  "endTime": "2025-10-24T20:51:37.615539586Z",
  "updateTime": "2025-10-24T20:51:37.615539586Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "pigweed.googlesource.com",
      "project": "pigweed/pigweed",
      "id": "49c68f64c2ff1670c566aeb4f29e282e4315bcaa",
      "ref": "refs/heads/main"
    }
  }
}
[20:51:37.991] Invocation finished in 1h50m43.805062456s with status SUCCEEDED