[12:20:34.062] New invocation is queued and will start shortly
[12:20:34.062] Triggered by user:v8-ci-builder@chops-service-accounts.iam.gserviceaccount.com
[12:20:35.177] Starting the invocation (attempt 1)
[12:20:35.210] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/8190e7d1363830f1068084b50e3dfd39958b7be2"
[12:20:35.210] Reconstructed gitiles commit from tags
[12:20:35.210] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:20:35.211] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:20:35.211] Buildbucket request:
{
  "requestId": "8865014442263392256",
  "builder": {
    "project": "v8",
    "bucket": "ci.br.extended",
    "builder": "V8 Linux64 - custom snapshot - debug"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8865014442263392256",
            "job": "v8/ci.br.extended-V8 Linux64 - custom snapshot - debug",
            "triggers": [
                  {
                        "buildbucket": {
                              "properties": {
                                    "newest_gitiles_revision": "8190e7d1363830f1068084b50e3dfd39958b7be2",
                                    "oldest_gitiles_revision": "8190e7d1363830f1068084b50e3dfd39958b7be2",
                                    "parent_build": "https://cr-buildbucket.appspot.com/build/8699587084298036097",
                                    "parent_buildername": "V8 Linux64 - custom snapshot - debug builder",
                                    "parent_gn_args": [
                                          "is_component_build = true",
                                          "is_debug = true",
                                          "target_cpu = \"x64\"",
                                          "use_remoteexec = true",
                                          "v8_embed_script = \"test/mjsunit/mjsunit.js\"",
                                          "v8_enable_backtrace = true",
                                          "v8_enable_google_benchmark = true",
                                          "v8_enable_slow_dchecks = true"
                                        ],
                                    "parent_got_revision": "8190e7d1363830f1068084b50e3dfd39958b7be2",
                                    "parent_got_revision_cp": "refs/branch-heads/14.2@{#31}",
                                    "parent_test_spec": {
                                          "swarming_dimensions": {
                                                "os": "Ubuntu-22.04"
                                              },
                                          "swarming_task_attrs": {
                                              },
                                          "tests": [
                                                [
                                                      "mjsunit",
                                                      2,
                                                      null,
                                                      "",
                                                      [
                                                            "--no-harness"
                                                          ],
                                                      {
                                                          },
                                                      {
                                                          }
                                                    ]
                                              ]
                                        },
                                    "revision": "8190e7d1363830f1068084b50e3dfd39958b7be2",
                                    "swarm_hashes": {
                                          "mjsunit": "dd48a50ed7bceaea40d14f6b568a0902e19a90c0601138961eeb38389fac5bce/319"
                                        }
                                  },
                              "tags": [
                                    "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/8190e7d1363830f1068084b50e3dfd39958b7be2",
                                    "gitiles_ref:refs/branch-heads/14.2",
                                    "parent_buildername:V8 Linux64 - custom snapshot - debug builder",
                                    "parent_buildnumber:524",
                                    "user_agent:recipe"
                                  ]
                            },
                        "id": "25536094-560b-4cad-8eda-c80545031a7d",
                        "title": "V8 Linux64 - custom snapshot - debug builder/524",
                        "url": "https://cr-buildbucket.appspot.com/build/8699587084298036097"
                      }
                ]
          },
      "newest_gitiles_revision": "8190e7d1363830f1068084b50e3dfd39958b7be2",
      "oldest_gitiles_revision": "8190e7d1363830f1068084b50e3dfd39958b7be2",
      "parent_build": "https://cr-buildbucket.appspot.com/build/8699587084298036097",
      "parent_buildername": "V8 Linux64 - custom snapshot - debug builder",
      "parent_gn_args": [
            "is_component_build = true",
            "is_debug = true",
            "target_cpu = \"x64\"",
            "use_remoteexec = true",
            "v8_embed_script = \"test/mjsunit/mjsunit.js\"",
            "v8_enable_backtrace = true",
            "v8_enable_google_benchmark = true",
            "v8_enable_slow_dchecks = true"
          ],
      "parent_got_revision": "8190e7d1363830f1068084b50e3dfd39958b7be2",
      "parent_got_revision_cp": "refs/branch-heads/14.2@{#31}",
      "parent_test_spec": {
            "swarming_dimensions": {
                  "os": "Ubuntu-22.04"
                },
            "swarming_task_attrs": {
                },
            "tests": [
                  [
                        "mjsunit",
                        2,
                        null,
                        "",
                        [
                              "--no-harness"
                            ],
                        {
                            },
                        {
                            }
                      ]
                ]
          },
      "swarm_hashes": {
            "mjsunit": "dd48a50ed7bceaea40d14f6b568a0902e19a90c0601138961eeb38389fac5bce/319"
          }
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "v8/v8",
    "id": "8190e7d1363830f1068084b50e3dfd39958b7be2",
    "ref": "refs/branch-heads/14.2"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8865014442263392256"
    },
    {
      "key": "scheduler_job_id",
      "value": "v8/ci.br.extended-V8 Linux64 - custom snapshot - debug"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    },
    {
      "key": "parent_buildername",
      "value": "V8 Linux64 - custom snapshot - debug builder"
    },
    {
      "key": "parent_buildnumber",
      "value": "524"
    },
    {
      "key": "user_agent",
      "value": "recipe"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[12:20:36.088] Scheduled build:
{
  "id": "8699584380261553329",
  "builder": {
    "project": "v8",
    "bucket": "ci.br.extended",
    "builder": "V8 Linux64 - custom snapshot - debug"
  },
  "number": 496,
  "createdBy": "project:v8",
  "createTime": "2025-10-30T12:20:35.343870211Z",
  "updateTime": "2025-10-30T12:20:35.343870211Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "v8/v8",
      "id": "8190e7d1363830f1068084b50e3dfd39958b7be2",
      "ref": "refs/branch-heads/14.2"
    }
  }
}
[12:20:36.088] Task URL: https://cr-buildbucket.appspot.com/build/8699584380261553329
[12:20:36.088] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:2:0) after 9m40s
[12:20:36.499] Received PubSub notification, asking Buildbucket for the build status
[12:20:36.515] Build status: SCHEDULED
[12:30:16.262] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:2:0)
[12:30:16.289] Build status: SCHEDULED
[12:30:16.290] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:4:0) after 6m36s
[12:36:52.320] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:4:0)
[12:36:52.339] Build status: SCHEDULED
[12:36:52.340] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:5:0) after 9m51s
[12:46:43.331] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:5:0)
[12:46:43.348] Build status: SCHEDULED
[12:46:43.348] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:6:0) after 6m56s
[12:53:39.471] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:6:0)
[12:53:39.485] Build status: SCHEDULED
[12:53:39.485] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:7:0) after 8m18s
[13:01:57.548] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:7:0)
[13:01:57.567] Build status: SCHEDULED
[13:01:57.567] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:8:0) after 3m5s
[13:05:02.628] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:8:0)
[13:05:02.644] Build status: SCHEDULED
[13:05:02.644] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:9:0) after 3m49s
[13:08:51.678] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:9:0)
[13:08:51.708] Build status: SCHEDULED
[13:08:51.708] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:10:0) after 4m2s
[13:12:53.727] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:10:0)
[13:12:53.746] Build status: SCHEDULED
[13:12:53.746] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:11:0) after 7m46s
[13:20:39.852] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:11:0)
[13:20:39.876] Build status: SCHEDULED
[13:20:39.876] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:12:0) after 8m35s
[13:29:14.914] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:12:0)
[13:29:14.938] Build status: SCHEDULED
[13:29:14.938] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:13:0) after 8m35s
[13:37:50.113] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:13:0)
[13:37:50.135] Build status: SCHEDULED
[13:37:50.135] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:14:0) after 1m5s
[13:38:55.242] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:14:0)
[13:38:55.258] Build status: SCHEDULED
[13:38:55.258] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:15:0) after 1m31s
[13:40:26.260] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:15:0)
[13:40:26.289] Build status: SCHEDULED
[13:40:26.289] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:16:0) after 5m6s
[13:45:32.393] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:16:0)
[13:45:32.437] Build status: SCHEDULED
[13:45:32.437] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:17:0) after 6m13s
[13:51:45.494] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:17:0)
[13:51:45.515] Build status: SCHEDULED
[13:51:45.515] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:18:0) after 7m33s
[13:56:54.298] Received PubSub notification, asking Buildbucket for the build status
[13:56:54.352] Build status: STARTED
[13:59:18.522] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:18:0)
[13:59:18.561] Build status: STARTED
[13:59:18.561] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:20:0) after 1m32s
[14:00:50.615] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:20:0)
[14:00:50.635] Build status: STARTED
[14:00:50.635] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:21:0) after 7m52s
[14:08:42.651] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:21:0)
[14:08:42.668] Build status: STARTED
[14:08:42.668] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:22:0) after 6m7s
[14:14:49.690] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:22:0)
[14:14:49.710] Build status: STARTED
[14:14:49.710] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:23:0) after 5m31s
[14:20:20.785] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:23:0)
[14:20:20.819] Build status: STARTED
[14:20:20.819] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:24:0) after 4m21s
[14:24:41.833] Handling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:24:0)
[14:24:41.851] Build status: STARTED
[14:24:41.851] Scheduling timer "check-buildbucket-build-status" (v8/ci.br.extended-V8 Linux64 - custom snapshot - debug:8865014442263392256:25:0) after 9m6s
[14:29:34.976] Received PubSub notification, asking Buildbucket for the build status
[14:29:34.998] Build:
{
  "id": "8699584380261553329",
  "builder": {
    "project": "v8",
    "bucket": "ci.br.extended",
    "builder": "V8 Linux64 - custom snapshot - debug"
  },
  "number": 496,
  "createdBy": "project:v8",
  "createTime": "2025-10-30T12:20:35.343870211Z",
  "startTime": "2025-10-30T13:56:54.106939876Z",
  "endTime": "2025-10-30T14:29:34.567241195Z",
  "updateTime": "2025-10-30T14:29:34.567241195Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "v8/v8",
      "id": "8190e7d1363830f1068084b50e3dfd39958b7be2",
      "ref": "refs/branch-heads/14.2"
    }
  }
}
[14:29:34.998] Invocation finished in 2h9m0.947842035s with status SUCCEEDED