1452327 - Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_profile_management.py TestSwitchProfileWithWorkspace.test_clone_existing_profile | IOError: Process killed after 120s because no connection to Marionette server could be establishe

Reporter

Description

•

6 years ago

treeherder

Filed by: ebalazs [at] mozilla.com

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

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

Eliza Balazs [:ebalazs_]

Comment 1

•

6 years ago

22:45:38     INFO -  1523054738266	Marionette	DEBUG	Received observer notification profile-after-change
22:47:38    ERROR -  TEST-UNEXPECTED-ERROR | testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestSwitchProfileWithWorkspace.test_clone_existing_profile | IOError: Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors
22:47:38     INFO -  Traceback (most recent call last):
22:47:38     INFO -    File "Z:\task_1523053771\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
22:47:38     INFO -      testMethod()
22:47:38     INFO -    File "Z:\task_1523053771\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py", line 250, in test_clone_existing_profile
22:47:38     INFO -      self.marionette.start_session()
22:47:38     INFO -    File "Z:\task_1523053771\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
22:47:38     INFO -      return func(*args, **kwargs)
22:47:38     INFO -    File "Z:\task_1523053771\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1210, in start_session
22:47:38     INFO -      self.start_binary(timeout)
22:47:38     INFO -    File "Z:\task_1523053771\build\venv\lib\site-packages\marionette_driver\marionette.py", line 644, in start_binary
22:47:38     INFO -      reraise(IOError, msg.format(timeout), tb)
22:47:38     INFO -    File "Z:\task_1523053771\build\venv\lib\site-packages\marionette_driver\marionette.py", line 635, in start_binary
22:47:38     INFO -      self.raise_for_port(timeout=timeout)
22:47:38     INFO -    File "Z:\task_1523053771\build\venv\lib\site-packages\marionette_driver\marionette.py", line 715, in raise_for_port
22:47:38     INFO -      self.host, self.port))

Comment hidden (Intermittent Failures Robot)

1 failures in 765 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://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1452327&startday=2018-04-02&endday=2018-04-08&tree=trunk

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

Comment 3

•

6 years ago

Hang during startup which is covered via bug 1414495.

Depends on: 1414495

Comment hidden (Intermittent Failures Robot)

1 failures in 782 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://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1452327&startday=2018-04-16&endday=2018-04-22&tree=trunk

Firefox Bug Husbandry Bot

Comment 5

•

6 years ago

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

Status: NEW → RESOLVED

Closed: 6 years ago

Resolution: --- → INCOMPLETE

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* mozilla-central: 1

Platform breakdown:
* windows10-64-nightly: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1452327&startday=2018-05-14&endday=2018-05-20&tree=trunk

Andreea Pavel [:apavel]

Comment 7

•

6 years ago

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

10:04:01     INFO -  TEST-START | testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestSwitchProfileWithWorkspace.test_clone_existing_profile
10:04:01     INFO -  1528106641195	Marionette	DEBUG	Closed connection 2
10:04:01     INFO -  1528106641198	Marionette	DEBUG	Accepted connection 3 from 127.0.0.1:51662
10:04:01     INFO -  1528106641204	Marionette	TRACE	3 -> [0,1,"WebDriver:NewSession",{}]
10:04:01     INFO -  1528106641210	Marionette	DEBUG	Register listener.js for window 2147483649
10:04:01     INFO -  1528106641217	Marionette	TRACE	3 <- [1,1,null,{"sessionId":"9b73aea3-0f20-4b4b-a10f-9bf674b34a22","capabilities":{"browserName":"firefox","browserVersion":"62.0a ... 8104637\\AppData\\Local\\Temp\\tmpoyi4hw.mozrunner","moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
10:04:01     INFO -  1528106641221	Marionette	TRACE	3 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
10:04:01     INFO -  1528106641223	Marionette	TRACE	3 <- [1,2,null,{"value":null}]
10:04:01     INFO -  1528106641228	Marionette	TRACE	3 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
10:04:01     INFO -  1528106641233	Marionette	TRACE	3 <- [1,3,null,{"value":null}]
10:04:01     INFO -  1528106641235	Marionette	TRACE	3 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
10:04:01     INFO -  1528106641236	Marionette	TRACE	3 <- [1,4,null,{"value":null}]
10:04:01     INFO -  1528106641241	Marionette	TRACE	3 -> [0,5,"WebDriver:DeleteSession",{}]
10:04:01     INFO -  1528106641244	Marionette	TRACE	3 <- [1,5,null,{"value":null}]
10:04:01     INFO -  1528106641247	Marionette	DEBUG	Closed connection 3
10:04:01     INFO -  Application command: Z:\task_1528104637\build\application\firefox\firefox.exe -no-remote -marionette -profile c:\users\task_1528104637\appdata\local\temp\tmpauscgf\tmp5gvztl.tmpypdfjaexternal
10:04:03     INFO -  1528106643470	Marionette	DEBUG	Received observer notification profile-after-change
10:04:03     INFO -  1528106643682	Marionette	DEBUG	Received observer notification command-line-startup
10:04:03     INFO -  1528106643683	Marionette	DEBUG	Received observer notification nsPref:changed
10:04:03     INFO -  1528106643683	Marionette	DEBUG	Init aborted (running=false, enabled=true, finalUIStartup=false)
10:06:01    ERROR -  TEST-UNEXPECTED-ERROR | testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestSwitchProfileWithWorkspace.test_clone_existing_profile | IOError: Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors
10:06:01     INFO -  Traceback (most recent call last):
10:06:01     INFO -    File "Z:\task_1528104637\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
10:06:01     INFO -      testMethod()
10:06:01     INFO -    File "Z:\task_1528104637\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py", line 250, in test_clone_existing_profile
10:06:01     INFO -      self.marionette.start_session()
10:06:01     INFO -    File "Z:\task_1528104637\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
10:06:01     INFO -      return func(*args, **kwargs)
10:06:01     INFO -    File "Z:\task_1528104637\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1211, in start_session
10:06:01     INFO -      self.start_binary(timeout)
10:06:01     INFO -    File "Z:\task_1528104637\build\venv\lib\site-packages\marionette_driver\marionette.py", line 640, in start_binary
10:06:01     INFO -      reraise(IOError, msg.format(timeout), tb)
10:06:01     INFO -    File "Z:\task_1528104637\build\venv\lib\site-packages\marionette_driver\marionette.py", line 631, in start_binary
10:06:01     INFO -      self.raise_for_port(timeout=timeout)
10:06:01     INFO -    File "Z:\task_1528104637\build\venv\lib\site-packages\marionette_driver\marionette.py", line 711, in raise_for_port
10:06:01     INFO -      self.host, self.port))
10:06:01     INFO -  TEST-INFO took 120611ms
10:06:01     INFO -  Application command: Z:\task_1528104637\build\application\firefox\firefox.exe -no-remote -marionette -profile c:\users\task_1528104637\appdata\local\temp\tmpauscgf\tmpjtkg8h.mozrunner
10:06:03     INFO -  1528106763776	Marionette	DEBUG	Received observer notification profile-after-change
10:06:03     INFO -  1528106763986	Marionette	DEBUG	Received observer notification command-line-startup
10:06:03     INFO -  1528106763987	Marionette	DEBUG	Received observer notification nsPref:changed
10:06:03     INFO -  1528106763987	Marionette	DEBUG	Init aborted (running=false, enabled=true, finalUIStartup=false)
10:06:07     INFO -  1528106767057	Marionette	DEBUG	Received observer notification sessionstore-windows-restored
10:06:07     INFO -  1528106767057	Marionette	DEBUG	Waiting for delayed startup...
10:06:08     INFO -  1528106768271	Marionette	DEBUG	Waiting for startup tests...
10:06:08     INFO -  1528106768469	Marionette	INFO	Listening on port 2828
10:06:08     INFO -  1528106768470	Marionette	DEBUG	Remote service is active
10:06:08     INFO -  1528106768772	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:51927
10:06:08     INFO -  1528106768869	Marionette	DEBUG	Closed connection 0
10:06:08     INFO -  1528106768873	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:51929
10:06:08     INFO -  1528106768978	Marionette	DEBUG	Accepted connection 2 from 127.0.0.1:51930
10:06:08     INFO -  1528106768983	Marionette	DEBUG	Closed connection 2
10:06:08     INFO -  1528106768984	Marionette	DEBUG	Closed connection 1
10:06:08     INFO -  1528106768988	Marionette	DEBUG	Accepted connection 3 from 127.0.0.1:51931
10:06:08     INFO -  1528106768994	Marionette	TRACE	3 -> [0,1,"WebDriver:NewSession",{}]
10:06:09     INFO -  1528106769092	Marionette	DEBUG	Register listener.js for window 4294967297
10:06:09     INFO -  1528106769111	Marionette	TRACE	3 <- [1,1,null,{"sessionId":"59642504-fc60-426f-9e0a-ed2ef990ee0a","capabilities":{"browserName":"firefox","browserVersion":"62.0a ... pData\\Local\\Temp\\tmpauscgf\\tmpjtkg8h.mozrunner","moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]

Status: RESOLVED → REOPENED

Resolution: INCOMPLETE → ---

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:
* mozilla-inbound: 1
* autoland: 1

Platform breakdown:
* windows10-64: 2

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

Firefox Bug Husbandry Bot

Comment 9

•

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

Razvan Maries

Comment 10

•

5 years ago

New occurrences: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=228638237&repo=mozilla-central&lineNumber=31574

Status: RESOLVED → REOPENED

Resolution: INCOMPLETE → ---

Comment hidden (Intermittent Failures Robot)

Firefox Bug Husbandry Bot

Comment 12

•

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

Cristina Coroiu [:ccoroiu]

Comment 13

•

5 years ago

Recent failure log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=238775699&repo=mozilla-inbound&lineNumber=31844

12:41:48 INFO - TEST-START | testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestSwitchProfileWithWorkspace.test_clone_existing_profile
12:41:48 INFO - 1554727308578 Marionette DEBUG Closed connection 3
12:41:48 INFO - 1554727308579 Marionette DEBUG Accepted connection 4 from 127.0.0.1:52329
12:41:48 INFO - 1554727308581 Marionette DEBUG 4 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
12:41:48 INFO - 1554727308582 Marionette TRACE [2147483649] Frame script loaded
12:41:48 INFO - 1554727308584 Marionette TRACE [2147483649] Frame script registered
12:41:48 INFO - 1554727308585 Marionette DEBUG 4 <- [1,1,null,{"sessionId":"63c8e19e-f147-4e6e-8140-f97bf4ecb3bf","capabilities":{"browserName":"firefox","browserVersion":"68.0a ... p\tmpalnpoa.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
12:41:48 INFO - 1554727308587 Marionette DEBUG 4 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
12:41:48 INFO - 1554727308587 Marionette DEBUG 4 <- [1,2,null,{"value":null}]
12:41:48 INFO - 1554727308590 Marionette DEBUG 4 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
12:41:48 INFO - 1554727308591 Marionette DEBUG 4 <- [1,3,null,{"value":null}]
12:41:48 INFO - 1554727308592 Marionette DEBUG 4 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
12:41:48 INFO - 1554727308592 Marionette DEBUG 4 <- [1,4,null,{"value":null}]
12:41:48 INFO - Application command: Z:\task_1554726112\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile c:\users\task_1554726112\appdata\local\temp\tmplqf5vn\tmpltv2al.tmpvcpn3cexternal
12:41:48 INFO - *** You are running in headless mode.
12:41:49 INFO - 1554727309121 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
12:41:49 INFO - 1554727309121 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
12:41:49 INFO - 1554727309121 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
12:41:49 INFO - 1554727309266 Marionette TRACE Received observer notification profile-after-change
12:41:49 INFO - 1554727309286 Marionette TRACE Received observer notification toplevel-window-ready
12:41:49 INFO - 1554727309333 Marionette TRACE Received observer notification command-line-startup
12:41:49 INFO - 1554727309333 Marionette TRACE Received observer notification nsPref:changed
12:41:49 INFO - 1554727309333 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false)
12:43:48 ERROR - TEST-UNEXPECTED-ERROR | testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestSwitchProfileWithWorkspace.test_clone_existing_profile | IOError: Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors
12:43:48 INFO - Traceback (most recent call last):
12:43:48 INFO - File "Z:\task_1554726112\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
12:43:48 INFO - testMethod()
12:43:48 INFO - File "Z:\task_1554726112\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py", line 250, in test_clone_existing_profile
12:43:48 INFO - self.marionette.start_session()
12:43:48 INFO - File "Z:\task_1554726112\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
12:43:48 INFO - return func(*args, **kwargs)
12:43:48 INFO - File "Z:\task_1554726112\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1103, in start_session
12:43:48 INFO - self.start_binary(timeout)
12:43:48 INFO - File "Z:\task_1554726112\build\venv\lib\site-packages\marionette_driver\marionette.py", line 491, in start_binary
12:43:48 INFO - reraise(IOError, msg.format(timeout), tb)
12:43:48 INFO - File "Z:\task_1554726112\build\venv\lib\site-packages\marionette_driver\marionette.py", line 482, in start_binary
12:43:48 INFO - self.raise_for_port(timeout=timeout)
12:43:48 INFO - File "Z:\task_1554726112\build\venv\lib\site-packages\marionette_driver\marionette.py", line 569, in raise_for_port
12:43:48 INFO - self.host, self.port))
12:43:48 INFO - TEST-INFO took 120337ms
12:43:48 INFO - Application command: Z:\task_1554726112\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile c:\users\task_1554726112\appdata\local\temp\tmplqf5vn\tmpnsdxdq.mozrunner
12:43:49 INFO - *** You are running in headless mode.

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: 5 years ago → 5 years ago

Resolution: --- → INCOMPLETE

Treeherder Bug Filer

Reporter

Updated

•

4 years ago

Updated

•

4 years ago

Status: RESOLVED → REOPENED

Resolution: INCOMPLETE → ---

Summary: Intermittent testing\marionette\harness\marionette_harness\tests\unit\test_profile_management.py TestSwitchProfileWithWorkspace.test_clone_existing_profile | IOError: Process killed after 120s because no connection to Marionette server could be → Intermittent testing/marionette/harness/marionette_harness/tests/unit/test_profile_management.py TestSwitchProfileWithWorkspace.test_clone_existing_profile | IOError: Process killed after 120s because no connection to Marionette server could be

BugBot [:suhaib / :marco/ :calixte]

Comment 18

•

4 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: REOPENED → RESOLVED

Closed: 5 years ago → 4 years ago

Resolution: --- → INCOMPLETE

BMO Automation

Updated

•

1 year ago

Product: Testing → Remote Protocol

BMO Automation

Comment 19

•

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 TestSwitchProfileWithWorkspace.test_clone_existing_profile | IOError: Process killed after 120s because no connection to Marionette server could be establishe

Categories

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

Tracking

(Not tracked)

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Depends on 1 open bug)

Details

(Keywords: intermittent-failure)

Crash Data

Security

(public)

User Story

Description

Comment 1

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

Updated

Comment 18

Updated

Comment 19