[20:01:48.423] New invocation is queued and will start shortly
[20:01:49.681] Starting the invocation (attempt 1)
[20:01:49.711] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/b78d6e35d55e5acc729a10acaa88fef2b4eae389"
[20:01:49.711] Popped gitiles commit info from properties and tags
[20:01:49.711] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:01:49.711] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:01:49.711] Buildbucket request:
{
  "requestId": "8868065720452343456",
  "builder": {
    "project": "fuchsia",
    "bucket": "global.ci",
    "builder": "bringup.x64-lto"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8868065720452343456",
            "job": "fuchsia/global.ci-bringup.x64-lto-e5a0ec7f",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://fuchsia.googlesource.com/integration",
                              "revision": "4dc658f3cbc22fd82f790d2ff3c3db27f7faf333"
                            },
                        "id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4dc658f3cbc22fd82f790d2ff3c3db27f7faf333",
                        "title": "4dc658f3cbc22fd82f790d2ff3c3db27f7faf333",
                        "url": "https://fuchsia.googlesource.com/integration/+/4dc658f3cbc22fd82f790d2ff3c3db27f7faf333"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://fuchsia.googlesource.com/integration",
                              "revision": "c849d26ec439679f3e39e55b5caa4965b12326eb"
                            },
                        "id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c849d26ec439679f3e39e55b5caa4965b12326eb",
                        "title": "c849d26ec439679f3e39e55b5caa4965b12326eb",
                        "url": "https://fuchsia.googlesource.com/integration/+/c849d26ec439679f3e39e55b5caa4965b12326eb"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://fuchsia.googlesource.com/integration",
                              "revision": "ee18b528cf86342a2c577d11ab16b99044ce1a45"
                            },
                        "id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ee18b528cf86342a2c577d11ab16b99044ce1a45",
                        "title": "ee18b528cf86342a2c577d11ab16b99044ce1a45",
                        "url": "https://fuchsia.googlesource.com/integration/+/ee18b528cf86342a2c577d11ab16b99044ce1a45"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://fuchsia.googlesource.com/integration",
                              "revision": "1368c6cd2c32773ce32aec0ee5666a1a9831a755"
                            },
                        "id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1368c6cd2c32773ce32aec0ee5666a1a9831a755",
                        "title": "1368c6cd2c32773ce32aec0ee5666a1a9831a755",
                        "url": "https://fuchsia.googlesource.com/integration/+/1368c6cd2c32773ce32aec0ee5666a1a9831a755"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://fuchsia.googlesource.com/integration",
                              "revision": "f1874e7746eb09caaf3d4751a4f062ba3d92eca7"
                            },
                        "id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f1874e7746eb09caaf3d4751a4f062ba3d92eca7",
                        "title": "f1874e7746eb09caaf3d4751a4f062ba3d92eca7",
                        "url": "https://fuchsia.googlesource.com/integration/+/f1874e7746eb09caaf3d4751a4f062ba3d92eca7"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://fuchsia.googlesource.com/integration",
                              "revision": "f325aae4ae30c7ef4f62845e4552bd257ee66af3"
                            },
                        "id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f325aae4ae30c7ef4f62845e4552bd257ee66af3",
                        "title": "f325aae4ae30c7ef4f62845e4552bd257ee66af3",
                        "url": "https://fuchsia.googlesource.com/integration/+/f325aae4ae30c7ef4f62845e4552bd257ee66af3"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://fuchsia.googlesource.com/integration",
                              "revision": "e15435aacd3e0eb85d3be7f8d5026a56a4dd3694"
                            },
                        "id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e15435aacd3e0eb85d3be7f8d5026a56a4dd3694",
                        "title": "e15435aacd3e0eb85d3be7f8d5026a56a4dd3694",
                        "url": "https://fuchsia.googlesource.com/integration/+/e15435aacd3e0eb85d3be7f8d5026a56a4dd3694"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://fuchsia.googlesource.com/integration",
                              "revision": "b78d6e35d55e5acc729a10acaa88fef2b4eae389"
                            },
                        "id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b78d6e35d55e5acc729a10acaa88fef2b4eae389",
                        "title": "b78d6e35d55e5acc729a10acaa88fef2b4eae389",
                        "url": "https://fuchsia.googlesource.com/integration/+/b78d6e35d55e5acc729a10acaa88fef2b4eae389"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "fuchsia.googlesource.com",
    "project": "integration",
    "id": "b78d6e35d55e5acc729a10acaa88fef2b4eae389",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8868065720452343456"
    },
    {
      "key": "scheduler_job_id",
      "value": "fuchsia/global.ci-bringup.x64-lto-e5a0ec7f"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[20:01:53.482] Scheduled build:
{
  "id": "8702635658369284753",
  "builder": {
    "project": "fuchsia",
    "bucket": "global.ci",
    "builder": "bringup.x64-lto"
  },
  "createdBy": "project:fuchsia",
  "createTime": "2025-09-26T20:01:49.780171539Z",
  "updateTime": "2025-09-26T20:01:49.780171539Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "fuchsia.googlesource.com",
      "project": "integration",
      "id": "b78d6e35d55e5acc729a10acaa88fef2b4eae389",
      "ref": "refs/heads/main"
    }
  },
  "canary": true
}
[20:01:53.482] Task URL: https://cr-buildbucket.appspot.com/build/8702635658369284753
[20:01:53.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-bringup.x64-lto-e5a0ec7f:8868065720452343456:2:0) after 8m26s
[20:01:56.528] Received PubSub notification, asking Buildbucket for the build status
[20:01:56.552] Build status: SCHEDULED
[20:04:11.175] Received PubSub notification, asking Buildbucket for the build status
[20:04:11.204] Build status: STARTED
[20:10:19.621] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-bringup.x64-lto-e5a0ec7f:8868065720452343456:2:0)
[20:10:19.733] Build status: STARTED
[20:10:19.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-bringup.x64-lto-e5a0ec7f:8868065720452343456:5:0) after 5m9s
[20:15:28.794] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-bringup.x64-lto-e5a0ec7f:8868065720452343456:5:0)
[20:15:28.811] Build status: STARTED
[20:15:28.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-bringup.x64-lto-e5a0ec7f:8868065720452343456:6:0) after 3m41s
[20:19:09.873] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-bringup.x64-lto-e5a0ec7f:8868065720452343456:6:0)
[20:19:09.901] Build status: STARTED
[20:19:09.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-bringup.x64-lto-e5a0ec7f:8868065720452343456:7:0) after 9m27s
[20:26:37.469] Received PubSub notification, asking Buildbucket for the build status
[20:26:37.487] Build:
{
  "id": "8702635658369284753",
  "builder": {
    "project": "fuchsia",
    "bucket": "global.ci",
    "builder": "bringup.x64-lto"
  },
  "createdBy": "project:fuchsia",
  "createTime": "2025-09-26T20:01:49.780171539Z",
  "startTime": "2025-09-26T20:04:10.974731791Z",
  "endTime": "2025-09-26T20:26:37.024133889Z",
  "updateTime": "2025-09-26T20:26:37.024133889Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "fuchsia.googlesource.com",
      "project": "integration",
      "id": "b78d6e35d55e5acc729a10acaa88fef2b4eae389",
      "ref": "refs/heads/main"
    }
  },
  "canary": true
}
[20:26:37.487] Invocation finished in 24m49.077462142s with status SUCCEEDED