Closed Bug 1763699 Opened 3 years ago Closed 3 years ago

Intermittent netwerk/test/unit/test_http2-proxy.js | application crashed [@ mozilla::net::nsSocketEvent::Run()]

Categories

(Core :: Networking, defect)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: crash, intermittent-failure)

Crash Data

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


[task 2022-04-07T17:59:24.103Z] 17:59:24     INFO -  TEST-START | netwerk/test/unit/test_http2-proxy.js
[task 2022-04-07T17:59:25.091Z] 17:59:25  WARNING -  TEST-UNEXPECTED-FAIL | netwerk/test/unit/test_http2-proxy.js | xpcshell return code: 1
[task 2022-04-07T17:59:25.105Z] 17:59:25     INFO -  TEST-INFO took 988ms
[task 2022-04-07T17:59:25.105Z] 17:59:25     INFO -  >>>>>>>
[task 2022-04-07T17:59:25.105Z] 17:59:25     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2022-04-07T17:59:25.105Z] 17:59:25     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2022-04-07T17:59:25.106Z] 17:59:25     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2022-04-07T17:59:25.106Z] 17:59:25     INFO -  running event loop
[task 2022-04-07T17:59:25.106Z] 17:59:25     INFO -  netwerk/test/unit/test_http2-proxy.js | Starting setup
[task 2022-04-07T17:59:25.106Z] 17:59:25     INFO -  (xpcshell/head.js) | test setup pending (2)
[task 2022-04-07T17:59:25.106Z] 17:59:25     INFO -  TEST-PASS | netwerk/test/unit/test_http2-proxy.js | setup - [setup : 327] "50070" != null
[task 2022-04-07T17:59:25.107Z] 17:59:25     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2022-04-07T17:59:25.107Z] 17:59:25     INFO -  TEST-PASS | netwerk/test/unit/test_http2-proxy.js | setup - [setup : 336] 54652 != null
[task 2022-04-07T17:59:25.107Z] 17:59:25     INFO -  "Initial proxy session count = 0"
[task 2022-04-07T17:59:25.107Z] 17:59:25     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
[task 2022-04-07T17:59:25.108Z] 17:59:25     INFO -  (xpcshell/head.js) | test setup finished (2)
[task 2022-04-07T17:59:25.108Z] 17:59:25     INFO -  netwerk/test/unit/test_http2-proxy.js | Starting proxy_success_one_session
[task 2022-04-07T17:59:25.108Z] 17:59:25     INFO -  (xpcshell/head.js) | test proxy_success_one_session pending (2)
[task 2022-04-07T17:59:25.108Z] 17:59:25     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (2)
[task 2022-04-07T17:59:25.109Z] 17:59:25     INFO -  TEST-PASS | netwerk/test/unit/test_http2-proxy.js | proxy_success_one_session - [proxy_success_one_session : 388] 0 == 0

[task 2022-04-07T17:59:25.142Z] 17:59:25     INFO -  PID 4268 | FATAL ERROR: Non-local network connections are disabled and a connection attempt to firefox.settings.services.mozilla.com (99.84.182.81) was made.
[task 2022-04-07T17:59:25.143Z] 17:59:25     INFO -  PID 4268 | 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 2022-04-07T17:59:25.143Z] 17:59:25     INFO -  <<<<<<<
[task 2022-04-07T17:59:31.441Z] 17:59:31  WARNING -  PROCESS-CRASH | netwerk/test/unit/test_http2-proxy.js | application crashed [@ mozilla::net::nsSocketEvent::Run()]
[task 2022-04-07T17:59:31.449Z] 17:59:31     INFO -  Mozilla crash reason: MOZ_CRASH(Attempting to connect to non-local address!)
[task 2022-04-07T17:59:31.449Z] 17:59:31     INFO -  Crash dump filename: C:\Users\task_164935161537265\AppData\Local\Temp\xpc-other-wk7m97en\207cb416-8a48-4f0b-b5be-85712bd2ba8b.dmp
[task 2022-04-07T17:59:31.449Z] 17:59:31     INFO -  Operating system: Windows NT
[task 2022-04-07T17:59:31.449Z] 17:59:31     INFO -                    10.0.19041
[task 2022-04-07T17:59:31.450Z] 17:59:31     INFO -  CPU: amd64
[task 2022-04-07T17:59:31.450Z] 17:59:31     INFO -       family 6 model 85 stepping 4
[task 2022-04-07T17:59:31.450Z] 17:59:31     INFO -       8 CPUs
[task 2022-04-07T17:59:31.450Z] 17:59:31     INFO -  GPU: UNKNOWN
[task 2022-04-07T17:59:31.450Z] 17:59:31     INFO -  Crash reason:  EXCEPTION_BREAKPOINT
[task 2022-04-07T17:59:31.451Z] 17:59:31     INFO -  Crash address: 0x8c3fe813
[task 2022-04-07T17:59:31.451Z] 17:59:31     INFO -  Process uptime: 1 seconds
[task 2022-04-07T17:59:31.451Z] 17:59:31     INFO -  Thread 9 (crashed) - Socket Thread 0  xul.dll!mozilla::net::nsSocketTransport::InitiateSocket() [nsSocketTransport2.cpp:f76058e6967924e5f47bf7661d39f7514de7d6bf : 1234 + 0x0]
[task 2022-04-07T17:59:31.451Z] 17:59:31     INFO -      rax = 0x00007ffd916b7485   rdx = 0x000000000000001b
[task 2022-04-07T17:59:31.451Z] 17:59:31     INFO -      rcx = 0x00007ffda7e2d478   rbx = 0x0000014e15f55f80
[task 2022-04-07T17:59:31.452Z] 17:59:31     INFO -      rsi = 0x0000002b0d4beb4c   rdi = 0x0000014e15fbb9c8
[task 2022-04-07T17:59:31.452Z] 17:59:31     INFO -      rbp = 0x0000000000000000   rsp = 0x0000002b0d4be8c0
[task 2022-04-07T17:59:31.452Z] 17:59:31     INFO -       r8 = 0x00007ffdc33a0980    r9 = 0x0000000000000000
[task 2022-04-07T17:59:31.452Z] 17:59:31     INFO -      r10 = 0x0000000000000000   r11 = 0x0000000000000246
[task 2022-04-07T17:59:31.453Z] 17:59:31     INFO -      r12 = 0x0000000088888800   r13 = 0x0000000080004004
[task 2022-04-07T17:59:31.453Z] 17:59:31     INFO -      r14 = 0x0000014e15d8ec00   r15 = 0x0000014e0c90aa00
[task 2022-04-07T17:59:31.453Z] 17:59:31     INFO -      rip = 0x00007ffd8c3fe813
[task 2022-04-07T17:59:31.453Z] 17:59:31     INFO -      Found by: given as instruction pointer in context
[task 2022-04-07T17:59:31.453Z] 17:59:31     INFO -   1  xul.dll!mozilla::net::nsSocketEvent::Run() [nsSocketTransport2.cpp:f76058e6967924e5f47bf7661d39f7514de7d6bf : 94 + 0x1b1]
[task 2022-04-07T17:59:31.454Z] 17:59:31     INFO -      rbx = 0x0000014e15f55f80   rbp = 0x0000000000000000
[task 2022-04-07T17:59:31.454Z] 17:59:31     INFO -      rsp = 0x0000002b0d4bec70   r12 = 0x0000000088888800
[task 2022-04-07T17:59:31.454Z] 17:59:31     INFO -      r13 = 0x0000000080004004   r14 = 0x0000014e15d8ec00
[task 2022-04-07T17:59:31.454Z] 17:59:31     INFO -      r15 = 0x0000014e0c90aa00   rip = 0x00007ffd8c407e72
[task 2022-04-07T17:59:31.454Z] 17:59:31     INFO -      Found by: call frame info
[task 2022-04-07T17:59:31.455Z] 17:59:31     INFO -   2  xul.dll!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:f76058e6967924e5f47bf7661d39f7514de7d6bf : 1146 + 0xd]
[task 2022-04-07T17:59:31.455Z] 17:59:31     INFO -      rbx = 0x0000014e15f55f80   rbp = 0x0000000000000000
[task 2022-04-07T17:59:31.455Z] 17:59:31     INFO -      rsp = 0x0000002b0d4bed00   r12 = 0x0000000088888800
[task 2022-04-07T17:59:31.455Z] 17:59:31     INFO -      r13 = 0x0000000080004004   r14 = 0x0000014e15d8ec00
[task 2022-04-07T17:59:31.456Z] 17:59:31     INFO -      r15 = 0x0000014e0c90aa00   rip = 0x00007ffd8d15fc62
[task 2022-04-07T17:59:31.456Z] 17:59:31     INFO -      Found by: call frame info
[task 2022-04-07T17:59:31.456Z] 17:59:31     INFO -   3  xul.dll!NS_ProcessNextEvent(nsIThread*, bool) [nsThreadUtils.cpp:f76058e6967924e5f47bf7661d39f7514de7d6bf : 466 + 0x1f]
[task 2022-04-07T17:59:31.457Z] 17:59:31     INFO -      rbx = 0x0000014e15f55f80   rbp = 0x0000000000000000
[task 2022-04-07T17:59:31.457Z] 17:59:31     INFO -      rsp = 0x0000002b0d4bf070   r12 = 0x0000000088888800
[task 2022-04-07T17:59:31.457Z] 17:59:31     INFO -      r13 = 0x0000000080004004   r14 = 0x0000014e15d8ec00
[task 2022-04-07T17:59:31.457Z] 17:59:31     INFO -      r15 = 0x0000014e0c90aa00   rip = 0x00007ffd8d177acb
[task 2022-04-07T17:59:31.457Z] 17:59:31     INFO -      Found by: call frame info
[task 2022-04-07T17:59:31.458Z] 17:59:31     INFO -   4  xul.dll!mozilla::net::nsSocketTransportService::Run() [nsSocketTransportService2.cpp:f76058e6967924e5f47bf7661d39f7514de7d6bf : 1167 + 0xc]
[task 2022-04-07T17:59:31.458Z] 17:59:31     INFO -      rbx = 0x0000014e15f55f80   rbp = 0x0000000000000000
[task 2022-04-07T17:59:31.458Z] 17:59:31     INFO -      rsp = 0x0000002b0d4bf0c0   r12 = 0x0000000088888800
[task 2022-04-07T17:59:31.458Z] 17:59:31     INFO -      r13 = 0x0000000080004004   r14 = 0x0000014e15d8ec00
[task 2022-04-07T17:59:31.459Z] 17:59:31     INFO -      r15 = 0x0000014e0c90aa00   rip = 0x00007ffd8d4c61bb
[task 2022-04-07T17:59:31.459Z] 17:59:31     INFO -      Found by: call frame info
[task 2022-04-07T17:59:31.459Z] 17:59:31     INFO -   5  xul.dll!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:f76058e6967924e5f47bf7661d39f7514de7d6bf : 1146 + 0xd]
[task 2022-04-07T17:59:31.459Z] 17:59:31     INFO -      rbx = 0x0000014e15f55f80   rbp = 0x0000000000000000
[task 2022-04-07T17:59:31.459Z] 17:59:31     INFO -      rsp = 0x0000002b0d4bf480   r12 = 0x0000000088888800
[task 2022-04-07T17:59:31.460Z] 17:59:31     INFO -      r13 = 0x0000000080004004   r14 = 0x0000014e15d8ec00
[task 2022-04-07T17:59:31.460Z] 17:59:31     INFO -      r15 = 0x0000014e0c90aa00   rip = 0x00007ffd8d15fc62
[task 2022-04-07T17:59:31.460Z] 17:59:31     INFO -      Found by: call frame info
[task 2022-04-07T17:59:31.460Z] 17:59:31     INFO -   6  xul.dll!mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) [MessagePump.cpp:f76058e6967924e5f47bf7661d39f7514de7d6bf : 300 + 0x26]
[task 2022-04-07T17:59:31.460Z] 17:59:31     INFO -      rbx = 0x0000014e15f55f80   rbp = 0x0000000000000000
[task 2022-04-07T17:59:31.461Z] 17:59:31     INFO -      rsp = 0x0000002b0d4bf7f0   r12 = 0x0000000088888800
[task 2022-04-07T17:59:31.461Z] 17:59:31     INFO -      r13 = 0x0000000080004004   r14 = 0x0000014e15d8ec00
[task 2022-04-07T17:59:31.461Z] 17:59:31     INFO -      r15 = 0x0000014e0c90aa00   rip = 0x00007ffd8bae5ce2
[task 2022-04-07T17:59:31.461Z] 17:59:31     INFO -      Found by: call frame info
[task 2022-04-07T17:59:31.462Z] 17:59:31     INFO -   7  xul.dll!MessageLoop::RunHandler() [message_loop.cc:f76058e6967924e5f47bf7661d39f7514de7d6bf : 324 + 0x16]
[task 2022-04-07T17:59:31.462Z] 17:59:31     INFO -      rbx = 0x0000014e15f55f80   rbp = 0x0000000000000000
[task 2022-04-07T17:59:31.462Z] 17:59:31     INFO -      rsp = 0x0000002b0d4bf890   r12 = 0x0000000088888800
[task 2022-04-07T17:59:31.462Z] 17:59:31     INFO -      r13 = 0x0000000080004004   r14 = 0x0000014e15d8ec00
[task 2022-04-07T17:59:31.462Z] 17:59:31     INFO -      r15 = 0x0000014e0c90aa00   rip = 0x00007ffd8c4e5d5f
[task 2022-04-07T17:59:31.462Z] 17:59:31     INFO -      Found by: call frame info
[task 2022-04-07T17:59:31.463Z] 17:59:31     INFO -   8  xul.dll!MessageLoop::Run() [message_loop.cc:f76058e6967924e5f47bf7661d39f7514de7d6bf : 306 + 0x5]
[task 2022-04-07T17:59:31.463Z] 17:59:31     INFO -      rbx = 0x0000014e15f55f80   rbp = 0x0000000000000000
[task 2022-04-07T17:59:31.463Z] 17:59:31     INFO -      rsp = 0x0000002b0d4bf8e0   r12 = 0x0000000088888800
[task 2022-04-07T17:59:31.463Z] 17:59:31     INFO -      r13 = 0x0000000080004004   r14 = 0x0000014e15d8ec00
[task 2022-04-07T17:59:31.463Z] 17:59:31     INFO -      r15 = 0x0000014e0c90aa00   rip = 0x00007ffd8bae5bae
[task 2022-04-07T17:59:31.463Z] 17:59:31     INFO -      Found by: call frame info
[task 2022-04-07T17:59:31.464Z] 17:59:31     INFO -   9  xul.dll!static nsThread::ThreadFunc(void*) [nsThread.cpp:f76058e6967924e5f47bf7661d39f7514de7d6bf : 392 + 0x8]
[task 2022-04-07T17:59:31.464Z] 17:59:31     INFO -      rbx = 0x0000014e15f55f80   rbp = 0x0000000000000000
[task 2022-04-07T17:59:31.464Z] 17:59:31     INFO -      rsp = 0x0000002b0d4bf940   r12 = 0x0000000088888800
[task 2022-04-07T17:59:31.464Z] 17:59:31     INFO -      r13 = 0x0000000080004004   r14 = 0x0000014e15d8ec00
[task 2022-04-07T17:59:31.465Z] 17:59:31     INFO -      r15 = 0x0000014e0c90aa00   rip = 0x00007ffd8c3a1747
[task 2022-04-07T17:59:31.465Z] 17:59:31     INFO -      Found by: call frame info
[task 2022-04-07T17:59:31.465Z] 17:59:31     INFO -  10  nss3.dll!_PR_NativeRunThread(void*) [pruthr.c:f76058e6967924e5f47bf7661d39f7514de7d6bf : 399 + 0xe]
[task 2022-04-07T17:59:31.465Z] 17:59:31     INFO -      rbx = 0x0000014e15f55f80   rbp = 0x0000000000000000
[task 2022-04-07T17:59:31.465Z] 17:59:31     INFO -      rsp = 0x0000002b0d4bfb00   r12 = 0x0000000088888800
[task 2022-04-07T17:59:31.466Z] 17:59:31     INFO -      r13 = 0x0000000080004004   r14 = 0x0000014e15d8ec00
[task 2022-04-07T17:59:31.466Z] 17:59:31     INFO -      r15 = 0x0000014e0c90aa00   rip = 0x00007ffda458680d
[task 2022-04-07T17:59:31.466Z] 17:59:31     INFO -      Found by: call frame info
[task 2022-04-07T17:59:31.466Z] 17:59:31     INFO -  11  nss3.dll!pr_root(void*) [w95thred.c:f76058e6967924e5f47bf7661d39f7514de7d6bf : 139 + 0xd]
[task 2022-04-07T17:59:31.466Z] 17:59:31     INFO -      rbx = 0x0000014e15f55f80   rbp = 0x0000000000000000
[task 2022-04-07T17:59:31.467Z] 17:59:31     INFO -      rsp = 0x0000002b0d4bfb70   r12 = 0x0000000088888800
[task 2022-04-07T17:59:31.467Z] 17:59:31     INFO -      r13 = 0x0000000080004004   r14 = 0x0000014e15d8ec00
[task 2022-04-07T17:59:31.467Z] 17:59:31     INFO -      r15 = 0x0000014e0c90aa00   rip = 0x00007ffda459e521
[task 2022-04-07T17:59:31.467Z] 17:59:31     INFO -      Found by: call frame info
[task 2022-04-07T17:59:31.467Z] 17:59:31     INFO -  12  ucrtbase.dll!RtlpReAllocateHeap + 0x1ce
[task 2022-04-07T17:59:31.468Z] 17:59:31     INFO -      rbx = 0x0000014e15f55f80   rbp = 0x0000000000000000
[task 2022-04-07T17:59:31.468Z] 17:59:31     INFO -      rsp = 0x0000002b0d4bfba0   r12 = 0x0000000088888800
[task 2022-04-07T17:59:31.468Z] 17:59:31     INFO -      r13 = 0x0000000080004004   r14 = 0x0000014e15d8ec00
[task 2022-04-07T17:59:31.468Z] 17:59:31     INFO -      r15 = 0x0000014e0c90aa00   rip = 0x00007ffdc32d1bb2
[task 2022-04-07T17:59:31.468Z] 17:59:31     INFO -      Found by: call frame info

Close since there is no failure for more than 3 months.

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.