[taskcluster 2025-12-23 22:06:26.644Z] Task ID: HlXEZyMHQR-cCVbI7FAEPw
[taskcluster 2025-12-23 22:06:26.644Z] Worker ID: 5161488792278183109
[taskcluster 2025-12-23 22:06:26.644Z] Worker Group: us-east4-a
[taskcluster 2025-12-23 22:06:26.644Z] Worker Node Type: projects/757942385826/machineTypes/n2-standard-4
[taskcluster 2025-12-23 22:06:26.644Z] Worker Pool: proj-wpt/ci
[taskcluster 2025-12-23 22:06:26.644Z] Worker Version: 46.1.0
[taskcluster 2025-12-23 22:06:26.644Z] Public IP: 34.11.25.133
[taskcluster 2025-12-23 22:06:26.644Z] Hostname: proj-wpt-ci-esfvfptxsqumjmf3t-epcw

8f84a9f2102e - Started downloading
ac811f28856d - Started downloading
ac811f28856d - Downloaded in 0.009 seconds
40516d781e70 - Started downloading
44ebbf800033 - Started downloading
44ebbf800033 - Downloaded in 0.011 seconds
a8884b21e40e - Started downloading
8f84a9f2102e - Downloaded in 0.209 seconds
4f4fb700ef54 - Started downloading
4f4fb700ef54 - Downloaded in 0.002 seconds
79c347416ee8 - Started downloading

...(2537 lines hidden)...

queue closed
 2:55.42 INFO Starting WebDriver: /home/test/web-platform-tests/_venv3/bin/chrome/chromedriver --port=55497 --ignore-explicit-port --url-base=/ --enable-chrome-logs --disable-build-check
Starting WebDriver: /home/test/web-platform-tests/_venv3/bin/chrome/chromedriver --port=55497 --ignore-explicit-port --url-base=/ --enable-chrome-logs --disable-build-check
 2:55.44 INFO Got WebDriver port 43685
Got WebDriver port 43685
 2:55.53 pid:6103 Full command: /home/test/web-platform-tests/_venv3/bin/chrome/chromedriver --port=55497 --ignore-explicit-port --url-base=/ --enable-chrome-logs --disable-build-check
pid:6103 Starting ChromeDriver 145.0.7594.0 (be91d6c0838c3aaaed632ee6f08508b91e90d452-refs/branch-heads/7594@{#1}) on port 0
PID 6103 | Starting ChromeDriver 145.0.7594.0 (be91d6c0838c3aaaed632ee6f08508b91e90d452-refs/branch-heads/7594@{#1}) on port 0
 2:55.53 pid:6103 Only local connections are allowed.
PID 6103 | Only local connections are allowed.
 2:55.53 pid:6103 Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
PID 6103 | Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
 2:55.53 pid:6103 ChromeDriver was started successfully on port 43685.
PID 6103 | ChromeDriver was started successfully on port 43685.
 2:55.53 INFO Webdriver started successfully.
Webdriver started successfully.
 2:55.53 INFO Starting runner
Starting runner
 2:56.49 pid:6103 [1766527925.835][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 6103 | [1766527925.835][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:56.50 pid:6103 [6151:6151:1223/221205.839795:WARNING:ui/gfx/linux/gbm_support_x11.cc:49] dri3 extension not supported.
PID 6103 | [6151:6151:1223/221205.839795:WARNING:ui/gfx/linux/gbm_support_x11.cc:49] dri3 extension not supported.
 2:56.50 pid:6103 [6151:6151:1223/221205.845184:WARNING:sandbox/policy/linux/sandbox_linux.cc:405] InitializeSandbox() called with multiple threads in process gpu-process.
PID 6103 | [6151:6151:1223/221205.845184:WARNING:sandbox/policy/linux/sandbox_linux.cc:405] InitializeSandbox() called with multiple threads in process gpu-process.
 2:56.71 pid:6103 [6120:6120:1223/221206.050813: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 6103 | [6120:6120:1223/221206.050813: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:56.71 pid:6103 [6120:6120:1223/221206.050838:WARNING:dbus/property.cc:174] GetAll request failed for: org.freedesktop.UPower.Device
PID 6103 | [6120:6120:1223/221206.050838:WARNING:dbus/property.cc:174] GetAll request failed for: org.freedesktop.UPower.Device
 2:56.74 pid:6103 [6120:6120:1223/221206.087032:WARNING:ui/base/idle/idle_linux.cc:113] None of the known D-Bus ScreenSaver services could be used.
PID 6103 | [6120:6120:1223/221206.087032:WARNING:ui/base/idle/idle_linux.cc:113] None of the known D-Bus ScreenSaver services could be used.
 2:57.06 TEST_START: /infrastructure/reftest/reftest_match-print.html
TEST-START | /infrastructure/reftest/reftest_match-print.html
 2:58.85 TEST_END: PASS
TEST-PASS | /infrastructure/reftest/reftest_match-print.html | took 1784ms
 2:58.85 TEST_START: /infrastructure/reftest/reftest_match_fail-print.html
TEST-START | /infrastructure/reftest/reftest_match_fail-print.html
 2:59.54 INFO Image hashes didn't match on page 2, checking pixel differences
Image hashes didn't match on page 2, checking pixel differences
 2:59.59 INFO Found 277 pixels different, maximum difference per channel 255 on page 2
Found 277 pixels different, maximum difference per channel 255 on page 2
 2:59.61 TEST_END: FAIL
TEST-FAIL | /infrastructure/reftest/reftest_match_fail-print.html | took 758ms
 2:59.61 TEST_START: /infrastructure/reftest/reftest_mismatch-num-pages-print.html
TEST-START | /infrastructure/reftest/reftest_mismatch-num-pages-print.html
 3:00.21 INFO Got different number of pages
Got different number of pages
 3:00.21 TEST_END: PASS
TEST-PASS | /infrastructure/reftest/reftest_mismatch-num-pages-print.html | took 600ms
 3:00.21 TEST_START: /infrastructure/reftest/reftest_mismatch-print.html
TEST-START | /infrastructure/reftest/reftest_mismatch-print.html
 3:01.03 INFO Image hashes didn't match on page 2, checking pixel differences
Image hashes didn't match on page 2, checking pixel differences
 3:01.04 INFO Found 13 pixels different, maximum difference per channel 255 on page 2
Found 13 pixels different, maximum difference per channel 255 on page 2
 3:01.04 TEST_END: PASS
TEST-PASS | /infrastructure/reftest/reftest_mismatch-print.html | took 833ms
 3:01.04 TEST_START: /infrastructure/reftest/reftest_mismatch_fail-print.html
TEST-START | /infrastructure/reftest/reftest_mismatch_fail-print.html
 3:01.71 TEST_END: FAIL
TEST-FAIL | /infrastructure/reftest/reftest_mismatch_fail-print.html | took 667ms
 3:01.71 TEST_START: /infrastructure/reftest/reftest_mismatch_page_margins-print.html
TEST-START | /infrastructure/reftest/reftest_mismatch_page_margins-print.html
 3:02.38 INFO Image hashes didn't match on page 1, checking pixel differences
Image hashes didn't match on page 1, checking pixel differences
 3:02.39 INFO Found 216 pixels different, maximum difference per channel 255 on page 1
Found 216 pixels different, maximum difference per channel 255 on page 1
 3:02.39 TEST_END: PASS
TEST-PASS | /infrastructure/reftest/reftest_mismatch_page_margins-print.html | took 678ms
 3:02.39 TEST_START: /infrastructure/reftest/reftest_wait_0-print.html
TEST-START | /infrastructure/reftest/reftest_wait_0-print.html
 3:05.42 TEST_END: PASS
TEST-PASS | /infrastructure/reftest/reftest_wait_0-print.html | took 3031ms
 3:05.42 TEST_START: /infrastructure/reftest/testdriver-print.html
TEST-START | /infrastructure/reftest/testdriver-print.html
 3:05.71 pid:6103 [6120:6135:1223/221215.056234:ERROR:google_apis/gcm/engine/registration_request.cc:291] Registration response error message: DEPRECATED_ENDPOINT
PID 6103 | [6120:6135:1223/221215.056234:ERROR:google_apis/gcm/engine/registration_request.cc:291] Registration response error message: DEPRECATED_ENDPOINT
 3:05.73 pid:6103 [6120:6135:1223/221215.071758:ERROR:google_apis/gcm/engine/mcs_client.cc:700]   Error code: 401  Error message: Authentication Failed: wrong_secret
PID 6103 | [6120:6135:1223/221215.071758:ERROR:google_apis/gcm/engine/mcs_client.cc:700]   Error code: 401  Error message: Authentication Failed: wrong_secret
 3:05.73 pid:6103 [6120:6135:1223/221215.071778:ERROR:google_apis/gcm/engine/mcs_client.cc:702] Failed to log in to GCM, resetting connection.
PID 6103 | [6120:6135:1223/221215.071778:ERROR:google_apis/gcm/engine/mcs_client.cc:702] Failed to log in to GCM, resetting connection.
 3:06.21 TEST_END: PASS
TEST-PASS | /infrastructure/reftest/testdriver-print.html | took 782ms
 3:06.21 INFO Restarting browser for new test group
Restarting browser for new test group
 3:06.26 INFO Closing logging queue
Closing logging queue
 3:06.26 INFO queue closed
queue closed
 3:06.38 INFO Starting WebDriver: /home/test/web-platform-tests/_venv3/bin/chrome/chromedriver --port=55467 --ignore-explicit-port --url-base=/ --enable-chrome-logs --disable-build-check
Starting WebDriver: /home/test/web-platform-tests/_venv3/bin/chrome/chromedriver --port=55467 --ignore-explicit-port --url-base=/ --enable-chrome-logs --disable-build-check
 3:06.40 INFO Got WebDriver port 43557
Got WebDriver port 43557
 3:06.50 pid:6354 Full command: /home/test/web-platform-tests/_venv3/bin/chrome/chromedriver --port=55467 --ignore-explicit-port --url-base=/ --enable-chrome-logs --disable-build-check
pid:6354 Starting ChromeDriver 145.0.7594.0 (be91d6c0838c3aaaed632ee6f08508b91e90d452-refs/branch-heads/7594@{#1}) on port 0
PID 6354 | Starting ChromeDriver 145.0.7594.0 (be91d6c0838c3aaaed632ee6f08508b91e90d452-refs/branch-heads/7594@{#1}) on port 0
 3:06.50 pid:6354 Only local connections are allowed.
PID 6354 | Only local connections are allowed.
 3:06.50 pid:6354 Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
PID 6354 | Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
 3:06.50 pid:6354 ChromeDriver was started successfully on port 43557.
PID 6354 | ChromeDriver was started successfully on port 43557.
 3:06.50 INFO Webdriver started successfully.
Webdriver started successfully.
 3:06.50 INFO Starting runner
Starting runner
 3:07.02 TEST_START: /infrastructure/webdriver/tests/test_load_file.py
TEST-START | /infrastructure/webdriver/tests/test_load_file.py
 3:07.71 INFO STDOUT: ============================= test session starts ==============================
STDOUT: ============================= test session starts ==============================
 3:07.71 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
 3:07.71 INFO STDOUT: rootdir: /home/test/web-platform-tests
STDOUT: rootdir: /home/test/web-platform-tests
 3:07.72 INFO STDOUT: plugins: asyncio-0.19.0
STDOUT: plugins: asyncio-0.19.0
 3:07.72 INFO STDOUT: asyncio: mode=strict
STDOUT: asyncio: mode=strict
 3:07.72 INFO STDOUT: collecting ... 
STDOUT: collecting ... 
 3:07.80 INFO STDOUT: collected 1 item
STDOUT: collected 1 item
 3:07.80 INFO STDOUT: infrastructure/webdriver/tests/test_load_file.py::test_load 
STDOUT: infrastructure/webdriver/tests/test_load_file.py::test_load 
 3:08.15 pid:6354 [6404:6404:1223/221217.491201:WARNING:ui/gfx/linux/gbm_support_x11.cc:49] dri3 extension not supported.
PID 6354 | [6404:6404:1223/221217.491201:WARNING:ui/gfx/linux/gbm_support_x11.cc:49] dri3 extension not supported.
 3:08.15 pid:6354 [1766527937.494][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 6354 | [1766527937.494][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.
 3:08.15 pid:6354 [6404:6404:1223/221217.495867:WARNING:sandbox/policy/linux/sandbox_linux.cc:405] InitializeSandbox() called with multiple threads in process gpu-process.
PID 6354 | [6404:6404:1223/221217.495867:WARNING:sandbox/policy/linux/sandbox_linux.cc:405] InitializeSandbox() called with multiple threads in process gpu-process.
 3:08.38 pid:6354 [6374:6374:1223/221217.724012: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 6354 | [6374:6374:1223/221217.724012: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
 3:08.38 pid:6354 [6374:6374:1223/221217.725414:WARNING:dbus/property.cc:174] GetAll request failed for: org.freedesktop.UPower.Device
PID 6354 | [6374:6374:1223/221217.725414:WARNING:dbus/property.cc:174] GetAll request failed for: org.freedesktop.UPower.Device
 3:08.45 pid:6354 [6374:6374:1223/221217.791850:WARNING:ui/base/idle/idle_linux.cc:113] None of the known D-Bus ScreenSaver services could be used.
PID 6354 | [6374:6374:1223/221217.791850:WARNING:ui/base/idle/idle_linux.cc:113] None of the known D-Bus ScreenSaver services could be used.
 3:08.53 INFO STDOUT: PASSED
STDOUT: PASSED
TEST-PASS | /infrastructure/webdriver/tests/test_load_file.py | test_load 
 3:08.61 TEST_END: Test OK. Subtests passed 1/1. Unexpected 0
TEST-OK | /infrastructure/webdriver/tests/test_load_file.py | took 1583ms
 3:08.61 INFO No more tests
No more tests
 3:08.61 INFO STDOUT: ============================== 1 passed in 0.90s ===============================
STDOUT: ============================== 1 passed in 0.90s ===============================
 3:08.61 INFO Closing logging queue
Closing logging queue
 3:08.61 INFO queue closed
queue closed
 3:08.76 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 172s
 3:08.76 INFO Got 1 unexpected results, with 0 unexpected passes
Got 1 unexpected results, with 0 unexpected passes
 3:08.76 wptserve INFO Stopped http server on 127.0.0.1:8000
wptserve Stopped http server on 127.0.0.1:8000
 3:08.76 wptserve INFO Stopped http server on 127.0.0.1:8002
wptserve Stopped http server on 127.0.0.1:8002
 3:08.76 wptserve INFO Stopped http server on 127.0.0.1:8001
wptserve Stopped http server on 127.0.0.1:8001
 3:08.78 wptserve INFO Stopped http server on 127.0.0.1:8003
wptserve Stopped http server on 127.0.0.1:8003
 3:08.78 wptserve INFO Stopped http server on 127.0.0.1:8444
wptserve Stopped http server on 127.0.0.1:8444
 3:08.78 wptserve INFO Stopped http server on 127.0.0.1:8443
wptserve Stopped http server on 127.0.0.1:8443
 3:08.79 wptserve INFO Stopped http server on 127.0.0.1:8445
wptserve Stopped http server on 127.0.0.1:8445
 3:08.79 wptserve INFO Stopped http server on 127.0.0.1:8446
wptserve Stopped http server on 127.0.0.1:8446
 3:08.80 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
 3:08.80 wptserve INFO Stopped http server on 127.0.0.1:9000
wptserve Stopped http server on 127.0.0.1:9000
 3:08.85 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))
 3:09.08 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))
 3:09.16 INFO Removed font: Ahem.ttf
Removed font: Ahem.ttf
 3:09.19 INFO Closing logging queue
Closing logging queue
 3:09.19 INFO queue closed
queue closed
[taskcluster 2025-12-23 22:12:20.019Z] === Task Finished ===
[taskcluster 2025-12-23 22:12:20.728Z] Unsuccessful task run with exit code: 1 completed in 354.088 seconds