Fix Webdriver BiDi events duplication #56803
Community-TC (pull_request)
Smoketests for wptrunner in firefox_android.
Details
View task in Taskcluster | View logs in Taskcluster | View task group in Taskcluster
Task Status
Started: 2026-01-12T12:56:43.166Z
Resolved: 2026-01-12T13:14:37.024Z
Task Execution Time: 17 minutes, 53 seconds, 858 milliseconds
Task Status: completed
Reason Resolved: completed
RunId: 0
Artifacts
- public/logs/live_backing.log
- public/logs/live.log
- public/results/checkrun.md
- public/results/emulator.log
- public/results/logcat-emulator-5554.log
- public/results/wptreport-firefox_android.json.gz
WPT Command: python3 ./wpt run --log-tbpl=- --log-wptreport=../artifacts/wptreport-firefox_android.json --logcat-dir=../artifacts/ --metadata=infrastructure/metadata/ --include=infrastructure/ --log-mach-level=info --log-mach=- -y --no-pause --no-restart-on-unexpected --install-fonts --verify-log-full --no-headless --install-browser --install-webdriver --logcat-dir /home/test/artifacts/ firefox_android
[taskcluster 2026-01-12 12:56:43.285Z] Task ID: F9iZqvqKS4C-Ox68ya2LSQ
[taskcluster 2026-01-12 12:56:43.286Z] Worker ID: 7186452459433841060
[taskcluster 2026-01-12 12:56:43.286Z] Worker Group: us-east1-d
[taskcluster 2026-01-12 12:56:43.286Z] Worker Node Type: projects/757942385826/machineTypes/n2-standard-4
[taskcluster 2026-01-12 12:56:43.286Z] Worker Pool: proj-wpt/ci
[taskcluster 2026-01-12 12:56:43.286Z] Worker Version: 46.1.0
[taskcluster 2026-01-12 12:56:43.286Z] Public IP: 34.23.173.195
[taskcluster 2026-01-12 12:56:43.286Z] Hostname: proj-wpt-ci-ncdqe-ektdmg2fipwnjr6a
ac811f28856d - Started downloading
ac811f28856d - Downloaded in 0.036 seconds
8f84a9f2102e - Started downloading
40516d781e70 - Started downloading
44ebbf800033 - Started downloading
44ebbf800033 - Downloaded in 0.051 seconds
8f84a9f2102e - Downloaded in 0.224 seconds
a8884b21e40e - Started downloading
4f4fb700ef54 - Started downloading
4f4fb700ef54 - Downloaded in 0.003 seconds
79c347416ee8 - Started downloading
...(4156 lines hidden)...
14:10.68 TEST_END: FAIL
TEST-FAIL | /infrastructure/reftest/reftest_multiple_mismatch-1.html | took 1026ms
14:10.68 TEST_START: /infrastructure/reftest/reftest_ref_timeout.html
TEST-START | /infrastructure/reftest/reftest_ref_timeout.html
14:32.72 TEST_END: TIMEOUT
TEST-TIMEOUT | /infrastructure/reftest/reftest_ref_timeout.html | took 22042ms
14:32.73 TEST_START: /infrastructure/reftest/reftest_timeout.html
TEST-START | /infrastructure/reftest/reftest_timeout.html
mem avail: 9068 of 15806 MiB (57.38%), swap free: 0 of 0 MiB ( 0.00%)
14:52.80 TEST_END: TIMEOUT
TEST-TIMEOUT | /infrastructure/reftest/reftest_timeout.html | took 20080ms
14:52.80 TEST_START: /infrastructure/reftest/reftest_wait_0.html
TEST-START | /infrastructure/reftest/reftest_wait_0.html
14:55.85 TEST_END: PASS
TEST-PASS | /infrastructure/reftest/reftest_wait_0.html | took 3044ms
14:55.85 TEST_START: /infrastructure/reftest/reftest_wait_TestRendered.html
TEST-START | /infrastructure/reftest/reftest_wait_TestRendered.html
14:56.93 TEST_END: PASS
TEST-PASS | /infrastructure/reftest/reftest_wait_TestRendered.html | took 1086ms
14:56.93 TEST_START: /infrastructure/reftest/reftest_window_load.html
TEST-START | /infrastructure/reftest/reftest_window_load.html
14:57.85 TEST_END: PASS
TEST-PASS | /infrastructure/reftest/reftest_window_load.html | took 911ms
14:57.85 TEST_START: /infrastructure/reftest/size.html
TEST-START | /infrastructure/reftest/size.html
14:58.99 TEST_END: PASS
TEST-PASS | /infrastructure/reftest/size.html | took 1147ms
14:58.99 TEST_START: /infrastructure/reftest/testdriver-in-ref.html
TEST-START | /infrastructure/reftest/testdriver-in-ref.html
15:19.11 TEST_END: TIMEOUT
TEST-TIMEOUT | /infrastructure/reftest/testdriver-in-ref.html | took 20121ms
15:19.11 TEST_START: /infrastructure/reftest-wait.html
TEST-START | /infrastructure/reftest-wait.html
15:21.15 TEST_END: PASS
TEST-PASS | /infrastructure/reftest-wait.html | took 2036ms
15:21.15 INFO Restarting browser for new test group
Restarting browser for new test group
15:21.33 INFO Closing logging queue
Closing logging queue
15:21.87 INFO STDOUT: cleanup aborted: args: /home/test/web-platform-tests/_venv3/android/android-sdk-linux/platform-tools/adb wait-for-device remount, exitcode: 1, stdout: Device must be bootloader unlocked
STDOUT: cleanup aborted: args: /home/test/web-platform-tests/_venv3/android/android-sdk-linux/platform-tools/adb wait-for-device remount, exitcode: 1, stdout: Device must be bootloader unlocked
15:21.33 INFO queue closed
queue closed
15:22.16 INFO STDOUT: cleanup aborted: args: /home/test/web-platform-tests/_venv3/android/android-sdk-linux/platform-tools/adb wait-for-device remount, exitcode: 1, stdout: Device must be bootloader unlocked
STDOUT: cleanup aborted: args: /home/test/web-platform-tests/_venv3/android/android-sdk-linux/platform-tools/adb wait-for-device remount, exitcode: 1, stdout: Device must be bootloader unlocked
15:22.20 INFO --certutil-binary not supplied; Firefox will not check certificates
--certutil-binary not supplied; Firefox will not check certificates
15:23.15 web-platform-tests INFO Granting important runtime permissions to org.mozilla.geckoview.test_runner
web-platform-tests Granting important runtime permissions to org.mozilla.geckoview.test_runner
15:27.24 web-platform-tests WARNING Unable to grant runtime permission android.permission.READ_EXTERNAL_STORAGE to org.mozilla.geckoview.test_runner due to args: /home/test/web-platform-tests/_venv3/android/android-sdk-linux/platform-tools/adb wait-for-device shell pm grant org.mozilla.geckoview.test_runner android.permission.READ_EXTERNAL_STORAGE; echo adb_returncode=$?, exitcode: 255, stdout:
Exception occurred while executing 'grant':
java.lang.SecurityException: Package org.mozilla.geckoview.test_runner has not requested permission android.permission.READ_EXTERNAL_STORAGE
at com.android.server.pm.permission.PermissionManagerServiceImpl.grantRuntimePermissionInternal(PermissionManagerServiceImpl.java:1423)
at com.android.server.pm.permission.PermissionManagerServiceImpl.grantRuntimePermission(PermissionManagerServiceImpl.java:1337)
at com.android.server.pm.permission.PermissionManagerService.grantRuntimePermission(PermissionManagerService.java:551)
at android.permission.PermissionManager.grantRuntimePermission(PermissionManager.java:609)
at com.android.server.pm.PackageManagerShellCommand.runGrantRevokePermission(PackageManagerShellCommand.java:2672)
at com.android.server.pm.PackageManagerShellCommand.onCommand(PackageManagerShellCommand.java:291)
at com.android.modules.utils.BasicShellCommandHandler.exec(BasicShellCommandHandler.java:97)
at android.os.ShellCommand.exec(ShellCommand.java:38)
at com.android.server.pm.PackageManagerService$IPackageManagerImpl.onShellCommand(PackageManagerService.java:6350)
at android.os.Binder.shellCommand(Binder.java:1068)
at android.os.Binder.onTransact(Binder.java:888)
at android.content.pm.IPackageManager$Stub.onTransact(IPackageManager.java:4352)
at com.android.server.pm.PackageManagerService$IPackageManagerImpl.onTransact(PackageManagerService.java:6334)
at android.os.Binder.execTransactInternal(Binder.java:1344)
at android.os.Binder.execTransact(Binder.java:1275)
web-platform-tests Unable to grant runtime permission android.permission.READ_EXTERNAL_STORAGE to org.mozilla.geckoview.test_runner due to args: /home/test/web-platform-tests/_venv3/android/android-sdk-linux/platform-tools/adb wait-for-device shell pm grant org.mozilla.geckoview.test_runner android.permission.READ_EXTERNAL_STORAGE; echo adb_returncode=$?, exitcode: 255, stdout:
Exception occurred while executing 'grant':
java.lang.SecurityException: Package org.mozilla.geckoview.test_runner has not requested permission android.permission.READ_EXTERNAL_STORAGE
at com.android.server.pm.permission.PermissionManagerServiceImpl.grantRuntimePermissionInternal(PermissionManagerServiceImpl.java:1423)
at com.android.server.pm.permission.PermissionManagerServiceImpl.grantRuntimePermission(PermissionManagerServiceImpl.java:1337)
at com.android.server.pm.permission.PermissionManagerService.grantRuntimePermission(PermissionManagerService.java:551)
at android.permission.PermissionManager.grantRuntimePermission(PermissionManager.java:609)
at com.android.server.pm.PackageManagerShellCommand.runGrantRevokePermission(PackageManagerShellCommand.java:2672)
at com.android.server.pm.PackageManagerShellCommand.onCommand(PackageManagerShellCommand.java:291)
at com.android.modules.utils.BasicShellCommandHandler.exec(BasicShellCommandHandler.java:97)
at android.os.ShellCommand.exec(ShellCommand.java:38)
at com.android.server.pm.PackageManagerService$IPackageManagerImpl.onShellCommand(PackageManagerService.java:6350)
at android.os.Binder.shellCommand(Binder.java:1068)
at android.os.Binder.onTransact(Binder.java:888)
at android.content.pm.IPackageManager$Stub.onTransact(IPackageManager.java:4352)
at com.android.server.pm.PackageManagerService$IPackageManagerImpl.onTransact(PackageManagerService.java:6334)
at android.os.Binder.execTransactInternal(Binder.java:1344)
at android.os.Binder.execTransact(Binder.java:1275)
15:27.36 web-platform-tests INFO launch_application: am start -W -n org.mozilla.geckoview.test_runner/org.mozilla.geckoview.test_runner.TestRunnerActivity -a android.intent.action.MAIN --es env0 MOZ_CRASHREPORTER=1 --es env1 MOZ_CRASHREPORTER_NO_REPORT=1 --es env2 MOZ_CRASHREPORTER_SHUTDOWN=1 --es env3 MOZ_HIDE_RESULTS_TABLE=1 --es env4 MOZ_IN_AUTOMATION=1 --es env5 MOZ_LOG=signaling:3,mtransport:4,DataChannel:4,jsep:4 --es env6 R_LOG_LEVEL=6 --es env7 R_LOG_DESTINATION=stderr --es env8 R_LOG_VERBOSE=1 --es env9 MOZ_PROCESS_LOG=/tmp/tmp8hhfnvjfpidlog --es env10 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es arg0 -no-remote --es arg1 -profile --es arg2 /data/local/tmp/test_root/profile --es arg3 --marionette --es arg4 --remote-allow-system-access --es arg5 about:blank
web-platform-tests launch_application: am start -W -n org.mozilla.geckoview.test_runner/org.mozilla.geckoview.test_runner.TestRunnerActivity -a android.intent.action.MAIN --es env0 MOZ_CRASHREPORTER=1 --es env1 MOZ_CRASHREPORTER_NO_REPORT=1 --es env2 MOZ_CRASHREPORTER_SHUTDOWN=1 --es env3 MOZ_HIDE_RESULTS_TABLE=1 --es env4 MOZ_IN_AUTOMATION=1 --es env5 MOZ_LOG=signaling:3,mtransport:4,DataChannel:4,jsep:4 --es env6 R_LOG_LEVEL=6 --es env7 R_LOG_DESTINATION=stderr --es env8 R_LOG_VERBOSE=1 --es env9 MOZ_PROCESS_LOG=/tmp/tmp8hhfnvjfpidlog --es env10 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es arg0 -no-remote --es arg1 -profile --es arg2 /data/local/tmp/test_root/profile --es arg3 --marionette --es arg4 --remote-allow-system-access --es arg5 about:blank
15:29.96 INFO Starting runner
Starting runner
15:31.48 TEST_START: /infrastructure/crashtests/example.html
TEST-START | /infrastructure/crashtests/example.html
15:32.51 TEST_END: PASS
TEST-PASS | /infrastructure/crashtests/example.html | took 1031ms
15:32.51 TEST_START: /infrastructure/crashtests/testdriver.html
TEST-START | /infrastructure/crashtests/testdriver.html
15:33.06 TEST_END: PASS
TEST-PASS | /infrastructure/crashtests/testdriver.html | took 551ms
15:33.06 INFO Restarting browser for new test group
Restarting browser for new test group
15:33.22 INFO Closing logging queue
Closing logging queue
15:33.66 INFO STDOUT: cleanup aborted: args: /home/test/web-platform-tests/_venv3/android/android-sdk-linux/platform-tools/adb wait-for-device remount, exitcode: 1, stdout: Device must be bootloader unlocked
STDOUT: cleanup aborted: args: /home/test/web-platform-tests/_venv3/android/android-sdk-linux/platform-tools/adb wait-for-device remount, exitcode: 1, stdout: Device must be bootloader unlocked
15:33.22 INFO queue closed
queue closed
15:33.91 INFO STDOUT: cleanup aborted: args: /home/test/web-platform-tests/_venv3/android/android-sdk-linux/platform-tools/adb wait-for-device remount, exitcode: 1, stdout: Device must be bootloader unlocked
STDOUT: cleanup aborted: args: /home/test/web-platform-tests/_venv3/android/android-sdk-linux/platform-tools/adb wait-for-device remount, exitcode: 1, stdout: Device must be bootloader unlocked
15:33.93 INFO --certutil-binary not supplied; Firefox will not check certificates
--certutil-binary not supplied; Firefox will not check certificates
15:34.22 INFO Starting WebDriver: /home/test/web-platform-tests/_venv3/bin/geckodriver --host 127.0.0.1 --port 0
Starting WebDriver: /home/test/web-platform-tests/_venv3/bin/geckodriver --host 127.0.0.1 --port 0
15:34.37 pid:8981 Full command: /home/test/web-platform-tests/_venv3/bin/geckodriver --host 127.0.0.1 --port 0
pid:8981 1768223663587 geckodriver INFO Listening on 127.0.0.1:37921
PID 8981 | 1768223663587 geckodriver INFO Listening on 127.0.0.1:37921
15:34.37 INFO Webdriver started successfully.
Webdriver started successfully.
15:34.37 INFO Starting runner
Starting runner
15:34.72 TEST_START: /infrastructure/webdriver/tests/test_load_file.py
TEST-START | /infrastructure/webdriver/tests/test_load_file.py
15:35.97 INFO STDOUT: ============================= test session starts ==============================
STDOUT: ============================= test session starts ==============================
15:35.97 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
15:35.97 INFO STDOUT: rootdir: /home/test/web-platform-tests
STDOUT: rootdir: /home/test/web-platform-tests
15:35.97 INFO STDOUT: plugins: asyncio-0.19.0
STDOUT: plugins: asyncio-0.19.0
15:35.97 INFO STDOUT: asyncio: mode=strict
STDOUT: asyncio: mode=strict
15:35.97 INFO STDOUT: collecting ...
STDOUT: collecting ...
15:36.02 INFO STDOUT: collected 1 item
STDOUT: collected 1 item
15:36.02 INFO STDOUT: infrastructure/webdriver/tests/test_load_file.py::test_load
STDOUT: infrastructure/webdriver/tests/test_load_file.py::test_load
15:36.13 pid:8981 1768223665362 mozdevice INFO Device is rooted
PID 8981 | 1768223665362 mozdevice INFO Device is rooted
15:40.67 INFO STDOUT: PASSED
STDOUT: PASSED
15:41.25 INFO STDOUT: ============================== 1 passed in 5.28s ===============================
STDOUT: ============================== 1 passed in 5.28s ===============================
TEST-PASS | /infrastructure/webdriver/tests/test_load_file.py | test_load
15:41.25 TEST_END: Test OK. Subtests passed 1/1. Unexpected 0
TEST-OK | /infrastructure/webdriver/tests/test_load_file.py | took 6535ms
15:41.26 INFO No more tests
No more tests
15:41.26 INFO Closing logging queue
Closing logging queue
15:41.26 INFO queue closed
queue closed
15:41.53 SUITE_END
web-platform-test
~~~~~~~~~~~~~~~~~
Ran 402 checks (223 subtests, 179 tests)
Expected results: 398 (1 known intermittents)
Skipped: 4 tests
Unexpected results: 0
Known Intermittent Results
--------------------------
/infrastructure/testdriver/actions/elementTiming.html
KNOWN-INTERMITTENT-FAIL TestDriver actions: element timing - assert_array_equals: expected property 1 to be 150 but got 149 (expected array [50, 150] got [50, 149])
@http://web-platform.test:8000/infrastructure/testdriver/actions/elementTiming.html:67:22
OK
SUITE-END | took 726s
15:41.53 INFO Got 0 unexpected results, with 0 unexpected passes
Got 0 unexpected results, with 0 unexpected passes
15:41.54 wptserve INFO Stopped http server on 127.0.0.1:8000
wptserve Stopped http server on 127.0.0.1:8000
15:41.55 wptserve INFO Stopped http server on 127.0.0.1:8001
wptserve Stopped http server on 127.0.0.1:8001
15:41.55 wptserve INFO Stopped http server on 127.0.0.1:8002
wptserve Stopped http server on 127.0.0.1:8002
15:41.56 wptserve INFO Stopped http server on 127.0.0.1:8003
wptserve Stopped http server on 127.0.0.1:8003
15:41.56 wptserve INFO Stopped http server on 127.0.0.1:8443
wptserve Stopped http server on 127.0.0.1:8443
15:41.56 wptserve INFO Stopped http server on 127.0.0.1:8444
wptserve Stopped http server on 127.0.0.1:8444
15:41.57 wptserve INFO Stopped http server on 127.0.0.1:8445
wptserve Stopped http server on 127.0.0.1:8445
15:41.57 wptserve INFO Stopped http server on 127.0.0.1:8446
wptserve Stopped http server on 127.0.0.1:8446
15:41.57 wptserve INFO Stopped http server on 127.0.0.1:9000
wptserve Stopped http server on 127.0.0.1:9000
15:41.84 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))
15:41.97 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))
15:42.16 INFO Removed font: Ahem.ttf
Removed font: Ahem.ttf
15:42.22 INFO Closing logging queue
Closing logging queue
15:42.22 INFO queue closed
queue closed
[taskcluster 2026-01-12 13:14:33.844Z] === Task Finished ===
[taskcluster 2026-01-12 13:14:35.932Z] Successful task run with exit code: 0 completed in 1072.649 seconds