1749311 - Intermittent telemetry/marionette/tests/client/test_fog_deletion_request_ping.py TestDeletionRequestPing.test_deletion_request_ping_across_sessions | OSError: Process has been unexpectedly closed (Exit code: 572) (Reason: Process

Status: RESOLVED INCOMPLETE

(Toolkit :: Telemetry, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-01-10T14:18:26.454Z] 14:18:26     INFO - TEST-START | telemetry/marionette/tests/client/test_fog_deletion_request_ping.py TestDeletionRequestPing.test_deletion_request_ping_across_sessions
[task 2022-01-10T14:18:26.455Z] 14:18:26     INFO - Application command: Z:\task_164182297022795\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile Z:\task_164182297022795\build\tmpppi8dxz_.mozrunner
[task 2022-01-10T14:18:26.748Z] 14:18:26     INFO -  1641824306758	Toolkit.Telemetry	TRACE	TelemetryController::observe - profile-after-change notified.
[task 2022-01-10T14:18:26.759Z] 14:18:26     INFO -  1641824306758	Toolkit.Telemetry	TRACE	TelemetryController::setupTelemetry
[task 2022-01-10T14:18:26.759Z] 14:18:26     INFO -  1641824306759	Toolkit.Telemetry	TRACE	TelemetryReportingPolicy::setup
[task 2022-01-10T14:18:26.760Z] 14:18:26     INFO -  1641824306759	Toolkit.Telemetry	CONFIG	TelemetryController::enableTelemetryRecording - canRecordBase:true, canRecordExtended: false
[task 2022-01-10T14:18:26.760Z] 14:18:26     INFO -  1641824306759	Toolkit.Telemetry	TRACE	TelemetrySession::earlyInit
[task 2022-01-10T14:18:26.764Z] 14:18:26     INFO -  1641824306763	Toolkit.Telemetry	INFO	TelemetryReportingPolicy::get dataSubmissionPolicyNotifiedDate - No date stored yet.
[task 2022-01-10T14:18:26.764Z] 14:18:26     INFO -  1641824306764	Toolkit.Telemetry	TRACE	UpdatePing::init - enabled: true
[task 2022-01-10T14:18:26.769Z] 14:18:26     INFO -  1641824306769	Toolkit.Telemetry	TRACE	TelemetryEnvironment::constructor
[task 2022-01-10T14:18:26.782Z] 14:18:26     INFO -  1641824306796	Toolkit.Telemetry	TRACE	TelemetryEnvironment::_getGFXData - Only one display adapter detected.
[task 2022-01-10T14:18:26.797Z] 14:18:26     INFO -  1641824306798	Toolkit.Telemetry	TRACE	TelemetryEnvironment::_updateSearchEngine - ignoring early call
[task 2022-01-10T14:18:26.798Z] 14:18:26     INFO -  1641824306798	Toolkit.Telemetry	TRACE	TelemetryEnvironment::_updateAddons
[task 2022-01-10T14:18:26.800Z] 14:18:26     INFO -  1641824306800	Toolkit.Telemetry	TRACE	TelemetryEnvironment::registerChangeListener for CrashAnnotator
[task 2022-01-10T14:18:26.813Z] 14:18:26     INFO -  1641824306814	Marionette	INFO	Marionette enabled
[task 2022-01-10T14:20:26.562Z] 14:20:26     INFO - TEST-UNEXPECTED-ERROR | telemetry/marionette/tests/client/test_fog_deletion_request_ping.py TestDeletionRequestPing.test_deletion_request_ping_across_sessions | OSError: Process has been unexpectedly closed (Exit code: 572) (Reason: Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors)
[task 2022-01-10T14:20:26.562Z] 14:20:26     INFO - Traceback (most recent call last):
[task 2022-01-10T14:20:26.562Z] 14:20:26     INFO -   File "Z:\task_164182297022795\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 183, in run
[task 2022-01-10T14:20:26.562Z] 14:20:26     INFO -     self.setUp()
[task 2022-01-10T14:20:26.562Z] 14:20:26     INFO -   File "Z:\task_164182297022795\build\venv\lib\site-packages\telemetry_harness\fog_testcase.py", line 23, in setUp
[task 2022-01-10T14:20:26.562Z] 14:20:26     INFO -     super(FOGTestCase, self).setUp(*args, **kwargs)
[task 2022-01-10T14:20:26.562Z] 14:20:26     INFO -   File "Z:\task_164182297022795\build\venv\lib\site-packages\telemetry_harness\testcase.py", line 37, in setUp
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -     super(TelemetryTestCase, self).setUp(*args, **kwargs)
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -   File "Z:\task_164182297022795\build\venv\lib\site-packages\marionette_harness\runner\mixins\window_manager.py", line 15, in setUp
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -     super(WindowManagerMixin, self).setUp()
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -   File "Z:\task_164182297022795\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 405, in setUp
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -     super(MarionetteTestCase, self).setUp()
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -   File "Z:\task_164182297022795\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 308, in setUp
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -     self.marionette.start_session()
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -   File "Z:\task_164182297022795\build\venv\lib\site-packages\marionette_driver\decorators.py", line 37, in _
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -     m._handle_socket_failure()
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -   File "Z:\task_164182297022795\build\venv\lib\site-packages\marionette_driver\marionette.py", line 756, in _handle_socket_failure
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -     reraise(
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -   File "Z:\task_164182297022795\build\venv\lib\site-packages\six.py", line 702, in reraise
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -     raise value.with_traceback(tb)
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -   File "Z:\task_164182297022795\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -     return func(*args, **kwargs)
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -   File "Z:\task_164182297022795\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1217, in start_session
[task 2022-01-10T14:20:26.564Z] 14:20:26     INFO -     self.start_binary(timeout)
[task 2022-01-10T14:20:26.565Z] 14:20:26     INFO -   File "Z:\task_164182297022795\build\venv\lib\site-packages\marionette_driver\marionette.py", line 553, in start_binary
[task 2022-01-10T14:20:26.565Z] 14:20:26     INFO -     reraise(IOError, IOError(msg.format(timeout)), sys.exc_info()[2])
[task 2022-01-10T14:20:26.565Z] 14:20:26     INFO -   File "Z:\task_164182297022795\build\venv\lib\site-packages\six.py", line 702, in reraise
[task 2022-01-10T14:20:26.565Z] 14:20:26     INFO -     raise value.with_traceback(tb)
[task 2022-01-10T14:20:26.565Z] 14:20:26     INFO -   File "Z:\task_164182297022795\build\venv\lib\site-packages\marionette_driver\marionette.py", line 543, in start_binary
[task 2022-01-10T14:20:26.565Z] 14:20:26     INFO -     self.raise_for_port(timeout=timeout)
[task 2022-01-10T14:20:26.565Z] 14:20:26     INFO -   File "Z:\task_164182297022795\build\venv\lib\site-packages\marionette_driver\marionette.py", line 635, in raise_for_port
[task 2022-01-10T14:20:26.565Z] 14:20:26     INFO -     raise socket.timeout(
[task 2022-01-10T14:20:26.565Z] 14:20:26     INFO - TEST-INFO took 120093ms
[task 2022-01-10T14:20:26.566Z] 14:20:26     INFO - TEST-START | telemetry/marionette/tests/client/test_fog_background_update_ping.py TestBackgroundUpdatePing.test_background_update_ping

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows10-64-2004-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1749311&startday=2022-01-10&endday=2022-01-16&tree=all

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 3

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=387683662&repo=mozilla-central

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• windows10-64-2004-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1749311&startday=2022-08-15&endday=2022-08-21&tree=all

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• windows10-32-2004-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1749311&startday=2022-08-29&endday=2022-09-04&tree=all

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• linux1804-64-devedition-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1749311&startday=2022-09-12&endday=2022-09-18&tree=all

Comment 7

[BugBot [:suhaib / :marco/ :calixte]]

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