[10:29:22.616] New invocation is queued and will start shortly
[10:29:23.744] Starting the invocation (attempt 1)
[10:29:23.764] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/cae7aad5ed894ba0641029a824c79057e25b049c"
[10:29:23.764] Popped gitiles commit info from properties and tags
[10:29:23.764] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:29:23.764] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:29:23.765] Buildbucket request:
{
  "requestId": "8866380392277347392",
  "builder": {
    "project": "v8",
    "bucket": "ci",
    "builder": "Linux ASAN Builder"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8866380392277347392",
            "job": "v8/Linux ASAN Builder",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/v8/v8",
                              "revision": "cae7aad5ed894ba0641029a824c79057e25b049c"
                            },
                        "id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@cae7aad5ed894ba0641029a824c79057e25b049c",
                        "title": "cae7aad5ed894ba0641029a824c79057e25b049c",
                        "url": "https://chromium.googlesource.com/v8/v8/+/cae7aad5ed894ba0641029a824c79057e25b049c"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "v8/v8",
    "id": "cae7aad5ed894ba0641029a824c79057e25b049c",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8866380392277347392"
    },
    {
      "key": "scheduler_job_id",
      "value": "v8/Linux ASAN Builder"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[10:29:24.272] Scheduled build:
{
  "id": "8700950330353147249",
  "builder": {
    "project": "v8",
    "bucket": "ci",
    "builder": "Linux ASAN Builder"
  },
  "number": 40192,
  "createdBy": "project:v8",
  "createTime": "2025-10-15T10:29:23.823844622Z",
  "updateTime": "2025-10-15T10:29:23.823844622Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "v8/v8",
      "id": "cae7aad5ed894ba0641029a824c79057e25b049c",
      "ref": "refs/heads/main"
    }
  }
}
[10:29:24.272] Task URL: https://cr-buildbucket.appspot.com/build/8700950330353147249
[10:29:24.272] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8866380392277347392:2:0) after 4m21s
[10:29:24.388] Received PubSub notification, asking Buildbucket for the build status
[10:29:24.411] Build status: SCHEDULED
[10:29:32.631] Received PubSub notification, asking Buildbucket for the build status
[10:29:32.678] Build status: STARTED
[10:33:45.286] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8866380392277347392:2:0)
[10:33:45.314] Build status: STARTED
[10:33:45.314] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8866380392277347392:5:0) after 2m51s
[10:36:36.331] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8866380392277347392:5:0)
[10:36:36.369] Build status: STARTED
[10:36:36.369] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8866380392277347392:6:0) after 9m11s
[10:45:47.518] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8866380392277347392:6:0)
[10:45:47.567] Build status: STARTED
[10:45:47.567] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8866380392277347392:7:0) after 5m6s
[10:50:53.580] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8866380392277347392:7:0)
[10:50:53.611] Build status: STARTED
[10:50:53.611] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8866380392277347392:8:0) after 9m49s
[11:00:42.674] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8866380392277347392:8:0)
[11:00:42.690] Build status: STARTED
[11:00:42.690] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8866380392277347392:9:0) after 7m58s
[11:07:40.948] Received PubSub notification, asking Buildbucket for the build status
[11:07:40.976] Build:
{
  "id": "8700950330353147249",
  "builder": {
    "project": "v8",
    "bucket": "ci",
    "builder": "Linux ASAN Builder"
  },
  "number": 40192,
  "createdBy": "project:v8",
  "createTime": "2025-10-15T10:29:23.823844622Z",
  "startTime": "2025-10-15T10:29:32.463814937Z",
  "endTime": "2025-10-15T11:07:40.674494575Z",
  "updateTime": "2025-10-15T11:07:40.674494575Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "v8/v8",
      "id": "cae7aad5ed894ba0641029a824c79057e25b049c",
      "ref": "refs/heads/main"
    }
  }
}
[11:07:40.976] Invocation finished in 38m18.371033008s with status SUCCEEDED