1434214 - Intermittent testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestQuitRestartWithoutWorkspace.test_restart_clean_creates_new_profile | IOError: Process killed because the connection to Marionette server is lost.

Reporter

Description

•

6 years ago

treeherder

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

https://treeherder.mozilla.org/logviewer.html#?job_id=159276268&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/VLQEsfGVSM2fJ4oPNw1uLg/runs/0/artifacts/public/test_info/marionette_errorsummary.log

08:56:24     INFO -  TEST-START | testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestQuitRestartWithoutWorkspace.test_quit_clean_creates_new_profile
08:56:24     INFO -  Application command: Z:\task_1517302282\build\application\firefox\firefox.exe -no-remote -marionette -profile c:\users\genericworker\appdata\local\temp\tmpafxkci.mozrunner
08:56:24     INFO -  1517302584919	Marionette	DEBUG	Received observer notification profile-after-change
08:56:25     INFO -  1517302584999	Marionette	DEBUG	Received observer notification command-line-startup
08:56:25     INFO -  1517302584999	Marionette	DEBUG	Enabled via flag
08:56:25     INFO -  [DEBUG SHUTDOWN] Register: decoder=000001C97019B800, count=1
08:56:25     INFO -  [DEBUG SHUTDOWN] ShutdownDecoder: decoder: 'wmf software video decoder - yuv420' (000001C970165220) flush:1
08:56:25     INFO -  [DEBUG SHUTDOWN] operator (): pool=000001C96DEF01C0 shutdown=false count=0
08:56:26     INFO -  1517302586146	Marionette	DEBUG	Received observer notification sessionstore-windows-restored
08:56:26     INFO -  1517302586407	Marionette	DEBUG	New connections are accepted
08:56:26     INFO -  1517302586408	Marionette	INFO	Listening on port 2828
08:56:26     INFO -  1517302586726	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:51382
08:56:26     INFO -  1517302586805	Marionette	DEBUG	Closed connection 0
08:56:26     INFO -  1517302586808	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:51391
08:56:26     INFO -  1517302586906	Marionette	DEBUG	Accepted connection 2 from 127.0.0.1:51395
08:56:26     INFO -  1517302586908	Marionette	DEBUG	Closed connection 2
08:56:26     INFO -  1517302586908	Marionette	DEBUG	Closed connection 1
08:56:26     INFO -  1517302586909	Marionette	DEBUG	Accepted connection 3 from 127.0.0.1:51396
08:56:26     INFO -  1517302586913	Marionette	TRACE	3 -> [0,1,"newSession",{}]
08:56:26     INFO -  1517302586975	Marionette	DEBUG	Register listener.js for window 4294967297
08:56:26     INFO -  1517302586981	Marionette	TRACE	3 <- [1,1,null,{"sessionId":"1a712bc4-61ed-4346-a69b-5a530e7bc4de","capabilities":{"browserName":"firefox","browserVersion":"60.0a ... cessID":7920,"moz:profile":"C:\\Users\\GenericWorker\\AppData\\Local\\Temp\\tmpafxkci.mozrunner","moz:webdriverClick":true}}]
08:56:26     INFO -  1517302586986	Marionette	TRACE	3 -> [0,2,"deleteSession",{}]
08:56:26     INFO -  1517302586988	Marionette	TRACE	3 <- [1,2,null,{}]
08:56:26     INFO -  TEST-PASS | testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestQuitRestartWithoutWorkspace.test_quit_clean_creates_new_profile | took 2617ms
08:56:26     INFO -  TEST-START | testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestQuitRestartWithoutWorkspace.test_quit_keeps_same_profile
08:56:26     INFO -  1517302586989	Marionette	DEBUG	Closed connection 3
08:56:26     INFO -  1517302586992	Marionette	DEBUG	Accepted connection 4 from 127.0.0.1:51397
08:56:26     INFO -  1517302586994	Marionette	TRACE	4 -> [0,1,"newSession",{}]
08:56:26     INFO -  1517302586995	Marionette	DEBUG	Register listener.js for window 4294967297
08:56:26     INFO -  1517302586997	Marionette	TRACE	4 <- [1,1,null,{"sessionId":"16a19afa-6f2e-4f3c-bfb4-acac9b55ab56","capabilities":{"browserName":"firefox","browserVersion":"60.0a ... cessID":7920,"moz:profile":"C:\\Users\\GenericWorker\\AppData\\Local\\Temp\\tmpafxkci.mozrunner","moz:webdriverClick":true}}]
08:56:27     INFO -  1517302586999	Marionette	TRACE	4 -> [0,2,"setTimeouts",{"script":30000}]
08:56:27     INFO -  1517302586999	Marionette	TRACE	4 <- [1,2,null,{}]
08:56:27     INFO -  1517302587001	Marionette	TRACE	4 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:56:27     INFO -  1517302587001	Marionette	TRACE	4 <- [1,3,null,{}]
08:56:27     INFO -  1517302587004	Marionette	TRACE	4 -> [0,4,"setTimeouts",{"implicit":0}]
08:56:27     INFO -  1517302587005	Marionette	TRACE	4 <- [1,4,null,{}]
08:56:27     INFO -  1517302587006	Marionette	TRACE	4 -> [0,5,"deleteSession",{}]
08:56:27     INFO -  1517302587007	Marionette	TRACE	4 <- [1,5,null,{}]
08:56:27     INFO -  Application command: Z:\task_1517302282\build\application\firefox\firefox.exe -no-remote -marionette -profile c:\users\genericworker\appdata\local\temp\tmpafxkci.mozrunner
08:56:27     INFO -  1517302587328	Marionette	DEBUG	Received observer notification profile-after-change
08:56:27     INFO -  1517302587364	Marionette	DEBUG	Received observer notification command-line-startup
08:56:27     INFO -  1517302587364	Marionette	DEBUG	Enabled via flag
08:56:28     INFO -  1517302588052	Marionette	DEBUG	Received observer notification sessionstore-windows-restored
08:56:28     INFO -  1517302588457	Marionette	DEBUG	New connections are accepted
08:56:28     INFO -  1517302588457	Marionette	INFO	Listening on port 2828
08:56:28     INFO -  1517302588684	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:51401
08:56:28     INFO -  1517302588782	Marionette	DEBUG	Closed connection 0
08:56:28     INFO -  1517302588784	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:51407
08:56:28     INFO -  1517302588883	Marionette	DEBUG	Accepted connection 2 from 127.0.0.1:51411
08:56:28     INFO -  1517302588885	Marionette	DEBUG	Closed connection 2
08:56:28     INFO -  1517302588885	Marionette	DEBUG	Closed connection 1
08:56:28     INFO -  1517302588886	Marionette	DEBUG	Accepted connection 3 from 127.0.0.1:51412
08:56:28     INFO -  1517302588887	Marionette	TRACE	3 -> [0,1,"newSession",{}]
08:56:28     INFO -  1517302588950	Marionette	DEBUG	Register listener.js for window 2147483649
08:56:28     INFO -  1517302588958	Marionette	TRACE	3 <- [1,1,null,{"sessionId":"101d8908-652c-43e7-8831-3960843fcc95","capabilities":{"browserName":"firefox","browserVersion":"60.0a ... cessID":1868,"moz:profile":"C:\\Users\\GenericWorker\\AppData\\Local\\Temp\\tmpafxkci.mozrunner","moz:webdriverClick":true}}]
08:56:28     INFO -  TEST-PASS | testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestQuitRestartWithoutWorkspace.test_quit_keeps_same_profile | took 1987ms
08:56:28     INFO -  TEST-START | testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestQuitRestartWithoutWorkspace.test_restart_clean_creates_new_profile
08:56:28     INFO -  1517302588975	Marionette	TRACE	3 -> [0,2,"deleteSession",{}]
08:56:28     INFO -  1517302588976	Marionette	TRACE	3 <- [1,2,null,{}]
08:56:28     INFO -  1517302588978	Marionette	DEBUG	Closed connection 3
08:56:28     INFO -  1517302588979	Marionette	DEBUG	Accepted connection 4 from 127.0.0.1:51413
08:56:28     INFO -  1517302588981	Marionette	TRACE	4 -> [0,1,"newSession",{}]
08:56:28     INFO -  1517302588982	Marionette	DEBUG	Register listener.js for window 2147483649
08:56:28     INFO -  1517302588985	Marionette	TRACE	4 <- [1,1,null,{"sessionId":"33722e86-e487-40e4-9422-c718fb25e33e","capabilities":{"browserName":"firefox","browserVersion":"60.0a ... cessID":1868,"moz:profile":"C:\\Users\\GenericWorker\\AppData\\Local\\Temp\\tmpafxkci.mozrunner","moz:webdriverClick":true}}]
08:56:28     INFO -  1517302588987	Marionette	TRACE	4 -> [0,2,"setTimeouts",{"script":30000}]
08:56:28     INFO -  1517302588987	Marionette	TRACE	4 <- [1,2,null,{}]
08:56:28     INFO -  1517302588989	Marionette	TRACE	4 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:56:28     INFO -  1517302588989	Marionette	TRACE	4 <- [1,3,null,{}]
08:56:28     INFO -  1517302588990	Marionette	TRACE	4 -> [0,4,"setTimeouts",{"implicit":0}]
08:56:28     INFO -  1517302588991	Marionette	TRACE	4 <- [1,4,null,{}]
08:56:29     INFO -  1517302589007	Marionette	TRACE	4 -> [0,5,"getContext",{}]
08:56:29     INFO -  1517302589007	Marionette	TRACE	4 <- [1,5,null,{"value":"content"}]
08:56:29     INFO -  1517302589008	Marionette	TRACE	4 -> [0,6,"deleteSession",{}]
08:56:29     INFO -  1517302589009	Marionette	TRACE	4 <- [1,6,null,{}]
08:56:29     INFO -  Application command: Z:\task_1517302282\build\application\firefox\firefox.exe -no-remote -marionette -profile c:\users\genericworker\appdata\local\temp\tmpd2m8ws.mozrunner
08:56:29     INFO -  1517302589514	Marionette	DEBUG	Received observer notification profile-after-change
09:00:30    ERROR -  TEST-UNEXPECTED-ERROR | testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestQuitRestartWithoutWorkspace.test_restart_clean_creates_new_profile | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Timed out waiting for connection on localhost:2828!)
09:00:30     INFO -  Traceback (most recent call last):
09:00:30     INFO -    File "Z:\task_1517302282\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
09:00:30     INFO -      testMethod()
09:00:30     INFO -    File "Z:\task_1517302282\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py", line 100, in test_restart_clean_creates_new_profile
09:00:30     INFO -      self.marionette.restart(clean=True)
09:00:30     INFO -    File "Z:\task_1517302282\build\venv\lib\site-packages\marionette_driver\decorators.py", line 31, in _
09:00:30     INFO -      m._handle_socket_failure()
09:00:30     INFO -    File "Z:\task_1517302282\build\venv\lib\site-packages\marionette_driver\marionette.py", line 830, in _handle_socket_failure
09:00:30     INFO -      reraise(IOError, message.format(returncode=returncode, reason=val), tb)
09:00:30     INFO -    File "Z:\task_1517302282\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
09:00:30     INFO -      return func(*args, **kwargs)
09:00:30     INFO -    File "Z:\task_1517302282\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1163, in restart
09:00:30     INFO -      self.raise_for_port(timeout=self.DEFAULT_STARTUP_TIMEOUT)
09:00:30     INFO -    File "Z:\task_1517302282\build\venv\lib\site-packages\marionette_driver\marionette.py", line 716, in raise_for_port
09:00:30     INFO -      self.host, self.port))
09:00:30     INFO -  TEST-INFO took 241379ms

Comment hidden (Intermittent Failures Robot)

2 failures in 735 pushes (0.003 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 2

Platform breakdown:
* windows10-64: 2

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1434214&startday=2018-01-29&endday=2018-02-04&tree=all

Henrik Skupin [:whimboo][⌚️UTC+1]

Updated

•

6 years ago

Depends on: 1433873

Comment hidden (Intermittent Failures Robot)

2 failures in 702 pushes (0.003 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 1
* autoland: 1

Platform breakdown:
* windows10-64: 2

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1434214&startday=2018-02-05&endday=2018-02-11&tree=all

Comment hidden (Intermittent Failures Robot)

2 failures in 685 pushes (0.003 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-central: 1
* autoland: 1

Platform breakdown:
* windows10-64-nightly: 1
* windows10-64: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1434214&startday=2018-02-12&endday=2018-02-18&tree=all

Comment hidden (Intermittent Failures Robot)

3 failures in 747 pushes (0.004 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 2
* autoland: 1

Platform breakdown:
* osx-10-10: 2
* windows7-32: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1434214&startday=2018-03-05&endday=2018-03-11&tree=all

Comment hidden (Intermittent Failures Robot)

1 failures in 814 pushes (0.001 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* autoland: 1

Platform breakdown:
* windows10-64: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1434214&startday=2018-03-12&endday=2018-03-18&tree=all

Firefox Bug Husbandry Bot

Comment 6

•

6 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: NEW → RESOLVED

Closed: 6 years ago

Resolution: --- → INCOMPLETE

Eliza Balazs [:ebalazs_]

Comment 7

•

6 years ago

Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=182231835&repo=autoland&lineNumber=31014

 INFO -  TEST-START | testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestQuitRestartWithoutWorkspace.test_restart_clean_creates_new_profile
08:56:54     INFO -  1528361814602	Marionette	DEBUG	Closed connection 2
08:56:54     INFO -  1528361814604	Marionette	DEBUG	Accepted connection 3 from 127.0.0.1:51591
08:56:54     INFO -  1528361814612	Marionette	TRACE	3 -> [0,1,"WebDriver:NewSession",{}]
08:56:54     INFO -  1528361814619	Marionette	DEBUG	Register listener.js for window 2147483649
08:56:54     INFO -  1528361814626	Marionette	TRACE	3 <- [1,1,null,{"sessionId":"bb7c8e1f-9d5b-463b-9297-f544e7dae97e","capabilities":{"browserName":"firefox","browserVersion":"62.0a ... 8360058\\AppData\\Local\\Temp\\tmpuujexi.mozrunner","moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
08:56:54     INFO -  1528361814633	Marionette	TRACE	3 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
08:56:54     INFO -  1528361814635	Marionette	TRACE	3 <- [1,2,null,{"value":null}]
08:56:54     INFO -  1528361814643	Marionette	TRACE	3 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
08:56:54     INFO -  1528361814645	Marionette	TRACE	3 <- [1,3,null,{"value":null}]
08:56:54     INFO -  1528361814681	Marionette	TRACE	3 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
08:56:54     INFO -  1528361814683	Marionette	TRACE	3 <- [1,4,null,{"value":null}]
08:56:54     INFO -  1528361814689	Marionette	TRACE	3 -> [0,5,"Marionette:GetContext",{}]
08:56:54     INFO -  1528361814690	Marionette	TRACE	3 <- [1,5,null,{"value":"content"}]
08:56:54     INFO -  1528361814697	Marionette	TRACE	3 -> [0,6,"WebDriver:DeleteSession",{}]
08:56:54     INFO -  1528361814701	Marionette	TRACE	3 <- [1,6,null,{"value":null}]
08:56:54     INFO -  Application command: Z:\task_1528360058\build\application\firefox\firefox.exe -no-remote -marionette -profile c:\users\task_1528360058\appdata\local\temp\tmpsqmj4z.mozrunner
08:56:55     INFO -  *** You are running in headless mode.
08:56:56     INFO -  1528361816991	Marionette	DEBUG	Received observer notification profile-after-change
08:56:57     INFO -  1528361817191	Marionette	DEBUG	Received observer notification command-line-startup
08:56:57     INFO -  1528361817192	Marionette	DEBUG	Received observer notification nsPref:changed
08:56:57     INFO -  1528361817192	Marionette	DEBUG	Init aborted (running=false, enabled=true, finalUIStartup=false)
09:00:56    ERROR -  TEST-UNEXPECTED-ERROR | testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestQuitRestartWithoutWorkspace.test_restart_clean_creates_new_profile | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Timed out waiting for connection on localhost:2828!)
09:00:56     INFO -  Traceback (most recent call last):
09:00:56     INFO -    File "Z:\task_1528360058\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
09:00:56     INFO -      testMethod()
09:00:56     INFO -    File "Z:\task_1528360058\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py", line 102, in test_restart_clean_creates_new_profile
09:00:56     INFO -      self.marionette.restart(clean=True)
09:00:56     INFO -    File "Z:\task_1528360058\build\venv\lib\site-packages\marionette_driver\decorators.py", line 31, in _
09:00:56     INFO -      m._handle_socket_failure()
09:00:56     INFO -    File "Z:\task_1528360058\build\venv\lib\site-packages\marionette_driver\marionette.py", line 824, in _handle_socket_failure
09:00:56     INFO -      reraise(IOError, message.format(returncode=returncode, reason=val), tb)
09:00:56     INFO -    File "Z:\task_1528360058\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
09:00:56     INFO -      return func(*args, **kwargs)
09:00:56     INFO -    File "Z:\task_1528360058\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1162, in restart
09:00:56     INFO -      self.raise_for_port(timeout=self.DEFAULT_STARTUP_TIMEOUT)
09:00:56     INFO -    File "Z:\task_1528360058\build\venv\lib\site-packages\marionette_driver\marionette.py", line 710, in raise_for_port
09:00:56     INFO -      self.host, self.port))
09:00:56     INFO -  TEST-INFO took 241622ms
09:00:56     INFO -  Application command: Z:\task_1528360058\build\application\firefox\firefox.exe -no-remote -marionette -profile c:\users\task_1528360058\appdata\local\temp\tmptkvflf.mozrunner
09:00:56     INFO -  *** You are running in headless mode.
09:00:58     INFO -  1528362058375	Marionette	DEBUG	Received observer notification profile-after-change
09:00:58     INFO -  1528362058575	Marionette	DEBUG	Received observer notification command-line-startup
09:00:58     INFO -  1528362058575	Marionette	DEBUG	Received observer notification nsPref:changed
09:00:58     INFO -  1528362058576	Marionette	DEBUG	Init aborted (running=false, enabled=true, finalUIStartup=false)
09:01:01     INFO -  1528362061973	Marionette	DEBUG	Received observer notification sessionstore-windows-restored
09:01:01     INFO -  1528362061974	Marionette	DEBUG	Waiting for delayed startup...
09:01:03     INFO -  1528362063178	Marionette	DEBUG	Waiting for startup tests...
09:01:03     INFO -  1528362063397	Marionette	INFO	Listening on port 2828
09:01:03     INFO -  1528362063398	Marionette	DEBUG	Remote service is active
09:01:03     INFO -  1528362063448	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:51885
09:01:03     INFO -  1528362063460	Marionette	DEBUG	Closed connection 0
09:01:03     INFO -  1528362063463	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:51886
09:01:03     INFO -  1528362063472	Marionette	TRACE	1 -> [0,1,"WebDriver:NewSession",{}]
09:01:03     INFO -  1528362063572	Marionette	DEBUG	Register listener.js for window 4294967297
09:01:03     INFO -  1528362063647	Marionette	TRACE	1 <- [1,1,null,{"sessionId":"61f0cc2d-70ff-49ac-a05f-cb64aeebad2e","capabilities":{"browserName":"firefox","browserVersion":"62.0a ... 8360058\\AppData\\Local\\Temp\\tmptkvflf.mozrunner","moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
09:01:03     INFO -  1528362063666	Marionette	TRACE	1 -> [0,2,"WebDriver:DeleteSession",{}]
09:01:03     INFO -  1528362063670	Marionette	TRACE	1 <- [1,2,null,{"value":null}]

Status: RESOLVED → REOPENED

Flags: needinfo?(dburns)

Resolution: INCOMPLETE → ---

Henrik Skupin [:whimboo][⌚️UTC+1]

Comment 8

•

6 years ago

This failure depends on a fix for bug 1433873.

Flags: needinfo?(dburns)

Comment hidden (Intermittent Failures Robot)

2 failures in 669 pushes (0.003 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* try: 1
* autoland: 1

Platform breakdown:
* windows10-64: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1434214&startday=2018-06-04&endday=2018-06-10&tree=trunk

Firefox Bug Husbandry Bot

Comment 10

•

6 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: REOPENED → RESOLVED

Closed: 6 years ago → 6 years ago

Resolution: --- → INCOMPLETE

Bogdan Tara[:bogdan_tara | bogdant]

Comment 11

•

5 years ago

This is still happening.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=235692301&repo=mozilla-central&lineNumber=31620

23:45:47 INFO - TEST-START | testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestQuitRestartWithoutWorkspace.test_restart_clean_creates_new_profile
23:45:47 INFO - 1553384747425 Marionette DEBUG 3 -> [0,2,"WebDriver:DeleteSession",{}]
23:45:47 INFO - 1553384747427 Marionette DEBUG 3 <- [1,2,null,{"value":null}]
23:45:47 INFO - 1553384747429 Marionette DEBUG Closed connection 3
23:45:47 INFO - 1553384747430 Marionette DEBUG Accepted connection 4 from 127.0.0.1:51686
23:45:47 INFO - 1553384747431 Marionette DEBUG 4 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
23:45:47 INFO - 1553384747433 Marionette TRACE [2147483649] Frame script loaded
23:45:47 INFO - 1553384747434 Marionette TRACE [2147483649] Frame script registered
23:45:47 INFO - 1553384747435 Marionette DEBUG 4 <- [1,1,null,{"sessionId":"de48886d-f1ea-41c0-8f18-c6d773bad5b1","capabilities":{"browserName":"firefox","browserVersion":"68.0a ... p\tmpmk2sin.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
23:45:47 INFO - 1553384747437 Marionette DEBUG 4 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
23:45:47 INFO - 1553384747438 Marionette DEBUG 4 <- [1,2,null,{"value":null}]
23:45:47 INFO - 1553384747439 Marionette DEBUG 4 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
23:45:47 INFO - 1553384747440 Marionette DEBUG 4 <- [1,3,null,{"value":null}]
23:45:47 INFO - 1553384747441 Marionette DEBUG 4 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
23:45:47 INFO - 1553384747442 Marionette DEBUG 4 <- [1,4,null,{"value":null}]
23:45:47 INFO - 1553384747444 Marionette DEBUG 4 -> [0,5,"Marionette:GetContext",{}]
23:45:47 INFO - 1553384747445 Marionette DEBUG 4 <- [1,5,null,{"value":"content"}]
23:45:47 INFO - 1553384747446 Marionette DEBUG 4 -> [0,6,"WebDriver:DeleteSession",{}]
23:45:47 INFO - 1553384747447 Marionette DEBUG 4 <- [1,6,null,{"value":null}]
23:45:47 INFO - Application command: Z:\task_1553384278\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile c:\users\task_1553384278\appdata\local\temp\tmpkxyf2d.mozrunner
23:45:47 INFO - *** You are running in headless mode.
23:45:47 INFO - 1553384747942 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
23:45:47 INFO - 1553384747942 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
23:45:47 INFO - 1553384747943 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
23:45:48 INFO - 1553384748076 Marionette TRACE Received observer notification profile-after-change
23:45:48 INFO - 1553384748096 Marionette TRACE Received observer notification toplevel-window-ready
23:45:48 INFO - 1553384748143 Marionette TRACE Received observer notification command-line-startup
23:45:48 INFO - 1553384748143 Marionette TRACE Received observer notification nsPref:changed
23:45:48 INFO - 1553384748143 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false)
23:48:58 ERROR - TEST-UNEXPECTED-ERROR | testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestQuitRestartWithoutWorkspace.test_restart_clean_creates_new_profile | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Timed out waiting for connection on 127.0.0.1:2828!)
23:48:58 INFO - Traceback (most recent call last):
23:48:58 INFO - File "Z:\task_1553384278\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
23:48:58 INFO - testMethod()
23:48:58 INFO - File "Z:\task_1553384278\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py", line 102, in test_restart_clean_creates_new_profile
23:48:58 INFO - self.marionette.restart(clean=True)
23:48:58 INFO - File "Z:\task_1553384278\build\venv\lib\site-packages\marionette_driver\decorators.py", line 36, in _
23:48:58 INFO - m._handle_socket_failure()
23:48:58 INFO - File "Z:\task_1553384278\build\venv\lib\site-packages\marionette_driver\marionette.py", line 678, in _handle_socket_failure
23:48:58 INFO - reraise(IOError, message.format(returncode=returncode, reason=val), tb)
23:48:58 INFO - File "Z:\task_1553384278\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
23:48:58 INFO - return func(*args, **kwargs)
23:48:58 INFO - File "Z:\task_1553384278\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1052, in restart
23:48:58 INFO - self.raise_for_port(timeout=self.DEFAULT_STARTUP_TIMEOUT)
23:48:58 INFO - File "Z:\task_1553384278\build\venv\lib\site-packages\marionette_driver\marionette.py", line 569, in raise_for_port
23:48:58 INFO - self.host, self.port))
23:48:58 INFO - TEST-INFO took 191344ms
23:48:58 INFO - Application command: Z:\task_1553384278\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile c:\users\task_1553384278\appdata\local\temp\tmpsutqhh.mozrunner

Status: RESOLVED → REOPENED

Resolution: INCOMPLETE → ---

Comment hidden (Intermittent Failures Robot)

Firefox Bug Husbandry Bot

Comment 16

•

5 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: REOPENED → RESOLVED

Closed: 6 years ago → 5 years ago

Resolution: --- → INCOMPLETE

BMO Automation

Updated

•

1 year ago

Product: Testing → Remote Protocol

BMO Automation

Comment 17

•

1 year ago

Moving bug to Testing::Marionette Client and Harness component per bug 1815831.

Component: Marionette → Marionette Client and Harness

Product: Remote Protocol → Testing

Bugzilla

Quick Search

Intermittent testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestQuitRestartWithoutWorkspace.test_restart_clean_creates_new_profile | IOError: Process killed because the connection to Marionette server is lost.

Categories

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

Tracking

(Not tracked)

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Crash Data

Security

(public)

User Story

Description

Comment 1

Updated

Comment 2

Comment 3

Comment 4

Comment 5

Comment 6

Comment 7

Comment 8

Comment 9

Comment 10

Comment 11

Comment 12

Comment 13

Comment 14

Comment 15

Comment 16

Updated

Comment 17