[10:36:03.101] New invocation is queued and will start shortly
[10:36:04.231] Starting the invocation (attempt 1)
[10:36:04.255] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/e4746e7564d601ae4dfb8355c0d7af00d1178694"
[10:36:04.255] Popped gitiles commit info from properties and tags
[10:36:04.255] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:36:04.256] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:36:04.256] Buildbucket request:
{
  "requestId": "8865292808743474336",
  "builder": {
    "project": "v8",
    "bucket": "ci",
    "builder": "Linux Debug Builder"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8865292808743474336",
            "job": "v8/Linux Debug Builder",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/v8/v8",
                              "revision": "ed9cd2b60f0c2af8a3befa63e68ea9c362a57cb9"
                            },
                        "id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@ed9cd2b60f0c2af8a3befa63e68ea9c362a57cb9",
                        "title": "ed9cd2b60f0c2af8a3befa63e68ea9c362a57cb9",
                        "url": "https://chromium.googlesource.com/v8/v8/+/ed9cd2b60f0c2af8a3befa63e68ea9c362a57cb9"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/v8/v8",
                              "revision": "27d43a6ee982f210a817f34526ef5586ca2ce5d2"
                            },
                        "id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@27d43a6ee982f210a817f34526ef5586ca2ce5d2",
                        "title": "27d43a6ee982f210a817f34526ef5586ca2ce5d2",
                        "url": "https://chromium.googlesource.com/v8/v8/+/27d43a6ee982f210a817f34526ef5586ca2ce5d2"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/v8/v8",
                              "revision": "6624c237b903d2c5112b953ea29741722e3d47df"
                            },
                        "id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@6624c237b903d2c5112b953ea29741722e3d47df",
                        "title": "6624c237b903d2c5112b953ea29741722e3d47df",
                        "url": "https://chromium.googlesource.com/v8/v8/+/6624c237b903d2c5112b953ea29741722e3d47df"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/v8/v8",
                              "revision": "e4746e7564d601ae4dfb8355c0d7af00d1178694"
                            },
                        "id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@e4746e7564d601ae4dfb8355c0d7af00d1178694",
                        "title": "e4746e7564d601ae4dfb8355c0d7af00d1178694",
                        "url": "https://chromium.googlesource.com/v8/v8/+/e4746e7564d601ae4dfb8355c0d7af00d1178694"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "v8/v8",
    "id": "e4746e7564d601ae4dfb8355c0d7af00d1178694",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8865292808743474336"
    },
    {
      "key": "scheduler_job_id",
      "value": "v8/Linux Debug Builder"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[10:36:04.752] Scheduled build:
{
  "id": "8699862746779007153",
  "builder": {
    "project": "v8",
    "bucket": "ci",
    "builder": "Linux Debug Builder"
  },
  "number": 40180,
  "createdBy": "project:v8",
  "createTime": "2025-10-27T10:36:04.345152893Z",
  "updateTime": "2025-10-27T10:36:04.345152893Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "v8/v8",
      "id": "e4746e7564d601ae4dfb8355c0d7af00d1178694",
      "ref": "refs/heads/main"
    }
  }
}
[10:36:04.752] Task URL: https://cr-buildbucket.appspot.com/build/8699862746779007153
[10:36:04.752] Scheduling timer "check-buildbucket-build-status" (v8/Linux Debug Builder:8865292808743474336:2:0) after 7m5s
[10:36:09.121] Received PubSub notification, asking Buildbucket for the build status
[10:36:09.141] Build status: SCHEDULED
[10:36:11.875] Received PubSub notification, asking Buildbucket for the build status
[10:36:11.899] Build status: STARTED
[10:43:09.815] Handling timer "check-buildbucket-build-status" (v8/Linux Debug Builder:8865292808743474336:2:0)
[10:43:09.829] Build status: STARTED
[10:43:09.829] Scheduling timer "check-buildbucket-build-status" (v8/Linux Debug Builder:8865292808743474336:5:0) after 1m17s
[10:44:26.963] Handling timer "check-buildbucket-build-status" (v8/Linux Debug Builder:8865292808743474336:5:0)
[10:44:26.984] Build status: STARTED
[10:44:26.984] Scheduling timer "check-buildbucket-build-status" (v8/Linux Debug Builder:8865292808743474336:6:0) after 2m47s
[10:47:13.998] Handling timer "check-buildbucket-build-status" (v8/Linux Debug Builder:8865292808743474336:6:0)
[10:47:14.015] Build status: STARTED
[10:47:14.015] Scheduling timer "check-buildbucket-build-status" (v8/Linux Debug Builder:8865292808743474336:7:0) after 5m22s
[10:52:36.029] Handling timer "check-buildbucket-build-status" (v8/Linux Debug Builder:8865292808743474336:7:0)
[10:52:36.049] Build status: STARTED
[10:52:36.049] Scheduling timer "check-buildbucket-build-status" (v8/Linux Debug Builder:8865292808743474336:8:0) after 9m38s
[11:02:14.066] Handling timer "check-buildbucket-build-status" (v8/Linux Debug Builder:8865292808743474336:8:0)
[11:02:14.080] Build status: STARTED
[11:02:14.080] Scheduling timer "check-buildbucket-build-status" (v8/Linux Debug Builder:8865292808743474336:9:0) after 5m48s
[11:08:02.108] Handling timer "check-buildbucket-build-status" (v8/Linux Debug Builder:8865292808743474336:9:0)
[11:08:02.132] Build status: STARTED
[11:08:02.132] Scheduling timer "check-buildbucket-build-status" (v8/Linux Debug Builder:8865292808743474336:10:0) after 4m50s
[11:12:52.193] Handling timer "check-buildbucket-build-status" (v8/Linux Debug Builder:8865292808743474336:10:0)
[11:12:52.210] Build status: STARTED
[11:12:52.210] Scheduling timer "check-buildbucket-build-status" (v8/Linux Debug Builder:8865292808743474336:11:0) after 7m34s
[11:20:10.175] Received PubSub notification, asking Buildbucket for the build status
[11:20:10.190] Build:
{
  "id": "8699862746779007153",
  "builder": {
    "project": "v8",
    "bucket": "ci",
    "builder": "Linux Debug Builder"
  },
  "number": 40180,
  "createdBy": "project:v8",
  "createTime": "2025-10-27T10:36:04.345152893Z",
  "startTime": "2025-10-27T10:36:11.055203725Z",
  "endTime": "2025-10-27T11:20:09.980926273Z",
  "updateTime": "2025-10-27T11:20:09.980926273Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "v8/v8",
      "id": "e4746e7564d601ae4dfb8355c0d7af00d1178694",
      "ref": "refs/heads/main"
    }
  }
}
[11:20:10.190] Invocation finished in 44m7.101898109s with status SUCCEEDED