[21:02:41.395] New invocation is queued and will start shortly
[21:02:42.642] Starting the invocation (attempt 1)
[21:02:42.668] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/devtools/devtools-frontend/+/5ddcd1d2823fd2013830e8f2dc27a1f7dbf9163a"
[21:02:42.668] Popped gitiles commit info from properties and tags
[21:02:42.668] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:02:42.669] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:02:42.670] Buildbucket request:
{
  "requestId": "8866249950703909088",
  "builder": {
    "project": "devtools-frontend",
    "bucket": "ci",
    "builder": "Linux Compile Debug"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8866249950703909088",
            "job": "devtools-frontend/Linux Compile Debug",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/devtools/devtools-frontend",
                              "revision": "5ddcd1d2823fd2013830e8f2dc27a1f7dbf9163a"
                            },
                        "id": "https://chromium.googlesource.com/devtools/devtools-frontend/+/refs/heads/main@5ddcd1d2823fd2013830e8f2dc27a1f7dbf9163a",
                        "title": "5ddcd1d2823fd2013830e8f2dc27a1f7dbf9163a",
                        "url": "https://chromium.googlesource.com/devtools/devtools-frontend/+/5ddcd1d2823fd2013830e8f2dc27a1f7dbf9163a"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "devtools/devtools-frontend",
    "id": "5ddcd1d2823fd2013830e8f2dc27a1f7dbf9163a",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8866249950703909088"
    },
    {
      "key": "scheduler_job_id",
      "value": "devtools-frontend/Linux Compile Debug"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[21:02:43.133] Scheduled build:
{
  "id": "8700819888653413873",
  "builder": {
    "project": "devtools-frontend",
    "bucket": "ci",
    "builder": "Linux Compile Debug"
  },
  "number": 20352,
  "createdBy": "project:devtools-frontend",
  "createTime": "2025-10-16T21:02:42.722331594Z",
  "updateTime": "2025-10-16T21:02:42.722331594Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "devtools/devtools-frontend",
      "id": "5ddcd1d2823fd2013830e8f2dc27a1f7dbf9163a",
      "ref": "refs/heads/main"
    }
  }
}
[21:02:43.133] Task URL: https://cr-buildbucket.appspot.com/build/8700819888653413873
[21:02:43.133] Scheduling timer "check-buildbucket-build-status" (devtools-frontend/Linux Compile Debug:8866249950703909088:2:0) after 8m7s
[21:02:44.122] Received PubSub notification, asking Buildbucket for the build status
[21:02:44.145] Build status: SCHEDULED
[21:02:49.101] Received PubSub notification, asking Buildbucket for the build status
[21:02:49.126] Build status: STARTED
[21:10:50.146] Handling timer "check-buildbucket-build-status" (devtools-frontend/Linux Compile Debug:8866249950703909088:2:0)
[21:10:50.166] Build status: STARTED
[21:10:50.166] Scheduling timer "check-buildbucket-build-status" (devtools-frontend/Linux Compile Debug:8866249950703909088:5:0) after 5m48s
[21:16:38.193] Handling timer "check-buildbucket-build-status" (devtools-frontend/Linux Compile Debug:8866249950703909088:5:0)
[21:16:38.250] Build status: STARTED
[21:16:38.250] Scheduling timer "check-buildbucket-build-status" (devtools-frontend/Linux Compile Debug:8866249950703909088:6:0) after 1m20s
[21:17:58.270] Handling timer "check-buildbucket-build-status" (devtools-frontend/Linux Compile Debug:8866249950703909088:6:0)
[21:17:58.292] Build status: STARTED
[21:17:58.292] Scheduling timer "check-buildbucket-build-status" (devtools-frontend/Linux Compile Debug:8866249950703909088:7:0) after 6m45s
[21:24:23.547] Received PubSub notification, asking Buildbucket for the build status
[21:24:23.564] Build:
{
  "id": "8700819888653413873",
  "builder": {
    "project": "devtools-frontend",
    "bucket": "ci",
    "builder": "Linux Compile Debug"
  },
  "number": 20352,
  "createdBy": "project:devtools-frontend",
  "createTime": "2025-10-16T21:02:42.722331594Z",
  "startTime": "2025-10-16T21:02:48.939456789Z",
  "endTime": "2025-10-16T21:24:23.105631327Z",
  "updateTime": "2025-10-16T21:24:23.105631327Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "devtools/devtools-frontend",
      "id": "5ddcd1d2823fd2013830e8f2dc27a1f7dbf9163a",
      "ref": "refs/heads/main"
    }
  }
}
[21:24:23.564] Invocation finished in 21m42.181295216s with status SUCCEEDED