[17:52:32.861] New invocation is queued and will start shortly
[17:52:33.950] Starting the invocation (attempt 1)
[17:52:33.989] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/71bff248824d7abc2e3801c3ce628edf9c82829b"
[17:52:33.989] Popped gitiles commit info from properties and tags
[17:52:33.989] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:52:33.989] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:52:33.990] Buildbucket request:
{
  "requestId": "8865084152855759104",
  "builder": {
    "project": "pigweed",
    "bucket": "pigweed.ci",
    "builder": "pigweed-mac-x86-gn-platform"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8865084152855759104",
            "job": "pigweed/pigweed.ci-pigweed-mac-x86-gn-platform",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "d0c3443198f37a2a8684ff9d221e843582623688"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@d0c3443198f37a2a8684ff9d221e843582623688",
                        "title": "d0c3443198f37a2a8684ff9d221e843582623688",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d0c3443198f37a2a8684ff9d221e843582623688"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "5282cf333058d68c4a1de56f69fa5032868ee461"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@5282cf333058d68c4a1de56f69fa5032868ee461",
                        "title": "5282cf333058d68c4a1de56f69fa5032868ee461",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/5282cf333058d68c4a1de56f69fa5032868ee461"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "67a1e0643268d3d8a97f4ecc794b94658ea33ba3"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@67a1e0643268d3d8a97f4ecc794b94658ea33ba3",
                        "title": "67a1e0643268d3d8a97f4ecc794b94658ea33ba3",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/67a1e0643268d3d8a97f4ecc794b94658ea33ba3"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "71bff248824d7abc2e3801c3ce628edf9c82829b"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@71bff248824d7abc2e3801c3ce628edf9c82829b",
                        "title": "71bff248824d7abc2e3801c3ce628edf9c82829b",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/71bff248824d7abc2e3801c3ce628edf9c82829b"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "pigweed.googlesource.com",
    "project": "pigweed/pigweed",
    "id": "71bff248824d7abc2e3801c3ce628edf9c82829b",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8865084152855759104"
    },
    {
      "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"
  }
}
[17:52:34.670] Scheduled build:
{
  "id": "8699654090951451505",
  "builder": {
    "project": "pigweed",
    "bucket": "pigweed.ci",
    "builder": "pigweed-mac-x86-gn-platform"
  },
  "createdBy": "project:pigweed",
  "createTime": "2025-10-29T17:52:34.049772369Z",
  "updateTime": "2025-10-29T17:52:34.049772369Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "pigweed.googlesource.com",
      "project": "pigweed/pigweed",
      "id": "71bff248824d7abc2e3801c3ce628edf9c82829b",
      "ref": "refs/heads/main"
    }
  },
  "canary": true
}
[17:52:34.670] Task URL: https://cr-buildbucket.appspot.com/build/8699654090951451505
[17:52:34.670] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:2:0) after 3m33s
[17:52:35.530] Received PubSub notification, asking Buildbucket for the build status
[17:52:35.571] Build status: SCHEDULED
[17:56:07.662] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:2:0)
[17:56:07.687] Build status: SCHEDULED
[17:56:07.687] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:4:0) after 4m10s
[18:00:17.817] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:4:0)
[18:00:17.834] Build status: SCHEDULED
[18:00:17.834] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:5:0) after 9m7s
[18:09:24.830] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:5:0)
[18:09:24.864] Build status: SCHEDULED
[18:09:24.864] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:6:0) after 3m6s
[18:12:30.913] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:6:0)
[18:12:30.960] Build status: SCHEDULED
[18:12:30.960] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:7:0) after 1m13s
[18:13:43.974] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:7:0)
[18:13:43.990] Build status: SCHEDULED
[18:13:43.990] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:8:0) after 6m16s
[18:20:00.038] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:8:0)
[18:20:00.057] Build status: SCHEDULED
[18:20:00.057] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:9:0) after 3m27s
[18:23:27.051] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:9:0)
[18:23:27.087] Build status: SCHEDULED
[18:23:27.087] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:10:0) after 6m12s
[18:29:39.153] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:10:0)
[18:29:39.169] Build status: SCHEDULED
[18:29:39.169] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:11:0) after 3m47s
[18:33:26.344] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:11:0)
[18:33:26.367] Build status: SCHEDULED
[18:33:26.367] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:12:0) after 7m10s
[18:40:36.461] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:12:0)
[18:40:36.487] Build status: SCHEDULED
[18:40:36.487] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:13:0) after 8m25s
[18:49:01.499] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:13:0)
[18:49:01.537] Build status: SCHEDULED
[18:49:01.537] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:14:0) after 3m19s
[18:52:20.575] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:14:0)
[18:52:20.592] Build status: SCHEDULED
[18:52:20.592] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:15:0) after 1m34s
[18:53:54.608] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:15:0)
[18:53:54.626] Build status: SCHEDULED
[18:53:54.626] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:16:0) after 1m58s
[18:55:52.687] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:16:0)
[18:55:52.721] Build status: SCHEDULED
[18:55:52.721] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:17:0) after 6m40s
[19:02:32.754] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:17:0)
[19:02:32.774] Build status: SCHEDULED
[19:02:32.774] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:18:0) after 7m2s
[19:09:34.787] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:18:0)
[19:09:34.816] Build status: SCHEDULED
[19:09:34.816] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:19:0) after 8m27s
[19:18:01.875] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:19:0)
[19:18:01.896] Build status: SCHEDULED
[19:18:01.896] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:20:0) after 9m27s
[19:27:29.058] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:20:0)
[19:27:29.075] Build status: SCHEDULED
[19:27:29.075] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:21:0) after 4m28s
[19:29:08.597] Received PubSub notification, asking Buildbucket for the build status
[19:29:08.624] Build status: STARTED
[19:31:57.087] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:21:0)
[19:31:57.107] Build status: STARTED
[19:31:57.107] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:23:0) after 7m15s
[19:39:12.132] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:23:0)
[19:39:12.161] Build status: STARTED
[19:39:12.161] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:24:0) after 9m3s
[19:48:15.189] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:24:0)
[19:48:15.210] Build status: STARTED
[19:48:15.210] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:25:0) after 8m12s
[19:56:27.215] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:25:0)
[19:56:27.233] Build status: STARTED
[19:56:27.233] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:26:0) after 4m47s
[20:01:14.347] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:26:0)
[20:01:14.371] Build status: STARTED
[20:01:14.371] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:27:0) after 4m36s
[20:05:50.389] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:27:0)
[20:05:50.405] Build status: STARTED
[20:05:50.405] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:28:0) after 2m12s
[20:08:02.409] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:28:0)
[20:08:02.427] Build status: STARTED
[20:08:02.435] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:29:0) after 2m36s
[20:10:38.490] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:29:0)
[20:10:38.554] Build status: STARTED
[20:10:38.554] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865084152855759104:30:0) after 9m36s
[20:13:42.063] Received PubSub notification, asking Buildbucket for the build status
[20:13:42.080] Build:
{
  "id": "8699654090951451505",
  "builder": {
    "project": "pigweed",
    "bucket": "pigweed.ci",
    "builder": "pigweed-mac-x86-gn-platform"
  },
  "createdBy": "project:pigweed",
  "createTime": "2025-10-29T17:52:34.049772369Z",
  "startTime": "2025-10-29T19:29:08.197969528Z",
  "endTime": "2025-10-29T20:13:41.645382127Z",
  "updateTime": "2025-10-29T20:13:41.645382127Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "pigweed.googlesource.com",
      "project": "pigweed/pigweed",
      "id": "71bff248824d7abc2e3801c3ce628edf9c82829b",
      "ref": "refs/heads/main"
    }
  },
  "canary": true
}
[20:13:42.080] Invocation finished in 2h21m9.230215395s with status SUCCEEDED