Closed Bug 1679198 Opened 4 years ago Closed 4 years ago

Intermittent [Tier2] testing/firefox-ui/tests/functional/safebrowsing/test_notification.py TestSafeBrowsingNotificationBar.test_notification_bar | IOError: Process has been unexpectedly closed (Exit code: 0) (Reason: [Errno 10053] An established connectio

Categories

(Testing :: Firefox UI Tests, defect, P5)

defect

Tracking

(firefox84 unaffected, firefox85 fixed)

RESOLVED FIXED
85 Branch
Tracking Status
firefox84 --- unaffected
firefox85 --- fixed

People

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

References

(Regression)

Details

(Keywords: intermittent-failure)

Filed by: nbeleuzu [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=322691151&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Ehj1Q-0YT-CsHMRwOYnkZA/runs/0/artifacts/public/logs/live_backing.log


[task 2020-11-24T10:59:27.337Z] 10:59:27     INFO -  1606215567335	Marionette	DEBUG	4 <- [1,26,null,{"value":null}]
[task 2020-11-24T10:59:27.387Z] 10:59:27     INFO -  [Parent 7704, Gecko_IOThread] WARNING: file z:/build/build/src/ipc/chromium/src/base/process_util_win.cc:167
[task 2020-11-24T10:59:27.387Z] 10:59:27     INFO -  JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'MarionetteEvents'
[task 2020-11-24T10:59:27.418Z] 10:59:27     INFO -  ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-24T10:59:27.457Z] 10:59:27     INFO -  ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-24T10:59:27.488Z] 10:59:27     INFO -  1606215567483	Marionette	DEBUG	Closed connection 4
[task 2020-11-24T10:59:27.567Z] 10:59:27     INFO -  ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-24T10:59:28.441Z] 10:59:28     INFO -  1606215568432	Marionette	TRACE	Received observer notification xpcom-will-shutdown
[task 2020-11-24T10:59:28.441Z] 10:59:28     INFO -  1606215568433	Marionette	INFO	Stopped listening on port 2828
[task 2020-11-24T10:59:28.441Z] 10:59:28     INFO -  1606215568433	Marionette	DEBUG	Marionette stopped listening
[task 2020-11-24T10:59:28.541Z] 10:59:28     INFO -  ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-11-24T10:59:29.842Z] 10:59:29     INFO - TEST-UNEXPECTED-ERROR | testing/firefox-ui/tests/functional/safebrowsing/test_notification.py TestSafeBrowsingNotificationBar.test_notification_bar | IOError: Process has been unexpectedly closed (Exit code: 0) (Reason: [Errno 10053] An established connection was aborted by the software in your host machine)
[task 2020-11-24T10:59:29.842Z] 10:59:29     INFO - Traceback (most recent call last):
[task 2020-11-24T10:59:29.842Z] 10:59:29     INFO -   File "Z:\task_1606215307\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 214, in run
[task 2020-11-24T10:59:29.842Z] 10:59:29     INFO -     testMethod()
[task 2020-11-24T10:59:29.842Z] 10:59:29     INFO -   File "Z:\task_1606215307\build\tests\firefox-ui\tests\testing\firefox-ui\tests\functional\safebrowsing\test_notification.py", line 59, in test_notification_bar
[task 2020-11-24T10:59:29.842Z] 10:59:29     INFO -     self.check_ignore_warning_link(unsafe_page)
[task 2020-11-24T10:59:29.842Z] 10:59:29     INFO -   File "Z:\task_1606215307\build\tests\firefox-ui\tests\testing\firefox-ui\tests\functional\safebrowsing\test_notification.py", line 87, in check_ignore_warning_link
[task 2020-11-24T10:59:29.842Z] 10:59:29     INFO -     button = self.marionette.find_element(By.ID, "seeDetailsButton")
[task 2020-11-24T10:59:29.842Z] 10:59:29     INFO -   File "Z:\task_1606215307\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1760, in find_element
[task 2020-11-24T10:59:29.843Z] 10:59:29     INFO -     return self._send_message("WebDriver:FindElement", body, key="value")
[task 2020-11-24T10:59:29.843Z] 10:59:29     INFO -   File "Z:\task_1606215307\build\venv\lib\site-packages\marionette_driver\decorators.py", line 37, in _
[task 2020-11-24T10:59:29.843Z] 10:59:29     INFO -     m._handle_socket_failure()
[task 2020-11-24T10:59:29.843Z] 10:59:29     INFO -   File "Z:\task_1606215307\build\venv\lib\site-packages\marionette_driver\marionette.py", line 712, in _handle_socket_failure
[task 2020-11-24T10:59:29.843Z] 10:59:29     INFO -     IOError, IOError(message.format(returncode=returncode, reason=exc)), tb
[task 2020-11-24T10:59:29.843Z] 10:59:29     INFO -   File "Z:\task_1606215307\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2020-11-24T10:59:29.843Z] 10:59:29     INFO -     return func(*args, **kwargs)
[task 2020-11-24T10:59:29.843Z] 10:59:29     INFO -   File "Z:\task_1606215307\build\venv\lib\site-packages\marionette_driver\marionette.py", line 617, in _send_message
[task 2020-11-24T10:59:29.843Z] 10:59:29     INFO -     msg = self.client.request(name, params)
[task 2020-11-24T10:59:29.843Z] 10:59:29     INFO -   File "Z:\task_1606215307\build\venv\lib\site-packages\marionette_driver\transport.py", line 288, in request
[task 2020-11-24T10:59:29.843Z] 10:59:29     INFO -     return self.receive()
[task 2020-11-24T10:59:29.843Z] 10:59:29     INFO -   File "Z:\task_1606215307\build\venv\lib\site-packages\marionette_driver\transport.py", line 161, in receive
[task 2020-11-24T10:59:29.843Z] 10:59:29     INFO -     chunk = self._sock.recv(bytes_to_recv)
[task 2020-11-24T10:59:29.843Z] 10:59:29     INFO - TEST-INFO took 7701ms
[task 2020-11-24T10:59:29.843Z] 10:59:29    ERROR - test_end for testing/firefox-ui/tests/functional/safebrowsing/test_notification.py TestSafeBrowsingNotificationBar.test_notification_bar logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"class_name": "test_notification.TestSafeBrowsingNotificationBar", "method_name": "test_notification_bar"}, "expected": "PASS", "test": "testing/firefox-ui/tests/functional/safebrowsing/test_notification.py TestSafeBrowsingNotificationBar.test_notification_bar", "message": "InvalidSessionIdException: Please start a session", "stack": "Traceback (most recent call last):\n  File \"Z:\\task_1606215307\\build\\venv\\lib\\site-packages\\marionette_harness\\marionette_test\\testcases.py\", line 247, in run\n    self.tearDown()\n  File \"Z:\\task_1606215307\\build\\tests\\firefox-ui\\tests\\testing\\firefox-ui\\tests\\functional\\safebrowsing\\test_notification.py\", line 48, in tearDown\n    super(TestSafeBrowsingNotificationBar, self).tearDown()\n  File \"Z:\\task_1606215307\\build\\venv\\lib\\site-packages\\marionette_harness\\runner\\mixins\\window_manager.py\", line 24, in tearDown\n    if len(self.marionette.chrome_window_handles) > len(self.start_windows):\n  File \"Z:\\task_1606215307\\build\\venv\\lib\\site-packages\\marionette_driver\\marionette.py\", line 1330, in chrome_window_handles\n    return self._send_message(\"WebDriver:GetChromeWindowHandles\")\n  File \"Z:\\task_1606215307\\build\\venv\\lib\\site-packages\\marionette_driver\\decorators.py\", line 27, in _\n    return func(*args, **kwargs)\n  File \"Z:\\task_1606215307\\build\\venv\\lib\\site-packages\\marionette_driver\\marionette.py\", line 614, in _send_message\n    raise errors.InvalidSessionIdException(\"Please start a session\")\n"}

The underlying failure here is a stack overflow:

[task 2020-11-24T10:59:26.977Z] 10:59:26     INFO -  =================================================================
[task 2020-11-24T10:59:26.977Z] 10:59:26     INFO -  ==8364==ERROR: AddressSanitizer: stack-overflow on address 0x0016d6403fa8 (pc 0x7ffcdf955925 bp 0x0016d6404770 sp 0x0016d6403fb0 T0)
[task 2020-11-24T10:59:26.977Z] 10:59:26     INFO -      <empty stack>
[task 2020-11-24T10:59:26.977Z] 10:59:26     INFO -  SUMMARY: AddressSanitizer: stack-overflow
[task 2020-11-24T10:59:26.977Z] 10:59:26     INFO -  ==8364==ABORTING
[task 2020-11-24T10:59:27.006Z] 10:59:27     INFO -  ###!!! [Parent][MessageChannel] Error: (msgtype=0x3A004E,name=PContent::Msg_NotifyProcessPriorityChanged) Channel error: cannot send/recv
[task 2020-11-24T10:59:27.045Z] 10:59:27     INFO -  1606215567037	Marionette	TRACE	[38] Frame script loaded
[task 2020-11-24T10:59:27.095Z] 10:59:27     INFO -  1606215567089	Marionette	TRACE	[38] MarionetteEvents actor created for window id 31
[task 2020-11-24T10:59:27.095Z] 10:59:27     INFO -  A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down

Looks like this regressed by bug 1678310, and got fixed by the backout of the offending patch.

Assignee: nobody → ckerschb
Status: NEW → RESOLVED
Closed: 4 years ago
Regressed by: 1678310
Resolution: --- → FIXED
Target Milestone: --- → 85 Branch
You need to log in before you can comment on or make changes to this bug.