[08:44:26.672] New invocation is queued and will start shortly
[08:44:27.746] Starting the invocation (attempt 1)
[08:44:27.774] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/749e74379d5f3362128367add032686fab717e7c"
[08:44:27.774] Popped gitiles commit info from properties and tags
[08:44:27.774] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:44:27.774] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:44:27.774] Buildbucket request:
{
  "requestId": "8865662218316475376",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "android-15-x64-rel"
  },
  "properties": {
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "chromium/src",
    "id": "749e74379d5f3362128367add032686fab717e7c",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8865662218316475376"
    },
    {
      "key": "scheduler_job_id",
      "value": "chromium/android-15-x64-rel"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[08:44:28.262] Scheduled build:
{
  "id": "8700232156457147569",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "android-15-x64-rel"
  },
  "number": 8611,
  "createdBy": "project:chromium",
  "createTime": "2025-10-23T08:44:27.823652526Z",
  "updateTime": "2025-10-23T08:44:27.823652526Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "chromium/src",
      "id": "749e74379d5f3362128367add032686fab717e7c",
      "ref": "refs/heads/main"
    }
  }
}
[08:44:28.262] Task URL: https://cr-buildbucket.appspot.com/build/8700232156457147569
[08:44:28.262] Scheduling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:2:0) after 9m58s
[08:44:29.409] Received PubSub notification, asking Buildbucket for the build status
[08:44:29.435] Build status: SCHEDULED
[08:44:34.695] Received PubSub notification, asking Buildbucket for the build status
[08:44:34.725] Build status: STARTED
[08:54:26.273] Handling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:2:0)
[08:54:26.290] Build status: STARTED
[08:54:26.290] Scheduling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:5:0) after 1m23s
[08:55:49.329] Handling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:5:0)
[08:55:49.419] Build status: STARTED
[08:55:49.419] Scheduling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:6:0) after 9m16s
[09:05:05.434] Handling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:6:0)
[09:05:05.488] Build status: STARTED
[09:05:05.488] Scheduling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:7:0) after 3m49s
[09:08:54.547] Handling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:7:0)
[09:08:54.570] Build status: STARTED
[09:08:54.570] Scheduling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:8:0) after 8m6s
[09:17:01.254] Handling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:8:0)
[09:17:01.270] Build status: STARTED
[09:17:01.270] Scheduling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:9:0) after 3m2s
[09:20:03.324] Handling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:9:0)
[09:20:03.339] Build status: STARTED
[09:20:03.339] Scheduling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:10:0) after 4m56s
[09:24:59.355] Handling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:10:0)
[09:24:59.406] Build status: STARTED
[09:24:59.406] Scheduling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:11:0) after 6m46s
[09:31:45.428] Handling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:11:0)
[09:31:45.462] Build status: STARTED
[09:31:45.462] Scheduling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:12:0) after 1m30s
[09:33:15.480] Handling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:12:0)
[09:33:15.499] Build status: STARTED
[09:33:15.499] Scheduling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:13:0) after 5m3s
[09:38:18.525] Handling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:13:0)
[09:38:18.625] Build status: STARTED
[09:38:18.625] Scheduling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:14:0) after 6m56s
[09:45:14.728] Handling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:14:0)
[09:45:14.744] Build status: STARTED
[09:45:14.744] Scheduling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:15:0) after 4m20s
[09:49:34.988] Handling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:15:0)
[09:49:35.006] Build status: STARTED
[09:49:35.006] Scheduling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:16:0) after 8m5s
[09:57:40.058] Handling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:16:0)
[09:57:40.083] Build status: STARTED
[09:57:40.083] Scheduling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:17:0) after 5m23s
[10:03:03.425] Handling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:17:0)
[10:03:03.494] Build status: STARTED
[10:03:03.494] Scheduling timer "check-buildbucket-build-status" (chromium/android-15-x64-rel:8865662218316475376:18:0) after 7m34s
[10:06:33.776] Received PubSub notification, asking Buildbucket for the build status
[10:06:33.791] Build:
{
  "id": "8700232156457147569",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "android-15-x64-rel"
  },
  "number": 8611,
  "createdBy": "project:chromium",
  "createTime": "2025-10-23T08:44:27.823652526Z",
  "startTime": "2025-10-23T08:44:33.829643182Z",
  "endTime": "2025-10-23T10:06:33.266978542Z",
  "updateTime": "2025-10-23T10:06:33.266978542Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "chromium/src",
      "id": "749e74379d5f3362128367add032686fab717e7c",
      "ref": "refs/heads/main"
    }
  }
}
[10:06:33.791] Invocation finished in 1h22m7.124952801s with status SUCCEEDED