Closed Bug 1724431 Opened 3 years ago Closed 2 years ago

Perma tier2 /url/failure.html | application crashed [@ mozilla::net::nsSocketTransport::OnSocketEvent(unsigned int,enum nsresult,class nsISupports *) + 0x6f3]

Categories

(Core :: DOM: Networking, defect)

Product:
Core
Component:
DOM: Networking
Type:
defect

Tracking

()

Status:
RESOLVED WORKSFORME

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: crash, intermittent-failure)

Crash Data

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

[task 2021-08-06T12:36:57.675Z] 12:36:57     INFO - TEST-START | /upgrade-insecure-requests/gen/srcdoc-inherit.meta/upgrade/worklet-layout.https.html
[task 2021-08-06T12:36:57.676Z] 12:36:57     INFO - Closing window 94367d3c-c91e-4fe4-9029-b8d7d83be761
[task 2021-08-06T12:36:57.993Z] 12:36:57     INFO - mozcrash Saved minidump as Z:\task_1628251731\build\blobber_upload_dir\c0d79285-954b-4024-b7b8-6569f7494809.dmp
[task 2021-08-06T12:36:57.995Z] 12:36:57     INFO - mozcrash Saved app info as Z:\task_1628251731\build\blobber_upload_dir\c0d79285-954b-4024-b7b8-6569f7494809.extra
[task 2021-08-06T12:36:58.169Z] 12:36:58     INFO - PROCESS-CRASH | /url/failure.html | application crashed [@ mozilla::net::nsSocketTransport::OnSocketEvent(unsigned int,enum nsresult,class nsISupports *) + 0x6f3]
[task 2021-08-06T12:36:58.169Z] 12:36:58     INFO - Mozilla crash reason: MOZ_CRASH(Attempting to connect to non-local address!)
[task 2021-08-06T12:36:58.169Z] 12:36:58     INFO - Crash dump filename: C:\Users\task_1628251731\AppData\Local\Temp\tmpn9693xnc\minidumps\c0d79285-954b-4024-b7b8-6569f7494809.dmp
[task 2021-08-06T12:36:58.169Z] 12:36:58     INFO - Operating system: Windows NT
[task 2021-08-06T12:36:58.169Z] 12:36:58     INFO -                   10.0.17134 
[task 2021-08-06T12:36:58.169Z] 12:36:58     INFO - CPU: amd64
[task 2021-08-06T12:36:58.169Z] 12:36:58     INFO -      family 6 model 85 stepping 7
[task 2021-08-06T12:36:58.169Z] 12:36:58     INFO -      8 CPUs
[task 2021-08-06T12:36:58.169Z] 12:36:58     INFO - 
[task 2021-08-06T12:36:58.169Z] 12:36:58     INFO - GPU: UNKNOWN
[task 2021-08-06T12:36:58.169Z] 12:36:58     INFO - 
[task 2021-08-06T12:36:58.169Z] 12:36:58     INFO - Crash reason:  EXCEPTION_BREAKPOINT
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO - Crash address: 0xd6c37887
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO - Process uptime: 13 seconds
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO - 
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO - Thread 5 (crashed) - Socket Thread 0  xul.dll!enum nsresult mozilla::net::nsSocketTransport::InitiateSocket + 0x647
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO -     rax = 0x00007fffe77df4b4   rdx = 0x00000000000000c0
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO -     rcx = 0x00007ff80cc4a8e0   rbx = 0x000001a90c78b280
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO -     rsi = 0x0000003f92ebf014   rdi = 0x000001a90b79f148
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO -     rbp = 0x0000000000000000   rsp = 0x0000003f92ebedc0
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO -      r8 = 0x00007ff82193a640    r9 = 0x0000000000000000
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO -     r10 = 0x0000000000000000   r11 = 0x0000000000000246
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO -     r12 = 0x0000000078944a00   r13 = 0x0000000000173d01
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO -     r14 = 0x000001a97897d0b0   r15 = 0x000001a90cad0400
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO -     rip = 0x00007fffd6c37887
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO -     Found by: given as instruction pointer in context
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO -  1  xul.dll!void mozilla::net::nsSocketTransport::OnSocketEvent(unsigned int,enum nsresult,class nsISupports *) + 0x6f3
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO -     rbx = 0x000001a90c78b280   rbp = 0x0000000000000000
[task 2021-08-06T12:36:58.170Z] 12:36:58     INFO -     rsp = 0x0000003f92ebf140   r12 = 0x0000000078944a00
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     r13 = 0x0000000000173d01   r14 = 0x000001a97897d0b0
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     r15 = 0x000001a90cad0400   rip = 0x00007fffd6c3ad83
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     Found by: call frame info
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -  2  xul.dll!virtual enum nsresult mozilla::net::nsSocketEvent::Run + 0x43
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     rbx = 0x000001a90c78b280   rbp = 0x0000000000000000
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     rsp = 0x0000003f92ebf1a0   r12 = 0x0000000078944a00
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     r13 = 0x0000000000173d01   r14 = 0x000001a97897d0b0
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     r15 = 0x000001a90cad0400   rip = 0x00007fffd6c62f93
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     Found by: call frame info
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -  3  xul.dll!virtual enum nsresult nsThread::ProcessNextEvent(bool,bool *) + 0xd62
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     rbx = 0x000001a90c78b280   rbp = 0x0000000000000000
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     rsp = 0x0000003f92ebf1d0   r12 = 0x0000000078944a00
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     r13 = 0x0000000000173d01   r14 = 0x000001a97897d0b0
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     r15 = 0x000001a90cad0400   rip = 0x00007fffd699e4d2
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     Found by: call frame info
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -  4  xul.dll!bool NS_ProcessNextEvent(class nsIThread *,bool) + 0x45
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     rbx = 0x000001a90c78b280   rbp = 0x0000000000000000
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     rsp = 0x0000003f92ebf390   r12 = 0x0000000078944a00
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     r13 = 0x0000000000173d01   r14 = 0x000001a97897d0b0
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     r15 = 0x000001a90cad0400   rip = 0x00007fffd69a61c5
[task 2021-08-06T12:36:58.171Z] 12:36:58     INFO -     Found by: call frame info
[task 2021-08-06T12:36:58.172Z] 12:36:58     INFO -  5  xul.dll!virtual enum nsresult mozilla::net::nsSocketTransportService::Run + 0xc3f
[task 2021-08-06T12:36:58.172Z] 12:36:58     INFO -     rbx = 0x000001a90c78b280   rbp = 0x0000000000000000
[task 2021-08-06T12:36:58.172Z] 12:36:58     INFO -     rsp = 0x0000003f92ebf3e0   r12 = 0x0000000078944a00
[task 2021-08-06T12:36:58.172Z] 12:36:58     INFO -     r13 = 0x0000000000173d01   r14 = 0x000001a97897d0b0
[task 2021-08-06T12:36:58.172Z] 12:36:58     INFO -     r15 = 0x000001a90cad0400   rip = 0x00007fffd6c45d1f
[task 2021-08-06T12:36:58.172Z] 12:36:58     INFO -     Found by: call frame info
[task 2021-08-06T12:36:58.172Z] 12:36:58     INFO -  6  xul.dll!virtual enum nsresult nsThread::ProcessNextEvent(bool,bool *) + 0xd62
[task 2021-08-06T12:36:58.172Z] 12:36:58     INFO -     rbx = 0x000001a90c78b280   rbp = 0x0000000000000000
[task 2021-08-06T12:36:58.172Z] 12:36:58     INFO -     rsp = 0x0000003f92ebf740   r12 = 0x0000000078944a00
[task 2021-08-06T12:36:58.172Z] 12:36:58     INFO -     r13 = 0x0000000000173d01   r14 = 0x000001a97897d0b0
[task 2021-08-06T12:36:58.172Z] 12:36:58     INFO -     r15 = 0x000001a90cad0400   rip = 0x00007fffd699e4d2
[task 2021-08-06T12:36:58.172Z] 12:36:58     INFO -     Found by: call frame info
[task 2021-08-06T12:36:58.172Z] 12:36:58     INFO -  7  xul.dll!bool NS_ProcessNextEvent(class nsIThread *,bool) + 0x45
[task 2021-08-06T12:36:58.172Z] 12:36:58     INFO -     rbx = 0x000001a90c78b280   rbp = 0x0000000000000000
[task 2021-08-06T12:36:58.172Z] 12:36:58     INFO -     rsp = 0x0000003f92ebf900   r12 = 0x0000000078944a00
[task 2021-08-06T12:36:58.172Z] 12:36:58     INFO -     r13 = 0x0000000000173d01   r14 = 0x000001a97897d0b0
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -     r15 = 0x000001a90cad0400   rip = 0x00007fffd69a61c5
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -     Found by: call frame info
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -  8  xul.dll!virtual void mozilla::ipc::MessagePumpForNonMainThreads::Run(class base::MessagePump::Delegate *) + 0x106
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -     rbx = 0x000001a90c78b280   rbp = 0x0000000000000000
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -     rsp = 0x0000003f92ebf950   r12 = 0x0000000078944a00
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -     r13 = 0x0000000000173d01   r14 = 0x000001a97897d0b0
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -     r15 = 0x000001a90cad0400   rip = 0x00007fffd7518086
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -     Found by: call frame info
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -  9  xul.dll!void MessageLoop::RunHandler + 0x70
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -     rbx = 0x000001a90c78b280   rbp = 0x0000000000000000
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -     rsp = 0x0000003f92ebf9b0   r12 = 0x0000000078944a00
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -     r13 = 0x0000000000173d01   r14 = 0x000001a97897d0b0
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -     r15 = 0x000001a90cad0400   rip = 0x00007fffd74782a0
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -     Found by: call frame info
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO - 10  xul.dll!void MessageLoop::Run + 0x70
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -     rbx = 0x000001a90c78b280   rbp = 0x0000000000000000
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -     rsp = 0x0000003f92ebfa00   r12 = 0x0000000078944a00
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -     r13 = 0x0000000000173d01   r14 = 0x000001a97897d0b0
[task 2021-08-06T12:36:58.173Z] 12:36:58     INFO -     r15 = 0x000001a90cad0400   rip = 0x00007fffd74781f0
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO -     Found by: call frame info
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO - 11  xul.dll!static void nsThread::ThreadFunc(void *) + 0x2a4
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO -     rbx = 0x000001a90c78b280   rbp = 0x0000000000000000
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO -     rsp = 0x0000003f92ebfa50   r12 = 0x0000000078944a00
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO -     r13 = 0x0000000000173d01   r14 = 0x000001a97897d0b0
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO -     r15 = 0x000001a90cad0400   rip = 0x00007fffd699a454
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO -     Found by: call frame info
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO - 12  nss3.dll!PR_NativeRunThread + 0x16a
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO -     rbx = 0x000001a90c78b280   rbp = 0x0000000000000000
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO -     rsp = 0x0000003f92ebfc00   r12 = 0x0000000078944a00
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO -     r13 = 0x0000000000173d01   r14 = 0x000001a97897d0b0
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO -     r15 = 0x000001a90cad0400   rip = 0x00007ff80c3d186a
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO -     Found by: call frame info
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO - 13  nss3.dll!PR_MD_CREATE_THREAD + 0xd9
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO -     rbx = 0x000001a90c78b280   rbp = 0x0000000000000000
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO -     rsp = 0x0000003f92ebfc70   r12 = 0x0000000078944a00
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO -     r13 = 0x0000000000173d01   r14 = 0x000001a97897d0b0
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO -     r15 = 0x000001a90cad0400   rip = 0x00007ff80c3bdc69
[task 2021-08-06T12:36:58.174Z] 12:36:58     INFO -     Found by: call frame info
[task 2021-08-06T12:36:58.175Z] 12:36:58     INFO - 14  ucrtbase.dll!RtlpHpSegPageRangeShrink + 0xda
[task 2021-08-06T12:36:58.175Z] 12:36:58     INFO -     rbx = 0x000001a90c78b280   rbp = 0x0000000000000000
[task 2021-08-06T12:36:58.175Z] 12:36:58     INFO -     rsp = 0x0000003f92ebfca0   r12 = 0x0000000078944a00
[task 2021-08-06T12:36:58.175Z] 12:36:58     INFO -     r13 = 0x0000000000173d01   r14 = 0x000001a97897d0b0
[task 2021-08-06T12:36:58.175Z] 12:36:58     INFO -     r15 = 0x000001a90cad0400   rip = 0x00007ff82186c4be
[task 2021-08-06T12:36:58.175Z] 12:36:58     INFO -     Found by: call frame info
[task 2021-08-06T12:36:58.175Z] 12:36:58     INFO -

I don't think this would be me. Possibly from this?
If not then let me know and I'll investigate further.

Flags: needinfo?(michelle) → needinfo?(aryx.bugmail)

Hi :jmaher,

Any ideas for when you are around?

I assume for these plus the other two permas we'd want to adjust test expectations?

Flags: needinfo?(jmaher)

all of these failures have a common failure if you scroll up:

[task 2021-08-13T00:58:16.812Z] 00:58:16  WARNING -   File "Z:\task_1628812922\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\base.py", line 806, in __call__
[task 2021-08-13T00:58:16.812Z] 00:58:16  WARNING -     return callback(url, payload)
[task 2021-08-13T00:58:16.812Z] 00:58:16  WARNING -   File "Z:\task_1628812922\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\base.py", line 822, in process_action
[task 2021-08-13T00:58:16.812Z] 00:58:16  WARNING -     result = action_handler(payload)
[task 2021-08-13T00:58:16.812Z] 00:58:16  WARNING -   File "Z:\task_1628812922\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\actions.py", line 59, in __call__
[task 2021-08-13T00:58:16.812Z] 00:58:16  WARNING -     self.protocol.action_sequence.send_actions({"actions": actions})
[task 2021-08-13T00:58:16.812Z] 00:58:16  WARNING -   File "Z:\task_1628812922\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 464, in send_actions
[task 2021-08-13T00:58:16.812Z] 00:58:16  WARNING -     self.marionette._send_message("WebDriver:PerformActions", actions)
[task 2021-08-13T00:58:16.812Z] 00:58:16  WARNING -   File "Z:\task_1628812922\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2021-08-13T00:58:16.812Z] 00:58:16  WARNING -     return func(*args, **kwargs)
[task 2021-08-13T00:58:16.812Z] 00:58:16  WARNING -   File "Z:\task_1628812922\build\venv\lib\site-packages\marionette_driver\marionette.py", line 630, in _send_message
[task 2021-08-13T00:58:16.812Z] 00:58:16  WARNING -     self._handle_error(err)
[task 2021-08-13T00:58:16.814Z] 00:58:16  WARNING -   File "Z:\task_1628812922\build\venv\lib\site-packages\marionette_driver\marionette.py", line 652, in _handle_error
[task 2021-08-13T00:58:16.815Z] 00:58:16  WARNING -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2021-08-13T00:58:16.816Z] 00:58:16  WARNING - marionette_driver.errors.InvalidArgumentException: Unknown pointerType: [object String] "touch"
[task 2021-08-13T00:58:16.816Z] 00:58:16  WARNING - stacktrace:
[task 2021-08-13T00:58:16.817Z] 00:58:16  WARNING - 	WebDriverError@chrome://remote/content/shared/webdriver/Errors.jsm:181:5
[task 2021-08-13T00:58:16.817Z] 00:58:16  WARNING - 	InvalidArgumentError@chrome://remote/content/shared/webdriver/Errors.jsm:310:5
[task 2021-08-13T00:58:16.817Z] 00:58:16  WARNING - 	assert.that/<@chrome://remote/content/shared/webdriver/Assert.jsm:435:13
[task 2021-08-13T00:58:16.817Z] 00:58:16  WARNING - 	assert.in@chrome://remote/content/shared/webdriver/Assert.jsm:388:47
[task 2021-08-13T00:58:16.817Z] 00:58:16  WARNING - 	action.PointerType.get@chrome://remote/content/marionette/action.js:402:10
[task 2021-08-13T00:58:16.817Z] 00:58:16  WARNING - 	Pointer@chrome://remote/content/marionette/action.js:588:39
[task 2021-08-13T00:58:16.817Z] 00:58:16  WARNING - 	fromJSON@chrome://remote/content/marionette/action.js:476:14
[task 2021-08-13T00:58:16.817Z] 00:58:16  WARNING - 	fromJSON@chrome://remote/content/marionette/action.js:842:39
[task 2021-08-13T00:58:16.817Z] 00:58:16  WARNING - 	fromJSON@chrome://remote/content/marionette/action.js:805:48
[task 2021-08-13T00:58:16.817Z] 00:58:16  WARNING - 	performActions@chrome://remote/content/marionette/actors/MarionetteCommandsChild.jsm:448:20
[task 2021-08-13T00:58:16.817Z] 00:58:16  WARNING - 	receiveMessage@chrome://remote/content/marionette/actors/MarionetteCommandsChild.jsm:141:31
[task 2021-08-13T00:58:16.817Z] 00:58:16  WARNING -

I don't know if this failure was around before, but it is likely that this failure is cascading down and somehow in the error handling causing the browser to send packet(s) to 1.2.3.8.

so questions to resolve:

  1. do we see this failure of touch actions on windows10 v1803?
  2. do we see this failure of touch actions on linux, osx ?
  3. if we disable the test(s) that use touch, does this go away?
  4. can we reproduce this on try server? is it intermittent and at what frequency?
Flags: needinfo?(jmaher)

Can these CCov tests somehow be run locally?

Flags: needinfo?(aryx.bugmail)

wait, I missed only ccov; likewise I missed that this is NOT running on win10 v2004, but instead v1803.

So just a recap for myself:

nsSocketTransport::InitiateSocket() is crashing because we want to prevent us from violating the xpc::AreNonLocalConnectionsDisabled() setting.

I see two ways forward here:

  1. Find who is trying to make this unwanted connection
  2. Downgrade this MOZ_CRASH to some NS_ERROR_* return and see who is catching it (or not)

The first will probably move this bug to a different component (Joel seem to suspect it is touch related), the latter might just solve it silently or make some existing error handling trigger and help to detect the issuer (and seems somewhat more consistent to me, we have a dynamic setting that could affect anybody opening a connection, so crashing looks overly rude to me here).

Sebastian, any thoughts which way to go?

Flags: needinfo?(aryx.bugmail)

Sorry for the noise, aryx.

Flags: needinfo?(aryx.bugmail) → needinfo?(dd.mozilla)
See Also: → https://bugzilla.mozilla.org/show_bug.cgi?id=995417
  1. The test is set to crash on all platforms except Windows 64-bit and gets skipped on Linux asan. Should the rules be updated to set Windows ccov as crash? (And the connection to 1.2.3.08 should be rejected at all.)
  2. More information regarding a failure are always welcome. Outside wpt, the connections are to production services and also contain the hostname. Theoretically adding the additional information sounds beneficial, practical it might only cover this one test.
Status: NEW → RESOLVED
Closed: 2 years ago
Flags: needinfo?(dd.mozilla)
Resolution: --- → WORKSFORME
You need to log in before you can comment on or make changes to this bug.