[02:55:05.798] New invocation is queued and will start shortly
[02:55:06.960] Starting the invocation (attempt 1)
[02:55:07.002] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6e9c88bf78060c6615d7095907824a098f9fab1c"
[02:55:07.002] Popped gitiles commit info from properties and tags
[02:55:07.003] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:55:07.003] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:55:07.003] Buildbucket request:
{
  "requestId": "8869670463910297424",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "linux-swangle-chromium-x64"
  },
  "properties": {
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "chromium/src",
    "id": "6e9c88bf78060c6615d7095907824a098f9fab1c",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8869670463910297424"
    },
    {
      "key": "scheduler_job_id",
      "value": "chromium/linux-swangle-chromium-x64"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[02:55:07.533] Scheduled build:
{
  "id": "8704240401924673329",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "linux-swangle-chromium-x64"
  },
  "number": 61150,
  "createdBy": "project:chromium",
  "createTime": "2025-09-09T02:55:07.066317084Z",
  "updateTime": "2025-09-09T02:55:07.066317084Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "chromium/src",
      "id": "6e9c88bf78060c6615d7095907824a098f9fab1c",
      "ref": "refs/heads/main"
    }
  }
}
[02:55:07.533] Task URL: https://cr-buildbucket.appspot.com/build/8704240401924673329
[02:55:07.533] Scheduling timer "check-buildbucket-build-status" (chromium/linux-swangle-chromium-x64:8869670463910297424:2:0) after 6m26s
[02:55:10.991] Received PubSub notification, asking Buildbucket for the build status
[02:55:11.006] Build status: SCHEDULED
[02:55:21.534] Received PubSub notification, asking Buildbucket for the build status
[02:55:21.677] Build status: STARTED
[03:01:33.586] Handling timer "check-buildbucket-build-status" (chromium/linux-swangle-chromium-x64:8869670463910297424:2:0)
[03:01:33.610] Build status: STARTED
[03:01:33.610] Scheduling timer "check-buildbucket-build-status" (chromium/linux-swangle-chromium-x64:8869670463910297424:5:0) after 9m54s
[03:11:27.626] Handling timer "check-buildbucket-build-status" (chromium/linux-swangle-chromium-x64:8869670463910297424:5:0)
[03:11:27.654] Build status: STARTED
[03:11:27.654] Scheduling timer "check-buildbucket-build-status" (chromium/linux-swangle-chromium-x64:8869670463910297424:6:0) after 6m12s
[03:17:39.719] Handling timer "check-buildbucket-build-status" (chromium/linux-swangle-chromium-x64:8869670463910297424:6:0)
[03:17:39.787] Build status: STARTED
[03:17:39.787] Scheduling timer "check-buildbucket-build-status" (chromium/linux-swangle-chromium-x64:8869670463910297424:7:0) after 3m53s
[03:21:32.804] Handling timer "check-buildbucket-build-status" (chromium/linux-swangle-chromium-x64:8869670463910297424:7:0)
[03:21:32.882] Build status: STARTED
[03:21:32.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-swangle-chromium-x64:8869670463910297424:8:0) after 3m54s
[03:25:26.907] Handling timer "check-buildbucket-build-status" (chromium/linux-swangle-chromium-x64:8869670463910297424:8:0)
[03:25:26.980] Build status: STARTED
[03:25:26.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-swangle-chromium-x64:8869670463910297424:9:0) after 1m30s
[03:26:57.000] Handling timer "check-buildbucket-build-status" (chromium/linux-swangle-chromium-x64:8869670463910297424:9:0)
[03:26:57.028] Build status: STARTED
[03:26:57.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-swangle-chromium-x64:8869670463910297424:10:0) after 9m44s
[03:27:26.678] Received PubSub notification, asking Buildbucket for the build status
[03:27:26.699] Build:
{
  "id": "8704240401924673329",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "linux-swangle-chromium-x64"
  },
  "number": 61150,
  "createdBy": "project:chromium",
  "createTime": "2025-09-09T02:55:07.066317084Z",
  "startTime": "2025-09-09T02:55:18.348921542Z",
  "endTime": "2025-09-09T03:27:26.339217217Z",
  "updateTime": "2025-09-09T03:27:26.339217217Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "chromium/src",
      "id": "6e9c88bf78060c6615d7095907824a098f9fab1c",
      "ref": "refs/heads/main"
    }
  }
}
[03:27:26.699] Invocation finished in 32m20.910297361s with status SUCCEEDED