[00:19:42.264] New invocation is queued and will start shortly
[00:19:43.420] Starting the invocation (attempt 1)
[00:19:43.442] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/d901ac7bb0b57f48f7923a67bd38845c031b59a3"
[00:19:43.442] Popped gitiles commit info from properties and tags
[00:19:43.442] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:19:43.442] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:19:43.442] Buildbucket request:
{
  "requestId": "8865240988990154016",
  "builder": {
    "project": "pigweed",
    "bucket": "pigweed.ci",
    "builder": "pigweed-mac-x86-gn-platform"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8865240988990154016",
            "job": "pigweed/pigweed.ci-pigweed-mac-x86-gn-platform",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "cc91dbff7944fb31453b3f0f5a308e1ef78d6d1b"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@cc91dbff7944fb31453b3f0f5a308e1ef78d6d1b",
                        "title": "cc91dbff7944fb31453b3f0f5a308e1ef78d6d1b",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/cc91dbff7944fb31453b3f0f5a308e1ef78d6d1b"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "9f6d1750fa3f1981eecc173d2f8660cb12c0702d"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@9f6d1750fa3f1981eecc173d2f8660cb12c0702d",
                        "title": "9f6d1750fa3f1981eecc173d2f8660cb12c0702d",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/9f6d1750fa3f1981eecc173d2f8660cb12c0702d"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "fb8325f2253fcf172fa6eecbe536f77c2cec6c23"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@fb8325f2253fcf172fa6eecbe536f77c2cec6c23",
                        "title": "fb8325f2253fcf172fa6eecbe536f77c2cec6c23",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/fb8325f2253fcf172fa6eecbe536f77c2cec6c23"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "d901ac7bb0b57f48f7923a67bd38845c031b59a3"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@d901ac7bb0b57f48f7923a67bd38845c031b59a3",
                        "title": "d901ac7bb0b57f48f7923a67bd38845c031b59a3",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d901ac7bb0b57f48f7923a67bd38845c031b59a3"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "pigweed.googlesource.com",
    "project": "pigweed/pigweed",
    "id": "d901ac7bb0b57f48f7923a67bd38845c031b59a3",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8865240988990154016"
    },
    {
      "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"
  }
}
[00:19:43.878] Scheduled build:
{
  "id": "8699810927026508433",
  "builder": {
    "project": "pigweed",
    "bucket": "pigweed.ci",
    "builder": "pigweed-mac-x86-gn-platform"
  },
  "createdBy": "project:pigweed",
  "createTime": "2025-10-28T00:19:43.512114417Z",
  "updateTime": "2025-10-28T00:19:43.512114417Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "pigweed.googlesource.com",
      "project": "pigweed/pigweed",
      "id": "d901ac7bb0b57f48f7923a67bd38845c031b59a3",
      "ref": "refs/heads/main"
    }
  }
}
[00:19:43.878] Task URL: https://cr-buildbucket.appspot.com/build/8699810927026508433
[00:19:43.878] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:2:0) after 3m0s
[00:19:44.847] Received PubSub notification, asking Buildbucket for the build status
[00:19:44.866] Build status: SCHEDULED
[00:22:43.943] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:2:0)
[00:22:43.994] Build status: SCHEDULED
[00:22:43.994] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:4:0) after 5m13s
[00:27:57.089] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:4:0)
[00:28:00.311] Build status: SCHEDULED
[00:28:00.311] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:5:0) after 6m46s
[00:34:46.326] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:5:0)
[00:34:46.345] Build status: SCHEDULED
[00:34:46.346] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:6:0) after 6m16s
[00:41:02.361] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:6:0)
[00:41:02.412] Build status: SCHEDULED
[00:41:02.412] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:7:0) after 9m36s
[00:50:38.473] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:7:0)
[00:50:38.495] Build status: SCHEDULED
[00:50:38.495] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:8:0) after 4m49s
[00:55:27.511] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:8:0)
[00:55:27.526] Build status: SCHEDULED
[00:55:27.526] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:9:0) after 6m37s
[01:02:04.547] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:9:0)
[01:02:04.569] Build status: SCHEDULED
[01:02:04.570] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:10:0) after 7m13s
[01:09:17.603] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:10:0)
[01:09:17.622] Build status: SCHEDULED
[01:09:17.622] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:11:0) after 5m57s
[01:15:14.814] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:11:0)
[01:15:14.831] Build status: SCHEDULED
[01:15:14.831] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:12:0) after 2m9s
[01:17:23.859] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:12:0)
[01:17:23.926] Build status: SCHEDULED
[01:17:23.926] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:13:0) after 4m37s
[01:22:00.977] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:13:0)
[01:22:00.993] Build status: SCHEDULED
[01:22:00.993] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:14:0) after 7m21s
[01:29:21.982] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:14:0)
[01:29:22.005] Build status: SCHEDULED
[01:29:22.005] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:15:0) after 9m20s
[01:38:42.041] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:15:0)
[01:38:42.058] Build status: SCHEDULED
[01:38:42.058] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:16:0) after 7m30s
[01:45:18.368] Received PubSub notification, asking Buildbucket for the build status
[01:45:18.429] Build status: STARTED
[01:46:12.335] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:16:0)
[01:46:12.354] Build status: STARTED
[01:46:12.354] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:18:0) after 7m26s
[01:53:38.365] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:18:0)
[01:53:38.393] Build status: STARTED
[01:53:38.393] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:19:0) after 5m59s
[01:59:37.405] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:19:0)
[01:59:37.422] Build status: STARTED
[01:59:37.422] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:20:0) after 1m51s
[02:01:28.439] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:20:0)
[02:01:28.505] Build status: STARTED
[02:01:28.505] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:21:0) after 6m28s
[02:07:56.625] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:21:0)
[02:07:56.640] Build status: STARTED
[02:07:56.640] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:22:0) after 2m3s
[02:09:59.633] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:22:0)
[02:09:59.693] Build status: STARTED
[02:09:59.693] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:23:0) after 9m26s
[02:19:25.773] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:23:0)
[02:19:25.804] Build status: STARTED
[02:19:25.804] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:24:0) after 2m53s
[02:22:18.888] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:24:0)
[02:22:18.913] Build status: STARTED
[02:22:18.914] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865240988990154016:25:0) after 9m56s
[02:28:37.705] Received PubSub notification, asking Buildbucket for the build status
[02:28:37.730] Build:
{
  "id": "8699810927026508433",
  "builder": {
    "project": "pigweed",
    "bucket": "pigweed.ci",
    "builder": "pigweed-mac-x86-gn-platform"
  },
  "createdBy": "project:pigweed",
  "createTime": "2025-10-28T00:19:43.512114417Z",
  "startTime": "2025-10-28T01:44:54.104529058Z",
  "endTime": "2025-10-28T02:28:37.344995181Z",
  "updateTime": "2025-10-28T02:28:37.344995181Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "pigweed.googlesource.com",
      "project": "pigweed/pigweed",
      "id": "d901ac7bb0b57f48f7923a67bd38845c031b59a3",
      "ref": "refs/heads/main"
    }
  }
}
[02:28:37.730] Invocation finished in 2h8m55.474610306s with status SUCCEEDED