[10:57:56.852] New invocation is queued and will start shortly
[10:57:58.047] Starting the invocation (attempt 1)
[10:57:58.073] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/b7d130445607cbd79c80b3499c308ee345928b18"
[10:57:58.073] Popped gitiles commit info from properties and tags
[10:57:58.073] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:57:58.074] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:57:58.074] Buildbucket request:
{
  "requestId": "8865835012976408832",
  "builder": {
    "project": "v8",
    "bucket": "ci",
    "builder": "Linux ASAN Builder"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8865835012976408832",
            "job": "v8/Linux ASAN Builder",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/v8/v8",
                              "revision": "95caad629ea6d7aa1ebd0adfa3f12f285fd67b12"
                            },
                        "id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@95caad629ea6d7aa1ebd0adfa3f12f285fd67b12",
                        "title": "95caad629ea6d7aa1ebd0adfa3f12f285fd67b12",
                        "url": "https://chromium.googlesource.com/v8/v8/+/95caad629ea6d7aa1ebd0adfa3f12f285fd67b12"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/v8/v8",
                              "revision": "75603efc396406c0a8a81eef0b56ef79519447d1"
                            },
                        "id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@75603efc396406c0a8a81eef0b56ef79519447d1",
                        "title": "75603efc396406c0a8a81eef0b56ef79519447d1",
                        "url": "https://chromium.googlesource.com/v8/v8/+/75603efc396406c0a8a81eef0b56ef79519447d1"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/v8/v8",
                              "revision": "aac714b5e1c5d744685b92f3a6a11ba04c45a305"
                            },
                        "id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@aac714b5e1c5d744685b92f3a6a11ba04c45a305",
                        "title": "aac714b5e1c5d744685b92f3a6a11ba04c45a305",
                        "url": "https://chromium.googlesource.com/v8/v8/+/aac714b5e1c5d744685b92f3a6a11ba04c45a305"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/v8/v8",
                              "revision": "af310d23b0adec6ba0a6d370ba3a10bdef4e618c"
                            },
                        "id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@af310d23b0adec6ba0a6d370ba3a10bdef4e618c",
                        "title": "af310d23b0adec6ba0a6d370ba3a10bdef4e618c",
                        "url": "https://chromium.googlesource.com/v8/v8/+/af310d23b0adec6ba0a6d370ba3a10bdef4e618c"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/v8/v8",
                              "revision": "a0d67d829d9e254bd3df4a9df4edd286c6cc183f"
                            },
                        "id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@a0d67d829d9e254bd3df4a9df4edd286c6cc183f",
                        "title": "a0d67d829d9e254bd3df4a9df4edd286c6cc183f",
                        "url": "https://chromium.googlesource.com/v8/v8/+/a0d67d829d9e254bd3df4a9df4edd286c6cc183f"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/v8/v8",
                              "revision": "b7d130445607cbd79c80b3499c308ee345928b18"
                            },
                        "id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@b7d130445607cbd79c80b3499c308ee345928b18",
                        "title": "b7d130445607cbd79c80b3499c308ee345928b18",
                        "url": "https://chromium.googlesource.com/v8/v8/+/b7d130445607cbd79c80b3499c308ee345928b18"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "v8/v8",
    "id": "b7d130445607cbd79c80b3499c308ee345928b18",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8865835012976408832"
    },
    {
      "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:57:58.636] Scheduled build:
{
  "id": "8700404950985866273",
  "builder": {
    "project": "v8",
    "bucket": "ci",
    "builder": "Linux ASAN Builder"
  },
  "number": 40262,
  "createdBy": "project:v8",
  "createTime": "2025-10-21T10:57:58.119966279Z",
  "updateTime": "2025-10-21T10:57:58.119966279Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "v8/v8",
      "id": "b7d130445607cbd79c80b3499c308ee345928b18",
      "ref": "refs/heads/main"
    }
  }
}
[10:57:58.636] Task URL: https://cr-buildbucket.appspot.com/build/8700404950985866273
[10:57:58.636] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:2:0) after 1m21s
[10:57:59.594] Received PubSub notification, asking Buildbucket for the build status
[10:57:59.639] Build status: SCHEDULED
[10:58:07.137] Received PubSub notification, asking Buildbucket for the build status
[10:58:07.154] Build status: STARTED
[10:59:19.747] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:2:0)
[10:59:19.760] Build status: STARTED
[10:59:19.760] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:5:0) after 1m15s
[11:00:34.870] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:5:0)
[11:00:34.888] Build status: STARTED
[11:00:34.888] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:6:0) after 5m13s
[11:05:47.902] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:6:0)
[11:05:47.916] Build status: STARTED
[11:05:47.916] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:7:0) after 1m45s
[11:07:32.931] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:7:0)
[11:07:32.952] Build status: STARTED
[11:07:32.952] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:8:0) after 5m4s
[11:12:36.929] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:8:0)
[11:12:37.103] Build status: STARTED
[11:12:37.103] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:9:0) after 5m42s
[11:18:19.169] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:9:0)
[11:18:19.183] Build status: STARTED
[11:18:19.183] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:10:0) after 2m9s
[11:20:28.197] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:10:0)
[11:20:28.225] Build status: STARTED
[11:20:28.225] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:11:0) after 3m5s
[11:23:33.485] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:11:0)
[11:23:33.518] Build status: STARTED
[11:23:33.518] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:12:0) after 7m12s
[11:30:45.581] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:12:0)
[11:30:45.599] Build status: STARTED
[11:30:45.599] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:13:0) after 3m39s
[11:34:24.652] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:13:0)
[11:34:24.671] Build status: STARTED
[11:34:24.671] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:14:0) after 4m38s
[11:39:02.690] Handling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:14:0)
[11:39:02.710] Build status: STARTED
[11:39:02.710] Scheduling timer "check-buildbucket-build-status" (v8/Linux ASAN Builder:8865835012976408832:15:0) after 6m24s
[11:44:28.889] Received PubSub notification, asking Buildbucket for the build status
[11:44:28.905] Build:
{
  "id": "8700404950985866273",
  "builder": {
    "project": "v8",
    "bucket": "ci",
    "builder": "Linux ASAN Builder"
  },
  "number": 40262,
  "createdBy": "project:v8",
  "createTime": "2025-10-21T10:57:58.119966279Z",
  "startTime": "2025-10-21T10:58:06.911589706Z",
  "endTime": "2025-10-21T11:44:10.332169010Z",
  "updateTime": "2025-10-21T11:44:10.332169010Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "v8/v8",
      "id": "b7d130445607cbd79c80b3499c308ee345928b18",
      "ref": "refs/heads/main"
    }
  }
}
[11:44:28.905] Invocation finished in 46m32.068166917s with status SUCCEEDED