Intermittent /webdriver/tests/classic/perform_actions/pointer_mouse.py | test_drag_and_drop[<random>] - assert (<random>) == <random>
Categories
(Remote Protocol :: Marionette, defect, P5)
Tracking
(firefox135 fixed)
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
Updated•1 year ago
|
Assignee | ||
Updated•1 year ago
|
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 3•1 year ago
|
||
This started failing on tier 1: https://treeherder.mozilla.org/logviewer?job_id=443230340&repo=mozilla-beta&lineNumber=94087
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 12•10 months ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Reporter | ||
Comment 13•9 months ago
|
||
treeherder |
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=460774256&repo=try
Assignee | ||
Comment 14•9 months ago
|
||
(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.
Reporter | ||
Comment 15•5 months ago
|
||
treeherder |
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=473029280&repo=try
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Comment 18•5 months ago
|
||
The recent failures seem to all depend on my patch from bug 1904665 which was landed and then backed out.
Assignee | ||
Updated•5 months ago
|
Assignee | ||
Comment 19•5 months ago
|
||
As it looks like this failure doesn't happen anymore. Lets close for now.
Reporter | ||
Comment 20•4 months ago
|
||
treeherder |
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=476684100&repo=try
Assignee | ||
Comment 21•4 months ago
|
||
This is still happening and this bug was actually wrongly filed. It should be for classic while bug 1918610 is covering the BiDi case.
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Comment 25•3 months ago
|
||
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
Assignee | ||
Comment 26•3 months ago
|
||
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.
Assignee | ||
Comment 27•3 months ago
|
||
We had a single failure only. Lets wait a couple more days with more landing activity and a fix for bug 1932916 as well.
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Comment 29•3 months ago
|
||
This fails still so rarely that it doesn't need to block bug 1922077 anymore.
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Updated•2 months ago
|
Assignee | ||
Comment 31•2 months ago
|
||
Updated•2 months ago
|
Comment hidden (Intermittent Failures Robot) |
Comment 33•2 months ago
|
||
Assignee | ||
Updated•2 months ago
|
Comment 34•2 months ago
|
||
bugherder |
Assignee | ||
Comment 35•2 months ago
|
||
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
Assignee | ||
Comment 36•2 months ago
|
||
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:
Assignee | ||
Comment 37•2 months ago
|
||
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?
Assignee | ||
Comment 38•2 months ago
|
||
Comment 39•2 months ago
|
||
Comment 41•2 months ago
|
||
bugherder |
Comment hidden (Intermittent Failures Robot) |
Assignee | ||
Updated•2 months ago
|
Assignee | ||
Updated•2 months ago
|
Description
•