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)
Core
Graphics: WebRender
Tracking
()
NEW
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
Comment hidden (Intermittent Failures Robot) |
Comment 2•14 days ago
|
||
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.
Description
•