Open Bug 1765047 Opened 2 years ago Updated 1 year ago

Intermittent Android <random_test> | application crashed [@ mozilla::net::nsSocketTransport::InitiateSocket] | Mozilla crash reason: MOZ_CRASH(Attempting to connect to non-local address!)

Categories

(Testing :: web-platform-tests, defect)

defect

Tracking

(Not tracked)

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: crash, intermittent-failure, leave-open, Whiteboard: [retriggered][stockwell disabled])

Crash Data

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


[task 2022-04-16T10:43:07.546Z] 10:43:07     INFO - TEST-START | /navigation-timing/secure-connection-start-reuse.https.html
[task 2022-04-16T10:43:07.549Z] 10:43:07     INFO - Setting pref privacy.reduceTimerPrecision to true
[task 2022-04-16T10:43:07.597Z] 10:43:07     INFO - Setting pref privacy.reduceTimerPrecision to false
[task 2022-04-16T10:43:07.636Z] 10:43:07     INFO - Closing window 0feddc4b-ff60-452e-b4bd-033fd44ab823
[task 2022-04-16T10:43:07.856Z] 10:43:07 CRITICAL - Loading initial page https://web-platform.test:8443/testharness_runner.html failed. Ensure that the there are no other programs bound to this port and that your firewall rules or network setup does not prevent access.
[task 2022-04-16T10:43:07.856Z] 10:43:07 CRITICAL - Traceback (most recent call last):
[task 2022-04-16T10:43:07.856Z] 10:43:07 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 164, in load_runner
[task 2022-04-16T10:43:07.856Z] 10:43:07 CRITICAL -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2022-04-16T10:43:07.856Z] 10:43:07 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 212, in dismiss_alert
[task 2022-04-16T10:43:07.856Z] 10:43:07 CRITICAL -     f()
[task 2022-04-16T10:43:07.856Z] 10:43:07 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 164, in <lambda>
[task 2022-04-16T10:43:07.856Z] 10:43:07 CRITICAL -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2022-04-16T10:43:07.856Z] 10:43:07 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1551, in navigate
[task 2022-04-16T10:43:07.856Z] 10:43:07 CRITICAL -     self._send_message("WebDriver:Navigate", {"url": url})
[task 2022-04-16T10:43:07.856Z] 10:43:07 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 37, in _
[task 2022-04-16T10:43:07.856Z] 10:43:07 CRITICAL -     m._handle_socket_failure()
[task 2022-04-16T10:43:07.856Z] 10:43:07 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 712, in _handle_socket_failure
[task 2022-04-16T10:43:07.856Z] 10:43:07 CRITICAL -     reraise(exc_cls, exc, tb)
[task 2022-04-16T10:43:07.857Z] 10:43:07 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/six/six.py", line 703, in reraise
[task 2022-04-16T10:43:07.857Z] 10:43:07 CRITICAL -     raise value
[task 2022-04-16T10:43:07.857Z] 10:43:07 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2022-04-16T10:43:07.857Z] 10:43:07 CRITICAL -     return func(*args, **kwargs)
[task 2022-04-16T10:43:07.857Z] 10:43:07 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 661, in _send_message
[task 2022-04-16T10:43:07.857Z] 10:43:07 CRITICAL -     msg = self.client.request(name, params)
[task 2022-04-16T10:43:07.857Z] 10:43:07 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 383, in request
[task 2022-04-16T10:43:07.857Z] 10:43:07 CRITICAL -     return self.receive()
[task 2022-04-16T10:43:07.857Z] 10:43:07 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 230, in receive
[task 2022-04-16T10:43:07.857Z] 10:43:07 CRITICAL -     raise socket.error("No data received over socket")
[task 2022-04-16T10:43:07.857Z] 10:43:07 CRITICAL - OSError: No data received over socket
[task 2022-04-16T10:43:07.857Z] 10:43:07 CRITICAL - 
[task 2022-04-16T10:43:07.862Z] 10:43:07  WARNING - Traceback (most recent call last):
[task 2022-04-16T10:43:07.862Z] 10:43:07  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/base.py", line 298, in run_test
[task 2022-04-16T10:43:07.862Z] 10:43:07  WARNING -     self.on_environment_change(test.environment)
[task 2022-04-16T10:43:07.862Z] 10:43:07  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 882, in on_environment_change
[task 2022-04-16T10:43:07.862Z] 10:43:07  WARNING -     self.protocol.testharness.load_runner(new_environment["protocol"])
[task 2022-04-16T10:43:07.862Z] 10:43:07  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 164, in load_runner
[task 2022-04-16T10:43:07.863Z] 10:43:07  WARNING -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2022-04-16T10:43:07.863Z] 10:43:07  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 212, in dismiss_alert
[task 2022-04-16T10:43:07.863Z] 10:43:07  WARNING -     f()
[task 2022-04-16T10:43:07.863Z] 10:43:07  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 164, in <lambda>
[task 2022-04-16T10:43:07.863Z] 10:43:07  WARNING -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2022-04-16T10:43:07.863Z] 10:43:07  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 1551, in navigate
[task 2022-04-16T10:43:07.863Z] 10:43:07  WARNING -     self._send_message("WebDriver:Navigate", {"url": url})
[task 2022-04-16T10:43:07.863Z] 10:43:07  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 37, in _
[task 2022-04-16T10:43:07.863Z] 10:43:07  WARNING -     m._handle_socket_failure()
[task 2022-04-16T10:43:07.863Z] 10:43:07  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 712, in _handle_socket_failure
[task 2022-04-16T10:43:07.863Z] 10:43:07  WARNING -     reraise(exc_cls, exc, tb)
[task 2022-04-16T10:43:07.863Z] 10:43:07  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/third_party/six/six.py", line 703, in reraise
[task 2022-04-16T10:43:07.863Z] 10:43:07  WARNING -     raise value
[task 2022-04-16T10:43:07.863Z] 10:43:07  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2022-04-16T10:43:07.863Z] 10:43:07  WARNING -     return func(*args, **kwargs)
[task 2022-04-16T10:43:07.864Z] 10:43:07  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/marionette.py", line 661, in _send_message
[task 2022-04-16T10:43:07.864Z] 10:43:07  WARNING -     msg = self.client.request(name, params)
[task 2022-04-16T10:43:07.864Z] 10:43:07  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 383, in request
[task 2022-04-16T10:43:07.864Z] 10:43:07  WARNING -     return self.receive()
[task 2022-04-16T10:43:07.864Z] 10:43:07  WARNING -   File "/builds/worker/workspace/build/venv/lib/python3.6/site-packages/marionette_driver/transport.py", line 230, in receive
[task 2022-04-16T10:43:07.864Z] 10:43:07  WARNING -     raise socket.error("No data received over socket")
[task 2022-04-16T10:43:07.864Z] 10:43:07  WARNING - OSError: No data received over socket
[task 2022-04-16T10:43:07.864Z] 10:43:07  WARNING - 
[task 2022-04-16T10:43:08.375Z] 10:43:08     INFO - mozcrash Copy/paste: /builds/worker/fetches/minidump-stackwalk/minidump-stackwalk --symbols-url=https://symbols.mozilla.org/ --human /tmp/tmpa2u3comb/0b6f8cbc-ffdd-318b-2acf-8830bbd9033b.dmp /builds/worker/workspace/build/symbols
[task 2022-04-16T10:43:13.217Z] 10:43:13     INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/0b6f8cbc-ffdd-318b-2acf-8830bbd9033b.dmp
[task 2022-04-16T10:43:13.217Z] 10:43:13     INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/0b6f8cbc-ffdd-318b-2acf-8830bbd9033b.extra
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO - PROCESS-CRASH | /navigation-timing/secure-connection-start-reuse.https.html | application crashed [@ mozilla::net::nsSocketTransport::InitiateSocket()]
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO - Mozilla crash reason: MOZ_CRASH(Attempting to connect to non-local address!)
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO - Crash dump filename: /tmp/tmpa2u3comb/0b6f8cbc-ffdd-318b-2acf-8830bbd9033b.dmp
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO - Operating system: Android
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO -                   0.0.0 Linux 3.10.0+ #260 SMP PREEMPT Fri May 19 12:48:14 PDT 2017 x86_64
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO - CPU: amd64
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO -      family 6 model 6 stepping 3
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO -      4 CPUs
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO - 
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO - Crash reason:  SIGSEGV / SEGV_MAPERR
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO - Crash address: 0x0
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO - Process uptime: not available
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO - 
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO - Thread 14 Socket Thread (crashed)
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO -  0  libxul.so!mozilla::net::nsSocketTransport::InitiateSocket() [nsSocketTransport2.cpp:1d47c0b691eaddf13bbba6c29dfa0a408cd6ff7d : 1237 + 0x29]
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO -      rax = 0x00007dc3d4d77d19    rdx = 0x0000000000000004
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO -      rcx = 0x00007dc3d99e4d10    rbx = 0x00007dc3c279e128
[task 2022-04-16T10:43:13.424Z] 10:43:13     INFO -      rsi = 0x00007dc3ebc702f0    rdi = 0x000000000000001b
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      rbp = 0x00007dc3ebc70ce0    rsp = 0x00007dc3ebc709a0
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -       r8 = 0x0000000000000000     r9 = 0x00007dc3f7ed5090
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      r10 = 0x0000000000000017    r11 = 0x0000000000000246
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      r12 = 0x00007dc3ebc70ad0    r13 = 0x00007dc3c1cfb160
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      r14 = 0x00007dc3ebc70bc0    r15 = 0x00007dc3c279e000
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      rip = 0x00007dc3cebcbe47
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -     Found by: given as instruction pointer in context
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -  1  libxul.so!mozilla::net::nsSocketTransport::OnSocketEvent(unsigned int, nsresult, nsISupports*) [nsSocketTransport2.cpp:1d47c0b691eaddf13bbba6c29dfa0a408cd6ff7d : 1997 + 0x11]
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      rbx = 0x00007dc3c279e108    rbp = 0x00007dc3ebc70d20
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc70cf0    r12 = 0x0000000000000000
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      r13 = 0x00007dc3ebc70de0    r14 = 0x0000000000000000
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      r15 = 0x00007dc3c279e000    rip = 0x00007dc3cebcde19
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -  2  libxul.so!mozilla::net::nsSocketEvent::Run() [nsSocketTransport2.cpp:1d47c0b691eaddf13bbba6c29dfa0a408cd6ff7d : 94 + 0x1a]
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      rbx = 0x00007dc3c27f3c00    rbp = 0x00007dc3ebc70d40
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc70d30    r12 = 0x00007dc3ebc70de0
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      r13 = 0x00007dc3ebc70de0    r14 = 0x00007dc3ebc70e8f
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      r15 = 0x00007dc3eb88aae0    rip = 0x00007dc3cebe7e77
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -  3  libxul.so!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:1d47c0b691eaddf13bbba6c29dfa0a408cd6ff7d : 1174 + 0x10]
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      rbx = 0x00007dc3ebc70d70    rbp = 0x00007dc3ebc70e70
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc70d50    r12 = 0x00007dc3ebc70de0
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      r13 = 0x00007dc3ebc70de0    r14 = 0x00007dc3ebc70e8f
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      r15 = 0x00007dc3eb88aae0    rip = 0x00007dc3cea26f2b
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -  4  libxul.so!NS_ProcessNextEvent(nsIThread*, bool) [nsThreadUtils.cpp:1d47c0b691eaddf13bbba6c29dfa0a408cd6ff7d : 465 + 0x12]
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      rbx = 0x00007dc3eb88aae0    rbp = 0x00007dc3ebc70eb0
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc70e80    r12 = 0x00007dc3ebc70ee0
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      r13 = 0x00007dc3ebc70eff    r14 = 0x0000000000000001
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -      r15 = 0x00007dc3ebc70e8f    rip = 0x00007dc3cea2b042
[task 2022-04-16T10:43:13.425Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -  5  libxul.so!mozilla::net::nsSocketTransportService::Run() [nsSocketTransportService2.cpp:1d47c0b691eaddf13bbba6c29dfa0a408cd6ff7d : 1202 + 0xb]
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      rbx = 0x00007dc3eb825080    rbp = 0x00007dc3ebc70f60
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc70ec0    r12 = 0x00007dc3ebc70ee0
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      r13 = 0x00007dc3ebc70eff    r14 = 0x0000000000000001
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      r15 = 0x00007dc3ebc70ee8    rip = 0x00007dc3cebd4f00
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -  6  libxul.so!{virtual override thunk({offset(-32)}, mozilla::net::nsSocketTransportService::Run())} + 0xc
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      rbx = 0x00007dc3ebc70fa0    rbp = 0x00007dc3ebc70f70
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc70f70    r12 = 0x00007dc3ebc71010
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      r13 = 0x00007dc3ebc71010    r14 = 0x00007dc3ebc710bf
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      r15 = 0x00007dc3eb88aae0    rip = 0x00007dc3cebd5733
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -  7  libxul.so!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:1d47c0b691eaddf13bbba6c29dfa0a408cd6ff7d : 1174 + 0x10]
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      rbx = 0x00007dc3ebc70fa0    rbp = 0x00007dc3ebc710a0
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc70f80    r12 = 0x00007dc3ebc71010
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      r13 = 0x00007dc3ebc71010    r14 = 0x00007dc3ebc710bf
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      r15 = 0x00007dc3eb88aae0    rip = 0x00007dc3cea26f2b
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -  8  libxul.so!NS_ProcessNextEvent(nsIThread*, bool) [nsThreadUtils.cpp:1d47c0b691eaddf13bbba6c29dfa0a408cd6ff7d : 465 + 0x12]
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      rbx = 0x00007dc3eb88aae0    rbp = 0x00007dc3ebc710e0
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc710b0    r12 = 0x00007dc3ebc710f8
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      r13 = 0x00007dc3d8f34160    r14 = 0x0000000000000000
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      r15 = 0x00007dc3ebc710bf    rip = 0x00007dc3cea2b042
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -  9  libxul.so!mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) [MessagePump.cpp:1d47c0b691eaddf13bbba6c29dfa0a408cd6ff7d : 300 + 0x9]
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      rbx = 0x00007dc3ebc711f8    rbp = 0x00007dc3ebc71130
[task 2022-04-16T10:43:13.426Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc710f0    r12 = 0x00007dc3ebc710f8
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -      r13 = 0x00007dc3d8f34160    r14 = 0x00007dc3d8f34140
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -      r15 = 0x00007dc3eb88aae0    rip = 0x00007dc3cf0ee9e8
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO - 10  libxul.so!MessageLoop::RunInternal() [message_loop.cc:1d47c0b691eaddf13bbba6c29dfa0a408cd6ff7d : 380 + 0x16]
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -      rbx = 0x00007dc3ebc711f8    rbp = 0x00007dc3ebc71170
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc71140    r12 = 0x00007dc3eb88ab28
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -      r13 = 0x000000000000000d    r14 = 0x00007dc3ebc71180
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -      r15 = 0x00007dc3eb88aae0    rip = 0x00007dc3cf08e44c
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO - 11  libxul.so!MessageLoop::Run() [message_loop.cc:1d47c0b691eaddf13bbba6c29dfa0a408cd6ff7d : 355 + 0x7]
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -      rbx = 0x00007dc3ebc711f8    rbp = 0x00007dc3ebc711b0
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc71180    r12 = 0x00007dc3eb88ab28
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -      r13 = 0x000000000000000d    r14 = 0x00007dc3ebc71180
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -      r15 = 0x00007dc3eb88aae0    rip = 0x00007dc3cf08e3b5
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO - 12  libxul.so!nsThread::ThreadFunc(void*) [nsThread.cpp:1d47c0b691eaddf13bbba6c29dfa0a408cd6ff7d : 378 + 0x7]
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -      rbx = 0x00007dc3ebc711f8    rbp = 0x00007dc3ebc713a0
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc711c0    r12 = 0x00007dc3eb88ab28
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -      r13 = 0x000000000000000d    r14 = 0x0000000000004cd0
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -      r15 = 0x00007dc3eb88aae0    rip = 0x00007dc3cea23d58
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO - 13  libnss3.so!_pt_root [ptthread.c:1d47c0b691eaddf13bbba6c29dfa0a408cd6ff7d : 201 + 0x9]
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -      rbx = 0x00007dc3ebc714f8    rbp = 0x00007dc3ebc713e0
[task 2022-04-16T10:43:13.427Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc713b0    r12 = 0x00007dc3eb81f680
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -      r13 = 0x0000000000000002    r14 = 0x0000000000004cd0
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -      r15 = 0x00007dc3ebc71450    rip = 0x00007dc3d92bc4c8
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO - 14  libc.so!__pthread_start(void*) + 0xb1
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -      rbx = 0x00007dc3ebc714d0    rbp = 0x0000000000004cbc
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc713f0    r12 = 0x00007dc3ebc71450
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -      r13 = 0x00007dc3d92bc37d    r14 = 0x00007dc3ebc71450
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -      r15 = 0x00007dc3ebc714e8    rip = 0x00007dc3f4a80772
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO - 15  libc.so!__start_thread + 0xb
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -      rbx = 0x00007dc3da2eb450    rbp = 0x0000000000004cbc
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc71440    r12 = 0x0000000000000002
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -      r13 = 0x00007dc3d92bc37d    r14 = 0x00007dc3ebc71450
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -      r15 = 0x00007dc3ebc714e8    rip = 0x00007dc3f4a209ec
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO - 16  libc.so!__bionic_clone + 0x35
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -      rbx = 0x00007dc3da2eb450    rbp = 0x0000000000004cbc
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc71450    r12 = 0x0000000000000002
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -      r13 = 0x00007dc3d92bc37d    r14 = 0x00007dc3ebc71450
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -      r15 = 0x00007dc3ebc714e8    rip = 0x00007dc3f4a13a66
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO - 17  0x7dc3ebd4f44f
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -      rbx = 0x00007dc3da2eb450    rbp = 0x0000000000004cbc
[task 2022-04-16T10:43:13.428Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc71458    r12 = 0x0000000000000002
[task 2022-04-16T10:43:13.429Z] 10:43:13     INFO -      r13 = 0x00007dc3d92bc37d    r14 = 0x00007dc3ebc71450
[task 2022-04-16T10:43:13.429Z] 10:43:13     INFO -      r15 = 0x00007dc3ebc714e8    rip = 0x00007dc3ebd4f450
[task 2022-04-16T10:43:13.429Z] 10:43:13     INFO -     Found by: call frame info
[task 2022-04-16T10:43:13.429Z] 10:43:13     INFO - 18  dalvik-LinearAlloc (deleted) + 0x1ffff
[task 2022-04-16T10:43:13.429Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc71478    rip = 0x00007dc3ebc31000
[task 2022-04-16T10:43:13.429Z] 10:43:13     INFO -     Found by: stack scanning
[task 2022-04-16T10:43:13.429Z] 10:43:13     INFO - 19  libnss3.so!pt_recvfrom_cont [ptio.c:1d47c0b691eaddf13bbba6c29dfa0a408cd6ff7d : 996 + 0x2e]
[task 2022-04-16T10:43:13.429Z] 10:43:13     INFO -      rsp = 0x00007dc3ebc714b8    rip = 0x00007dc3d92bc37d
[task 2022-04-16T10:43:13.429Z] 10:43:13     INFO -     Found by: stack scanning
[task 2022-04-16T10:43:13.429Z] 10:43:13     INFO - 

There are 2 tests that crash:

  • /navigation-timing/secure-connection-start-reuse.https.html: failure log
  • /mixed-content/tentative/autoupgrades/audio-upgrade.https.sub.html: failure log

Hi Dragana! Can you please take a look at this? The failures like this one, are classified with Bug 1401570.

Flags: needinfo?(dd.mozilla)

Loading initial page https://web-platform.test:8443/testharness_runner.html failed. Ensure that the there are no other programs bound to this port and that your firewall rules or network setup does not prevent access.

New Android wpt issue.

Component: Networking → web-platform-tests
Flags: needinfo?(dd.mozilla) → needinfo?(james)
Product: Core → Testing

I first see this fail in https://treeherder.mozilla.org/jobs?repo=mozilla-central&group_state=expanded&selectedTaskRun=IjQYEtNAStagX4d01Xei9Q.0&searchStr=android%2C7.0%2Cx86-64%2Clite%2Cwebrender%2Cdebug%2Cweb%2Cplatform%2Ctests%2Cwithout%2Cfission%2Cenabled%2Ctest-android-em-7.0-x86_64-lite-qr%2Fdebug-geckoview-web-platform-tests-nofis-e10s%2Cwpt2&revision=f9afaaf33533d59d88eb31c159cf5e6676808c30 which is quite a bit earlier than that, but it might be an even earlier changeset that introduced the problem since it's intermittent.

The actual issue is that on android (only) something is trying to resolve example.com, which crashes in CI. I can't obviously see anything in mixed-content or navigation-timing that should be doing that, so I'm not quite sure where the problem is originating.

Flags: needinfo?(james)
Flags: needinfo?(james)
Flags: needinfo?(james)

After some more retriggers I managed to reporduce this all the way back to https://treeherder.mozilla.org/jobs?repo=mozilla-central&group_state=expanded&searchStr=android%2C7.0%2Cx86-64%2Clite%2Cwebrender%2Cdebug%2Cweb%2Cplatform%2Ctests%2Cwithout%2Cfission%2Cenabled%2Ctest-android-em-7.0-x86_64-lite-qr%2Fdebug-geckoview-web-platform-tests-nofis-e10s%2Cwpt2&revision=d14c155fb35a9ae59561ca33344ecb91e42dc1b1

The good news is that does have a wpt sync in it, which seems like a plausible candidate, but I can't obviously see a changed test that should be connecting to example.com (there are some payment tests using example.com as a test URL, but they seem to be disabled everywhere or run later in the chunk).

I also did a try push with HTTP logging turned all the way up: https://treeherder.mozilla.org/jobs?repo=try&selectedTaskRun=dJzLCFkqRhWjA4uDNUusnA.0&revision=68f084c71c3b85c9e06da05496325796639d8547 The crash occurs in some of the jobs, but I'm not seeing anything obvious in the logs to explain what's going on here. It seems like we're trying to load https://web-platform.test:8443/testharness_runner.html as expected, and then at some point we end up doing

5-16 12:37:56.947 15263 15285 I Gecko   : [Parent 15263: Unnamed thread 7cc607622de0]: D/nsSocketTransport STS dispatch [7cc5f0dda7e0]
05-16 12:37:56.947 15263 15285 I Gecko   : [Parent 15263: Unnamed thread 7cc607622de0]: D/nsSocketTransport OnDispatchedEvent Same Thread Skip Signal
05-16 12:37:56.947 15263 15285 I Gecko   : [Parent 15263: Unnamed thread 7cc607622de0]: D/nsSocketTransport nsSocketTransport::OnSocketEvent [this=7cc5f0db4000 type=0 status=0 param=0]
05-16 12:37:56.947 15263 15285 I Gecko   : [Parent 15263: Unnamed thread 7cc607622de0]: D/nsSocketTransport   MSG_ENSURE_CONNECT
05-16 12:37:56.947 15263 15285 I Gecko   : [Parent 15263: Unnamed thread 7cc607622de0]: D/nsSocketTransport nsSocketTransport::ResolveHost [this=7cc5f0db4000 example.com:443] mProxyTransparentResolvesHost=0
05-16 12:37:56.947 15263 15285 I Gecko   : [Parent 15263: Unnamed thread 7cc607622de0]: D/nsSocketTransport nsSocketTransport::PostEvent [this=7cc5f0db4000 type=1 status=0 param=0]
05-16 12:37:56.947 15263 15285 I Gecko   : [Parent 15263: Unnamed thread 7cc607622de0]: D/nsSocketTransport STS dispatch [7cc5f0dfd680]
05-16 12:37:56.947 15263 15285 I Gecko   : [Parent 15263: Unnamed thread 7cc607622de0]: D/nsSocketTransport OnDispatchedEvent Same Thread Skip Signal
05-16 12:37:56.947 15263 15285 I Gecko   : [Parent 15263: Unnamed thread 7cc607622de0]: D/nsSocketTransport nsSocketTransport::OnSocketEvent [this=7cc5f0db4000 type=0 status=0 param=0]
05-16 12:37:56.947 15263 15285 I Gecko   : [Parent 15263: Unnamed thread 7cc607622de0]: D/nsSocketTransport   MSG_ENSURE_CONNECT

This appears to be before the load of testharness_runner.html is complete.

The fact that this is happening before we've loaded any test, and is only android debug, makes me suspicious that there's something going on in gecko that initiates the example.com connection. Although that doesn't quite explain why it's so tightly associated with specific tests.

Anyway, maybe someone from the network team can take a look and hopefully tell me that I've overlooked something very obvious.

Flags: needinfo?(james) → needinfo?(dd.mozilla)
Pushed by archaeopteryx@coole-files.de:
https://hg.mozilla.org/integration/autoland/rev/4b474427615a
disabled secure-contexts/basic-dedicated-worker.html on Android debug
Whiteboard: [retriggered][stockwell disable-recommended] → [retriggered][stockwell needswork:owner]

No failures here since the disable patch reached central on the 18th.

Whiteboard: [retriggered][stockwell disable-recommended] → [retriggered][stockwell disabled]
Summary: High frequency Android <random_test> | application crashed [@ mozilla::net::nsSocketTransport::InitiateSocket()] | Mozilla crash reason: MOZ_CRASH(Attempting to connect to non-local address!) → Intermittent Android <random_test> | application crashed [@ mozilla::net::nsSocketTransport::InitiateSocket()] | Mozilla crash reason: MOZ_CRASH(Attempting to connect to non-local address!)
Flags: needinfo?(dd.mozilla)
Crash Signature: [@ mozilla::net::nsSocketTransport::InitiateSocket()] → [@ mozilla::net::nsSocketTransport::InitiateSocket]
Summary: Intermittent Android <random_test> | application crashed [@ mozilla::net::nsSocketTransport::InitiateSocket()] | Mozilla crash reason: MOZ_CRASH(Attempting to connect to non-local address!) → Intermittent Android <random_test> | application crashed [@ mozilla::net::nsSocketTransport::InitiateSocket] | Mozilla crash reason: MOZ_CRASH(Attempting to connect to non-local address!)
You need to log in before you can comment on or make changes to this bug.