[12:19:18.089] New invocation is queued and will start shortly
[12:19:19.211] Starting the invocation (attempt 1)
[12:19:19.294] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e6cba166a559a744f6a7da8f8cf67d037de1ef5d"
[12:19:19.294] Popped gitiles commit info from properties and tags
[12:19:19.297] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:19:19.297] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:19:19.297] Buildbucket request:
{
  "requestId": "8870722130811185120",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "android-desktop-x64-deterministic-dbg"
  },
  "properties": {
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "chromium/src",
    "id": "e6cba166a559a744f6a7da8f8cf67d037de1ef5d",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8870722130811185120"
    },
    {
      "key": "scheduler_job_id",
      "value": "chromium/android-desktop-x64-deterministic-dbg"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[12:19:20.213] Scheduled build:
{
  "id": "8705292068671650465",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "android-desktop-x64-deterministic-dbg"
  },
  "number": 28,
  "createdBy": "project:chromium",
  "createTime": "2025-08-28T12:19:19.500840615Z",
  "updateTime": "2025-08-28T12:19:19.500840615Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "chromium/src",
      "id": "e6cba166a559a744f6a7da8f8cf67d037de1ef5d",
      "ref": "refs/heads/main"
    }
  }
}
[12:19:20.213] Task URL: https://cr-buildbucket.appspot.com/build/8705292068671650465
[12:19:20.213] Scheduling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:2:0) after 9m19s
[12:19:20.937] Received PubSub notification, asking Buildbucket for the build status
[12:19:20.957] Build status: SCHEDULED
[12:19:25.540] Received PubSub notification, asking Buildbucket for the build status
[12:19:25.560] Build status: STARTED
[12:28:39.277] Handling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:2:0)
[12:28:39.299] Build status: STARTED
[12:28:39.299] Scheduling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:5:0) after 4m53s
[12:33:32.318] Handling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:5:0)
[12:33:32.343] Build status: STARTED
[12:33:32.343] Scheduling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:6:0) after 2m47s
[12:36:19.408] Handling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:6:0)
[12:36:19.424] Build status: STARTED
[12:36:19.424] Scheduling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:7:0) after 4m19s
[12:40:38.490] Handling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:7:0)
[12:40:38.513] Build status: STARTED
[12:40:38.513] Scheduling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:8:0) after 9m52s
[12:50:30.532] Handling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:8:0)
[12:50:30.549] Build status: STARTED
[12:50:30.549] Scheduling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:9:0) after 5m13s
[12:55:43.572] Handling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:9:0)
[12:55:43.641] Build status: STARTED
[12:55:43.641] Scheduling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:10:0) after 5m7s
[13:00:50.795] Handling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:10:0)
[13:00:50.810] Build status: STARTED
[13:00:50.810] Scheduling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:11:0) after 2m58s
[13:03:49.202] Handling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:11:0)
[13:03:49.223] Build status: STARTED
[13:03:49.223] Scheduling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:12:0) after 1m51s
[13:05:40.532] Handling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:12:0)
[13:05:40.549] Build status: STARTED
[13:05:40.549] Scheduling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:13:0) after 7m37s
[13:13:17.563] Handling timer "check-buildbucket-build-status" (chromium/android-desktop-x64-deterministic-dbg:8870722130811185120:13:0)
[13:13:17.583] Build:
{
  "id": "8705292068671650465",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "android-desktop-x64-deterministic-dbg"
  },
  "number": 28,
  "createdBy": "project:chromium",
  "createTime": "2025-08-28T12:19:19.500840615Z",
  "startTime": "2025-08-28T12:19:25.238658375Z",
  "endTime": "2025-08-28T13:12:50.271125705Z",
  "updateTime": "2025-08-28T13:12:50.271125705Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "chromium/src",
      "id": "e6cba166a559a744f6a7da8f8cf67d037de1ef5d",
      "ref": "refs/heads/main"
    }
  }
}
[13:13:17.583] Invocation finished in 53m59.507210414s with status SUCCEEDED