Closed Bug 1871346 Opened 1 year ago Closed 2 months ago

Intermittent /webdriver/tests/classic/perform_actions/pointer_mouse.py | test_drag_and_drop[<random>] - assert (<random>) == <random>

Categories

(Remote Protocol :: Marionette, defect, P5)

All
macOS
defect
Points:
2

Tracking

(firefox135 fixed)

RESOLVED FIXED
135 Branch
Tracking Status
firefox135 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: whimboo)

References

Details

(Keywords: intermittent-failure, intermittent-testcase, Whiteboard: [webdriver:m14])

Attachments

(2 files)

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=440988701&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Dz9eLErAQ8yV6AXBFgLMLg/runs/0/artifacts/public/logs/live_backing.log


[task 2023-12-21T07:28:23.170Z] 07:28:23     INFO - TEST-PASS | /webdriver/tests/bidi/input/perform_actions/pointer_mouse.py | test_click_element_in_shadow_tree[inner-open] 
[task 2023-12-21T07:28:23.170Z] 07:28:23     INFO - TEST-PASS | /webdriver/tests/bidi/input/perform_actions/pointer_mouse.py | test_click_element_in_shadow_tree[inner-closed] 
[task 2023-12-21T07:28:23.170Z] 07:28:23     INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/bidi/input/perform_actions/pointer_mouse.py | test_click_navigation - concurrent.futures._base.TimeoutError
[task 2023-12-21T07:28:23.170Z] 07:28:23     INFO - future = <Future pending>, timeout = 2.0
[task 2023-12-21T07:28:23.170Z] 07:28:23     INFO - 
[task 2023-12-21T07:28:23.170Z] 07:28:23     INFO -     async def wait_for_future_safe(future: Coroutine, timeout: float = 2.0):
[task 2023-12-21T07:28:23.170Z] 07:28:23     INFO -         try:
[task 2023-12-21T07:28:23.170Z] 07:28:23     INFO -             return await asyncio.wait_for(
[task 2023-12-21T07:28:23.170Z] 07:28:23     INFO -                 asyncio.shield(future),
[task 2023-12-21T07:28:23.170Z] 07:28:23     INFO - >               timeout=timeout * configuration["timeout_multiplier"],
[task 2023-12-21T07:28:23.170Z] 07:28:23     INFO -             )
[task 2023-12-21T07:28:23.170Z] 07:28:23     INFO - 
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO - configuration = {'browser': {'args': ['--profile',
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                       '/var/folders/9n/0nzhqkzx28l6_wsqbk3f78xw000014/T/tmpqevom563.mozrunner'],
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -              'binary': '/opt/worker/tasks/task_170314002785627/build/application/Firefox '
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                        'Nightly.app/Contents/MacOS/firefox',
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -              'env': {'DYLD_LIBRARY_PATH': '/opt/worker/tasks/task_170314002785627/build/application/Firefox '
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                                           'Nightly.app/Contents/MacOS',
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                      'EXTRA_MOZHARNESS_CONFIG': '{"installer_url": '
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                                                 '"https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/CuQ9KYzqTMaPfAx39bCKFg/artifacts/public/build/target.dmg", '
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                                                 '"test_packages_url": '
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                                                 '"https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/CuQ9KYzqTMaPfAx39bCKFg/artifacts/public/build/target.test_packages.json"}',
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                      'GECKO_HEAD_REPOSITORY': 'https://hg.mozilla.org/integration/autoland',
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                      'GECKO_HEAD_REV': '2339ef37f5863c45acf6280581ec0994b40f11d5',
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                      'GNOME_DISABLE_CRASH_DIALOG': '1',
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                      'HOME': '/Users/cltbld',
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                      'LANG': 'en_US.UTF-8',
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                      'LC_ALL': 'en_US.UTF-8',
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                      'LOGNAME': 'cltbld',
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                      'MINIDUMP_SAVE_PATH': '/opt/worker/tasks/task_170314002785627/build/blobber_upload_dir',
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                      'MOZHARNESS_TEST_PATHS': '{"web-platform-tests-wdspec": '
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                                               '["/_mozilla/webdriver/bidi/browsing_context/create", '
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                                               '"/_mozilla/webdriver/bidi/errors", '
[task 2023-12-21T07:28:23.171Z] 07:28:23     INFO -                                               '"/_mozilla/webdriver/bidi/session/end", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/_mozilla/webdriver/classic", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/_mozilla/webdriver/classic/get_window_handle", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/_mozilla/webdriver/classic/perform_actions", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/_mozilla/webdriver/harness", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/browsing_context/capture_screenshot", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/browsing_context/context_created", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/browsing_context/dom_content_loaded", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/browsing_context/handle_user_prompt", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/browsing_context/navigate", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/browsing_context/reload", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/browsing_context/user_prompt_closed", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/input/perform_actions", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/network/add_intercept", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/network/combined", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/network/continue_with_auth", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/network/remove_intercept", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/script/add_preload_script", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/script/disown", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/script/message", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/script/remove_preload_script", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/bidi/session/subscribe", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/add_cookie", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/delete_all_cookies", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/dismiss_alert", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/element_send_keys", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/find_element", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/find_elements", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/forward", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/get_alert_text", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/get_current_url", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/get_element_property", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/get_element_tag_name", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/get_page_source", '
[task 2023-12-21T07:28:23.172Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/get_window_handle", '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/interface", '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/maximize_window", '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/new_session", '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/permissions", '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/release_actions", '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/set_window_rect", '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/switch_to_parent_frame", '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                               '"/webdriver/tests/classic/take_screenshot"]}',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'MOZ_AUTOMATION': '1',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'MOZ_CRASHREPORTER': '1',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'MOZ_CRASHREPORTER_NO_REPORT': '1',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'MOZ_CRASHREPORTER_SHUTDOWN': '1',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'MOZ_DEVELOPER_OBJ_DIR': '/builds/worker/workspace/obj-build',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'MOZ_DEVELOPER_REPO_DIR': '/builds/worker/checkouts/gecko',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'MOZ_FETCHES': '[{"artifact": '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                     '"public/build/fix-stacks.tar.zst", '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                     '"extract": true, "task": '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                     '"ITgEC9BBRh2j5ck29xFlNQ"}, {"artifact": '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                     '"public/build/geckodriver.tar.gz", '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                     '"extract": true, "task": '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                     '"POC9Jlx2RSuEr1LUvj9Bvg"}, {"artifact": '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                     '"public/build/minidump-stackwalk.tar.zst", '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                     '"extract": true, "task": '
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                                     '"C1IAio_fTval2MlOG0FkpQ"}]',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'MOZ_FETCHES_DIR': '/opt/worker/tasks/task_170314002785627/fetches',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'MOZ_HEADLESS': '1',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'MOZ_HEADLESS_HEIGHT': '1200',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'MOZ_HEADLESS_WIDTH': '1600',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'MOZ_LOG': 'signaling:3,mtransport:4,DataChannel:4,jsep:4',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'MOZ_NODE_PATH': '/usr/local/bin/node',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'MOZ_SCM_LEVEL': '3',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'MOZ_WINDOW_OCCLUSION': '0',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'NSS_MAX_MP_PBE_ITERATION_COUNT': '10',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'PATH': '/opt/worker/tasks/task_170314002785627/build/venv/bin:/usr/local/bin:/usr/bin:/bin:/usr/sbin:/sbin',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'PWD': '/opt/worker',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'PYTHON': '/usr/local/bin/python3',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'REQUIRE_GPU': '0',
[task 2023-12-21T07:28:23.173Z] 07:28:23     INFO -                      'RUN_ID': '0',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'RUST_BACKTRACE': '1',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'R_LOG_DESTINATION': 'stderr',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'R_LOG_LEVEL': '6',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'R_LOG_VERBOSE': '1',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'SCCACHE_DISABLE': '1',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'SHELL': '/bin/bash',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'SHLVL': '1',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DhtzIp8dfJ/Listeners',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'TASKCLUSTER_ROOT_URL': 'https://firefox-ci-tc.services.mozilla.com',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'TASKCLUSTER_WORKER_LOCATION': '{"cloud":"standalone","host":"mdc1"}',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'TASK_ID': 'Dz9eLErAQ8yV6AXBFgLMLg',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'TESTS_BY_MANIFEST_URL': 'https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/MdBjwoPkR8-PNyhNRg0opA/artifacts/public/tests-by-manifest.json.gz',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'TMPDIR': '/var/folders/9n/0nzhqkzx28l6_wsqbk3f78xw000014/T/',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'USER': 'cltbld',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'VIRTUAL_ENV': 'venv',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'WPT_STASH_CONFIG': '["/var/folders/9n/0nzhqkzx28l6_wsqbk3f78xw000014/T/pymp-hmdqlfge/listener-15ja49db", '
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                                          '"rjDiGNHkHUGFb9/s9FhgRu2Yt6UcpbU49eJS31VwQSo="]',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'XPC_FLAGS': '0x0',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'XPC_SERVICE_NAME': '0',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      'XRE_NO_WINDOWS_CRASH_DIALOG': '1',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      '_': '/usr/local/bin/start-worker',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                      '__CF_USER_TEXT_ENCODING': '0x24:0x0:0x0'}},
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -  'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                                                   '/var/folders/9n/0nzhqkzx28l6_wsqbk3f78xw000014/T/tmpqevom563.mozrunner'],
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                                          'env': {'DYLD_LIBRARY_PATH': '/opt/worker/tasks/task_170314002785627/build/application/Firefox '
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                                                                       'Nightly.app/Contents/MacOS',
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                                                  'EXTRA_MOZHARNESS_CONFIG': '{"installer_url": '
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                                                                             '"https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/CuQ9KYzqTMaPfAx39bCKFg/artifacts/public/build/target.dmg", '
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                                                                             '"test_packages_url": '
[task 2023-12-21T07:28:23.174Z] 07:28:23     INFO -                                                                             '"https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/CuQ9KYzqTMaPfAx39bCKFg/artifacts/public/build/target.test_packages.json"}',
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                  'GECKO_HEAD_REPOSITORY': 'https://hg.mozilla.org/integration/autoland',
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                  'GECKO_HEAD_REV': '2339ef37f5863c45acf6280581ec0994b40f11d5',
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                  'GNOME_DISABLE_CRASH_DIALOG': '1',
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                  'HOME': '/Users/cltbld',
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                  'LANG': 'en_US.UTF-8',
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                  'LC_ALL': 'en_US.UTF-8',
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                  'LOGNAME': 'cltbld',
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                  'MINIDUMP_SAVE_PATH': '/opt/worker/tasks/task_170314002785627/build/blobber_upload_dir',
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                  'MOZHARNESS_TEST_PATHS': '{"web-platform-tests-wdspec": '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '["/_mozilla/webdriver/bidi/browsing_context/create", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/_mozilla/webdriver/bidi/errors", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/_mozilla/webdriver/bidi/session/end", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/_mozilla/webdriver/classic", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/_mozilla/webdriver/classic/get_window_handle", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/_mozilla/webdriver/classic/perform_actions", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/_mozilla/webdriver/harness", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/browsing_context/capture_screenshot", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/browsing_context/context_created", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/browsing_context/dom_content_loaded", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/browsing_context/handle_user_prompt", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/browsing_context/navigate", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/browsing_context/reload", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/browsing_context/user_prompt_closed", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/input/perform_actions", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/network/add_intercept", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/network/combined", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/network/continue_with_auth", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/network/remove_intercept", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/script/add_preload_script", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/script/disown", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/script/message", '
[task 2023-12-21T07:28:23.175Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/script/remove_preload_script", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/bidi/session/subscribe", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/add_cookie", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/delete_all_cookies", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/dismiss_alert", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/element_send_keys", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/find_element", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/find_elements", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/forward", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/get_alert_text", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/get_current_url", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/get_element_property", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/get_element_tag_name", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/get_page_source", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/get_window_handle", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/interface", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/maximize_window", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/new_session", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/permissions", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/release_actions", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/set_window_rect", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/switch_to_parent_frame", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                           '"/webdriver/tests/classic/take_screenshot"]}',
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                  'MOZ_AUTOMATION': '1',
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                  'MOZ_CRASHREPORTER': '1',
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                  'MOZ_CRASHREPORTER_NO_REPORT': '1',
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                  'MOZ_CRASHREPORTER_SHUTDOWN': '1',
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                  'MOZ_DEVELOPER_OBJ_DIR': '/builds/worker/workspace/obj-build',
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                  'MOZ_DEVELOPER_REPO_DIR': '/builds/worker/checkouts/gecko',
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                  'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                  'MOZ_FETCHES': '[{"artifact": '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                 '"public/build/fix-stacks.tar.zst", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                 '"extract": '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                 'true, "task": '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                 '"ITgEC9BBRh2j5ck29xFlNQ"}, '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                 '{"artifact": '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                 '"public/build/geckodriver.tar.gz", '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                 '"extract": '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                 'true, "task": '
[task 2023-12-21T07:28:23.176Z] 07:28:23     INFO -                                                                 '"POC9Jlx2RSuEr1LUvj9Bvg"}, '
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                                 '{"artifact": '
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                                 '"public/build/minidump-stackwalk.tar.zst", '
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                                 '"extract": '
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                                 'true, "task": '
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                                 '"C1IAio_fTval2MlOG0FkpQ"}]',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'MOZ_FETCHES_DIR': '/opt/worker/tasks/task_170314002785627/fetches',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'MOZ_HEADLESS': '1',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'MOZ_HEADLESS_HEIGHT': '1200',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'MOZ_HEADLESS_WIDTH': '1600',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'MOZ_LOG': 'signaling:3,mtransport:4,DataChannel:4,jsep:4',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'MOZ_NODE_PATH': '/usr/local/bin/node',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'MOZ_SCM_LEVEL': '3',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'MOZ_WINDOW_OCCLUSION': '0',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'NSS_MAX_MP_PBE_ITERATION_COUNT': '10',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'PATH': '/opt/worker/tasks/task_170314002785627/build/venv/bin:/usr/local/bin:/usr/bin:/bin:/usr/sbin:/sbin',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'PWD': '/opt/worker',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'PYTHON': '/usr/local/bin/python3',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'REQUIRE_GPU': '0',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'RUN_ID': '0',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'RUST_BACKTRACE': '1',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'R_LOG_DESTINATION': 'stderr',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'R_LOG_LEVEL': '6',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'R_LOG_VERBOSE': '1',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'SCCACHE_DISABLE': '1',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'SHELL': '/bin/bash',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'SHLVL': '1',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DhtzIp8dfJ/Listeners',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'TASKCLUSTER_ROOT_URL': 'https://firefox-ci-tc.services.mozilla.com',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'TASKCLUSTER_WORKER_LOCATION': '{"cloud":"standalone","host":"mdc1"}',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'TASK_ID': 'Dz9eLErAQ8yV6AXBFgLMLg',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'TESTS_BY_MANIFEST_URL': 'https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/MdBjwoPkR8-PNyhNRg0opA/artifacts/public/tests-by-manifest.json.gz',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'TMPDIR': '/var/folders/9n/0nzhqkzx28l6_wsqbk3f78xw000014/T/',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'USER': 'cltbld',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'VIRTUAL_ENV': 'venv',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'WPT_STASH_CONFIG': '["/var/folders/9n/0nzhqkzx28l6_wsqbk3f78xw000014/T/pymp-hmdqlfge/listener-15ja49db", '
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                                      '"rjDiGNHkHUGFb9/s9FhgRu2Yt6UcpbU49eJS31VwQSo="]',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'XPC_FLAGS': '0x0',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'XPC_SERVICE_NAME': '0',
[task 2023-12-21T07:28:23.177Z] 07:28:23     INFO -                                                  'XRE_NO_WINDOWS_CRASH_DIALOG': '1',
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -                                                  '_': '/usr/local/bin/start-worker',
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -                                                  '__CF_USER_TEXT_ENCODING': '0x24:0x0:0x0'}}},
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -  'host': '127.0.0.1',
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -  'port': 53688,
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -  'timeout_multiplier': 1,
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -  'webdriver': {'args': ['-vv',
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -                         '--binary',
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -                         '/opt/worker/tasks/task_170314002785627/build/application/Firefox '
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -                         'Nightly.app/Contents/MacOS/firefox'],
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -                'binary': '/opt/worker/tasks/task_170314002785627/fetches/geckodriver'}}
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO - future     = <Future pending>
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO - timeout    = 2.0
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO - 
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO - tests/web-platform/tests/webdriver/tests/support/fixtures_bidi.py:115: 
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO - 
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO - fut = <Future cancelled>, timeout = 2.0
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO - 
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -     async def wait_for(fut, timeout, *, loop=None):
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -         """Wait for the single Future or coroutine to complete, with timeout.
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -     
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -         Coroutine will be wrapped in Task.
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -     
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -         Returns result of the Future or coroutine.  When a timeout occurs,
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -         it cancels the task and raises TimeoutError.  To avoid the task
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -         cancellation, wrap it in shield().
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -     
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -         If the wait is cancelled, the task is also cancelled.
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -     
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -         This function is a coroutine.
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -         """
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -         if loop is None:
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -             loop = events.get_event_loop()
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -     
[task 2023-12-21T07:28:23.178Z] 07:28:23     INFO -         if timeout is None:
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -             return await fut
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -     
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -         if timeout <= 0:
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -             fut = ensure_future(fut, loop=loop)
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -     
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -             if fut.done():
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -                 return fut.result()
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -     
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -             fut.cancel()
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -             raise futures.TimeoutError()
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -     
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -         waiter = loop.create_future()
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -         timeout_handle = loop.call_later(timeout, _release_waiter, waiter)
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -         cb = functools.partial(_release_waiter, waiter)
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -     
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -         fut = ensure_future(fut, loop=loop)
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -         fut.add_done_callback(cb)
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -     
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -         try:
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -             # wait until the future completes or the timeout
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -             try:
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -                 await waiter
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -             except futures.CancelledError:
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -                 fut.remove_done_callback(cb)
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -                 fut.cancel()
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -                 raise
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -     
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -             if fut.done():
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -                 return fut.result()
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -             else:
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -                 fut.remove_done_callback(cb)
[task 2023-12-21T07:28:23.179Z] 07:28:23     INFO -                 # We must ensure that the task is not running
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO -                 # after wait_for() returns.
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO -                 # See https://bugs.python.org/issue32751
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO -                 await _cancel_and_wait(fut, loop=loop)
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - >               raise futures.TimeoutError()
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - E               concurrent.futures._base.TimeoutError
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - 
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - cb         = functools.partial(<function _release_waiter at 0x7fa500c09830>, <Future finished result=None>)
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - fut        = <Future cancelled>
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - loop       = <_UnixSelectorEventLoop running=False closed=False debug=False>
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - timeout    = 2.0
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - timeout_handle = <TimerHandle cancelled when=50.902286258>
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - waiter     = <Future finished result=None>
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - 
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - /Library/Frameworks/Python.framework/Versions/3.7/lib/python3.7/asyncio/tasks.py:449: TimeoutError
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - 
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - During handling of the above exception, another exception occurred:
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - 
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - bidi_session = <webdriver.bidi.client.BidiSession object at 0x7fa504e41590>
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - top_context = {'children': [{'children': [], 'context': '8589934596', 'url': 'https://web-platform.test:8443/webdriver/tests/support...+++++++++++++%7D%0A++++++++++++++++%29%3B%0A++++++++++++%0A++++++++++++%3C%2Fscript%3E&mime=text%2Fhtml&charset=UTF-8'}
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - url = <function url.<locals>.url at 0x7fa5024b0320>
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - inline = <function inline.<locals>.inline at 0x7fa502486050>
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - subscribe_events = <function subscribe_events.<locals>.subscribe_events at 0x7fa5021b6e60>
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - wait_for_event = <function wait_for_event.<locals>.wait_for_event at 0x7fa5021b6f80>
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - wait_for_future_safe = <function wait_for_future_safe.<locals>.wait_for_future_safe at 0x7fa5049cb830>
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - get_element = <function get_element.<locals>.get_element at 0x7fa502456830>
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO - 
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO -     async def test_click_navigation(
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO -         bidi_session,
[task 2023-12-21T07:28:23.180Z] 07:28:23     INFO -         top_context,
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -         url,
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -         inline,
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -         subscribe_events,
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -         wait_for_event,
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -         wait_for_future_safe,
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -         get_element,
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -     ):
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -         await subscribe_events(events=["browsingContext.load"])
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -     
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -         destination = url("/webdriver/tests/support/html/test_actions.html")
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -         start = inline(f'<a href="{destination}" id="link">destination</a>')
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -     
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -         async def click_link():
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -             link = await get_element("#link")
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -     
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -             actions = Actions()
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -             (
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -                 actions.add_pointer()
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -                 .pointer_move(x=0, y=0, origin=get_element_origin(link))
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -                 .pointer_down(button=0)
[task 2023-12-21T07:28:23.181Z] 07:28:23     INFO -                 .pointer_up(button=0)
[task 2023-12-21T07:28:23.182Z] 07:28:23     INFO -             )
<...>
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO -                                                  'XPC_FLAGS': '0x0',
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO -                                                  'XPC_SERVICE_NAME': '0',
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO -                                                  'XRE_NO_WINDOWS_CRASH_DIALOG': '1',
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO -                                                  '_': '/usr/local/bin/start-worker',
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO -                                                  '__CF_USER_TEXT_ENCODING': '0x24:0x0:0x0'}}},
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO -  'host': '127.0.0.1',
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO -  'port': 53688,
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO -  'timeout_multiplier': 1,
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO -  'webdriver': {'args': ['-vv',
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO -                         '--binary',
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO -                         '/opt/worker/tasks/task_170314002785627/build/application/Firefox '
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO -                         'Nightly.app/Contents/MacOS/firefox'],
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO -                'binary': '/opt/worker/tasks/task_170314002785627/fetches/geckodriver'}}
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO - future     = <Future pending>
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO - timeout    = 2.0
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO - 
[task 2023-12-21T07:28:23.199Z] 07:28:23     INFO - tests/web-platform/tests/webdriver/tests/support/fixtures_bidi.py:117: AttributeError
[task 2023-12-21T07:28:23.200Z] 07:28:23     INFO - PID 3554 | 1703143683673	RemoteAgent	TRACE	Module windowglobal/network.sys.mjs not found for WINDOW_GLOBAL
[task 2023-12-21T07:28:23.201Z] 07:28:23     INFO - .
[task 2023-12-21T07:28:23.201Z] 07:28:23     INFO - PID 3554 | 1703143683673	RemoteAgent	TRACE	Created MessageHandler WINDOW_GLOBAL for session d72d5334-2d2e-448a-8373-aee5e5e049ce
[task 2023-12-21T07:28:23.203Z] 07:28:23     INFO - .
[task 2023-12-21T07:28:23.203Z] 07:28:23     INFO - PID 3554 | 1703143683673	Marionette	TRACE	[11] MarionetteCommands actor destroyed for window id 8589934650
[task 2023-12-21T07:28:23.204Z] 07:28:23     INFO - .
[task 2023-12-21T07:28:23.204Z] 07:28:23     INFO - PID 3554 | 1703143683673	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session d72d5334-2d2e-448a-8373-aee5e5e049ce is being destroyed
[task 2023-12-21T07:28:23.204Z] 07:28:23     INFO - PID 3554 | 1703143683673	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session d72d5334-2d2e-448a-8373-aee5e5e049ce
[task 2023-12-21T07:28:23.205Z] 07:28:23     INFO - TEST-OK | /webdriver/tests/bidi/input/perform_actions/pointer_mouse.py | took 9404ms
Group: firefox-core-security
Severity: -- → S4
Priority: -- → P5
Summary: Intermittent [tier 2] /webdriver/tests/bidi/input/perform_actions/pointer_mouse.py | single tracking bug → Intermittent /webdriver/tests/bidi/input/perform_actions/pointer_mouse.py | single tracking bug
Status: NEW → RESOLVED
Closed: 10 months ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

(In reply to Treeherder Bug Filer from comment #13)

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=460774256&repo=try

This is just bug 1819763.

Status: REOPENED → RESOLVED
Closed: 10 months ago9 months ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

The recent failures seem to all depend on my patch from bug 1904665 which was landed and then backed out.

Blocks: 1904665
No longer blocks: 1904665
Depends on: 1904665

As it looks like this failure doesn't happen anymore. Lets close for now.

Status: REOPENED → RESOLVED
Closed: 9 months ago5 months ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

This is still happening and this bug was actually wrongly filed. It should be for classic while bug 1918610 is covering the BiDi case.

See Also: → 1918610
Summary: Intermittent /webdriver/tests/bidi/input/perform_actions/pointer_mouse.py | single tracking bug → Intermittent /webdriver/tests/classic/perform_actions/pointer_mouse.py | test_drag_and_drop[20-0-0] - assert (55.0 + 20) == 55.0
No longer depends on: 1904859
Duplicate of this bug: 1918610

The test was still failing for me with a couple of days old build but rebasing to latest main it works just fine for me. Also it was just marked as intermittently failing in CI, so that no more test failures were logged since October 24th.

I'll push a new try build to check that including the meta data reverted for bug 1918610 as well:
https://treeherder.mozilla.org/jobs?repo=try&tier=1%2C2%2C3&revision=dee93c273abbe3ec676dc1fca6660fcfb919279e

On bug 1922077 I landed a patch that enabled those tests again:
https://hg.mozilla.org/integration/autoland/rev/6eda5763eee9

If no more failures appear for this particular test over the weekend I would close this bug as incomplete.

We had a single failure only. Lets wait a couple more days with more landing activity and a fix for bug 1932916 as well.

This fails still so rarely that it doesn't need to block bug 1922077 anymore.

No longer blocks: 1922077
Status: REOPENED → NEW
Component: WebDriver BiDi → Marionette
Summary: Intermittent /webdriver/tests/classic/perform_actions/pointer_mouse.py | test_drag_and_drop[20-0-0] - assert (55.0 + 20) == 55.0 → Intermittent /webdriver/tests/classic/perform_actions/pointer_mouse.py | test_drag_and_drop[<random>] - assert (<random>) == <random>
Assignee: nobody → hskupin
Status: NEW → ASSIGNED
Pushed by hskupin@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/b72396579e4a [wdspec] Temporarily mark some test_drag_and_drop tests as pass and fail. r=webdriver-reviewers,Sasha

Two days ago I was seeing this failure a lot when running the test locally. Now that bug 1937118 has landed I'm no longer able to reproduce it on my machine after removing the test expectations. I've now pushed a try build to see how the status looks like in CI:

https://treeherder.mozilla.org/jobs?repo=try&revision=16e95c267fd614c1af89409a7c5aea5781219d54

Depends on: 1937118
OS: Unspecified → macOS
Hardware: Unspecified → All

Ok, I've pushed a try build with some more logging so that we can actually see how the mouse gets moved over the curse of the test.

Here is the related output:

[task 2024-12-20T09:09:54.393Z] 09:09:54     INFO - PID 1790 | 1734685794391	Marionette	DEBUG	0 -> [0,11,"WebDriver:PerformActions",{"actions":[{"actions":[{"origin":{"element-6066-11e4-a52e-4f735466cecf":"4fe76166-fc38-4cf3-83e6-e3f5d201c4a7"},"type":"pointerMove","x":0,"y":0},{"button":0,"type":"pointerDown"},{"duration":300,"origin":"pointer","type":"pointerMove","x":20,"y":0},{"button":0,"type":"pointerUp"},{"duration":100,"origin":"pointer","type":"pointerMove","x":80,"y":50}],"id":"pointer_id","parameters":{"pointerType":"mouse"},"type":"pointer"}]}]
[task 2024-12-20T09:09:54.396Z] 09:09:54     INFO - PID 1790 | 1734685794395	Marionette	TRACE	Dispatching tick 1/5
[task 2024-12-20T09:09:54.396Z] 09:09:54     INFO - PID 1790 | 1734685794395	Marionette	TRACE	Dispatch PointerMoveAction mouse with id: pointer_id x: 0 y: 0
[task 2024-12-20T09:09:54.401Z] 09:09:54     INFO - PID 1790 | 1734685794400	Marionette	TRACE	moveOverTime start: 0,0 target: 58,303 duration: 0
[task 2024-12-20T09:09:54.401Z] 09:09:54     INFO - PID 1790 | 1734685794400	Marionette	TRACE	PointerMoveAction.performPointerMoveStep [58,303]
[task 2024-12-20T09:09:54.407Z] 09:09:54     INFO - PID 1790 | *** mouse move 58/303
[task 2024-12-20T09:09:54.408Z] 09:09:54     INFO - PID 1790 | 1734685794407	Marionette	TRACE	Dispatching tick 2/5
[task 2024-12-20T09:09:54.409Z] 09:09:54     INFO - PID 1790 | 1734685794407	Marionette	TRACE	Dispatch PointerDownAction mouse with id: pointer_id button: 0
[task 2024-12-20T09:09:54.425Z] 09:09:54     INFO - PID 1790 | 1734685794424	Marionette	TRACE	Dispatching tick 3/5
[task 2024-12-20T09:09:54.426Z] 09:09:54     INFO - PID 1790 | 1734685794424	Marionette	TRACE	Dispatch PointerMoveAction mouse with id: pointer_id x: 20 y: 0
[task 2024-12-20T09:09:54.426Z] 09:09:54     INFO - PID 1790 | 1734685794424	Marionette	TRACE	moveOverTime start: 58,303 target: 78,303 duration: 300
[task 2024-12-20T09:09:54.479Z] 09:09:54     INFO - PID 1790 | 1734685794478	Marionette	TRACE	PointerMoveAction.performPointerMoveStep [61,303]
[task 2024-12-20T09:09:54.479Z] 09:09:54     INFO - PID 1790 | *** mouse move 61/303
[task 2024-12-20T09:09:54.622Z] 09:09:54     INFO - PID 1790 | 1734685794620	Marionette	TRACE	PointerMoveAction.performPointerMoveStep [71,303]
[task 2024-12-20T09:09:54.622Z] 09:09:54     INFO - PID 1790 | *** mouse move 71/303
[task 2024-12-20T09:09:54.761Z] 09:09:54     INFO - PID 1790 | 1734685794759	Marionette	TRACE	PointerMoveAction.performPointerMoveStep [78,303]
[task 2024-12-20T09:09:54.761Z] 09:09:54     INFO - PID 1790 | *** mouse move 78/303
[task 2024-12-20T09:09:54.762Z] 09:09:54     INFO - PID 1790 | 1734685794760	Marionette	TRACE	Dispatching tick 4/5
[task 2024-12-20T09:09:54.763Z] 09:09:54     INFO - PID 1790 | 1734685794761	Marionette	TRACE	Dispatch PointerUpAction mouse with id: pointer_id button: 0

As it can be seen we get mousemove events from Gecko that tell us that the event dispatching is correctly done. So why do we have that assertion failure for the position of the dragTarget then? I pushed a try build to verify that the related drag events are delayed (because they are asyn) and as such the position gets updated as well with a delay:

https://treeherder.mozilla.org/jobs?repo=try&selectedTaskRun=dZxGgAsVQb66Wv1eGb0odA.0&revision=ae3519306a846c0d188b57c49bec3a69dfc1fd11

So yes, this is indeed a delay here:

[task 2024-12-20T11:36:02.870Z] 11:36:02     INFO - PID 1016 | 1734694562868	Marionette	DEBUG	0 -> [0,202,"WebDriver:GetElementRect",{"id":"1812cd85-9a61-4bc3-bc7b-1a98664469c7"}]
[task 2024-12-20T11:36:02.871Z] 11:36:02     INFO - PID 1016 | 1734694562869	Marionette	DEBUG	0 <- [1,202,null,{"x":63,"y":311.8999938964844,"width":7,"height":7}]
[task 2024-12-20T11:36:02.872Z] 11:36:02     INFO - PID 1016 | 1734694562870	webdriver::server	DEBUG	<- 200 OK {"value":{"x":63.0,"y":311.8999938964844,"width":7.0,"height":7.0}}
[task 2024-12-20T11:36:02.872Z] 11:36:02     INFO - STDOUT: *** waiting for target to be at position
[task 2024-12-20T11:36:02.873Z] 11:36:02     INFO - PID 1016 | 1734694562870	webdriver::server	DEBUG	-> GET /session/a0ca0fff-a033-456b-9d69-d928e9c7b194/element/1812cd85-9a61-4bc3-bc7b-1a98664469c7/rect
[task 2024-12-20T11:36:02.874Z] 11:36:02     INFO - PID 1016 | 1734694562871	Marionette	DEBUG	0 -> [0,203,"WebDriver:GetElementRect",{"id":"1812cd85-9a61-4bc3-bc7b-1a98664469c7"}]
[task 2024-12-20T11:36:02.874Z] 11:36:02     INFO - PID 1016 | 1734694562872	Marionette	DEBUG	0 <- [1,203,null,{"x":63,"y":311.8999938964844,"width":7,"height":7}]
[task 2024-12-20T11:36:02.875Z] 11:36:02     INFO - PID 1016 | 1734694562873	webdriver::server	DEBUG	<- 200 OK {"value":{"x":63.0,"y":311.8999938964844,"width":7.0,"height":7.0}}
[task 2024-12-20T11:36:03.122Z] 11:36:03     INFO - PID 1016 | 1734694563121	webdriver::server	DEBUG	-> GET /session/a0ca0fff-a033-456b-9d69-d928e9c7b194/element/1812cd85-9a61-4bc3-bc7b-1a98664469c7/rect
[task 2024-12-20T11:36:03.123Z] 11:36:03     INFO - PID 1016 | 1734694563122	Marionette	DEBUG	0 -> [0,204,"WebDriver:GetElementRect",{"id":"1812cd85-9a61-4bc3-bc7b-1a98664469c7"}]
[task 2024-12-20T11:36:03.125Z] 11:36:03     INFO - PID 1016 | 1734694563124	Marionette	DEBUG	0 <- [1,204,null,{"x":65,"y":314.8999938964844,"width":7,"height":7}]
[task 2024-12-20T11:36:03.126Z] 11:36:03     INFO - PID 1016 | 1734694563124	webdriver::server	DEBUG	<- 200 OK {"value":{"x":65.0,"y":314.8999938964844,"width":7.0,"height":7.0}}

So I wonder if the remote end has to check if a drag and drop action is ongoing and wait until the dragend event has been fired? Or should the test be updated to wait until the dragged element reached its target position?

Pushed by hskupin@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/d13caad2c616 [wdspec] Fix WebDriver classic and BiDi drag and drop position tests. r=webdriver-reviewers,Sasha
Created web-platform-tests PR https://github.com/web-platform-tests/wpt/pull/49808 for changes under testing/web-platform/tests
Upstream PR merged by moz-wptsync-bot
Status: ASSIGNED → RESOLVED
Points: --- → 2
Closed: 5 months ago2 months ago
Resolution: --- → FIXED
Target Milestone: --- → 135 Branch
Whiteboard: [webdriver:m14]
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: