[13:36:18.587] New invocation is queued and will start shortly
[13:36:19.930] Starting the invocation (attempt 1)
[13:36:19.954] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/db5ee2fd867c860e5ff1588f67c69b73e8cb67e1"
[13:36:19.954] Popped gitiles commit info from properties and tags
[13:36:19.954] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:36:19.955] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:36:19.955] Buildbucket request:
{
  "requestId": "8865462661810308128",
  "builder": {
    "project": "v8",
    "bucket": "ci",
    "builder": "V8 Linux64 - official - builder"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8865462661810308128",
            "job": "v8/ci-V8 Linux64 - official - builder",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/v8/v8",
                              "revision": "db5ee2fd867c860e5ff1588f67c69b73e8cb67e1"
                            },
                        "id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@db5ee2fd867c860e5ff1588f67c69b73e8cb67e1",
                        "title": "db5ee2fd867c860e5ff1588f67c69b73e8cb67e1",
                        "url": "https://chromium.googlesource.com/v8/v8/+/db5ee2fd867c860e5ff1588f67c69b73e8cb67e1"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "v8/v8",
    "id": "db5ee2fd867c860e5ff1588f67c69b73e8cb67e1",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8865462661810308128"
    },
    {
      "key": "scheduler_job_id",
      "value": "v8/ci-V8 Linux64 - official - builder"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[13:36:20.468] Scheduled build:
{
  "id": "8700032599674830049",
  "builder": {
    "project": "v8",
    "bucket": "ci",
    "builder": "V8 Linux64 - official - builder"
  },
  "number": 11914,
  "createdBy": "project:v8",
  "createTime": "2025-10-25T13:36:20.001908802Z",
  "updateTime": "2025-10-25T13:36:20.001908802Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "v8/v8",
      "id": "db5ee2fd867c860e5ff1588f67c69b73e8cb67e1",
      "ref": "refs/heads/main"
    }
  }
}
[13:36:20.468] Task URL: https://cr-buildbucket.appspot.com/build/8700032599674830049
[13:36:20.469] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:2:0) after 5m15s
[13:36:20.998] Received PubSub notification, asking Buildbucket for the build status
[13:36:21.020] Build status: SCHEDULED
[13:41:35.571] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:2:0)
[13:41:35.587] Build status: SCHEDULED
[13:41:35.588] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:4:0) after 1m43s
[13:43:18.645] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:4:0)
[13:43:18.691] Build status: SCHEDULED
[13:43:18.691] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:5:0) after 4m44s
[13:47:11.378] Received PubSub notification, asking Buildbucket for the build status
[13:47:11.443] Build status: STARTED
[13:48:02.702] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:5:0)
[13:48:02.758] Build status: STARTED
[13:48:02.758] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:7:0) after 4m35s
[13:52:37.760] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:7:0)
[13:52:37.786] Build status: STARTED
[13:52:37.786] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:8:0) after 9m40s
[14:02:17.799] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:8:0)
[14:02:17.814] Build status: STARTED
[14:02:17.814] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:9:0) after 5m20s
[14:07:38.155] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:9:0)
[14:07:38.172] Build status: STARTED
[14:07:38.172] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:10:0) after 2m55s
[14:10:33.252] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:10:0)
[14:10:33.270] Build status: STARTED
[14:10:33.270] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:11:0) after 1m46s
[14:12:19.281] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:11:0)
[14:12:19.313] Build status: STARTED
[14:12:19.313] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:12:0) after 8m50s
[14:21:09.490] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:12:0)
[14:21:09.503] Build status: STARTED
[14:21:09.503] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:13:0) after 5m2s
[14:26:11.569] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:13:0)
[14:26:11.624] Build status: STARTED
[14:26:11.624] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:14:0) after 7m16s
[14:33:27.688] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:14:0)
[14:33:27.704] Build status: STARTED
[14:33:27.704] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:15:0) after 8m50s
[14:42:17.858] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:15:0)
[14:42:17.877] Build status: STARTED
[14:42:17.877] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:16:0) after 5m31s
[14:47:48.891] Handling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:16:0)
[14:47:48.909] Build status: STARTED
[14:47:48.909] Scheduling timer "check-buildbucket-build-status" (v8/ci-V8 Linux64 - official - builder:8865462661810308128:17:0) after 4m45s
[14:49:43.286] Received PubSub notification, asking Buildbucket for the build status
[14:49:43.304] Build:
{
  "id": "8700032599674830049",
  "builder": {
    "project": "v8",
    "bucket": "ci",
    "builder": "V8 Linux64 - official - builder"
  },
  "number": 11914,
  "createdBy": "project:v8",
  "createTime": "2025-10-25T13:36:20.001908802Z",
  "startTime": "2025-10-25T13:47:11.228820184Z",
  "endTime": "2025-10-25T14:49:43.046744938Z",
  "updateTime": "2025-10-25T14:49:43.046744938Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "v8/v8",
      "id": "db5ee2fd867c860e5ff1588f67c69b73e8cb67e1",
      "ref": "refs/heads/main"
    }
  }
}
[14:49:43.304] Invocation finished in 1h13m24.72360015s with status SUCCEEDED