[11:19:52.972] New invocation is queued and will start shortly
[11:19:54.091] Starting the invocation (attempt 1)
[11:19:54.119] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e095e7ce553d63f90d958a5453838521c04f210b"
[11:19:54.119] Popped gitiles commit info from properties and tags
[11:19:54.119] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:19:54.120] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:19:54.120] Buildbucket request:
{
  "requestId": "8869638705512395680",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "Dawn Win10 x64 DEPS Builder"
  },
  "properties": {
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "chromium/src",
    "id": "e095e7ce553d63f90d958a5453838521c04f210b",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8869638705512395680"
    },
    {
      "key": "scheduler_job_id",
      "value": "chromium/Dawn Win10 x64 DEPS Builder"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[11:19:54.593] Scheduled build:
{
  "id": "8704208643591692977",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "Dawn Win10 x64 DEPS Builder"
  },
  "number": 125171,
  "createdBy": "project:chromium",
  "createTime": "2025-09-09T11:19:54.173489726Z",
  "updateTime": "2025-09-09T11:19:54.173489726Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "chromium/src",
      "id": "e095e7ce553d63f90d958a5453838521c04f210b",
      "ref": "refs/heads/main"
    }
  }
}
[11:19:54.593] Task URL: https://cr-buildbucket.appspot.com/build/8704208643591692977
[11:19:54.593] Scheduling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:2:0) after 1m36s
[11:19:55.581] Received PubSub notification, asking Buildbucket for the build status
[11:19:55.598] Build status: SCHEDULED
[11:20:00.245] Received PubSub notification, asking Buildbucket for the build status
[11:20:00.340] Build status: STARTED
[11:21:30.607] Handling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:2:0)
[11:21:30.636] Build status: STARTED
[11:21:30.636] Scheduling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:5:0) after 6m22s
[11:27:52.654] Handling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:5:0)
[11:27:52.673] Build status: STARTED
[11:27:52.673] Scheduling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:6:0) after 2m10s
[11:30:02.691] Handling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:6:0)
[11:30:02.712] Build status: STARTED
[11:30:02.712] Scheduling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:7:0) after 1m6s
[11:31:08.710] Handling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:7:0)
[11:31:08.724] Build status: STARTED
[11:31:08.724] Scheduling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:8:0) after 9m57s
[11:41:05.739] Handling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:8:0)
[11:41:05.757] Build status: STARTED
[11:41:05.757] Scheduling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:9:0) after 7m6s
[11:48:11.773] Handling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:9:0)
[11:48:11.801] Build status: STARTED
[11:48:11.802] Scheduling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:10:0) after 6m26s
[11:54:37.869] Handling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:10:0)
[11:54:37.938] Build status: STARTED
[11:54:37.938] Scheduling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:11:0) after 1m21s
[11:55:58.954] Handling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:11:0)
[11:55:58.971] Build status: STARTED
[11:55:58.971] Scheduling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:12:0) after 3m35s
[11:59:33.990] Handling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:12:0)
[11:59:34.016] Build status: STARTED
[11:59:34.016] Scheduling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:13:0) after 2m43s
[12:02:17.000] Handling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:13:0)
[12:02:17.097] Build status: STARTED
[12:02:17.097] Scheduling timer "check-buildbucket-build-status" (chromium/Dawn Win10 x64 DEPS Builder:8869638705512395680:14:0) after 3m29s
[12:05:24.201] Received PubSub notification, asking Buildbucket for the build status
[12:05:24.220] Build:
{
  "id": "8704208643591692977",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "Dawn Win10 x64 DEPS Builder"
  },
  "number": 125171,
  "createdBy": "project:chromium",
  "createTime": "2025-09-09T11:19:54.173489726Z",
  "startTime": "2025-09-09T11:20:00.085705482Z",
  "endTime": "2025-09-09T12:05:23.748622369Z",
  "updateTime": "2025-09-09T12:05:23.748622369Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "chromium/src",
      "id": "e095e7ce553d63f90d958a5453838521c04f210b",
      "ref": "refs/heads/main"
    }
  }
}
[12:05:24.220] Invocation finished in 45m31.262034083s with status SUCCEEDED