[11:48:46.741] New invocation is queued and will start shortly
[11:48:47.925] Starting the invocation (attempt 1)
[11:48:48.048] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/30e97aafc11939cc248e96966c102500e5f01604"
[11:48:48.048] Popped gitiles commit info from properties and tags
[11:48:48.048] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:48:48.049] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:48:48.049] Buildbucket request:
{
  "requestId": "8867553157294460656",
  "builder": {
    "project": "v8",
    "bucket": "ci",
    "builder": "Win V8 FYI Release (NVIDIA)"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8867553157294460656",
            "job": "v8/Win V8 FYI Release (NVIDIA)",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/v8/v8",
                              "revision": "5e43edfe2347b6bf78e2c5a03fd281a690580c91"
                            },
                        "id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@5e43edfe2347b6bf78e2c5a03fd281a690580c91",
                        "title": "5e43edfe2347b6bf78e2c5a03fd281a690580c91",
                        "url": "https://chromium.googlesource.com/v8/v8/+/5e43edfe2347b6bf78e2c5a03fd281a690580c91"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/v8/v8",
                              "revision": "0dda84e3f51d41dbe19d5263332b008b6450fc31"
                            },
                        "id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@0dda84e3f51d41dbe19d5263332b008b6450fc31",
                        "title": "0dda84e3f51d41dbe19d5263332b008b6450fc31",
                        "url": "https://chromium.googlesource.com/v8/v8/+/0dda84e3f51d41dbe19d5263332b008b6450fc31"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/v8/v8",
                              "revision": "b9dd7c7522c728c127654b2149b801e046612c73"
                            },
                        "id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@b9dd7c7522c728c127654b2149b801e046612c73",
                        "title": "b9dd7c7522c728c127654b2149b801e046612c73",
                        "url": "https://chromium.googlesource.com/v8/v8/+/b9dd7c7522c728c127654b2149b801e046612c73"
                      },
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/v8/v8",
                              "revision": "30e97aafc11939cc248e96966c102500e5f01604"
                            },
                        "id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@30e97aafc11939cc248e96966c102500e5f01604",
                        "title": "30e97aafc11939cc248e96966c102500e5f01604",
                        "url": "https://chromium.googlesource.com/v8/v8/+/30e97aafc11939cc248e96966c102500e5f01604"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "v8/v8",
    "id": "30e97aafc11939cc248e96966c102500e5f01604",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8867553157294460656"
    },
    {
      "key": "scheduler_job_id",
      "value": "v8/Win V8 FYI Release (NVIDIA)"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[11:48:48.540] Scheduled build:
{
  "id": "8702123095212234225",
  "builder": {
    "project": "v8",
    "bucket": "ci",
    "builder": "Win V8 FYI Release (NVIDIA)"
  },
  "number": 27007,
  "createdBy": "project:v8",
  "createTime": "2025-10-02T11:48:48.098321970Z",
  "updateTime": "2025-10-02T11:48:48.098321970Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "v8/v8",
      "id": "30e97aafc11939cc248e96966c102500e5f01604",
      "ref": "refs/heads/main"
    }
  }
}
[11:48:48.540] Task URL: https://cr-buildbucket.appspot.com/build/8702123095212234225
[11:48:48.540] Scheduling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:2:0) after 9m46s
[11:48:49.538] Received PubSub notification, asking Buildbucket for the build status
[11:48:49.560] Build status: SCHEDULED
[11:48:54.754] Received PubSub notification, asking Buildbucket for the build status
[11:48:54.773] Build status: STARTED
[11:58:34.551] Handling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:2:0)
[11:58:34.591] Build status: STARTED
[11:58:34.591] Scheduling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:5:0) after 1m9s
[11:59:43.608] Handling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:5:0)
[11:59:43.639] Build status: STARTED
[11:59:43.639] Scheduling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:6:0) after 3m59s
[12:03:42.731] Handling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:6:0)
[12:03:42.750] Build status: STARTED
[12:03:42.750] Scheduling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:7:0) after 1m14s
[12:04:56.780] Handling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:7:0)
[12:04:56.867] Build status: STARTED
[12:04:56.867] Scheduling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:8:0) after 7m23s
[12:12:19.882] Handling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:8:0)
[12:12:19.905] Build status: STARTED
[12:12:19.905] Scheduling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:9:0) after 8m0s
[12:20:19.925] Handling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:9:0)
[12:20:19.946] Build status: STARTED
[12:20:19.946] Scheduling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:10:0) after 6m43s
[12:27:03.014] Handling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:10:0)
[12:27:03.034] Build status: STARTED
[12:27:03.034] Scheduling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:11:0) after 3m38s
[12:30:41.040] Handling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:11:0)
[12:30:41.055] Build status: STARTED
[12:30:41.055] Scheduling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:12:0) after 1m14s
[12:31:55.118] Handling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:12:0)
[12:31:55.134] Build status: STARTED
[12:31:55.134] Scheduling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:13:0) after 6m14s
[12:38:09.205] Handling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:13:0)
[12:38:09.451] Build status: STARTED
[12:38:09.451] Scheduling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:14:0) after 8m24s
[12:46:33.473] Handling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:14:0)
[12:46:33.488] Build status: STARTED
[12:46:33.488] Scheduling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:15:0) after 7m44s
[12:54:17.500] Handling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:15:0)
[12:54:17.516] Build status: STARTED
[12:54:17.516] Scheduling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:16:0) after 8m49s
[13:03:06.529] Handling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:16:0)
[13:03:06.550] Build status: STARTED
[13:03:06.550] Scheduling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:17:0) after 9m20s
[13:12:26.584] Handling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:17:0)
[13:12:26.601] Build status: STARTED
[13:12:26.601] Scheduling timer "check-buildbucket-build-status" (v8/Win V8 FYI Release (NVIDIA):8867553157294460656:18:0) after 9m28s
[13:15:44.965] Received PubSub notification, asking Buildbucket for the build status
[13:15:44.989] Build:
{
  "id": "8702123095212234225",
  "builder": {
    "project": "v8",
    "bucket": "ci",
    "builder": "Win V8 FYI Release (NVIDIA)"
  },
  "number": 27007,
  "createdBy": "project:v8",
  "createTime": "2025-10-02T11:48:48.098321970Z",
  "startTime": "2025-10-02T11:48:54.591560017Z",
  "endTime": "2025-10-02T13:15:44.593424320Z",
  "updateTime": "2025-10-02T13:15:44.593424320Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "v8/v8",
      "id": "30e97aafc11939cc248e96966c102500e5f01604",
      "ref": "refs/heads/main"
    }
  }
}
[13:15:44.989] Invocation finished in 1h26m58.259925465s with status SUCCEEDED