[20:22:46.616] New invocation is queued and will start shortly
[20:22:47.681] Starting the invocation (attempt 1)
[20:22:47.720] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/2bdf9dfd53028614d1c950f86ed3c41dc599ab21"
[20:22:47.720] Popped gitiles commit info from properties and tags
[20:22:47.720] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:22:47.721] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:22:47.722] Buildbucket request:
{
  "requestId": "8869060967761734160",
  "builder": {
    "project": "fuchsia",
    "bucket": "global.ci",
    "builder": "docsgen-sdk"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8869060967761734160",
            "job": "fuchsia/global.ci-docsgen-sdk-e5a0ec7f",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://fuchsia.googlesource.com/integration",
                              "revision": "17dca7a224a9af1d80da65c2a09abf93f8618232"
                            },
                        "id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@17dca7a224a9af1d80da65c2a09abf93f8618232",
                        "title": "17dca7a224a9af1d80da65c2a09abf93f8618232",
                        "url": "https://fuchsia.googlesource.com/integration/+/17dca7a224a9af1d80da65c2a09abf93f8618232"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://fuchsia.googlesource.com/integration",
                              "revision": "b53124cd5888a4f8622938687ebfb5c213124a63"
                            },
                        "id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b53124cd5888a4f8622938687ebfb5c213124a63",
                        "title": "b53124cd5888a4f8622938687ebfb5c213124a63",
                        "url": "https://fuchsia.googlesource.com/integration/+/b53124cd5888a4f8622938687ebfb5c213124a63"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://fuchsia.googlesource.com/integration",
                              "revision": "7fe356cfd6b5c8c8ee296972549143a197c4d53d"
                            },
                        "id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7fe356cfd6b5c8c8ee296972549143a197c4d53d",
                        "title": "7fe356cfd6b5c8c8ee296972549143a197c4d53d",
                        "url": "https://fuchsia.googlesource.com/integration/+/7fe356cfd6b5c8c8ee296972549143a197c4d53d"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://fuchsia.googlesource.com/integration",
                              "revision": "90608c6e2d1c647b49e764d759cd26df19db300a"
                            },
                        "id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@90608c6e2d1c647b49e764d759cd26df19db300a",
                        "title": "90608c6e2d1c647b49e764d759cd26df19db300a",
                        "url": "https://fuchsia.googlesource.com/integration/+/90608c6e2d1c647b49e764d759cd26df19db300a"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://fuchsia.googlesource.com/integration",
                              "revision": "6d16b40595f5fdb9533148ad5afcb3473269ed0b"
                            },
                        "id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6d16b40595f5fdb9533148ad5afcb3473269ed0b",
                        "title": "6d16b40595f5fdb9533148ad5afcb3473269ed0b",
                        "url": "https://fuchsia.googlesource.com/integration/+/6d16b40595f5fdb9533148ad5afcb3473269ed0b"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://fuchsia.googlesource.com/integration",
                              "revision": "260d9cf6bc8d880b21762be8f6add8a5a69d2034"
                            },
                        "id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@260d9cf6bc8d880b21762be8f6add8a5a69d2034",
                        "title": "260d9cf6bc8d880b21762be8f6add8a5a69d2034",
                        "url": "https://fuchsia.googlesource.com/integration/+/260d9cf6bc8d880b21762be8f6add8a5a69d2034"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://fuchsia.googlesource.com/integration",
                              "revision": "2bdf9dfd53028614d1c950f86ed3c41dc599ab21"
                            },
                        "id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2bdf9dfd53028614d1c950f86ed3c41dc599ab21",
                        "title": "2bdf9dfd53028614d1c950f86ed3c41dc599ab21",
                        "url": "https://fuchsia.googlesource.com/integration/+/2bdf9dfd53028614d1c950f86ed3c41dc599ab21"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "fuchsia.googlesource.com",
    "project": "integration",
    "id": "2bdf9dfd53028614d1c950f86ed3c41dc599ab21",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8869060967761734160"
    },
    {
      "key": "scheduler_job_id",
      "value": "fuchsia/global.ci-docsgen-sdk-e5a0ec7f"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[20:22:48.128] Scheduled build:
{
  "id": "8703630905849220945",
  "builder": {
    "project": "fuchsia",
    "bucket": "global.ci",
    "builder": "docsgen-sdk"
  },
  "createdBy": "project:fuchsia",
  "createTime": "2025-09-15T20:22:47.819902563Z",
  "updateTime": "2025-09-15T20:22:47.819902563Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "fuchsia.googlesource.com",
      "project": "integration",
      "id": "2bdf9dfd53028614d1c950f86ed3c41dc599ab21",
      "ref": "refs/heads/main"
    }
  }
}
[20:22:48.128] Task URL: https://cr-buildbucket.appspot.com/build/8703630905849220945
[20:22:48.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-docsgen-sdk-e5a0ec7f:8869060967761734160:2:0) after 6m30s
[20:22:49.161] Received PubSub notification, asking Buildbucket for the build status
[20:22:49.182] Build status: SCHEDULED
[20:22:55.112] Received PubSub notification, asking Buildbucket for the build status
[20:22:55.173] Build status: STARTED
[20:29:18.190] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-docsgen-sdk-e5a0ec7f:8869060967761734160:2:0)
[20:29:18.211] Build status: STARTED
[20:29:18.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-docsgen-sdk-e5a0ec7f:8869060967761734160:5:0) after 5m40s
[20:34:58.240] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-docsgen-sdk-e5a0ec7f:8869060967761734160:5:0)
[20:34:58.255] Build status: STARTED
[20:34:58.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-docsgen-sdk-e5a0ec7f:8869060967761734160:6:0) after 4m49s
[20:39:47.428] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-docsgen-sdk-e5a0ec7f:8869060967761734160:6:0)
[20:39:47.441] Build status: STARTED
[20:39:47.441] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-docsgen-sdk-e5a0ec7f:8869060967761734160:7:0) after 9m31s
[20:49:18.555] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-docsgen-sdk-e5a0ec7f:8869060967761734160:7:0)
[20:49:18.574] Build status: STARTED
[20:49:18.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-docsgen-sdk-e5a0ec7f:8869060967761734160:8:0) after 3m5s
[20:52:23.627] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-docsgen-sdk-e5a0ec7f:8869060967761734160:8:0)
[20:52:23.653] Build status: STARTED
[20:52:23.653] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-docsgen-sdk-e5a0ec7f:8869060967761734160:9:0) after 1m31s
[20:53:37.153] Received PubSub notification, asking Buildbucket for the build status
[20:53:37.219] Build:
{
  "id": "8703630905849220945",
  "builder": {
    "project": "fuchsia",
    "bucket": "global.ci",
    "builder": "docsgen-sdk"
  },
  "createdBy": "project:fuchsia",
  "createTime": "2025-09-15T20:22:47.819902563Z",
  "startTime": "2025-09-15T20:22:52.830587702Z",
  "endTime": "2025-09-15T20:53:35.864625088Z",
  "updateTime": "2025-09-15T20:53:35.864625088Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "fuchsia.googlesource.com",
      "project": "integration",
      "id": "2bdf9dfd53028614d1c950f86ed3c41dc599ab21",
      "ref": "refs/heads/main"
    }
  }
}
[20:53:37.219] Invocation finished in 30m50.607292382s with status SUCCEEDED