1822081 - Intermittent [Tier 2] TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/script/remove_preload_script/sandbox_tentative.py | expected OK

Status: RESOLVED DUPLICATE of bug 1630162

(Remote Protocol :: WebDriver BiDi, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2023-03-13T14:09:43.753Z] 14:09:43     INFO - PID 6620 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2023-03-13T14:09:43.759Z] 14:09:43     INFO - PID 6620 | JavaScript error: resource://services-settings/Utils.jsm, line 237: TypeError: NetworkError: Network request failed
[task 2023-03-13T14:09:52.437Z] 14:09:52     INFO - PID 6620 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.sys.mjs", 370))
[task 2023-03-13T14:10:06.123Z] 14:10:06     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/bidi/script/remove_preload_script/sandbox_tentative.py | expected OK
[task 2023-03-13T14:10:06.123Z] 14:10:06     INFO - TEST-INFO took 30011ms
[task 2023-03-13T14:10:11.140Z] 14:10:11     INFO - STDERR: Traceback (most recent call last):
[task 2023-03-13T14:10:11.144Z] 14:10:11     INFO - STDERR:   File "Z:\task_167871210245536\build\venv\lib\site-packages\mozprocess\processhandler.py", line 640, in _custom_wait
[task 2023-03-13T14:10:11.144Z] 14:10:11     INFO -     item = self._process_events.get(timeout=timeout)
[task 2023-03-13T14:10:11.144Z] 14:10:11     INFO - STDERR:   File "c:\mozilla-build\python3\lib\queue.py", line 179, in get
[task 2023-03-13T14:10:11.144Z] 14:10:11     INFO -     raise Empty
[task 2023-03-13T14:10:11.145Z] 14:10:11     INFO - STDERR: _queue.Empty
[task 2023-03-13T14:10:11.145Z] 14:10:11     INFO - STDERR: IO Completion Port unexpectedly closed
[task 2023-03-13T14:10:11.145Z] 14:10:11     INFO - Closing logging queue
[task 2023-03-13T14:10:11.146Z] 14:10:11     INFO - queue closed
[task 2023-03-13T14:10:11.148Z] 14:10:11    ERROR - Traceback (most recent call last):
[task 2023-03-13T14:10:11.148Z] 14:10:11    ERROR -   File "Z:\task_167871210245536\build\venv\lib\site-packages\mozprocess\processhandler.py", line 640, in _custom_wait
[task 2023-03-13T14:10:11.148Z] 14:10:11    ERROR -     item = self._process_events.get(timeout=timeout)
[task 2023-03-13T14:10:11.148Z] 14:10:11    ERROR -   File "c:\mozilla-build\python3\lib\queue.py", line 179, in get
[task 2023-03-13T14:10:11.148Z] 14:10:11    ERROR -     raise Empty
[task 2023-03-13T14:10:11.148Z] 14:10:11    ERROR - _queue.Empty
[task 2023-03-13T14:10:11.148Z] 14:10:11    ERROR - 
[task 2023-03-13T14:10:11.148Z] 14:10:11    ERROR - During handling of the above exception, another exception occurred:
[task 2023-03-13T14:10:11.148Z] 14:10:11    ERROR - 
[task 2023-03-13T14:10:11.148Z] 14:10:11    ERROR - Traceback (most recent call last):
[task 2023-03-13T14:10:11.148Z] 14:10:11    ERROR -   File "Z:\task_167871210245536\build\tests\web-platform\tests\tools\wptrunner\wptrunner\testrunner.py", line 379, in run
[task 2023-03-13T14:10:11.149Z] 14:10:11    ERROR -     new_state = f()
[task 2023-03-13T14:10:11.149Z] 14:10:11    ERROR -   File "Z:\task_167871210245536\build\tests\web-platform\tests\tools\wptrunner\wptrunner\testrunner.py", line 785, in restart_runner
[task 2023-03-13T14:10:11.149Z] 14:10:11    ERROR -     self.stop_runner(force=self.state.force_stop)
[task 2023-03-13T14:10:11.149Z] 14:10:11    ERROR -   File "Z:\task_167871210245536\build\tests\web-platform\tests\tools\wptrunner\wptrunner\testrunner.py", line 806, in stop_runner
[task 2023-03-13T14:10:11.149Z] 14:10:11    ERROR -     self.browser.stop(force=force)
[task 2023-03-13T14:10:11.149Z] 14:10:11    ERROR -   File "Z:\task_167871210245536\build\tests\web-platform\tests\tools\wptrunner\wptrunner\testrunner.py", line 238, in stop
[task 2023-03-13T14:10:11.149Z] 14:10:11    ERROR -     self.browser.stop(force=force)
[task 2023-03-13T14:10:11.149Z] 14:10:11    ERROR -   File "Z:\task_167871210245536\build\tests\web-platform\tests\tools\wptrunner\wptrunner\browsers\firefox.py", line 938, in stop
[task 2023-03-13T14:10:11.149Z] 14:10:11    ERROR -     super().stop(force=force)
[task 2023-03-13T14:10:11.149Z] 14:10:11    ERROR -   File "Z:\task_167871210245536\build\tests\web-platform\tests\tools\wptrunner\wptrunner\browsers\base.py", line 367, in stop
[task 2023-03-13T14:10:11.149Z] 14:10:11    ERROR -     kill_result = self._proc.kill(timeout=5)
[task 2023-03-13T14:10:11.149Z] 14:10:11    ERROR -   File "Z:\task_167871210245536\build\venv\lib\site-packages\mozprocess\processhandler.py", line 905, in kill
[task 2023-03-13T14:10:11.149Z] 14:10:11    ERROR -     self.proc.kill(sig=sig, timeout=timeout)
[task 2023-03-13T14:10:11.150Z] 14:10:11    ERROR -   File "Z:\task_167871210245536\build\venv\lib\site-packages\mozprocess\processhandler.py", line 264, in kill
[task 2023-03-13T14:10:11.150Z] 14:10:11    ERROR -     self.returncode = self.wait(timeout)
[task 2023-03-13T14:10:11.150Z] 14:10:11    ERROR -   File "Z:\task_167871210245536\build\venv\lib\site-packages\mozprocess\processhandler.py", line 285, in wait
[task 2023-03-13T14:10:11.150Z] 14:10:11    ERROR -     self.returncode = self._custom_wait(timeout=timeout)
[task 2023-03-13T14:10:11.150Z] 14:10:11    ERROR -   File "Z:\task_167871210245536\build\venv\lib\site-packages\mozprocess\processhandler.py", line 646, in _custom_wait
[task 2023-03-13T14:10:11.150Z] 14:10:11    ERROR -     raise OSError(
[task 2023-03-13T14:10:11.150Z] 14:10:11    ERROR - OSError: IO Completion Port failed to signal process shutdown
[task 2023-03-13T14:10:11.150Z] 14:10:11    ERROR - 
[task 2023-03-13T14:10:18.579Z] 14:10:18  WARNING - Command left in remote_queue during cleanup: 'stop', ()