Closed Bug 1407156 Opened 7 years ago Closed 6 months ago

Intermittent test_cli_arguments.py TestCommandLineArguments.test_startup_timeout | IOError: Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors

Categories

(Testing :: Marionette Client and Harness, defect, P5)

Version 3
defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: archaeopteryx [at] coole-files.de

https://treeherder.mozilla.org/logviewer.html#?job_id=135830456&repo=autoland

https://queue.taskcluster.net/v1/task/ZWp56KzdSU-qlr5gjPsDRw/runs/0/artifacts/public/logs/live_backing.log

00:26:45     INFO -  1507595205373	Marionette	DEBUG	Received observer notification "profile-after-change"
00:26:45     INFO -  1507595205408	Marionette	DEBUG	Received observer notification "command-line-startup"
00:26:45     INFO -  1507595205408	Marionette	INFO	Enabled via --marionette
00:26:45     INFO -  Application command: Z:\task_1507593660\build\application\firefox\firefox.exe -no-remote -marionette -profile c:\users\genericworker\appdata\local\temp\tmp2mjwpm.mozrunner
00:26:46     INFO -  1507595206195	Marionette	DEBUG	Received observer notification "profile-after-change"
00:26:46     INFO -  1507595206289	Marionette	DEBUG	Received observer notification "command-line-startup"
00:26:46     INFO -  1507595206289	Marionette	INFO	Enabled via --marionette
00:26:47     INFO -  1507595207378	Marionette	DEBUG	Received observer notification "sessionstore-windows-restored"
00:26:47     INFO -  1507595207700	Marionette	INFO	Listening on port 2828
00:26:48     INFO -  1507595208189	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:57126
00:26:48     INFO -  1507595208193	Marionette	DEBUG	Closed connection 0
00:26:48     INFO -  1507595208194	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:57127
00:26:48     INFO -  1507595208210	Marionette	TRACE	1 -> [0,1,"newSession",{}]
00:26:48     INFO -  1507595208286	Marionette	DEBUG	Register listener.js for window 4294967297
00:26:48     INFO -  1507595208296	Marionette	TRACE	1 <- [1,1,null,{"sessionId":"7ba209dd-7a84-4dfb-abb1-11b7acc98e2d","capabilities":{"browserName":"firefox","browserVersion":"58.0a1","platformName":"windows_nt","platformVersion":"10.0","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"moz:accessibilityChecks":false,"moz:headless":false,"moz:processID":8284,"moz:profile":"C:\\Users\\GenericWorker\\AppData\\Local\\Temp\\tmp2mjwpm.mozrunner","moz:webdriverClick":false}}]
00:26:48     INFO -  1507595208300	Marionette	TRACE	1 -> [0,2,"deleteSession",{}]
00:26:48     INFO -  1507595208302	Marionette	TRACE	1 <- [1,2,null,{}]
00:26:48     INFO -  Application command: Z:\task_1507593660\build\application\firefox\firefox.exe -no-remote -marionette -profile c:\users\genericworker\appdata\local\temp\tmpmupgd_.mozrunner
00:26:48     INFO -  1507595208825	Marionette	DEBUG	Received observer notification "profile-after-change"
00:28:48    ERROR -  TEST-UNEXPECTED-ERROR | test_cli_arguments.py TestCommandLineArguments.test_startup_timeout | IOError: Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors
00:28:48     INFO -  Traceback (most recent call last):
00:28:48     INFO -    File "Z:\task_1507593660\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 187, in run
00:28:48     INFO -      self.tearDown()
00:28:48     INFO -    File "Z:\task_1507593660\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_cli_arguments.py", line 21, in tearDown
00:28:48     INFO -      super(TestCommandLineArguments, self).tearDown()
00:28:48     INFO -    File "Z:\task_1507593660\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 322, in tearDown
00:28:48     INFO -      self.marionette.start_session()
00:28:48     INFO -    File "Z:\task_1507593660\build\venv\lib\site-packages\marionette_driver\decorators.py", line 23, in _
00:28:48     INFO -      return func(*args, **kwargs)
00:28:48     INFO -    File "Z:\task_1507593660\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1230, in start_session
00:28:48     INFO -      self.start_binary(timeout)
00:28:48     INFO -    File "Z:\task_1507593660\build\venv\lib\site-packages\marionette_driver\marionette.py", line 634, in start_binary
00:28:48     INFO -      self.raise_for_port(timeout=timeout)
00:28:48     INFO -    File "Z:\task_1507593660\build\venv\lib\site-packages\marionette_driver\marionette.py", line 722, in raise_for_port
00:28:48     INFO -      self.host, self.port))
00:28:48     INFO -  TEST-INFO took 123634ms
My patch on bug 1410366 should help here.
Depends on: 1410366
Actually this failure depends on bug 1382162, which is about the missing `sessionstore-window-restored` notification.
Depends on: 1382162
No longer depends on: 1410366
https://wiki.mozilla.org/Bugmasters#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
See Also: → 1530144
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 7 years ago5 years ago
Resolution: --- → INCOMPLETE
Product: Testing → Remote Protocol
Moving bug to Testing::Marionette Client and Harness component per bug 1815831.
Component: Marionette → Marionette Client and Harness
Product: Remote Protocol → Testing
You need to log in before you can comment on or make changes to this bug.