[taskcluster 2025-12-23 22:08:31.854Z] Task ID: Puiy0Z2AQz28lYxW01XWuw
[taskcluster 2025-12-23 22:08:31.854Z] Worker ID: 9168626591488978117
[taskcluster 2025-12-23 22:08:31.854Z] Worker Group: us-central1-b
[taskcluster 2025-12-23 22:08:31.854Z] Worker Node Type: projects/757942385826/machineTypes/n2-standard-4
[taskcluster 2025-12-23 22:08:31.854Z] Worker Pool: proj-wpt/ci
[taskcluster 2025-12-23 22:08:31.854Z] Worker Version: 46.1.0
[taskcluster 2025-12-23 22:08:31.854Z] Public IP: 34.45.155.78
[taskcluster 2025-12-23 22:08:31.854Z] Hostname: proj-wpt-ci-fo6h6-nhq1wse0x6qbrzaw

[taskcluster 2025-12-23 22:08:32.820Z] === Task Starting ===
+ '[' '' ']'
+ '[' -d /etc/profile.d ']'
+ for i in /etc/profile.d/*.sh
+ '[' -r /etc/profile.d/01-locale-fix.sh ']'
+ . /etc/profile.d/01-locale-fix.sh
+++ /usr/bin/locale-check C.UTF-8
++ eval
+ for i in /etc/profile.d/*.sh
+ '[' -r /etc/profile.d/apps-bin-path.sh ']'
+ . /etc/profile.d/apps-bin-path.sh

...(2511 lines hidden)...

TEST-PASS | /infrastructure/testdriver/virtual_authenticator.html | Can add a credential 
TEST-PASS | /infrastructure/testdriver/virtual_authenticator.html | Can get the credentials 
TEST-PASS | /infrastructure/testdriver/virtual_authenticator.html | Can remove a credential 
TEST-PASS | /infrastructure/testdriver/virtual_authenticator.html | Can remove all credentials 
TEST-PASS | /infrastructure/testdriver/virtual_authenticator.html | Can set user verified 
TEST-PASS | /infrastructure/testdriver/virtual_authenticator.html | Can remove a virtual authenticator 
 2:46.03 TEST_END: Test OK. Subtests passed 7/7. Unexpected 0
TEST-OK | /infrastructure/testdriver/virtual_authenticator.html | took 559ms
 2:46.03 TEST_START: /infrastructure/testharness/full.stop/full-stop.html
TEST-START | /infrastructure/testharness/full.stop/full-stop.html
TEST-PASS | /infrastructure/testharness/full.stop/full-stop.html | full-stop 
 2:46.38 TEST_END: Test OK. Subtests passed 1/1. Unexpected 0
TEST-OK | /infrastructure/testharness/full.stop/full-stop.html | took 350ms
 2:46.38 TEST_START: /infrastructure/testharness/lone-surrogates.html
TEST-START | /infrastructure/testharness/lone-surrogates.html
TEST-PASS | /infrastructure/testharness/lone-surrogates.html | passing test with lone surrogate U+d800 in name 
TEST-FAIL | /infrastructure/testharness/lone-surrogates.html | failing test with lone surrogate U+d800 in name - assert_true: expected true got false
    at Test.<anonymous> (http://web-platform.test:8000/infrastructure/testharness/lone-surrogates.html:7:14)
    at Test.step (http://web-platform.test:8000/resources/testharness.js:2869:25)
    at test (http://web-platform.test:8000/resources/testharness.js:633:30)
    at http://web-platform.test:8000/infrastructure/testharness/lone-surrogates.html:7:3
TEST-PASS | /infrastructure/testharness/lone-surrogates.html | passing test with lone surrogate in assert 
TEST-FAIL | /infrastructure/testharness/lone-surrogates.html | failing test with lone surrogate in assert - assert_true: lone U+d800 surrogate expected true got false
    at Test.<anonymous> (http://web-platform.test:8000/infrastructure/testharness/lone-surrogates.html:9:14)
    at Test.step (http://web-platform.test:8000/resources/testharness.js:2869:25)
    at test (http://web-platform.test:8000/resources/testharness.js:633:30)
    at http://web-platform.test:8000/infrastructure/testharness/lone-surrogates.html:9:3
 2:46.74 TEST_END: Test OK. Subtests passed 2/4. Unexpected 0
TEST-OK | /infrastructure/testharness/lone-surrogates.html | took 362ms
 2:46.74 TEST_START: /infrastructure/window/minimize-1.html
TEST-START | /infrastructure/window/minimize-1.html
TEST-PASS | /infrastructure/window/minimize-1.html | Minimize a window 
 2:47.11 TEST_END: Test OK. Subtests passed 1/1. Unexpected 0
TEST-OK | /infrastructure/window/minimize-1.html | took 373ms
 2:47.12 TEST_START: /infrastructure/window/minimize-2.html
TEST-START | /infrastructure/window/minimize-2.html
TEST-PASS | /infrastructure/window/minimize-2.html | Use testdriver to check window is not minimized 
 2:47.46 TEST_END: Test OK. Subtests passed 1/1. Unexpected 0
TEST-OK | /infrastructure/window/minimize-2.html | took 346ms
 2:47.46 INFO Restarting browser for new test group
Restarting browser for new test group
 2:47.51 INFO Closing logging queue
Closing logging queue
 2:47.51 INFO queue closed
queue closed
 2:47.60 INFO Starting WebDriver: /home/test/web-platform-tests/_venv3/bin/chrome/chromedriver --port=35151 --ignore-explicit-port --url-base=/ --enable-chrome-logs --disable-build-check
Starting WebDriver: /home/test/web-platform-tests/_venv3/bin/chrome/chromedriver --port=35151 --ignore-explicit-port --url-base=/ --enable-chrome-logs --disable-build-check
 2:47.61 INFO Got WebDriver port 35315
Got WebDriver port 35315
 2:47.71 pid:6228 Full command: /home/test/web-platform-tests/_venv3/bin/chrome/chromedriver --port=35151 --ignore-explicit-port --url-base=/ --enable-chrome-logs --disable-build-check
pid:6228 Starting ChromeDriver 145.0.7594.0 (be91d6c0838c3aaaed632ee6f08508b91e90d452-refs/branch-heads/7594@{#1}) on port 0
PID 6228 | Starting ChromeDriver 145.0.7594.0 (be91d6c0838c3aaaed632ee6f08508b91e90d452-refs/branch-heads/7594@{#1}) on port 0
 2:47.71 pid:6228 Only local connections are allowed.
PID 6228 | Only local connections are allowed.
 2:47.71 pid:6228 Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
PID 6228 | Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
 2:47.71 pid:6228 ChromeDriver was started successfully on port 35315.
PID 6228 | ChromeDriver was started successfully on port 35315.
 2:47.71 INFO Webdriver started successfully.
Webdriver started successfully.
 2:47.71 INFO Starting runner
Starting runner
 2:48.43 pid:6228 [6275:6275:1223/221250.077483:WARNING:ui/gfx/linux/gbm_support_x11.cc:49] dri3 extension not supported.
PID 6228 | [6275:6275:1223/221250.077483:WARNING:ui/gfx/linux/gbm_support_x11.cc:49] dri3 extension not supported.
 2:48.44 pid:6228 [6275:6275:1223/221250.082219:WARNING:sandbox/policy/linux/sandbox_linux.cc:405] InitializeSandbox() called with multiple threads in process gpu-process.
PID 6228 | [6275:6275:1223/221250.082219:WARNING:sandbox/policy/linux/sandbox_linux.cc:405] InitializeSandbox() called with multiple threads in process gpu-process.
 2:48.47 pid:6228 [1766527970.119][WARNING]: You are using an unsupported command-line switch: --disable-build-check. Please don't report bugs that cannot be reproduced with this switch removed.
PID 6228 | [1766527970.119][WARNING]: You are using an unsupported command-line switch: --disable-build-check. Please don't report bugs that cannot be reproduced with this switch removed.
 2:48.66 pid:6228 [6245:6245:1223/221250.302050:ERROR:dbus/object_proxy.cc:573] Failed to call method: org.freedesktop.DBus.Properties.GetAll: object_path= /org/freedesktop/UPower/devices/DisplayDevice: org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.UPower was not provided by any .service files
PID 6228 | [6245:6245:1223/221250.302050:ERROR:dbus/object_proxy.cc:573] Failed to call method: org.freedesktop.DBus.Properties.GetAll: object_path= /org/freedesktop/UPower/devices/DisplayDevice: org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.UPower was not provided by any .service files
 2:48.66 pid:6228 [6245:6245:1223/221250.302070:WARNING:dbus/property.cc:174] GetAll request failed for: org.freedesktop.UPower.Device
PID 6228 | [6245:6245:1223/221250.302070:WARNING:dbus/property.cc:174] GetAll request failed for: org.freedesktop.UPower.Device
 2:48.68 pid:6228 [6245:6245:1223/221250.320798:WARNING:ui/base/idle/idle_linux.cc:113] None of the known D-Bus ScreenSaver services could be used.
PID 6228 | [6245:6245:1223/221250.320798:WARNING:ui/base/idle/idle_linux.cc:113] None of the known D-Bus ScreenSaver services could be used.
 2:48.78 TEST_START: /infrastructure/crashtests/example.html
TEST-START | /infrastructure/crashtests/example.html
 2:49.29 TEST_END: PASS
TEST-PASS | /infrastructure/crashtests/example.html | took 506ms
 2:49.29 TEST_START: /infrastructure/crashtests/testdriver.html
TEST-START | /infrastructure/crashtests/testdriver.html
 2:49.55 TEST_END: PASS
TEST-PASS | /infrastructure/crashtests/testdriver.html | took 261ms
 2:49.55 INFO Restarting browser for new test group
Restarting browser for new test group
 2:49.60 INFO Closing logging queue
Closing logging queue
 2:49.60 INFO queue closed
queue closed
 2:49.69 INFO Starting WebDriver: /home/test/web-platform-tests/_venv3/bin/chrome/chromedriver --port=34781 --ignore-explicit-port --url-base=/ --enable-chrome-logs --disable-build-check
Starting WebDriver: /home/test/web-platform-tests/_venv3/bin/chrome/chromedriver --port=34781 --ignore-explicit-port --url-base=/ --enable-chrome-logs --disable-build-check
 2:49.71 INFO Got WebDriver port 40043
Got WebDriver port 40043
 2:49.80 pid:6366 Full command: /home/test/web-platform-tests/_venv3/bin/chrome/chromedriver --port=34781 --ignore-explicit-port --url-base=/ --enable-chrome-logs --disable-build-check
pid:6366 Starting ChromeDriver 145.0.7594.0 (be91d6c0838c3aaaed632ee6f08508b91e90d452-refs/branch-heads/7594@{#1}) on port 0
PID 6366 | Starting ChromeDriver 145.0.7594.0 (be91d6c0838c3aaaed632ee6f08508b91e90d452-refs/branch-heads/7594@{#1}) on port 0
 2:49.80 pid:6366 Only local connections are allowed.
PID 6366 | Only local connections are allowed.
 2:49.80 pid:6366 Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
PID 6366 | Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
 2:49.80 pid:6366 ChromeDriver was started successfully on port 40043.
PID 6366 | ChromeDriver was started successfully on port 40043.
 2:49.80 INFO Webdriver started successfully.
Webdriver started successfully.
 2:49.80 INFO Starting runner
Starting runner
 2:50.27 TEST_START: /infrastructure/webdriver/tests/test_load_file.py
TEST-START | /infrastructure/webdriver/tests/test_load_file.py
 2:50.82 INFO STDOUT: ============================= test session starts ==============================
STDOUT: ============================= test session starts ==============================
 2:50.82 INFO STDOUT: platform linux -- Python 3.10.12, pytest-8.2.1, pluggy-unknown -- /usr/bin/python3
STDOUT: platform linux -- Python 3.10.12, pytest-8.2.1, pluggy-unknown -- /usr/bin/python3
 2:50.82 INFO STDOUT: rootdir: /home/test/web-platform-tests
STDOUT: rootdir: /home/test/web-platform-tests
 2:50.82 INFO STDOUT: plugins: asyncio-0.19.0
STDOUT: plugins: asyncio-0.19.0
 2:50.82 INFO STDOUT: asyncio: mode=strict
STDOUT: asyncio: mode=strict
 2:50.82 INFO STDOUT: collecting ... 
STDOUT: collecting ... 
 2:50.89 INFO STDOUT: collected 1 item
STDOUT: collected 1 item
 2:50.89 INFO STDOUT: infrastructure/webdriver/tests/test_load_file.py::test_load 
STDOUT: infrastructure/webdriver/tests/test_load_file.py::test_load 
 2:51.14 pid:6366 [6416:6416:1223/221252.782547:WARNING:ui/gfx/linux/gbm_support_x11.cc:49] dri3 extension not supported.
PID 6366 | [6416:6416:1223/221252.782547:WARNING:ui/gfx/linux/gbm_support_x11.cc:49] dri3 extension not supported.
 2:51.14 pid:6366 [6416:6416:1223/221252.785940:WARNING:sandbox/policy/linux/sandbox_linux.cc:405] InitializeSandbox() called with multiple threads in process gpu-process.
PID 6366 | [6416:6416:1223/221252.785940:WARNING:sandbox/policy/linux/sandbox_linux.cc:405] InitializeSandbox() called with multiple threads in process gpu-process.
 2:51.16 pid:6366 [1766527972.799][WARNING]: You are using an unsupported command-line switch: --disable-build-check. Please don't report bugs that cannot be reproduced with this switch removed.
PID 6366 | [1766527972.799][WARNING]: You are using an unsupported command-line switch: --disable-build-check. Please don't report bugs that cannot be reproduced with this switch removed.
 2:51.32 pid:6366 [6386:6386:1223/221252.960875:ERROR:dbus/object_proxy.cc:573] Failed to call method: org.freedesktop.DBus.Properties.GetAll: object_path= /org/freedesktop/UPower/devices/DisplayDevice: org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.UPower was not provided by any .service files
PID 6366 | [6386:6386:1223/221252.960875:ERROR:dbus/object_proxy.cc:573] Failed to call method: org.freedesktop.DBus.Properties.GetAll: object_path= /org/freedesktop/UPower/devices/DisplayDevice: org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.UPower was not provided by any .service files
 2:51.32 pid:6366 [6386:6386:1223/221252.961070:WARNING:dbus/property.cc:174] GetAll request failed for: org.freedesktop.UPower.Device
PID 6366 | [6386:6386:1223/221252.961070:WARNING:dbus/property.cc:174] GetAll request failed for: org.freedesktop.UPower.Device
 2:51.34 pid:6366 [6386:6386:1223/221252.986386:WARNING:ui/base/idle/idle_linux.cc:113] None of the known D-Bus ScreenSaver services could be used.
PID 6366 | [6386:6386:1223/221252.986386:WARNING:ui/base/idle/idle_linux.cc:113] None of the known D-Bus ScreenSaver services could be used.
 2:51.42 INFO STDOUT: PASSED
STDOUT: PASSED
 2:51.49 INFO STDOUT: ============================== 1 passed in 0.67s ===============================
STDOUT: ============================== 1 passed in 0.67s ===============================
TEST-PASS | /infrastructure/webdriver/tests/test_load_file.py | test_load 
 2:51.49 TEST_END: Test OK. Subtests passed 1/1. Unexpected 0
TEST-OK | /infrastructure/webdriver/tests/test_load_file.py | took 1224ms
 2:51.49 INFO No more tests
No more tests
 2:51.49 INFO Closing logging queue
Closing logging queue
 2:51.49 INFO queue closed
queue closed
 2:51.61 SUITE_END

web-platform-test
~~~~~~~~~~~~~~~~~
Ran 439 checks (252 subtests, 187 tests)
Expected results: 436
Skipped: 2 tests
Unexpected results: 1
  subtest: 1 (1 fail)

Unexpected Results
------------------
/infrastructure/testdriver/bidi/subscription.html
  FAIL Assert testdriver can subscribe globally - assert_equals: expected "2. POPUP MESSAGE" but got "1. MAIN WINDOW MESSAGE"
    at assertLogEvent (http://web-platform.test:8000/infrastructure/testdriver/bidi/subscription.html:19:9)
    at Test.<anonymous> (http://web-platform.test:8000/infrastructure/testdriver/bidi/subscription.html:135:9)
SUITE-END | took 156s
 2:51.62 INFO Got 1 unexpected results, with 0 unexpected passes
Got 1 unexpected results, with 0 unexpected passes
 2:51.62 wptserve INFO Stopped http server on 127.0.0.1:8000
wptserve Stopped http server on 127.0.0.1:8000
 2:51.62 wptserve INFO Stopped http server on 127.0.0.1:8002
wptserve Stopped http server on 127.0.0.1:8002
 2:51.64 wptserve INFO Stopped http server on 127.0.0.1:8001
wptserve Stopped http server on 127.0.0.1:8001
 2:51.64 wptserve INFO Stopped http server on 127.0.0.1:8003
wptserve Stopped http server on 127.0.0.1:8003
 2:51.64 wptserve INFO Stopped http server on 127.0.0.1:8445
wptserve Stopped http server on 127.0.0.1:8445
 2:51.64 wptserve INFO Stopped http server on 127.0.0.1:8443
wptserve Stopped http server on 127.0.0.1:8443
 2:51.64 wptserve INFO Stopped http server on 127.0.0.1:8444
wptserve Stopped http server on 127.0.0.1:8444
 2:51.64 wptserve INFO Stopped http server on 127.0.0.1:8446
wptserve Stopped http server on 127.0.0.1:8446
 2:51.66 wptserve INFO Stopped WebTransport over HTTP/3 server on 127.0.0.1:11000
wptserve Stopped WebTransport over HTTP/3 server on 127.0.0.1:11000
 2:51.69 wptserve INFO Stopped http server on 127.0.0.1:9000
wptserve Stopped http server on 127.0.0.1:9000
 2:51.75 wptserve INFO Close on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8888))
wptserve Close on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8888))
 2:51.94 wptserve INFO Close on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8889))
wptserve Close on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8889))
 2:51.97 INFO Removed font: Ahem.ttf
Removed font: Ahem.ttf
 2:52.00 INFO Closing logging queue
Closing logging queue
 2:52.00 INFO queue closed
queue closed
[taskcluster 2025-12-23 22:12:55.093Z] === Task Finished ===
[taskcluster 2025-12-23 22:12:55.761Z] Unsuccessful task run with exit code: 1 completed in 263.909 seconds