Closed Bug 1851376 Opened 2 years ago Closed 2 years ago

Intermittent FATAL ERROR: Non-local network connections are disabled and a connection attempt to content-signature-2.cdn.mozilla.net (34.160.144.191) was made. | Hit MOZ_CRASH(Attempting to connect to non-local address! opener is [resource://services-sett

Categories

(Remote Protocol :: Agent, defect, P5)

defect
Points:
1

Tracking

(firefox-esr102 unaffected, firefox-esr115 fixed, firefox117 unaffected, firefox118 unaffected, firefox119 wontfix, firefox120 wontfix, firefox121 fixed)

RESOLVED FIXED
121 Branch
Tracking Status
firefox-esr102 --- unaffected
firefox-esr115 --- fixed
firefox117 --- unaffected
firefox118 --- unaffected
firefox119 --- wontfix
firefox120 --- wontfix
firefox121 --- fixed

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [webdriver:m9])

Attachments

(1 file)

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


[task 2023-09-03T12:14:31.037Z] 12:14:31     INFO - TEST-START | /_mozilla/webdriver/classic/new_session/create.py
[task 2023-09-03T12:14:31.131Z] 12:14:31     INFO - STDOUT: ============================= test session starts =============================
[task 2023-09-03T12:14:31.138Z] 12:14:31     INFO - STDOUT: platform win32 -- Python 3.9.10, pytest-7.0.1, pluggy-unknown -- Z:\task_169374020365428\build\venv\Scripts\python.exe
[task 2023-09-03T12:14:31.141Z] 12:14:31     INFO - STDOUT: rootdir: Z:\task_169374020365428\build
[task 2023-09-03T12:14:31.142Z] 12:14:31     INFO - STDOUT: plugins: asyncio-0.19.0
[task 2023-09-03T12:14:31.142Z] 12:14:31     INFO - STDOUT: asyncio: mode=strict
[task 2023-09-03T12:14:31.144Z] 12:14:31     INFO - STDOUT: collecting ... 
[task 2023-09-03T12:14:31.155Z] 12:14:31     INFO - STDOUT: collected 1 item
[task 2023-09-03T12:14:31.156Z] 12:14:31     INFO - STDOUT: tests/web-platform/mozilla/tests/webdriver/classic/new_session/create.py::test_valid_content_type 
[task 2023-09-03T12:14:31.159Z] 12:14:31     INFO - PID 7740 | 1693743271157	webdriver::server	DEBUG	-> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile", "C:\\Users\\task_169374020365428\\AppData\\Local\\Temp\\tmp8wryke0o.mozrunner"]}}}}
[task 2023-09-03T12:14:31.159Z] 12:14:31     INFO - PID 7740 | 1693743271157	geckodriver::capabilities	DEBUG	Trying to read firefox version from ini files
[task 2023-09-03T12:14:31.161Z] 12:14:31     INFO - PID 7740 | 1693743271158	geckodriver::capabilities	DEBUG	Found version 119.0
[task 2023-09-03T12:14:31.161Z] 12:14:31     INFO - PID 7740 | 1693743271160	geckodriver::browser	DEBUG	Backing up prefs to "C:\\Users\\task_169374020365428\\AppData\\Local\\Temp\\tmp8wryke0o.mozrunner\\user.geckodriver_backup"
[task 2023-09-03T12:14:31.173Z] 12:14:31     INFO - PID 7740 | 1693743271175	mozrunner::runner	INFO	Running command: "Z:\\task_169374020365428\\build\\application\\firefox\\firefox.exe" "--marionette" "--profile" "C:\\Users\\task_169374020365428\\AppData\\Local\\Temp\\tmp8wryke0o.mozrunner" "-no-remote"
[task 2023-09-03T12:14:31.180Z] 12:14:31     INFO - PID 7740 | 1693743271178	geckodriver::marionette	DEBUG	Waiting 60s to connect to browser on 127.0.0.1
[task 2023-09-03T12:14:31.181Z] 12:14:31     INFO - PID 7740 | 1693743271178	geckodriver::browser	TRACE	Failed to open C:\Users\task_169374020365428\AppData\Local\Temp\tmp8wryke0o.mozrunner\MarionetteActivePort
[task 2023-09-03T12:14:31.181Z] 12:14:31     INFO - PID 7740 | 1693743271178	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-03T12:14:31.286Z] 12:14:31     INFO - PID 7740 | 1693743271286	geckodriver::browser	TRACE	Failed to open C:\Users\task_169374020365428\AppData\Local\Temp\tmp8wryke0o.mozrunner\MarionetteActivePort
[task 2023-09-03T12:14:31.289Z] 12:14:31     INFO - PID 7740 | 1693743271287	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-03T12:14:31.318Z] 12:14:31     INFO - PID 7740 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_169374020365428\AppData\Local\Temp\tmp8wryke0o.mozrunner\runtests_leaks_3644.log
[task 2023-09-03T12:14:31.396Z] 12:14:31     INFO - PID 7740 | 1693743271397	geckodriver::browser	TRACE	Failed to open C:\Users\task_169374020365428\AppData\Local\Temp\tmp8wryke0o.mozrunner\MarionetteActivePort
[task 2023-09-03T12:14:31.399Z] 12:14:31     INFO - PID 7740 | 1693743271397	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-03T12:14:31.459Z] 12:14:31     INFO - PID 7740 | [Parent 7420, Main Thread] WARNING: NS_ENSURE_TRUE(mRequest) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsBaseChannel.cpp:901
[task 2023-09-03T12:14:31.474Z] 12:14:31     INFO - PID 7740 | [Parent 7420, Main Thread] WARNING: NS_ENSURE_TRUE(mRequest) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsBaseChannel.cpp:901
[task 2023-09-03T12:14:31.476Z] 12:14:31     INFO - PID 7740 | [Parent 7420, Main Thread] WARNING: NS_ENSURE_TRUE(mRequest) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsBaseChannel.cpp:901
[task 2023-09-03T12:14:31.480Z] 12:14:31     INFO - PID 7740 | [Parent 7420, Main Thread] WARNING: NS_ENSURE_TRUE(mRequest) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsBaseChannel.cpp:901
[task 2023-09-03T12:14:31.482Z] 12:14:31     INFO - PID 7740 | [Parent 7420, Main Thread] WARNING: NS_ENSURE_TRUE(mRequest) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsBaseChannel.cpp:901
[task 2023-09-03T12:14:31.483Z] 12:14:31     INFO - PID 7740 | [Parent 7420, Main Thread] WARNING: NS_ENSURE_TRUE(mRequest) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsBaseChannel.cpp:901
[task 2023-09-03T12:14:31.485Z] 12:14:31     INFO - PID 7740 | [Parent 7420, Main Thread] WARNING: NS_ENSURE_TRUE(mRequest) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsBaseChannel.cpp:901
[task 2023-09-03T12:14:31.486Z] 12:14:31     INFO - PID 7740 | [Parent 7420, Main Thread] WARNING: NS_ENSURE_TRUE(mRequest) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsBaseChannel.cpp:901
[task 2023-09-03T12:14:31.487Z] 12:14:31     INFO - PID 7740 | [Parent 7420, Main Thread] WARNING: NS_ENSURE_TRUE(mRequest) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsBaseChannel.cpp:901
[task 2023-09-03T12:14:31.487Z] 12:14:31     INFO - PID 7740 | [Parent 7420, Main Thread] WARNING: NS_ENSURE_TRUE(mRequest) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsBaseChannel.cpp:901
[task 2023-09-03T12:14:31.488Z] 12:14:31     INFO - PID 7740 | [Parent 7420, Main Thread] WARNING: NS_ENSURE_TRUE(mRequest) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsBaseChannel.cpp:901
[task 2023-09-03T12:14:31.488Z] 12:14:31     INFO - PID 7740 | [Parent 7420, Main Thread] WARNING: NS_ENSURE_TRUE(mRequest) failed: file /builds/worker/checkouts/gecko/netwerk/base/nsBaseChannel.cpp:901
[task 2023-09-03T12:14:31.508Z] 12:14:31     INFO - PID 7740 | 1693743271508	geckodriver::browser	TRACE	Failed to open C:\Users\task_169374020365428\AppData\Local\Temp\tmp8wryke0o.mozrunner\MarionetteActivePort
[task 2023-09-03T12:14:31.511Z] 12:14:31     INFO - PID 7740 | 1693743271508	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-03T12:14:31.570Z] 12:14:31     INFO - PID 7740 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_169374020365428\AppData\Local\Temp\tmp8wryke0o.mozrunner\runtests_leaks_3644_gpu_pid2036.log
[task 2023-09-03T12:14:31.617Z] 12:14:31     INFO - PID 7740 | 1693743271617	geckodriver::browser	TRACE	Failed to open C:\Users\task_169374020365428\AppData\Local\Temp\tmp8wryke0o.mozrunner\MarionetteActivePort
[task 2023-09-03T12:14:31.619Z] 12:14:31     INFO - PID 7740 | 1693743271617	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-03T12:14:31.726Z] 12:14:31     INFO - PID 7740 | 1693743271727	geckodriver::browser	TRACE	Failed to open C:\Users\task_169374020365428\AppData\Local\Temp\tmp8wryke0o.mozrunner\MarionetteActivePort
[task 2023-09-03T12:14:31.730Z] 12:14:31     INFO - PID 7740 | 1693743271727	geckodriver::marionette	TRACE	Retrying in 100ms
[task 2023-09-03T12:14:31.731Z] 12:14:31     INFO - PID 7740 | 1693743271728	Marionette	INFO	Marionette enabled
[task 2023-09-03T12:14:31.732Z] 12:14:31     INFO - PID 7740 | 1693743271731	Marionette	TRACE	Received observer notification final-ui-startup
[task 2023-09-03T12:14:31.789Z] 12:14:31     INFO - PID 7740 | 1693743271802	Marionette	INFO	Listening on port 53618
[task 2023-09-03T12:14:31.804Z] 12:14:31     INFO - PID 7740 | 1693743271804	Marionette	DEBUG	Marionette is listening
[task 2023-09-03T12:14:31.836Z] 12:14:31     INFO - PID 7740 | Read port: 53618
[task 2023-09-03T12:14:31.852Z] 12:14:31     INFO - PID 7740 | Initializing context 0000020F9D6BC000 surface 0000020F9D641CA0 on display 0000020F9D676100
[task 2023-09-03T12:14:31.864Z] 12:14:31     INFO - PID 7740 | [GPU 2036, Renderer] WARNING: Failed to make GL context current!: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:473
[task 2023-09-03T12:14:31.865Z] 12:14:31     INFO - PID 7740 | EGL Error: 0x3009
[task 2023-09-03T12:14:31.866Z] 12:14:31     INFO - PID 7740 | [GPU 2036, Renderer] WARNING: GLContext::InitWithPrefix failed!: file /builds/worker/checkouts/gecko/gfx/gl/GLContext.cpp:327
[task 2023-09-03T12:14:31.867Z] 12:14:31     INFO - PID 7740 | Destroying context 0000020F9D6BC000 surface 0000020F9D641CA0 on display 0000020F9D676100
[task 2023-09-03T12:14:31.867Z] 12:14:31     INFO - PID 7740 | [GPU 2036, Renderer] WARNING: Failed to create GLContext from PBuffer: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:1181
[task 2023-09-03T12:14:31.868Z] 12:14:31     INFO - PID 7740 | Initializing context 0000020F9D6FA000 surface 0000020F9D63F0C0 on display 0000020F9D676100
[task 2023-09-03T12:14:31.869Z] 12:14:31     INFO - PID 7740 | GL_VENDOR: Google Inc. (Microsoft)
[task 2023-09-03T12:14:31.870Z] 12:14:31     INFO - PID 7740 | mVendor: Unknown
[task 2023-09-03T12:14:31.870Z] 12:14:31     INFO - PID 7740 | GL_RENDERER: ANGLE (Microsoft, Microsoft Basic Render Driver Direct3D11 vs_5_0 ps_5_0, D3D11-10.0.22621.1194)
[task 2023-09-03T12:14:31.871Z] 12:14:31     INFO - PID 7740 | mRenderer: Microsoft Basic Render Driver
[task 2023-09-03T12:14:31.872Z] 12:14:31     INFO - PID 7740 | mIsMesa: 0
[task 2023-09-03T12:14:31.872Z] 12:14:31     INFO - PID 7740 | [GPU 2036, Renderer] WARNING: framebuffer_multisample marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp:638
[task 2023-09-03T12:14:31.945Z] 12:14:31     INFO - PID 7740 | 1693743271948	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:53619
[task 2023-09-03T12:14:32.086Z] 12:14:32     INFO - PID 7740 | 1693743272090	geckodriver::marionette	DEBUG	Connection to Marionette established on 127.0.0.1:53618.
[task 2023-09-03T12:14:32.179Z] 12:14:32     INFO - PID 7740 | 1693743272192	Marionette	DEBUG	0 -> [0,1,"WebDriver:NewSession",{}]
[task 2023-09-03T12:14:32.199Z] 12:14:32     INFO - PID 7740 | 1693743272198	Marionette	DEBUG	Waiting for initial application window
[task 2023-09-03T12:14:32.445Z] 12:14:32     INFO - PID 7740 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_169374020365428\AppData\Local\Temp\tmp8wryke0o.mozrunner\runtests_leaks_3644_tab_pid5344.log
[task 2023-09-03T12:14:32.680Z] 12:14:32     INFO - PID 7740 | [Parent 7420, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1711
[task 2023-09-03T12:14:33.450Z] 12:14:33     INFO - PID 7740 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_169374020365428\AppData\Local\Temp\tmp8wryke0o.mozrunner\runtests_leaks_3644_tab_pid6168.log
[task 2023-09-03T12:14:33.466Z] 12:14:33     INFO - PID 7740 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_169374020365428\AppData\Local\Temp\tmp8wryke0o.mozrunner\runtests_leaks_3644_tab_pid3132.log
[task 2023-09-03T12:14:33.482Z] 12:14:33     INFO - PID 7740 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_169374020365428\AppData\Local\Temp\tmp8wryke0o.mozrunner\runtests_leaks_3644_tab_pid6180.log
[task 2023-09-03T12:14:33.781Z] 12:14:33     INFO - PID 7740 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2023-09-03T12:14:33.781Z] 12:14:33     INFO - PID 7740 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2023-09-03T12:14:33.781Z] 12:14:33     INFO - PID 7740 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2023-09-03T12:14:34.106Z] 12:14:34     INFO - PID 7740 | [Parent 7420, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1235
[task 2023-09-03T12:14:34.326Z] 12:14:34     INFO - PID 7740 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_169374020365428\AppData\Local\Temp\tmp8wryke0o.mozrunner\runtests_leaks_3644_tab_pid1092.log
[task 2023-09-03T12:14:34.347Z] 12:14:34     INFO - PID 7740 | FATAL ERROR: Non-local network connections are disabled and a connection attempt to content-signature-2.cdn.mozilla.net (34.160.144.191) was made.
[task 2023-09-03T12:14:34.355Z] 12:14:34     INFO - PID 7740 | You should only access hostnames available via the test networking proxy (if running mochitests) or from a test-specific httpd.js server (if running xpcshell tests). Browser services should be disabled or redirected to a local server.
[task 2023-09-03T12:14:34.364Z] 12:14:34     INFO - PID 7740 | Hit MOZ_CRASH(Attempting to connect to non-local address! opener is [resource://services-settings/Utils.sys.mjs:269:14], uri is [https://content-signature-2.cdn.mozilla.net/chains/remote-settings.content-signature.mozilla.org-2023-10-09-11-30-14.chain]) at /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpChannel.cpp:7046
[task 2023-09-03T12:14:34.435Z] 12:14:34     INFO - PID 7740 | [Child 1092, Main Thread] WARNING: Fallback to FallbackRenderer: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2649
[task 2023-09-03T12:14:34.457Z] 12:14:34     INFO - PID 7740 | [Child 1092, Main Thread] WARNING: Fallback to FallbackRenderer: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2649
[task 2023-09-03T12:14:34.464Z] 12:14:34     INFO - PID 7740 | [Child 1092, Main Thread] WARNING: Fallback to FallbackRenderer: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2649
[task 2023-09-03T12:14:34.471Z] 12:14:34     INFO - PID 7740 | [Child 1092, Main Thread] WARNING: Fallback to FallbackRenderer: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2649
[task 2023-09-03T12:14:34.478Z] 12:14:34     INFO - PID 7740 | [Child 1092, Main Thread] WARNING: Fallback to FallbackRenderer: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2649
[task 2023-09-03T12:14:34.718Z] 12:14:34     INFO - STDOUT: Initializing stack-fixing for the first stack frame, this may take a while...
[task 2023-09-03T12:14:35.078Z] 12:14:35     INFO - STDOUT: FAILED
[task 2023-09-03T12:14:35.083Z] 12:14:35     INFO - STDOUT: ================================== FAILURES ===================================
[task 2023-09-03T12:14:35.084Z] 12:14:35     INFO - STDOUT: ___________________________ test_valid_content_type ___________________________
[task 2023-09-03T12:14:35.085Z] 12:14:35     INFO - STDOUT: new_session = <function fixture_new_session.<locals>.new_session at 0x000001B6F126D1F0>
[task 2023-09-03T12:14:35.086Z] 12:14:35     INFO - STDOUT: configuration = {'browser': {'args': ['--profile', 'C:\\Users\\task_169374020365428\\AppData\\Local\\Temp\\tmp8wryke0o.mozrunner'], 'b...\Users\\task_169374020365428\\AppData\\Local\\Temp\\tmp8wryke0o.mozrunner']}}, 'host': '127.0.0.1', 'port': 58784, ...}
[task 2023-09-03T12:14:35.087Z] 12:14:35     INFO - STDOUT:     def test_valid_content_type(new_session, configuration):
[task 2023-09-03T12:14:35.087Z] 12:14:35     INFO - STDOUT:         headers = {"content-type": "application/json"}
[task 2023-09-03T12:14:35.089Z] 12:14:35     INFO - STDOUT:         response, _ = new_session(
[task 2023-09-03T12:14:35.089Z] 12:14:35     INFO - STDOUT:             {"capabilities": {"alwaysMatch": dict(configuration["capabilities"])}},
[task 2023-09-03T12:14:35.090Z] 12:14:35     INFO - STDOUT:             headers=headers,
[task 2023-09-03T12:14:35.090Z] 12:14:35     INFO - STDOUT:         )
[task 2023-09-03T12:14:35.091Z] 12:14:35     INFO - STDOUT: >       assert_success(response)
[task 2023-09-03T12:14:35.091Z] 12:14:35     INFO - STDOUT: _          = None
[task 2023-09-03T12:14:35.093Z] 12:14:35     INFO - STDOUT: configuration = {'browser': {'args': ['--profile',
[task 2023-09-03T12:14:35.093Z] 12:14:35     INFO -                       'C:\\Users\\task_169374020365428\\AppData\\Local\\Temp\\tmp8wryke0o.mozrunner'],
[task 2023-09-03T12:14:35.093Z] 12:14:35     INFO -              'binary': 'Z:\\task_169374020365428\\build\\application\\firefox\\firefox.exe'},
[task 2023-09-03T12:14:35.093Z] 12:14:35     INFO -  'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-09-03T12:14:35.093Z] 12:14:35     INFO -                                                   'C:\\Users\\task_169374020365428\\AppData\\Local\\Temp\\tmp8wryke0o.mozrunner']}},
[task 2023-09-03T12:14:35.093Z] 12:14:35     INFO -  'host': '127.0.0.1',
[task 2023-09-03T12:14:35.093Z] 12:14:35     INFO -  'port': 58784,
[task 2023-09-03T12:14:35.093Z] 12:14:35     INFO -  'timeout_multiplier': 4,
[task 2023-09-03T12:14:35.093Z] 12:14:35     INFO -  'webdriver': {'args': ['-vv',
[task 2023-09-03T12:14:35.093Z] 12:14:35     INFO -                         '--binary',
[task 2023-09-03T12:14:35.093Z] 12:14:35     INFO -                         'Z:\\task_169374020365428\\build\\application\\firefox\\firefox.exe'],
[task 2023-09-03T12:14:35.093Z] 12:14:35     INFO -                'binary': 'Z:\\task_169374020365428\\fetches\\geckodriver.exe'}}
[task 2023-09-03T12:14:35.097Z] 12:14:35     INFO - 
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO - TEST-UNEXPECTED-FAIL | /_mozilla/webdriver/classic/new_session/create.py | test_valid_content_type - AssertionError: unknown error (500): Failed to decode response from marionette
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO - new_session = <function fixture_new_session.<locals>.new_session at 0x000001B6F126D1F0>
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO - configuration = {'browser': {'args': ['--profile', 'C:\\Users\\task_169374020365428\\AppData\\Local\\Temp\\tmp8wryke0o.mozrunner'], 'b...\Users\\task_169374020365428\\AppData\\Local\\Temp\\tmp8wryke0o.mozrunner']}}, 'host': '127.0.0.1', 'port': 58784, ...}
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO - 
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -     def test_valid_content_type(new_session, configuration):
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -         headers = {"content-type": "application/json"}
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -         response, _ = new_session(
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -             {"capabilities": {"alwaysMatch": dict(configuration["capabilities"])}},
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -             headers=headers,
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -         )
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO - >       assert_success(response)
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO - 
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO - _          = None
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO - configuration = {'browser': {'args': ['--profile',
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -                       'C:\\Users\\task_169374020365428\\AppData\\Local\\Temp\\tmp8wryke0o.mozrunner'],
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -              'binary': 'Z:\\task_169374020365428\\build\\application\\firefox\\firefox.exe'},
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -  'capabilities': {'moz:firefoxOptions': {'args': ['--profile',
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -                                                   'C:\\Users\\task_169374020365428\\AppData\\Local\\Temp\\tmp8wryke0o.mozrunner']}},
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -  'host': '127.0.0.1',
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -  'port': 58784,
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -  'timeout_multiplier': 4,
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -  'webdriver': {'args': ['-vv',
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -                         '--binary',
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -                         'Z:\\task_169374020365428\\build\\application\\firefox\\firefox.exe'],
[task 2023-09-03T12:14:35.098Z] 12:14:35     INFO -                'binary': 'Z:\\task_169374020365428\\fetches\\geckodriver.exe'}}
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO - headers    = {'Connection': 'keep-alive', 'content-type': 'application/json'}
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO - new_session = <function fixture_new_session.<locals>.new_session at 0x000001B6F126D1F0>
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO - response   = <Response status=500 error=<UnknownErrorException http_status=500>>
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO - 
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO - tests\web-platform\mozilla\tests\webdriver\classic\new_session\create.py:11: 
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO - _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO - 
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO - response = <Response status=500 error=<UnknownErrorException http_status=500>>
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO - value = None
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO - 
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO -     def assert_success(response, value=None):
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO -         """
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO -         Verify that the provided webdriver.Response instance described
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO -         a valid success response as defined by `dfn-send-a-response` and
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO -         the provided response value.
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO -     
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO -         :param response: ``webdriver.Response`` instance.
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO -         :param value: Expected value of the response body, if any.
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO -         """
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO - >       assert response.status == 200, str(response.error)
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO - E       AssertionError: unknown error (500): Failed to decode response from marionette
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO - E         
[task 2023-09-03T12:14:35.099Z] 12:14:35     INFO - E       assert 500 == 200
[task 2023-09-03T12:14:35.100Z] 12:14:35     INFO - E         +500
[task 2023-09-03T12:14:35.100Z] 12:14:35     INFO - E         -200
[task 2023-09-03T12:14:35.100Z] 12:14:35     INFO - 
[task 2023-09-03T12:14:35.100Z] 12:14:35     INFO - response   = <Response status=500 error=<UnknownErrorException http_status=500>>
[task 2023-09-03T12:14:35.100Z] 12:14:35     INFO - value      = None
[task 2023-09-03T12:14:35.100Z] 12:14:35     INFO - 
[task 2023-09-03T12:14:35.100Z] 12:14:35     INFO - tests\web-platform\tests\webdriver\tests\support\asserts.py:67: AssertionError
[task 2023-09-03T12:14:35.101Z] 12:14:35     INFO - mozcrash Copy/paste: Z:\task_169374020365428\fetches\minidump-stackwalk\minidump-stackwalk.exe --symbols-url=https://symbols.mozilla.org/ --cyborg=C:\Users\task_169374020365428\AppData\Local\Temp\tmpw1nrve66\5a70c751-d6d3-477e-9e1a-029ba9674f19.trace C:\Users\task_169374020365428\AppData\Local\Temp\tmp8wryke0o.mozrunner\minidumps\5a70c751-d6d3-477e-9e1a-029ba9674f19.dmp Z:\task_169374020365428\build\symbols
[task 2023-09-03T12:14:47.029Z] 12:14:47     INFO - mozcrash Saved minidump as Z:\task_169374020365428\build\blobber_upload_dir\5a70c751-d6d3-477e-9e1a-029ba9674f19.dmp
[task 2023-09-03T12:14:47.037Z] 12:14:47     INFO - mozcrash Saved app info as Z:\task_169374020365428\build\blobber_upload_dir\5a70c751-d6d3-477e-9e1a-029ba9674f19.extra
[task 2023-09-03T12:14:47.671Z] 12:14:47     INFO - PROCESS-CRASH | Attempting to connect to non-local address! opener is [resource://services-settings/Utils.sys.mjs:269:14], uri is [https://content-signature-2.cdn.mozilla.net/chains/remote-settings.content-signature.mozilla.org-2023-10-09-11-30-14.chain] [@ mozilla::net::nsHttpChannel::OnStartRequest] | /_mozilla/webdriver/classic/new_session/create.py 
[task 2023-09-03T12:14:47.671Z] 12:14:47     INFO - Process type: main
[task 2023-09-03T12:14:47.671Z] 12:14:47     INFO - Process pid: 7420
[task 2023-09-03T12:14:47.671Z] 12:14:47     INFO - Mozilla crash reason: Attempting to connect to non-local address! opener is [resource://services-settings/Utils.sys.mjs:269:14], uri is [https://content-signature-2.cdn.mozilla.net/chains/remote-settings.content-signature.mozilla.org-2023-10-09-11-30-14.chain]
[task 2023-09-03T12:14:47.682Z] 12:14:47     INFO - Crash dump filename: C:\Users\task_169374020365428\AppData\Local\Temp\tmp8wryke0o.mozrunner\minidumps\5a70c751-d6d3-477e-9e1a-029ba9674f19.dmp
[task 2023-09-03T12:14:47.682Z] 12:14:47     INFO - Operating system: Windows NT
[task 2023-09-03T12:14:47.682Z] 12:14:47     INFO -                   10.0.22621
[task 2023-09-03T12:14:47.682Z] 12:14:47     INFO - CPU: amd64
[task 2023-09-03T12:14:47.682Z] 12:14:47     INFO -      family 6 model 85 stepping 7
[task 2023-09-03T12:14:47.682Z] 12:14:47     INFO -      8 CPUs
[task 2023-09-03T12:14:47.682Z] 12:14:47     INFO - 
[task 2023-09-03T12:14:47.682Z] 12:14:47     INFO - Crash reason:  EXCEPTION_BREAKPOINT
[task 2023-09-03T12:14:47.682Z] 12:14:47     INFO - Crash address: 0x7ff88d6e0940
[task 2023-09-03T12:14:47.682Z] 12:14:47     INFO - Crashing instruction: `int 0x3`
[task 2023-09-03T12:14:47.683Z] 12:14:47     INFO - No memory accessed by instruction
[task 2023-09-03T12:14:47.683Z] 12:14:47     INFO - Process uptime: 3 seconds
[task 2023-09-03T12:14:47.683Z] 12:14:47     INFO - 
[task 2023-09-03T12:14:47.683Z] 12:14:47     INFO - Thread 0 MainThread (crashed)
[task 2023-09-03T12:14:47.683Z] 12:14:47     INFO -  0  xul.dll!MOZ_Crash(char const*, int, char const*) [Assertions.h:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 281]
[task 2023-09-03T12:14:47.683Z] 12:14:47     INFO -     Found by: inlining
[task 2023-09-03T12:14:47.683Z] 12:14:47     INFO -  1  xul.dll!mozilla::net::nsHttpChannel::OnStartRequest(nsIRequest*) [nsHttpChannel.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 7039 + 0x1f]
[task 2023-09-03T12:14:47.683Z] 12:14:47     INFO -      rax = 0x00007ff8c0976d88    rdx = 0x0000000000000000
[task 2023-09-03T12:14:47.683Z] 12:14:47     INFO -      rcx = 0x0fe1e525c17b0000    rbx = 0x000001ddadb76600
[task 2023-09-03T12:14:47.683Z] 12:14:47     INFO -      rsi = 0x000001ddadb75808    rdi = 0x000001ddadb74108
[task 2023-09-03T12:14:47.683Z] 12:14:47     INFO -      rbp = 0x00007ff88d140050    rsp = 0x00000030815fed00
[task 2023-09-03T12:14:47.683Z] 12:14:47     INFO -       r8 = 0x000000000000000e     r9 = 0x00007ff8d39fcbf8
[task 2023-09-03T12:14:47.683Z] 12:14:47     INFO -      r10 = 0x00007ff8d3870000    r11 = 0x00000030815fe2b0
[task 2023-09-03T12:14:47.683Z] 12:14:47     INFO -      r12 = 0x00007ff896b956aa    r13 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-03T12:14:47.684Z] 12:14:47     INFO -      r14 = 0x0000000000000003    r15 = 0x000001ddb088f5f0
[task 2023-09-03T12:14:47.684Z] 12:14:47     INFO -      rip = 0x00007ff88d6e0940
[task 2023-09-03T12:14:47.684Z] 12:14:47     INFO -     Found by: given as instruction pointer in context
[task 2023-09-03T12:14:47.684Z] 12:14:47     INFO -  2  xul.dll!nsInputStreamPump::OnStateStart() [nsInputStreamPump.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 503 + 0xf]
[task 2023-09-03T12:14:47.684Z] 12:14:47     INFO -      rbx = 0x000001dd9bb4b9b0    rsi = 0x000001ddadb76600
[task 2023-09-03T12:14:47.684Z] 12:14:47     INFO -      rdi = 0x000001ddadb766a0    rbp = 0x00007ff88d140050
[task 2023-09-03T12:14:47.684Z] 12:14:47     INFO -      rsp = 0x00000030815fedc0    r12 = 0x00007ff896b956aa
[task 2023-09-03T12:14:47.684Z] 12:14:47     INFO -      r13 = 0xaaaaaaaaaaaaaaaa    r14 = 0x0000000000000002
[task 2023-09-03T12:14:47.684Z] 12:14:47     INFO -      r15 = 0x000001ddb088f5f0    rip = 0x00007ff88d14020f
[task 2023-09-03T12:14:47.684Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.684Z] 12:14:47     INFO -  3  xul.dll!nsInputStreamPump::OnInputStreamReady(nsIAsyncInputStream*) [nsInputStreamPump.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 408 + 0x7]
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -      rbx = 0x000001ddadb766a0    rsi = 0x000001ddadb76618
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -      rdi = 0x000001ddadb76600    rbp = 0x00007ff88d140050
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -      rsp = 0x00000030815fee20    r12 = 0x00007ff896b956aa
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -      r13 = 0xaaaaaaaaaaaaaaaa    r14 = 0x0000000000000001
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -      r15 = 0x000001ddb088f5f0    rip = 0x00007ff88d13fd74
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -  4  xul.dll!CallbackHolder::CallbackHolder::<lambda_1>::operator()() const [nsPipe3.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 73]
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -     Found by: inlining
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -  5  xul.dll!NS_NewCancelableRunnableFunction<`lambda at /builds/worker/checkouts/gecko/xpcom/io/nsPipe3.cpp:71:35'>::FuncCancelableRunnable::Run() [nsThreadUtils.h:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 667 + 0x1c]
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -      rbx = 0x000001dd9bb71180    rsi = 0x000001ddb088f5f0
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -      rdi = 0x000001dd9bb6c250    rbp = 0x00000030815fef38
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -      rsp = 0x00000030815feea0    r12 = 0x0000000000000000
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -      r13 = 0xaaaaaaaaaaaaaaaa    r14 = 0x000001dd9bb6c260
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -      r15 = 0x000001ddb088f5f0    rip = 0x00007ff88ceb9057
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -  6  xul.dll!mozilla::RunnableTask::Run() [TaskController.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 559 + 0xc]
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -      rbx = 0x000001dd9bb71180    rbp = 0x00000030815fef38
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -      rsp = 0x00000030815feed0    r12 = 0x0000000000000000
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -      r13 = 0xaaaaaaaaaaaaaaaa    r14 = 0x000001dd9bb6c260
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -      r15 = 0x000001ddb088f5f0    rip = 0x00007ff88cf0780e
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -  7  xul.dll!mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex &> const&) [TaskController.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 886 + 0xf]
[task 2023-09-03T12:14:47.685Z] 12:14:47     INFO -      rbx = 0x000001dd9bb71180    rsi = 0x000001dd9bb6c0e0
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -      rbp = 0x00000030815fef38    rsp = 0x00000030815fef00
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -      r12 = 0x0000000000000000    r13 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -      r14 = 0x000001dd9bb6c260    r15 = 0x000001ddb088f5f0
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -      rip = 0x00007ff88ceff1f2
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -  8  xul.dll!mozilla::TaskController::ExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex &> const&) [TaskController.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 709 + 0xa]
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -      rbx = 0x000001dd9bb41600    rsi = 0x000001dd9bb6c0e0
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -      rdi = 0x00000030815ff188    rbp = 0x0000000000000001
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -      rsp = 0x00000030815ff0d0    r12 = 0x000001dd9bb71198
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -      r13 = 0xaaaaaaaaaaaaaaaa    r14 = 0x00000030815ff2b0
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -      r15 = 0x0000000000000000    rip = 0x00007ff88cefd748
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -  9  xul.dll!mozilla::TaskController::ProcessPendingMTTask(bool) [TaskController.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 495 + 0x7]
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -      rbx = 0x0000000000000000    rsi = 0x000001dd9bb6c0e0
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -      rdi = 0x000001dd9bb6c110    rbp = 0x0000000000000001
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -      rsp = 0x00000030815ff160    r12 = 0x000001dd9bb71198
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -      r13 = 0xaaaaaaaaaaaaaaaa    r14 = 0x00000030815ff2b0
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -      r15 = 0x0000000000000000    rip = 0x00007ff88cefdbba
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO - 10  xul.dll!mozilla::TaskController::TaskController::<lambda_4>::operator()() const [TaskController.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 218]
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -     Found by: inlining
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO - 11  xul.dll!mozilla::detail::RunnableFunction<`lambda at /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:218:7'>::Run() [nsThreadUtils.h:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 548 + 0x12]
[task 2023-09-03T12:14:47.686Z] 12:14:47     INFO -      rbx = 0x000001dda95f0000    rsi = 0x000001dd9bb71180
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      rdi = 0x00000030815ff3df    rbp = 0x0000000000000001
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      rsp = 0x00000030815ff1e0    r12 = 0x000001dd9bb71198
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      r13 = 0xaaaaaaaaaaaaaaaa    r14 = 0x00000030815ff2b0
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      r15 = 0x0000000000000000    rip = 0x00007ff88cf0a177
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO - 12  xul.dll!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 1199 + 0xc]
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      rbx = 0x000001dda95f0000    rbp = 0x0000000000000001
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      rsp = 0x00000030815ff210    r12 = 0x000001dd9bb71198
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      r13 = 0xaaaaaaaaaaaaaaaa    r14 = 0x00000030815ff2b0
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      r15 = 0x0000000000000000    rip = 0x00007ff88cf22587
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO - 13  xul.dll!NS_ProcessNextEvent(nsIThread*, bool) [nsThreadUtils.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 480 + 0x16]
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      rbx = 0x0000000000000000    rsi = 0x000001dd9bb0bbc0
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      rdi = 0x000001dd9bb761a0    rbp = 0x0000000000000001
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      rsp = 0x00000030815ff3b0    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      r13 = 0x00007ff89697f6da    r14 = 0x000001dd9bb0bbf0
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      r15 = 0x000001dd9bb0bbe0    rip = 0x00007ff88cf29875
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO - 14  xul.dll!mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [MessagePump.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 85 + 0x9]
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      rbx = 0x000001dd9bb71180    rsi = 0x000001dd9bb0bbc0
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      rbp = 0x0000000000000001    rsp = 0x00000030815ff400
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      r12 = 0xaaaaaaaaaaaaaaaa    r13 = 0x00007ff89697f6da
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      r14 = 0x000001dd9bb0bbf0    r15 = 0x000001dd9bb0bbe0
[task 2023-09-03T12:14:47.687Z] 12:14:47     INFO -      rip = 0x00007ff88da103da
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO - 15  xul.dll!MessageLoop::RunHandler() [message_loop.cc:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 363 + 0x7]
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -      rbx = 0x000000009bb71f01    rsi = 0x000001dd9bb761a0
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -      rdi = 0x000001dd9bb71180    rbp = 0x00000030815ff490
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -      rsp = 0x00000030815ff470    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -      r13 = 0x00007ff89697f6da    r14 = 0x00000030815ff890
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -      r15 = 0x0002002100000018    rip = 0x00007ff88d993340
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO - 16  xul.dll!MessageLoop::Run() [message_loop.cc:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 345 + 0x4]
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -      rbx = 0x000000009bb71f01    rsi = 0x000001dd9bb71f10
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -      rdi = 0x000001dd9bb71180    rbp = 0x0002000100000000
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -      rsp = 0x00000030815ff4c0    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -      r13 = 0x00007ff89697f6da    r14 = 0x00000030815ff890
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -      r15 = 0x0002002100000018    rip = 0x00007ff88d9931ff
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO - 17  xul.dll!nsBaseAppShell::Run() [nsBaseAppShell.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 148 + 0xc]
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -      rbx = 0x000000009bb71f01    rbp = 0x0002000100000000
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -      rsp = 0x00000030815ff510    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -      r13 = 0x00007ff89697f6da    r14 = 0x00000030815ff890
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -      r15 = 0x0002002100000018    rip = 0x00007ff891bbaf08
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO - 18  xul.dll!nsAppShell::Run() [nsAppShell.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 532 + 0x7]
[task 2023-09-03T12:14:47.688Z] 12:14:47     INFO -      rbx = 0x000000009bb71f01    rsi = 0x000001dd9bb71f10
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      rdi = 0x000001dda94816d0    rbp = 0x0002000100000000
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      rsp = 0x00000030815ff550    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      r13 = 0x00007ff89697f6da    r14 = 0x00000030815ff890
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      r15 = 0x0002002100000018    rip = 0x00007ff891c9818a
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO - 19  xul.dll!nsAppStartup::Run() [nsAppStartup.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 295 + 0xc]
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      rbx = 0x0000000000000000    rsi = 0x000001dda94f9510
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      rdi = 0x00000030815ff680    rbp = 0x0002000100000000
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      rsp = 0x00000030815ff5c0    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      r13 = 0x00007ff89697f6da    r14 = 0x00000030815ff890
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      r15 = 0x0002002100000018    rip = 0x00007ff893b26618
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO - 20  xul.dll!XREMain::XRE_mainRun() [nsAppRunner.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 5685 + 0xc]
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      rbx = 0x0000000000000000    rsi = 0x0000000080004005
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      rdi = 0x00000030815ff680    rbp = 0x0002000100000000
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      rsp = 0x00000030815ff620    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      r13 = 0x00007ff89697f6da    r14 = 0x00000030815ff890
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      r15 = 0x0002002100000018    rip = 0x00007ff893c9fb2a
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO - 21  xul.dll!XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 5886 + 0x7]
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      rbx = 0x0000000000000000    rsi = 0x00000030815ffa90
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      rdi = 0x0000000000000000    rbp = 0x0000000000000000
[task 2023-09-03T12:14:47.689Z] 12:14:47     INFO -      rsp = 0x00000030815ff990    r12 = 0xaaaaaaaaaaaaaaaa
[task 2023-09-03T12:14:47.690Z] 12:14:47     INFO -      r13 = 0x0000000000000005    r14 = 0x00000030815ffa10
[task 2023-09-03T12:14:47.690Z] 12:14:47     INFO -      r15 = 0x0000000000000000    rip = 0x00007ff893ca2b81
[task 2023-09-03T12:14:47.690Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.690Z] 12:14:47     INFO - 22  xul.dll!XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 5942 + 0xf]
[task 2023-09-03T12:14:47.690Z] 12:14:47     INFO -      rbx = 0x0000000000000005    rsi = 0x00000030815ffbd0
[task 2023-09-03T12:14:47.690Z] 12:14:47     INFO -      rdi = 0x000001dd9bb04160    rbp = 0x000000000000001f
[task 2023-09-03T12:14:47.690Z] 12:14:47     INFO -      rsp = 0x00000030815ffa70    r12 = 0x000001dd9b8eda00
[task 2023-09-03T12:14:47.690Z] 12:14:47     INFO -      r13 = 0x0000000000000005    r14 = 0x00000030815ffa90
[task 2023-09-03T12:14:47.690Z] 12:14:47     INFO -      r15 = 0xaaaaaaaaaaaaaaaa    rip = 0x00007ff893ca3717
[task 2023-09-03T12:14:47.690Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.690Z] 12:14:47     INFO - 23  firefox.exe!do_main(int, char**, char**) [nsBrowserApp.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 196]
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -     Found by: inlining
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO - 24  firefox.exe!NS_internal_main(int, char**, char**) [nsBrowserApp.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 445 + 0x185]
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -      rbx = 0x000001dd9b8eed10    rsi = 0x000001dd9bb04160
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -      rdi = 0x0000000000000005    rbp = 0x000000000000001f
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -      rsp = 0x00000030815ffb50    r12 = 0x000001dd9b8eda00
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -      r13 = 0x0000000000000005    r14 = 0x00007ff6e9cf25bb
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -      r15 = 0xaaaaaaaaaaaaaaaa    rip = 0x00007ff6e9c517d8
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO - 25  firefox.exe!wmain(int, wchar_t**) [nsWindowsWMain.cpp:6457d1c20254411bb6c0fb12e699e3f75303c9b5 : 151 + 0x14]
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -      rbx = 0x000001dd9b8edb68    rsi = 0x000001dd9bb04340
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -      rdi = 0x000001dd9bb04160    rbp = 0x000000000000001f
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -      rsp = 0x00000030815ffd20    r12 = 0x000001dd9b8eda00
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -      r13 = 0x0000000000000005    r14 = 0x000000000000000a
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -      r15 = 0x000001dd9bb06220    rip = 0x00007ff6e9c5120f
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO - 26  firefox.exe!invoke_main() [exe_common.inl : 90]
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -     Found by: inlining
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO - 27  firefox.exe!__scrt_common_main_seh() [exe_common.inl : 288 + 0x21]
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -      rbx = 0x000001dd9b8eda00    rsi = 0x0000000000000000
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -      rdi = 0x000001dd9b8ff480    rbp = 0x0000000000000000
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -      rsp = 0x00000030815ffde0    r12 = 0x0000000000000000
[task 2023-09-03T12:14:47.691Z] 12:14:47     INFO -      r13 = 0x0000000000000000    r14 = 0x0000000000000000
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO -      r15 = 0x0000000000000000    rip = 0x00007ff6e9cedfa8
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO - 28  kernel32.dll!BaseThreadInitThunk + 0x1c
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO -      rbx = 0x0000000000000000    rsi = 0x0000000000000000
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO -      rdi = 0x0000000000000000    rbp = 0x0000000000000000
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO -      rsp = 0x00000030815ffe20    r12 = 0x0000000000000000
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO -      r13 = 0x0000000000000000    r14 = 0x0000000000000000
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO -      r15 = 0x0000000000000000    rip = 0x00007ff8d1bb269d
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO - 29  ntdll.dll!RtlUserThreadStart + 0x27
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO -      rbx = 0x0000000000000000    rsi = 0x0000000000000000
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO -      rdi = 0x0000000000000000    rbp = 0x0000000000000000
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO -      rsp = 0x00000030815ffe50    r12 = 0x0000000000000000
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO -      r13 = 0x0000000000000000    r14 = 0x0000000000000000
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO -      r15 = 0x0000000000000000    rip = 0x00007ff8d38ca9f8
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO -     Found by: call frame info
[task 2023-09-03T12:14:47.692Z] 12:14:47     INFO - 

The full crash line is:

Hit MOZ_CRASH(Attempting to connect to non-local address! opener is [resource://services-settings/Utils.sys.mjs:269:14], uri is [https://content-signature-2.cdn.mozilla.net/chains/remote-settings.content-signature.mozilla.org-2023-10-09-11-30-14.chain]) at /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpChannel.cpp:7046

So this means that this is about remote settings. There is nothing that I can find in our code base which lists https://content-signature-2.cdn.mozilla.net so maybe that is some address which gets fetched as well, or is a redirect. Maybe this is a regression from my patch on bug 1821981 where we disabled the download of intermediate certificates?

Dana, would you have some additional information? Thanks.

Flags: needinfo?(dkeeler)
See Also: 1754363

Yeah, that's probably remote settings. You should probably just disable it entirely.

Flags: needinfo?(dkeeler)

So right now we set security.remote_settings.intermediates.enabled=false. But I cannot find any other pref to disable all remote_settings. There is only security.remote_settings.crlite_filters.enabled but not sure how this is related here.

Actually the patch on bug 1821981 landed on August 31st on mozilla-central, and the referenced try build here is a staging release: 119.0b1. I was asking the sheriffs on which mozilla-central commit it was based off and it's actually from September 2nd:

https://hg.mozilla.org/mozilla-central/rev/ee62282ee19c0c3cceebbdb2cd283127260a46d6

As such there shouldn't have been an overlap, but maybe we wait until this crash happens again or how often it happens to actually act on it. Right now it still happened only once.

Mathieu works on remote settings.

Flags: needinfo?(dkeeler) → needinfo?(mathieu)

Set release status flags based on info from the regressing bug 1849972

(In reply to Dana Keeler (she/her) (use needinfo) (:keeler for reviews) from comment #6)

Mathieu works on remote settings.

Mathieu, could you please get back to us regarding my question in comment 5? Thanks.

One possibility to disable Remote Settings is to point it at a fake URI, like here:
https://searchfox.org/mozilla-central/source/testing/marionette/client/marionette_driver/geckoinstance.py#154
Services.prefs.setCharPref("services.settings.server", "data:,#remote-settings-dummy/v1");

Several components can be at the origin of the connection, since pulling Remote Settings data from an empty profile will trigger a synchronization.

We could try to trace it with debug logs:
Services.prefs.setCharPref("services.settings.loglevel", "debug");

Flags: needinfo?(mathieu)

Interesting. While we use this above mentioned dummy URL for Marionette we do set this preference's value to an empty string with geckodriver. Given that the failures that we have here are all for web-platform tests I wonder if that might actually cause a problem.

Given that the number of failures is quite low the chance to actually fetch it is low. Interesting is at least that all the failures so far are happening on the beta branch. No failure yet on mozilla-central or autoland. So it's maybe a feature that is enabled for release builds but not dev builds?

Flags: needinfo?(mathieu)

The problem is that Remote Settings has more than 60 uses cases, and I don't have much bandwidth to investigate this thoroughly :/

Flags: needinfo?(mathieu)

Yes, I didn't expect that you investigate this deeply. My question was only around which dummy URL to use. If the one you mentioned should always be used or if an empty string would work as well (as what we do in geckodriver for WebDriver tests). I actually want to have it in sync for both of our test harnesses. Thanks.

Ok, so I'll change that to use this dummy URL then. Thanks. But one last question, do we have to set this preference before Firefox starts or is it read during runtime? I assume because this code uses a lazy getter we need to specify it before Firefox starts.

Flags: needinfo?(mathieu)

If allowServerURLOverride returns true, it does not have to be set before startup ;)

Flags: needinfo?(mathieu)

Ah right. It's a different flag. Thanks.

Assignee: nobody → hskupin
Status: NEW → ASSIGNED
Pushed by hskupin@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/6d4c590f4e5c [geckodriver] Set "services.settings.server" to a dummy URL. r=webdriver-reviewers,Sasha
Status: ASSIGNED → RESOLVED
Closed: 2 years ago
Resolution: --- → FIXED
Target Milestone: --- → 121 Branch

The patch landed in nightly and beta is affected.
:whimboo, is this bug important enough to require an uplift?

  • If yes, please nominate the patch for beta approval.
  • If no, please set status-firefox120 to wontfix.

For more information, please visit BugBot documentation.

Flags: needinfo?(hskupin)

firefox-esr115 is affected as well. Lets wait a couple of days for an uplift request. I want to be sure that the patch really fixed the problem.

See Also: → 1863290
Points: --- → 1
Flags: needinfo?(hskupin)
Whiteboard: [webdriver:m9]
See Also: → 1885261
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: