[20:13:46.050] New invocation is queued and will start shortly
[20:13:47.114] Starting the invocation (attempt 1)
[20:13:47.147] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/182a101ef43a1ceddb20ac4c22794bbd8f88778a"
[20:13:47.147] Popped gitiles commit info from properties and tags
[20:13:47.147] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:13:47.147] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:13:47.147] Buildbucket request:
{
  "requestId": "8865075268066036032",
  "builder": {
    "project": "pigweed",
    "bucket": "pigweed.ci",
    "builder": "pigweed-mac-x86-gn-platform"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8865075268066036032",
            "job": "pigweed/pigweed.ci-pigweed-mac-x86-gn-platform",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "7a1ea2e25c1800b04b987ed0863cf0d7a91fd12d"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@7a1ea2e25c1800b04b987ed0863cf0d7a91fd12d",
                        "title": "7a1ea2e25c1800b04b987ed0863cf0d7a91fd12d",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/7a1ea2e25c1800b04b987ed0863cf0d7a91fd12d"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "1d0c07ffecf0eac74ea5a23abe5a899821f68fd6"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@1d0c07ffecf0eac74ea5a23abe5a899821f68fd6",
                        "title": "1d0c07ffecf0eac74ea5a23abe5a899821f68fd6",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/1d0c07ffecf0eac74ea5a23abe5a899821f68fd6"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "e82c55784f4f97ffa1d36b814827bbcdd7c01c1e"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@e82c55784f4f97ffa1d36b814827bbcdd7c01c1e",
                        "title": "e82c55784f4f97ffa1d36b814827bbcdd7c01c1e",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/e82c55784f4f97ffa1d36b814827bbcdd7c01c1e"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "835e78f5e7a1f228c8af60e88bf30050a378e10a"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@835e78f5e7a1f228c8af60e88bf30050a378e10a",
                        "title": "835e78f5e7a1f228c8af60e88bf30050a378e10a",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/835e78f5e7a1f228c8af60e88bf30050a378e10a"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "5ebb2b420434148c39558224c062cfc7e113fa05"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@5ebb2b420434148c39558224c062cfc7e113fa05",
                        "title": "5ebb2b420434148c39558224c062cfc7e113fa05",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/5ebb2b420434148c39558224c062cfc7e113fa05"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "6d02518face0018ab330235a715c4277efa04356"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@6d02518face0018ab330235a715c4277efa04356",
                        "title": "6d02518face0018ab330235a715c4277efa04356",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/6d02518face0018ab330235a715c4277efa04356"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "3a949c558c97a34cbc3b2b9a316ff408f0a26712"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@3a949c558c97a34cbc3b2b9a316ff408f0a26712",
                        "title": "3a949c558c97a34cbc3b2b9a316ff408f0a26712",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/3a949c558c97a34cbc3b2b9a316ff408f0a26712"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://pigweed.googlesource.com/pigweed/pigweed",
                              "revision": "182a101ef43a1ceddb20ac4c22794bbd8f88778a"
                            },
                        "id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@182a101ef43a1ceddb20ac4c22794bbd8f88778a",
                        "title": "182a101ef43a1ceddb20ac4c22794bbd8f88778a",
                        "url": "https://pigweed.googlesource.com/pigweed/pigweed/+/182a101ef43a1ceddb20ac4c22794bbd8f88778a"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "pigweed.googlesource.com",
    "project": "pigweed/pigweed",
    "id": "182a101ef43a1ceddb20ac4c22794bbd8f88778a",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8865075268066036032"
    },
    {
      "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"
  }
}
[20:13:47.527] Scheduled build:
{
  "id": "8699645206204268177",
  "builder": {
    "project": "pigweed",
    "bucket": "pigweed.ci",
    "builder": "pigweed-mac-x86-gn-platform"
  },
  "createdBy": "project:pigweed",
  "createTime": "2025-10-29T20:13:47.204051370Z",
  "updateTime": "2025-10-29T20:13:47.204051370Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "pigweed.googlesource.com",
      "project": "pigweed/pigweed",
      "id": "182a101ef43a1ceddb20ac4c22794bbd8f88778a",
      "ref": "refs/heads/main"
    }
  }
}
[20:13:47.527] Task URL: https://cr-buildbucket.appspot.com/build/8699645206204268177
[20:13:47.527] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:2:0) after 4m8s
[20:13:48.609] Received PubSub notification, asking Buildbucket for the build status
[20:13:48.635] Build status: SCHEDULED
[20:17:55.552] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:2:0)
[20:17:55.567] Build status: SCHEDULED
[20:17:55.567] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:4:0) after 5m24s
[20:23:19.671] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:4:0)
[20:23:19.690] Build status: SCHEDULED
[20:23:19.690] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:5:0) after 2m43s
[20:26:02.744] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:5:0)
[20:26:02.761] Build status: SCHEDULED
[20:26:02.761] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:6:0) after 1m13s
[20:27:15.796] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:6:0)
[20:27:15.833] Build status: SCHEDULED
[20:27:15.833] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:7:0) after 8m31s
[20:35:46.818] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:7:0)
[20:35:46.834] Build status: SCHEDULED
[20:35:46.834] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:8:0) after 3m36s
[20:39:22.847] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:8:0)
[20:39:22.880] Build status: SCHEDULED
[20:39:22.880] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:9:0) after 1m50s
[20:41:12.864] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:9:0)
[20:41:12.890] Build status: SCHEDULED
[20:41:12.891] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:10:0) after 7m53s
[20:49:05.946] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:10:0)
[20:49:05.965] Build status: SCHEDULED
[20:49:05.965] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:11:0) after 1m17s
[20:50:23.070] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:11:0)
[20:50:23.090] Build status: SCHEDULED
[20:50:23.090] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:12:0) after 1m9s
[20:51:32.174] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:12:0)
[20:51:32.201] Build status: SCHEDULED
[20:51:32.201] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:13:0) after 2m16s
[20:53:48.213] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:13:0)
[20:53:48.291] Build status: SCHEDULED
[20:53:48.291] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:14:0) after 7m35s
[21:01:23.360] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:14:0)
[21:01:23.382] Build status: SCHEDULED
[21:01:23.382] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:15:0) after 7m38s
[21:09:01.448] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:15:0)
[21:09:01.538] Build status: SCHEDULED
[21:09:01.538] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:16:0) after 4m24s
[21:13:25.606] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:16:0)
[21:13:25.626] Build status: SCHEDULED
[21:13:25.626] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:17:0) after 2m8s
[21:15:33.709] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:17:0)
[21:15:33.729] Build status: SCHEDULED
[21:15:33.729] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:18:0) after 2m47s
[21:18:20.893] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:18:0)
[21:18:20.906] Build status: SCHEDULED
[21:18:20.906] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:19:0) after 4m9s
[21:22:29.922] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:19:0)
[21:22:29.940] Build status: SCHEDULED
[21:22:29.940] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:20:0) after 4m56s
[21:27:25.984] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:20:0)
[21:27:26.015] Build status: SCHEDULED
[21:27:26.015] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:21:0) after 2m43s
[21:30:09.091] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:21:0)
[21:30:09.113] Build status: SCHEDULED
[21:30:09.114] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:22:0) after 5m32s
[21:35:41.179] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:22:0)
[21:35:41.203] Build status: SCHEDULED
[21:35:41.203] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:23:0) after 9m19s
[21:45:00.218] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:23:0)
[21:45:00.238] Build status: SCHEDULED
[21:45:00.238] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:24:0) after 3m43s
[21:48:43.364] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:24:0)
[21:48:43.381] Build status: SCHEDULED
[21:48:43.381] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:25:0) after 6m26s
[21:55:09.365] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:25:0)
[21:55:09.390] Build status: SCHEDULED
[21:55:09.390] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:26:0) after 6m5s
[21:55:28.376] Received PubSub notification, asking Buildbucket for the build status
[21:55:28.395] Build status: STARTED
[22:01:14.593] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:26:0)
[22:01:14.608] Build status: STARTED
[22:01:14.608] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:28:0) after 8m12s
[22:09:26.624] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:28:0)
[22:09:26.640] Build status: STARTED
[22:09:26.641] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:29:0) after 8m38s
[22:18:04.682] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:29:0)
[22:18:04.706] Build status: STARTED
[22:18:04.706] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:30:0) after 4m42s
[22:22:46.769] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:30:0)
[22:22:46.786] Build status: STARTED
[22:22:46.786] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:31:0) after 5m51s
[22:28:37.805] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:31:0)
[22:28:37.825] Build status: STARTED
[22:28:37.825] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:32:0) after 5m9s
[22:33:46.913] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:32:0)
[22:33:46.930] Build status: STARTED
[22:33:46.930] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.ci-pigweed-mac-x86-gn-platform:8865075268066036032:33:0) after 5m58s
[22:38:34.247] Received PubSub notification, asking Buildbucket for the build status
[22:38:34.290] Build:
{
  "id": "8699645206204268177",
  "builder": {
    "project": "pigweed",
    "bucket": "pigweed.ci",
    "builder": "pigweed-mac-x86-gn-platform"
  },
  "createdBy": "project:pigweed",
  "createTime": "2025-10-29T20:13:47.204051370Z",
  "startTime": "2025-10-29T21:55:28.208706470Z",
  "endTime": "2025-10-29T22:38:31.841554485Z",
  "updateTime": "2025-10-29T22:38:31.841554485Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "pigweed.googlesource.com",
      "project": "pigweed/pigweed",
      "id": "182a101ef43a1ceddb20ac4c22794bbd8f88778a",
      "ref": "refs/heads/main"
    }
  }
}
[22:38:34.290] Invocation finished in 2h24m48.244185342s with status SUCCEEDED