[23:17:22.487] New invocation is queued and will start shortly
[23:17:22.487] Triggered by user:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com
[23:17:23.655] Starting the invocation (attempt 1)
[23:17:23.682] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9b5d15ffb1650dfb6275acad4503bbab4a322a24"
[23:17:23.682] Popped gitiles commit info from properties and tags
[23:17:23.682] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:17:23.682] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:17:23.682] Buildbucket request:
{
  "requestId": "8867781625492795792",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "WebKit Win10"
  },
  "properties": {
      "$bootstrap/trigger": {
            "commits": [
                  {
                        "host": "chromium.googlesource.com",
                        "id": "9b5d15ffb1650dfb6275acad4503bbab4a322a24",
                        "project": "chromium/src",
                        "ref": "refs/heads/main"
                      }
                ]
          },
      "deps_revision_overrides": {
            "src": "9b5d15ffb1650dfb6275acad4503bbab4a322a24"
          },
      "instruction_dependencies": [
            {
                  "instruction_id": "bot_update_repro_instructions",
                  "invocation_id": "build-8702352959676049345"
                },
            {
                  "instruction_id": "generate_build_files_repro_instructions",
                  "invocation_id": "build-8702352959676049345"
                },
            {
                  "instruction_id": "compile_repro_instructions",
                  "invocation_id": "build-8702352959676049345"
                }
          ],
      "parent_build_id": "8702352959676049345",
      "parent_builder_group": "chromium.win",
      "parent_buildername": "Win Builder",
      "parent_got_angle_revision": "f95cbef49892088120dddc09c088c2e8b7973a54",
      "parent_got_dawn_revision": "cee9cb0d67e749bf42f5e90cb3b8a6f525dbb920",
      "parent_got_revision": "9b5d15ffb1650dfb6275acad4503bbab4a322a24",
      "parent_got_revision_cp": "refs/heads/main@{#1522541}",
      "parent_got_swiftshader_revision": "5f1c459a11bb4899301e89609cbf7547f9f31e20",
      "parent_got_v8_revision": "37f82dbb9f640dc5eea09870dd391cd3712546e5",
      "parent_got_v8_revision_cp": "refs/heads/14.2.231@{#1}",
      "parent_got_webrtc_revision": "3570f5b974e5b5d8e2660d0bbbea5d405068a476",
      "parent_got_webrtc_revision_cp": "refs/heads/main@{#45765}",
      "swarm_hashes": {
            "base_unittests": "12032dbe2a0ac7a032b0040337f7e9a09c7eddabcd199459e256ce336484e8c9/480",
            "blink_web_tests": "707e1100f973f5deb5c417422213f625c25e30edd9435a3fece94c55ae11631c/566",
            "blink_wpt_tests": "5886b59bfa8587f9db1cfe140727eaccdb49fae18c401655fa1864930d043df1/566",
            "chrome_wpt_tests": "b8d573fe1f5f16f0282cd8c48b48a117435eb57f3f245b242d242613594bd9f5/566",
            "headless_shell_wpt": "8b53282b728076a8d568a958ff4cd0a65915cd8ee5fbac4b294c894c7e57dd56/566",
            "sbox_integration_tests": "c7cf5605711c5f5ec76faf9adac6c10d9a132f347a353f3aaa149326f67a7625/401",
            "sbox_unittests": "bb4a200afd44c641fb975beff977b0816b9d468181e9365892da907ab0a06205/323",
            "sbox_validation_tests": "5f5eaf1d7ba1926f763793fc74fba2a8a2c5765891642f12117ba7d262ffd747/323"
          },
      "swarming_command_lines_cwd": "out\\046e-Win_Builder",
      "swarming_command_lines_digest": "c54077f12a6e6e42a67cc4dacdd2e6f41353e32d5ebe46cc565870fcd13dd8ad/93",
      "swarming_rts_command_lines_digest": "9484f33bed6c51d11cc0b1225b7532c3b2ccd522df35d72f0208c718535666e9/92"
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "chromium/src",
    "id": "9b5d15ffb1650dfb6275acad4503bbab4a322a24",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8867781625492795792"
    },
    {
      "key": "scheduler_job_id",
      "value": "chromium/WebKit Win10"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    },
    {
      "key": "parent_buildername",
      "value": "Win Builder"
    },
    {
      "key": "parent_buildnumber",
      "value": "254142"
    },
    {
      "key": "user_agent",
      "value": "recipe"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[23:17:24.230] Scheduled build:
{
  "id": "8702351563521968417",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "WebKit Win10"
  },
  "number": 162727,
  "createdBy": "project:chromium",
  "createTime": "2025-09-29T23:17:23.739000450Z",
  "updateTime": "2025-09-29T23:17:23.739000450Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "chromium/src",
      "id": "9b5d15ffb1650dfb6275acad4503bbab4a322a24",
      "ref": "refs/heads/main"
    }
  },
  "canary": true
}
[23:17:24.230] Task URL: https://cr-buildbucket.appspot.com/build/8702351563521968417
[23:17:24.230] Scheduling timer "check-buildbucket-build-status" (chromium/WebKit Win10:8867781625492795792:2:0) after 1m0s
[23:17:24.498] Received PubSub notification, asking Buildbucket for the build status
[23:17:24.542] Build status: SCHEDULED
[23:17:29.670] Received PubSub notification, asking Buildbucket for the build status
[23:17:29.697] Build status: STARTED
[23:18:24.306] Handling timer "check-buildbucket-build-status" (chromium/WebKit Win10:8867781625492795792:2:0)
[23:18:24.345] Build status: STARTED
[23:18:24.345] Scheduling timer "check-buildbucket-build-status" (chromium/WebKit Win10:8867781625492795792:5:0) after 7m54s
[23:26:18.365] Handling timer "check-buildbucket-build-status" (chromium/WebKit Win10:8867781625492795792:5:0)
[23:26:18.379] Build status: STARTED
[23:26:18.380] Scheduling timer "check-buildbucket-build-status" (chromium/WebKit Win10:8867781625492795792:6:0) after 2m22s
[23:28:40.377] Handling timer "check-buildbucket-build-status" (chromium/WebKit Win10:8867781625492795792:6:0)
[23:28:40.400] Build status: STARTED
[23:28:40.400] Scheduling timer "check-buildbucket-build-status" (chromium/WebKit Win10:8867781625492795792:7:0) after 1m40s
[23:30:20.369] Handling timer "check-buildbucket-build-status" (chromium/WebKit Win10:8867781625492795792:7:0)
[23:30:20.387] Build status: STARTED
[23:30:20.387] Scheduling timer "check-buildbucket-build-status" (chromium/WebKit Win10:8867781625492795792:8:0) after 7m52s
[23:34:48.176] Received PubSub notification, asking Buildbucket for the build status
[23:34:48.210] Build:
{
  "id": "8702351563521968417",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "WebKit Win10"
  },
  "number": 162727,
  "createdBy": "project:chromium",
  "createTime": "2025-09-29T23:17:23.739000450Z",
  "startTime": "2025-09-29T23:17:29.502656784Z",
  "endTime": "2025-09-29T23:34:47.871880763Z",
  "updateTime": "2025-09-29T23:34:47.871880763Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "chromium/src",
      "id": "9b5d15ffb1650dfb6275acad4503bbab4a322a24",
      "ref": "refs/heads/main"
    }
  },
  "canary": true
}
[23:34:48.210] Invocation finished in 17m25.733320072s with status SUCCEEDED