Closed Bug 1939570 Opened 2 months ago Closed 17 days ago

Intermittent [tier 2] TEST-UNEXPECTED-TIMEOUT | /svg/types/elements/SVGGeometryElement-rect.svg | TestRunner hit external timeout (this may indicate a hang)

Categories

(Core :: SVG, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2024-12-31T11:28:39.915Z] 11:28:39     INFO - TEST-START | /svg/types/elements/SVGGeometryElement-rect.svg
[task 2024-12-31T11:28:39.936Z] 11:28:39     INFO - Setting pref layout.css.transition-behavior.enabled to true
[task 2024-12-31T11:29:14.920Z] 11:29:14     INFO - Got timeout in harness
[task 2024-12-31T11:29:14.923Z] 11:29:14     INFO - TEST-UNEXPECTED-TIMEOUT | /svg/types/elements/SVGGeometryElement-rect.svg | TestRunner hit external timeout (this may indicate a hang)
[task 2024-12-31T11:29:14.923Z] 11:29:14     INFO - TEST-INFO took 35008ms
[task 2024-12-31T11:29:14.924Z] 11:29:14     INFO - Restarting browser for new test group
[task 2024-12-31T11:29:24.932Z] 11:29:24  WARNING - Forcibly terminating runner process
[task 2024-12-31T11:29:24.972Z] 11:29:24     INFO - Browser exited with return code -15
[task 2024-12-31T11:29:25.006Z] 11:29:25     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpb3h2p_rm
[task 2024-12-31T11:29:25.021Z] 11:29:25     INFO - PID 22528 | [CodeCoverage] Setting handlers for process 22528.
[task 2024-12-31T11:29:25.021Z] 11:29:25     INFO - PID 22528 | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-12-31T11:29:25.022Z] 11:29:25     INFO - PID 22528 | 1735644517617	Marionette	INFO	Marionette enabled
[task 2024-12-31T11:29:25.023Z] 11:29:25     INFO - PID 22528 | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-12-31T11:29:25.023Z] 11:29:25     INFO - PID 22528 | 1735644517808	Marionette	INFO	Listening on port 60473
[task 2024-12-31T11:29:25.024Z] 11:29:25     INFO - PID 22528 | [CodeCoverage] Setting handlers for process 22652.
[task 2024-12-31T11:29:25.025Z] 11:29:25     INFO - PID 22528 | [GFX1-]: Calling WaitFlushedEvent::Run: is delayed: 2068
[task 2024-12-31T11:29:25.025Z] 11:29:25     INFO - PID 22528 | console.error: ({})
[task 2024-12-31T11:29:25.026Z] 11:29:25     INFO - Starting runner
[task 2024-12-31T11:31:44.974Z] 11:31:44     INFO - STDERR: Exception in thread 
[task 2024-12-31T11:31:44.975Z] 11:31:44     INFO - STDERR: Thread-729
[task 2024-12-31T11:31:44.975Z] 11:31:44     INFO - STDERR: :
[task 2024-12-31T11:31:44.976Z] 11:31:44     INFO - STDERR: Traceback (most recent call last):
[task 2024-12-31T11:31:44.976Z] 11:31:44     INFO - STDERR:   File "/usr/lib/python3.8/threading.py", line 932, in _bootstrap_inner
[task 2024-12-31T11:31:44.976Z] 11:31:44     INFO - STDERR:     
[task 2024-12-31T11:31:44.976Z] 11:31:44     INFO - STDERR: self.run()
[task 2024-12-31T11:31:44.977Z] 11:31:44     INFO - STDERR:   File "/usr/lib/python3.8/threading.py", line 1254, in run
[task 2024-12-31T11:31:44.977Z] 11:31:44     INFO - STDERR:     
[task 2024-12-31T11:31:44.978Z] 11:31:44     INFO - STDERR: self.function(*self.args, **self.kwargs)
[task 2024-12-31T11:31:44.978Z] 11:31:44     INFO - STDERR:   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/testrunner.py", line 281, in init_timeout
[task 2024-12-31T11:31:44.980Z] 11:31:44     INFO - STDERR:     
[task 2024-12-31T11:31:44.980Z] 11:31:44     INFO - STDERR: self.send_message("init_failed")
[task 2024-12-31T11:31:44.980Z] 11:31:44     INFO - STDERR:   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/testrunner.py", line 275, in send_message
[task 2024-12-31T11:31:44.980Z] 11:31:44     INFO - STDERR:     
[task 2024-12-31T11:31:44.980Z] 11:31:44     INFO - STDERR: self.command_queue.put((command, args))
[task 2024-12-31T11:31:44.980Z] 11:31:44     INFO - STDERR:   File "/usr/lib/python3.8/multiprocessing/queues.py", line 82, in put
[task 2024-12-31T11:31:44.981Z] 11:31:44     INFO - STDERR:     
[task 2024-12-31T11:31:44.981Z] 11:31:44     INFO - STDERR: raise ValueError(f"Queue {self!r} is closed")
[task 2024-12-31T11:31:44.981Z] 11:31:44     INFO - STDERR: ValueError
[task 2024-12-31T11:31:44.981Z] 11:31:44     INFO - STDERR: : 
[task 2024-12-31T11:31:44.981Z] 11:31:44     INFO - STDERR: Queue <multiprocessing.queues.Queue object at 0x7f3d794c6520> is closed
[task 2024-12-31T11:35:45.927Z] 11:35:45 CRITICAL - Loading initial page http://web-platform.test:8000/testharness_runner.html failed. Ensure that the there are no other programs bound to this port and that your firewall rules or network setup does not prevent access.
[task 2024-12-31T11:35:45.928Z] 11:35:45 CRITICAL - Traceback (most recent call last):
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 182, in load_runner
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 230, in dismiss_alert
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL -     f()
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 182, in <lambda>
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL -     self.dismiss_alert(lambda: self.marionette.navigate(url))
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 1709, in navigate
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL -     self._send_message("WebDriver:Navigate", {"url": url})
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/decorators.py", line 24, in _
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL -     return func(*args, **kwargs)
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 776, in _send_message
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL -     self._handle_error(err)
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL -   File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 788, in _handle_error
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL - marionette_driver.errors.TimeoutException: Navigation timed out after 300000 ms
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL - stacktrace:
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL - 	RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL - 	WebDriverError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:197:5
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL - 	TimeoutError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:771:5
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL - 	bail@chrome://remote/content/marionette/sync.sys.mjs:197:19
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL - 
[task 2024-12-31T11:35:45.929Z] 11:35:45 CRITICAL - 
[task 2024-12-31T11:35:45.932Z] 11:35:45     INFO - PID 22528 | 1735644945931	Marionette	INFO	Stopped listening on port 60473
[task 2024-12-31T11:35:46.130Z] 11:35:46     INFO - Browser exited with return code -15
[task 2024-12-31T11:35:46.131Z] 11:35:46     INFO - Closing logging queue
[task 2024-12-31T11:35:46.131Z] 11:35:46     INFO - queue closed
[task 2024-12-31T11:35:46.172Z] 11:35:46     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmph_i5h2go
[task 2024-12-31T11:35:46.227Z] 11:35:46     INFO - PID 22813 | [CodeCoverage] Setting handlers for process 22813.
[task 2024-12-31T11:35:46.228Z] 11:35:46     INFO - PID 22813 | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-12-31T11:35:46.228Z] 11:35:46     INFO - PID 22813 | 1735644565909	Marionette	INFO	Marionette enabled
[task 2024-12-31T11:35:46.229Z] 11:35:46     INFO - PID 22813 | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-12-31T11:35:46.229Z] 11:35:46     INFO - PID 22813 | 1735644566102	Marionette	INFO	Listening on port 49822
[task 2024-12-31T11:35:46.229Z] 11:35:46     INFO - PID 22813 | console.error: ({})
[task 2024-12-31T11:35:46.229Z] 11:35:46     INFO - Starting runner
[task 2024-12-31T11:35:47.740Z] 11:35:47     INFO - PID 22813 | [CodeCoverage] Setting handlers for process 22922.
[task 2024-12-31T11:35:47.768Z] 11:35:47     INFO - PID 22813 | [CodeCoverage] Setting handlers for process 22916.
[task 2024-12-31T11:35:49.498Z] 11:35:49     INFO - PID 22813 | [CodeCoverage] Setting handlers for process 23160.
[task 2024-12-31T11:35:49.991Z] 11:35:49     INFO - TEST-START | /uievents/textInput/api.html
Status: NEW → RESOLVED
Closed: 17 days ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.