Open Bug 1927314 Opened 15 days ago Updated 14 days ago

Intermittent TEST-UNEXPECTED-CRASH | /webdriver/tests/bidi/input/perform_actions/key.py | expected OK

Categories

(Core :: Graphics: WebRender, defect, P5)

defect

Tracking

()

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2024-10-27T00:55:07.768Z] 00:55:07     INFO - 0x7fff6b999000 - 0x7fff6b9cefff  libxpc.dylib  ???
[task 2024-10-27T00:55:07.768Z] 00:55:07     INFO - 
[task 2024-10-27T00:55:07.768Z] 00:55:07     INFO - Unloaded modules:
[task 2024-10-27T00:55:07.768Z] 00:55:07     INFO - Found a crash dump file; changing status to CRASH
[task 2024-10-27T00:55:07.768Z] 00:55:07     INFO - TEST-UNEXPECTED-CRASH | /webdriver/tests/bidi/input/perform_actions/key.py | expected OK
[task 2024-10-27T00:55:07.769Z] 00:55:07     INFO - TEST-INFO took 29191ms
[task 2024-10-27T00:55:11.452Z] 00:55:11     INFO - PID 3519 | #01: RustMozCrash [mozglue/static/rust/wrappers.cpp:18]
[task 2024-10-27T00:55:11.453Z] 00:55:11     INFO - PID 3519 | 1729990486151	Marionette	DEBUG	0 -> [0,36,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2024-10-27T00:55:11.454Z] 00:55:11     INFO - PID 3519 | 1729990486151	Marionette	DEBUG	0 <- [1,36,null,{"value":null}]
[task 2024-10-27T00:55:11.454Z] 00:55:11     INFO - PID 3519 | #02: mozglue_static::panic_hook [mozglue/static/rust/lib.rs:84]
[task 2024-10-27T00:55:11.455Z] 00:55:11     INFO - PID 3519 | 1729990486152	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-10-27T00:55:11.455Z] 00:55:11     INFO - PID 3519 | 1729990486152	webdriver::server	DEBUG	-> POST /session/d863e178-51bf-496c-84d0-bce692f2563f/timeouts {"pageLoad": 300000}
[task 2024-10-27T00:55:11.456Z] 00:55:11     INFO - PID 3519 | #03: core::ops::function::Fn::call::h3901142baf33c043 [/opt/worker/tasks/task_172998592565094/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL + 0x929acac]
[task 2024-10-27T00:55:11.456Z] 00:55:11     INFO - PID 3519 | #04: std::panicking::rust_panic_with_hook [git:github.com/rust-lang/rust:library/std/src/panicking.rs:eeb90cda1969383f56a2637cbd3037bdf598841c:810]
[task 2024-10-27T00:55:11.457Z] 00:55:11     INFO - PID 3519 | #05: std::panicking::begin_panic_handler::{{closure}} [git:github.com/rust-lang/rust:library/std/src/panicking.rs:eeb90cda1969383f56a2637cbd3037bdf598841c:0]
[task 2024-10-27T00:55:11.458Z] 00:55:11     INFO - PID 3519 | #06: std::sys::backtrace::__rust_end_short_backtrace::ha2fdb908654ed930 [/opt/worker/tasks/task_172998592565094/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL + 0x9f7f039]
[task 2024-10-27T00:55:11.458Z] 00:55:11     INFO - PID 3519 | 1729990486155	Marionette	DEBUG	0 -> [0,37,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2024-10-27T00:55:11.459Z] 00:55:11     INFO - PID 3519 | 1729990486156	Marionette	DEBUG	0 <- [1,37,null,{"value":null}]
[task 2024-10-27T00:55:11.459Z] 00:55:11     INFO - PID 3519 | #07: rust_begin_unwind [/opt/worker/tasks/task_172998592565094/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL + 0x9f7fdec]
[task 2024-10-27T00:55:11.460Z] 00:55:11     INFO - PID 3519 | 1729990486157	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-10-27T00:55:11.460Z] 00:55:11     INFO - PID 3519 | #08: core::panicking::panic_fmt::h292eaa4d42e451fa [/opt/worker/tasks/task_172998592565094/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL + 0x9f9fd3a]
[task 2024-10-27T00:55:11.461Z] 00:55:11     INFO - PID 3519 | 1729990486158	webdriver::server	DEBUG	-> POST /session/d863e178-51bf-496c-84d0-bce692f2563f/timeouts {"script": 30000}
[task 2024-10-27T00:55:11.462Z] 00:55:11     INFO - PID 3519 | 1729990486159	Marionette	DEBUG	0 -> [0,38,"WebDriver:SetTimeouts",{"script":30000}]
[task 2024-10-27T00:55:11.462Z] 00:55:11     INFO - PID 3519 | #09: core::panicking::assert_failed_inner [git:github.com/rust-lang/rust:library/core/src/panicking.rs:eeb90cda1969383f56a2637cbd3037bdf598841c:401]
[task 2024-10-27T00:55:11.463Z] 00:55:11     INFO - PID 3519 | 1729990486159	Marionette	DEBUG	0 <- [1,38,null,{"value":null}]
[task 2024-10-27T00:55:11.464Z] 00:55:11     INFO - PID 3519 | #10: core::panicking::assert_failed [git:github.com/rust-lang/rust:library/core/src/panicking.rs:eeb90cda1969383f56a2637cbd3037bdf598841c:355]
[task 2024-10-27T00:55:11.465Z] 00:55:11     INFO - PID 3519 | #11: wr_glyph_rasterizer::rasterizer::GlyphKey::new::h8fa7c63d0f200486 [/opt/worker/tasks/task_172998592565094/build/application/Firefox NightlyDebug.app/Contents/MacOS/XUL + 0x9006f29]
[task 2024-10-27T00:55:11.465Z] 00:55:11     INFO - PID 3519 | 1729990486162	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2024-10-27T00:55:11.466Z] 00:55:11     INFO - PID 3519 | #12: webrender::prim_store::text_run::TextRunPrimitive::request_resources [gfx/wr/webrender/src/prim_store/text_run.rs:472]
[task 2024-10-27T00:55:11.466Z] 00:55:11     INFO - PID 3519 | 1729990486162	webdriver::server	DEBUG	-> GET /session/d863e178-51bf-496c-84d0-bce692f2563f/window
[task 2024-10-27T00:55:11.467Z] 00:55:11     INFO - PID 3519 | #13: webrender::prepare::prepare_interned_prim_for_render [gfx/wr/webrender/src/prepare.rs:478]
[task 2024-10-27T00:55:11.467Z] 00:55:11     INFO - PID 3519 | 1729990486163	Marionette	DEBUG	0 -> [0,39,"WebDriver:GetWindowHandle",{}]
[task 2024-10-27T00:55:11.468Z] 00:55:11     INFO - PID 3519 | 1729990486163	Marionette	DEBUG	0 <- [1,39,null,{"value":"1808723f-85ed-4a61-93af-b0f3d9b57956"}]
[task 2024-10-27T00:55:11.469Z] 00:55:11     INFO - PID 3519 | #14: webrender::prepare::prepare_primitives [gfx/wr/webrender/src/prepare.rs:81]
[task 2024-10-27T00:55:11.469Z] 00:55:11     INFO - PID 3519 | 1729990486164	webdriver::server	DEBUG	<- 200 OK {"value":"1808723f-85ed-4a61-93af-b0f3d9b57956"}
[task 2024-10-27T00:55:11.470Z] 00:55:11     INFO - PID 3519 | 1729990486164	webdriver::server	DEBUG	-> GET /session/d863e178-51bf-496c-84d0-bce692f2563f/window
[task 2024-10-27T00:55:11.471Z] 00:55:11     INFO - PID 3519 | #15: webrender::frame_builder::FrameBuilder::build [gfx/wr/webrender/src/frame_builder.rs:573]
[task 2024-10-27T00:55:11.471Z] 00:55:11     INFO - PID 3519 | #16: webrender::render_backend::Document::build_frame [gfx/wr/webrender/src/render_backend.rs:530]
[task 2024-10-27T00:55:11.472Z] 00:55:11     INFO - PID 3519 | #17: webrender::render_backend::RenderBackend::update_document [gfx/wr/webrender/src/render_backend.rs:1466]
[task 2024-10-27T00:55:11.473Z] 00:55:11     INFO - PID 3519 | #18: webrender::render_backend::RenderBackend::process_api_msg [gfx/wr/webrender/src/render_backend.rs:1153]
[task 2024-10-27T00:55:11.473Z] 00:55:11     INFO - PID 3519 | 1729990486168	Marionette	DEBUG	0 -> [0,40,"WebDriver:GetWindowHandle",{}]
[task 2024-10-27T00:55:11.474Z] 00:55:11     INFO - PID 3519 | 1729990486168	Marionette	DEBUG	0 <- [1,40,null,{"value":"1808723f-85ed-4a61-93af-b0f3d9b57956"}]
[task 2024-10-27T00:55:11.475Z] 00:55:11     INFO - PID 3519 | #19: std::sys::backtrace::__rust_begin_short_backtrace [git:github.com/rust-lang/rust:library/std/src/sys/backtrace.rs:eeb90cda1969383f56a2637cbd3037bdf598841c:152]
[task 2024-10-27T00:55:11.476Z] 00:55:11     INFO - PID 3519 | 1729990486170	webdriver::server	DEBUG	<- 200 OK {"value":"1808723f-85ed-4a61-93af-b0f3d9b57956"}
[task 2024-10-27T00:55:11.476Z] 00:55:11     INFO - PID 3519 | #20: core::ops::function::FnOnce::call_once{{vtable.shim}} [git:github.com/rust-lang/rust:library/core/src/ops/function.rs:eeb90cda1969383f56a2637cbd3037bdf598841c:250]
[task 2024-10-27T00:55:11.477Z] 00:55:11     INFO - PID 3519 | 1729990486170	webdriver::server	DEBUG	-> GET /session/d863e178-51bf-496c-84d0-bce692f2563f/window/handles
[task 2024-10-27T00:55:11.478Z] 00:55:11     INFO - PID 3519 | #21: std::sys::pal::unix::thread::Thread::new::thread_start [git:github.com/rust-lang/rust:library/std/src/sys/pal/unix/thread.rs:eeb90cda1969383f56a2637cbd3037bdf598841c:108]
[task 2024-10-27T00:55:11.479Z] 00:55:11     INFO - PID 3519 | #22: _pthread_start [/usr/lib/system/libsystem_pthread.dylib + 0x6109]
[task 2024-10-27T00:55:11.479Z] 00:55:11     INFO - PID 3519 | [Child 3558, IPC I/O Child] WARNING: [3A7F890CCBB88C3C.112C878A61B1F871]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:364
[task 2024-10-27T00:55:11.480Z] 00:55:11     INFO - PID 3519 | [Child 3557, IPC I/O Child] WARNING: [F1A10AFEF8208847.E1B1B293FAD754CD]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:364
[task 2024-10-27T00:55:11.481Z] 00:55:11     INFO - PID 3519 | [Child 3552, IPC I/O Child] WARNING: [EC353909D437469A.3D0CAC39E5C6015E]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:364
[task 2024-10-27T00:55:11.482Z] 00:55:11     INFO - PID 3519 | [Child 3550, IPC I/O Child] WARNING: [F3D488303ECFCEC0.19458CC67964981E]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:364
[task 2024-10-27T00:55:11.482Z] 00:55:11     INFO - PID 3519 | [Utility 3551, IPC I/O Child] WARNING: [522A360BBF4C35E2.F5EA56FBC34AAD89]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:364
[task 2024-10-27T00:55:11.483Z] 00:55:11     INFO - PID 3519 | [Child 3548, IPC I/O Child] WARNING: [ECA44CFFC4C9D0CA.FBE7BB1761D21E22]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:364
[task 2024-10-27T00:55:11.483Z] 00:55:11     INFO - PID 3519 | Exiting due to channel error.
[task 2024-10-27T00:55:11.484Z] 00:55:11     INFO - PID 3519 | [Child 3559, IPC I/O Child] WARNING: [5FA943AB6A351F1A.FB878197903EAB52]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:364
[task 2024-10-27T00:55:11.485Z] 00:55:11     INFO - PID 3519 | 1729990486548	webdriver::server	DEBUG	Teardown session
[task 2024-10-27T00:55:11.486Z] 00:55:11     INFO - PID 3519 | [Socket 3547, IPC I/O Child] WARNING: [2D2A9B47BB596F17.7C70A86B49FB1EB8]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:364
[task 2024-10-27T00:55:11.486Z] 00:55:11     INFO - PID 3519 | [Utility 3551, Main Thread] WARNING: Shutting down Utility process early due to a crash!: file /builds/worker/checkouts/gecko/ipc/glue/UtilityProcessChild.cpp:358
[task 2024-10-27T00:55:11.487Z] 00:55:11     INFO - PID 3519 | Exiting due to channel error.
[task 2024-10-27T00:55:11.488Z] 00:55:11     INFO - PID 3519 | Exiting due to channel error.
[task 2024-10-27T00:55:11.488Z] 00:55:11     INFO - PID 3519 | Exiting due to channel error.
[task 2024-10-27T00:55:11.489Z] 00:55:11     INFO - PID 3519 | Exiting due to channel error.
[task 2024-10-27T00:55:11.489Z] 00:55:11     INFO - PID 3519 | Exiting due to channel error.
[task 2024-10-27T00:55:11.490Z] 00:55:11     INFO - PID 3519 | [Socket 3547, Main Thread] WARNING: Shutting down Socket process early due to a crash!: file /builds/worker/checkouts/gecko/netwerk/ipc/SocketProcessChild.cpp:234
[task 2024-10-27T00:55:11.491Z] 00:55:11     INFO - PID 3519 | 1729990486549	geckodriver::marionette	ERROR	Failed to close browser connection: unknown error
[task 2024-10-27T00:55:11.491Z] 00:55:11     INFO - PID 3519 | 1729990486549	webdriver::server	DEBUG	<- 500 Internal Server Error {"value":{"error":"unknown error","message":"Failed to decode response from marionette","stacktrace":""}}
[task 2024-10-27T00:55:11.492Z] 00:55:11     INFO - PID 3519 | 1729990486550	webdriver::server	DEBUG	-> GET /session/d863e178-51bf-496c-84d0-bce692f2563f/window
[task 2024-10-27T00:55:11.493Z] 00:55:11     INFO - PID 3519 | 1729990486550	webdriver::server	DEBUG	<- 404 Not Found {"value":{"error":"invalid session id","message":"Tried to run command without establishing a connection","stacktrace":""}}
[task 2024-10-27T00:55:11.494Z] 00:55:11     INFO - PID 3519 | 1729990486552	webdriver::server	DEBUG	-> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile", "/var/folders/gz/qm7lqzhd05x5 ... : "/var/folders/gz/qm7lqzhd05x5j0ck0ghtdmrr000014/T/tmpdyfmsgmp.mozrunner/runtests_leaks_3144.log"}}, "webSocketUrl": true}}}
[task 2024-10-27T00:55:11.494Z] 00:55:11     INFO - PID 3519 | 1729990486553	geckodriver::capabilities	DEBUG	Trying to read firefox version from ini files
[task 2024-10-27T00:55:11.495Z] 00:55:11     INFO - PID 3519 | 1729990486553	geckodriver::capabilities	DEBUG	Found version 133.0a1
[task 2024-10-27T00:55:11.495Z] 00:55:11     INFO - PID 3519 | 1729990486554	geckodriver::browser	DEBUG	Backing up prefs to "/var/folders/gz/qm7lqzhd05x5j0ck0ghtdmrr000014/T/tmpdyfmsgmp.mozrunner/user.geckodriver_backup_1"
[task 2024-10-27T00:55:11.496Z] 00:55:11     INFO - PID 3519 | 1729990486565	mozrunner::runner	INFO	Running command: MOZ_CRASHREPORTER="1" MOZ_CRASHREPORTER_NO_REPORT="1" MOZ_CRASHREPORTER_SHUTDOWN="1" "/opt/worker/tasks/task ... 00014/T/tmpdyfmsgmp.mozrunner" "--remote-debugging-port" "9222" "--remote-allow-hosts" "localhost" "-foreground" "-no-remote"
[task 2024-10-27T00:55:11.497Z] 00:55:11     INFO - PID 3519 | 1729990486566	geckodriver::marionette	DEBUG	Waiting 60s to connect to browser on 127.0.0.1
[task 2024-10-27T00:55:11.498Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.498Z] 00:55:11     INFO - PID 3519 | 1729990486566	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.499Z] 00:55:11     INFO - PID 3519 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/gz/qm7lqzhd05x5j0ck0ghtdmrr000014/T/tmpdyfmsgmp.mozrunner/runtests_leaks_3144.log
[task 2024-10-27T00:55:11.500Z] 00:55:11     INFO - PID 3519 | [Parent 3561, Main Thread] WARNING: Last startup was detected as a crash.: file /builds/worker/checkouts/gecko/toolkit/components/startup/nsAppStartup.cpp:948
[task 2024-10-27T00:55:11.500Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.501Z] 00:55:11     INFO - PID 3519 | 1729990486807	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.502Z] 00:55:11     INFO - PID 3519 | GL_VENDOR: Intel Inc.
[task 2024-10-27T00:55:11.502Z] 00:55:11     INFO - PID 3519 | mVendor: Intel
[task 2024-10-27T00:55:11.503Z] 00:55:11     INFO - PID 3519 | GL_RENDERER: Intel(R) UHD Graphics 630
[task 2024-10-27T00:55:11.503Z] 00:55:11     INFO - PID 3519 | mRenderer: Unknown
[task 2024-10-27T00:55:11.504Z] 00:55:11     INFO - PID 3519 | mIsMesa: 0
[task 2024-10-27T00:55:11.505Z] 00:55:11     INFO - PID 3519 | 1729990487032	RemoteAgent	DEBUG	Setting recommended pref browser.tabs.warnOnClose to false
[task 2024-10-27T00:55:11.505Z] 00:55:11     INFO - PID 3519 | 1729990487033	RemoteAgent	DEBUG	Setting recommended pref datareporting.policy.dataSubmissionPolicyAccepted to false
[task 2024-10-27T00:55:11.506Z] 00:55:11     INFO - PID 3519 | 1729990487033	RemoteAgent	DEBUG	Setting recommended pref dom.screenorientation.allow-lock to true
[task 2024-10-27T00:55:11.507Z] 00:55:11     INFO - PID 3519 | 1729990487033	RemoteAgent	DEBUG	Setting recommended pref privacy.trackingprotection.enabled to false
[task 2024-10-27T00:55:11.507Z] 00:55:11     INFO - PID 3519 | 1729990487035	RemoteAgent	DEBUG	WebDriver BiDi enabled
[task 2024-10-27T00:55:11.508Z] 00:55:11     INFO - PID 3519 | 1729990487035	Marionette	INFO	Marionette enabled
[task 2024-10-27T00:55:11.508Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.509Z] 00:55:11     INFO - PID 3519 | 1729990487045	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.510Z] 00:55:11     INFO - PID 3519 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/gz/qm7lqzhd05x5j0ck0ghtdmrr000014/T/tmpdyfmsgmp.mozrunner/runtests_leaks_3144_socket_pid3563.log
[task 2024-10-27T00:55:11.510Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.511Z] 00:55:11     INFO - PID 3519 | 1729990487151	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.512Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.512Z] 00:55:11     INFO - PID 3519 | 1729990487388	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.513Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.514Z] 00:55:11     INFO - PID 3519 | 1729990487635	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.514Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.515Z] 00:55:11     INFO - PID 3519 | 1729990487856	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.515Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.516Z] 00:55:11     INFO - PID 3519 | 1729990488090	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.517Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.517Z] 00:55:11     INFO - PID 3519 | 1729990488283	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.518Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.518Z] 00:55:11     INFO - PID 3519 | 1729990488533	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.519Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.520Z] 00:55:11     INFO - PID 3519 | 1729990488780	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.520Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.521Z] 00:55:11     INFO - PID 3519 | 1729990488887	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.521Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.522Z] 00:55:11     INFO - PID 3519 | 1729990489126	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.522Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.523Z] 00:55:11     INFO - PID 3519 | 1729990489364	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.524Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.524Z] 00:55:11     INFO - PID 3519 | 1729990489600	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.525Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.525Z] 00:55:11     INFO - PID 3519 | 1729990489700	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.526Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.526Z] 00:55:11     INFO - PID 3519 | 1729990489924	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.527Z] 00:55:11     INFO - PID 3519 | Read port: 49919
[task 2024-10-27T00:55:11.528Z] 00:55:11     INFO - PID 3519 | 1729990490162	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2024-10-27T00:55:11.528Z] 00:55:11     INFO - PID 3519 | 1729990490252	Marionette	TRACE	Received observer notification final-ui-startup
[task 2024-10-27T00:55:11.529Z] 00:55:11     INFO - PID 3519 | 1729990490255	RemoteAgent	TRACE	Received observer notification final-ui-startup
[task 2024-10-27T00:55:11.530Z] 00:55:11     INFO - PID 3519 | console.error: "Warning: unrecognized command line flag" "-remote-allow-hosts"
[task 2024-10-27T00:55:11.530Z] 00:55:11     INFO - PID 3519 | 1729990490372	Marionette	INFO	Listening on port 49945
[task 2024-10-27T00:55:11.531Z] 00:55:11     INFO - PID 3519 | 1729990490372	Marionette	DEBUG	Marionette is listening
[task 2024-10-27T00:55:11.532Z] 00:55:11     INFO - PID 3519 | Read port: 49945
[task 2024-10-27T00:55:11.532Z] 00:55:11     INFO - PID 3519 | 1729990490497	RemoteAgent	TRACE	Available local IP addresses: 127.0.0.1, [::1]
[task 2024-10-27T00:55:11.533Z] 00:55:11     INFO - PID 3519 | WebDriver BiDi listening on ws://127.0.0.1:9222
[task 2024-10-27T00:55:11.533Z] 00:55:11     INFO - PID 3519 | 1729990490531	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:49946
[task 2024-10-27T00:55:11.534Z] 00:55:11     INFO - PID 3519 | 1729990490539	geckodriver::marionette	DEBUG	Connection to Marionette established on 127.0.0.1:49945.
[task 2024-10-27T00:55:11.535Z] 00:55:11     INFO - PID 3519 | 1729990490552	Marionette	DEBUG	0 -> [0,1,"WebDriver:NewSession",{"webSocketUrl":true}]
[task 2024-10-27T00:55:11.535Z] 00:55:11     INFO - PID 3519 | 1729990490559	WebDriver BiDi	DEBUG	Registered session handler: /session/24ede189-42bc-4160-8d94-419d67591e29
[task 2024-10-27T00:55:11.536Z] 00:55:11     INFO - PID 3519 | 1729990490559	WebDriver BiDi	DEBUG	Waiting for initial application window
[task 2024-10-27T00:55:11.537Z] 00:55:11     INFO - PID 3519 | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2024-10-27T00:55:11.537Z] 00:55:11     INFO - PID 3519 | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2024-10-27T00:55:11.538Z] 00:55:11     INFO - PID 3519 | [Parent 3561, Main Thread] WARNING: Please do not use mouseenter/leave events in chrome. They are slower than mouseover/out!: '!nsContentUtils::IsChromeDoc(window->GetExtantDoc())', file /builds/worker/checkouts/gecko/dom/events/EventListenerManager.cpp:462
[task 2024-10-27T00:55:11.539Z] 00:55:11     INFO - PID 3519 | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2024-10-27T00:55:11.539Z] 00:55:11     INFO - PID 3519 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/gz/qm7lqzhd05x5j0ck0ghtdmrr000014/T/tmpdyfmsgmp.mozrunner/runtests_leaks_3144_tab_pid3564.log
[task 2024-10-27T00:55:11.540Z] 00:55:11     INFO - PID 3519 | [WARN  webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2024-10-27T00:55:11.541Z] 00:55:11     INFO - PID 3519 | [Parent 3561, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1672
[task 2024-10-27T00:55:11.541Z] 00:55:11     INFO - PID 3519 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-10-27T00:55:11.542Z] 00:55:11     INFO - PID 3519 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-10-27T00:55:11.542Z] 00:55:11     INFO - PID 3519 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-10-27T00:55:11.543Z] 00:55:11     INFO - PID 3519 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/gz/qm7lqzhd05x5j0ck0ghtdmrr000014/T/tmpdyfmsgmp.mozrunner/runtests_leaks_3144_tab_pid3565.log
[task 2024-10-27T00:55:11.544Z] 00:55:11     INFO - PID 3519 | [Parent 3561, sqldb:places.sqlite #2] WARNING: Suboptimal indexes for the SQL statement 0x1b3c9cbc0 (http://mzl.la/1FuID0j).: file /builds/worker/checkouts/gecko/storage/mozStoragePrivateHelpers.cpp:113
[task 2024-10-27T00:55:11.545Z] 00:55:11     INFO - PID 3519 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/gz/qm7lqzhd05x5j0ck0ghtdmrr000014/T/tmpdyfmsgmp.mozrunner/runtests_leaks_3144_tab_pid3566.log
[task 2024-10-27T00:55:11.545Z] 00:55:11     INFO - PID 3519 | [Parent 3561, sqldb:places.sqlite #2] WARNING: Suboptimal indexes for the SQL statement 0x1b3c9cbc0 (http://mzl.la/1FuID0j).: file /builds/worker/checkouts/gecko/storage/mozStoragePrivateHelpers.cpp:113
[task 2024-10-27T00:55:11.546Z] 00:55:11     INFO - PID 3519 | [WARN  glean_core::error_recording] networking.nss_initialization: Timespan value already recorded. New value discarded.
[task 2024-10-27T00:55:11.547Z] 00:55:11     INFO - PID 3519 | [WARN  glean_core::error_recording] networking.loading_certs_task: Timespan value already recorded. New value discarded.
[task 2024-10-27T00:55:11.547Z] 00:55:11     INFO - PID 3519 | [WARN  glean_core::error_recording] fog.initialization: Timespan value already recorded. New value discarded.
[task 2024-10-27T00:55:11.548Z] 00:55:11     INFO - PID 3519 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/gz/qm7lqzhd05x5j0ck0ghtdmrr000014/T/tmpdyfmsgmp.mozrunner/runtests_leaks_3144_utility_pid3567.log
[task 2024-10-27T00:55:11.549Z] 00:55:11     INFO - PID 3519 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/gz/qm7lqzhd05x5j0ck0ghtdmrr000014/T/tmpdyfmsgmp.mozrunner/runtests_leaks_3144_tab_pid3568.log
[task 2024-10-27T00:55:11.549Z] 00:55:11     INFO - PID 3519 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/gz/qm7lqzhd05x5j0ck0ghtdmrr000014/T/tmpdyfmsgmp.mozrunner/runtests_leaks_3144_tab_pid3569.log
[task 2024-10-27T00:55:11.550Z] 00:55:11     INFO - PID 3519 | [ERROR error_support::handling] logins-unexpected: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-10-27T00:55:11.551Z] 00:55:11     INFO - PID 3519 | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Remote settings error: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-10-27T00:55:11.552Z] 00:55:11     INFO - PID 3519 | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error for Amo: Error from Remote Settings: Remote settings error: Error parsing URL: relative URL with a cannot-be-a-base base"
<...>
[task 2024-10-27T00:55:12.676Z] 00:55:12     INFO - TEST-START | /webdriver/tests/bidi/input/perform_actions/key_events.py

Not sure but maybe related to bug 1896279?

Crash details:
https://treeherder.mozilla.org/logviewer?job_id=479946539&repo=autoland&lineNumber=52349

[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO - PROCESS-CRASH | assertion `left == right` failed
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO -   left: 0
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO -  right: 1073741824 [@ std::sys::backtrace::__rust_end_short_backtrace] | /webdriver/tests/bidi/input/perform_actions/key.py 
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO - Process type: main
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO - Process pid: 3546
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO - Mozilla crash reason: assertion `left == right` failed
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO -   left: 0
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO -  right: 1073741824
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO - Crash dump filename: /var/folders/gz/qm7lqzhd05x5j0ck0ghtdmrr000014/T/tmpdyfmsgmp.mozrunner/minidumps/1BD24A9E-C07A-499D-B942-92204A50C43B.dmp
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO - Operating system: Mac OS X
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO -                   10.15.7 19H524
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO - CPU: amd64
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO -      family 6 model 158 stepping 10
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO -      12 CPUs
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO - 
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO - Crash reason:  EXC_BAD_ACCESS / KERN_INVALID_ADDRESS
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO - Crash address: 0x0000000000000000
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO - Crashing instruction: `mov dword [0x0], r14d`
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO - Memory accessed by instruction:
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO -   0. Address: 0x0000000000000000
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO -      Size: 4
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO - Mac Crash Info:
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO - 
[task 2024-10-27T00:55:07.501Z] 00:55:07     INFO - Mac Boot Args: chunklist-security-epoch=0 -chunklist-no-rev2-dev
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO - 
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO - Process uptime: 8 seconds
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO - 
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO - Thread 68 WRRenderBackend#1 (crashed)
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -  0  XUL!MOZ_Crash(char const*, int, char const*) [Assertions.h:10a1e7c8284954ea8f54004b09f2700710a59d18 : 317]
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -     Found by: inlining
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -  1  XUL!RustMozCrash [wrappers.cpp:10a1e7c8284954ea8f54004b09f2700710a59d18 : 18 + 0x15]
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -      rax = 0x0000000108db2b08    rdx = 0x0000000000000000
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -      rcx = 0x000070000de4f0ac    rbx = 0x000070000de45708
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -      rsi = 0x00000000000120a8    rdi = 0x00007fff9200cca8
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -      rbp = 0x000070000de454f0    rsp = 0x000070000de454e0
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -       r8 = 0x00000000000130a8     r9 = 0x0000000000000000
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -      r10 = 0x00007fff9200ccc8    r11 = 0x00007fff9200ccc0
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -      r12 = 0x0000000000000001    r13 = 0x000000000000009c
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -      r14 = 0x0000000000000461    r15 = 0x000070000de45708
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -      rip = 0x0000000120d9c225
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -     Found by: given as instruction pointer in context
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -  2  XUL!mozglue_static::panic_hook [lib.rs:10a1e7c8284954ea8f54004b09f2700710a59d18 : 102 + 0xc]
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -      rbx = 0x0000000000000461    rbp = 0x000070000de45920
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -      rsp = 0x000070000de45500    r12 = 0x0000000000000001
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -      r13 = 0x000000000000009c    r14 = 0x000070000de45504
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -      r15 = 0x000070000de45708    rip = 0x0000000120d9c00c
[task 2024-10-27T00:55:07.502Z] 00:55:07     INFO -     Found by: call frame info
[..]
[task 2024-10-27T00:55:07.505Z] 00:55:07     INFO - 11  XUL!core::panicking::assert_failed [panicking.rs:eeb90cda1969383f56a2637cbd3037bdf598841c : 365 + 0x9]
[task 2024-10-27T00:55:07.505Z] 00:55:07     INFO -      rbx = 0x00000001bbc10600    rbp = 0x000070000de45c50
[task 2024-10-27T00:55:07.505Z] 00:55:07     INFO -      rsp = 0x000070000de45c30    r12 = 0x0000000000000001
[task 2024-10-27T00:55:07.505Z] 00:55:07     INFO -      r13 = 0x000000000000009c    r14 = 0x0000000000000016
[task 2024-10-27T00:55:07.505Z] 00:55:07     INFO -      r15 = 0x00000001bb96945c    rip = 0x0000000120afb910
[task 2024-10-27T00:55:07.505Z] 00:55:07     INFO -     Found by: call frame info
[task 2024-10-27T00:55:07.505Z] 00:55:07     INFO - 12  XUL!wr_glyph_rasterizer::rasterizer::GlyphKey::new [rasterizer.rs:10a1e7c8284954ea8f54004b09f2700710a59d18 : 1121 + 0x4]
[task 2024-10-27T00:55:07.505Z] 00:55:07     INFO -      rbx = 0x00000001bbc10600    rbp = 0x000070000de45ca0
[task 2024-10-27T00:55:07.505Z] 00:55:07     INFO -      rsp = 0x000070000de45c60    r12 = 0x0000000000000001
[task 2024-10-27T00:55:07.505Z] 00:55:07     INFO -      r13 = 0x000000000000009c    r14 = 0x0000000000000016
[task 2024-10-27T00:55:07.505Z] 00:55:07     INFO -      r15 = 0x00000001bb96945c    rip = 0x0000000120b07f29
[task 2024-10-27T00:55:07.505Z] 00:55:07     INFO -     Found by: call frame info
[task 2024-10-27T00:55:07.506Z] 00:55:07     INFO - 13  XUL!webrender::prim_store::text_run::TextRunPrimitive::request_resources::{{closure}} [text_run.rs:10a1e7c8284954ea8f54004b09f2700710a59d18 : 476]
[task 2024-10-27T00:55:07.506Z] 00:55:07     INFO -     Found by: inlining
[task 2024-10-27T00:55:07.506Z] 00:55:07     INFO - 14  XUL!core::iter::adapters::map::map_fold::{{closure}} [map.rs:eeb90cda1969383f56a2637cbd3037bdf598841c : 89]
[task 2024-10-27T00:55:07.506Z] 00:55:07     INFO -     Found by: inlining
[task 2024-10-27T00:55:07.506Z] 00:55:07     INFO - 15  XUL!<core::slice::iter::Iter<T> as core::iter::traits::iterator::Iterator>::fold [macros.rs:eeb90cda1969383f56a2637cbd3037bdf598841c : 232]
[task 2024-10-27T00:55:07.506Z] 00:55:07     INFO -     Found by: inlining
[..]
Component: WebDriver BiDi → Graphics: WebRender
Product: Remote Protocol → Core
You need to log in before you can comment on or make changes to this bug.