Closed Bug 1611892 Opened 6 years ago Closed 6 years ago

Intermittent TC TEST-UNEXPECTED-TIMEOUT | /referrer-policy/4K-1/gen/top.meta/unset/img-tag/cross-http.no-redirect.http.html | Executor hit external timeout (this may indicate a hang)

Categories

(Core :: DOM: Security, defect, P5)

Product:
Core
Component:
DOM: Security
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, Whiteboard: [domsecurity-intermittent])

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=286694813&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/S8zS8TQATMGRDOFWn-xS-g/runs/0/artifacts/public/logs/live_backing.log

[task 2020-01-28T05:46:42.457Z] 05:46:42 INFO - TEST-START | /referrer-policy/4K-1/gen/top.meta/unset/img-tag/cross-http.no-redirect.http.html
[task 2020-01-28T05:46:42.495Z] 05:46:42 INFO - PID 3988 | [CodeCoverage] Requested flush for 740.
[task 2020-01-28T05:46:44.141Z] 05:46:44 INFO - PID 3988 | [CodeCoverage] flush completed.
[task 2020-01-28T05:46:44.405Z] 05:46:44 INFO - PID 3988 | [CodeCoverage] JS flush completed.
[task 2020-01-28T05:46:44.405Z] 05:46:44 INFO - PID 3988 | [CodeCoverage] Requested flush for 6948.
[task 2020-01-28T05:46:44.405Z] 05:46:44 INFO - PID 3988 | [CodeCoverage] Requested flush for 10620.
[task 2020-01-28T05:46:44.405Z] 05:46:44 INFO - PID 3988 | [CodeCoverage] Requested flush for 4804.
[task 2020-01-28T05:46:48.585Z] 05:46:48 INFO - PID 3988 | [CodeCoverage] flush completed.
[task 2020-01-28T05:46:48.604Z] 05:46:48 INFO - PID 3988 | [CodeCoverage] JS flush completed.
[task 2020-01-28T05:46:52.367Z] 05:46:52 INFO - PID 3988 | [CodeCoverage] flush completed.
[task 2020-01-28T05:46:52.406Z] 05:46:52 INFO - PID 3988 | [CodeCoverage] JS flush completed.
[task 2020-01-28T05:46:56.139Z] 05:46:56 INFO - PID 3988 | [CodeCoverage] flush completed.
[task 2020-01-28T05:46:56.184Z] 05:46:56 INFO - PID 3988 | [CodeCoverage] JS flush completed.
[task 2020-01-28T05:46:57.452Z] 05:46:57 INFO - PID 3988 | [CodeCoverage] Requested flush for 740.
[task 2020-01-28T05:46:59.115Z] 05:46:59 INFO - PID 3988 | [CodeCoverage] flush completed.
[task 2020-01-28T05:46:59.337Z] 05:46:59 INFO - PID 3988 | [CodeCoverage] JS flush completed.
[task 2020-01-28T05:46:59.338Z] 05:46:59 INFO - PID 3988 | [CodeCoverage] Requested flush for 6948.
[task 2020-01-28T05:46:59.338Z] 05:46:59 INFO - PID 3988 | [CodeCoverage] Requested flush for 10620.
[task 2020-01-28T05:46:59.338Z] 05:46:59 INFO - PID 3988 | [CodeCoverage] Requested flush for 4804.
[task 2020-01-28T05:46:59.338Z] 05:46:59 INFO - PID 3988 | [CodeCoverage] Requested flush for 7256.
[task 2020-01-28T05:47:02.475Z] 05:47:02 INFO - TEST-UNEXPECTED-TIMEOUT | /referrer-policy/4K-1/gen/top.meta/unset/img-tag/cross-http.no-redirect.http.html | Executor hit external timeout (this may indicate a hang)
[task 2020-01-28T05:47:02.475Z] 05:47:02 INFO - File "c:\mozilla-build\python\Lib\threading.py", line 774, in __bootstrap
[task 2020-01-28T05:47:02.475Z] 05:47:02 INFO - self.__bootstrap_inner()
[task 2020-01-28T05:47:02.475Z] 05:47:02 INFO - File "c:\mozilla-build\python\Lib\threading.py", line 801, in __bootstrap_inner
[task 2020-01-28T05:47:02.475Z] 05:47:02 INFO - self.run()
[task 2020-01-28T05:47:02.475Z] 05:47:02 INFO - File "c:\mozilla-build\python\Lib\threading.py", line 754, in run
[task 2020-01-28T05:47:02.475Z] 05:47:02 INFO - self.__target(*self.__args, **self.__kwargs)
[task 2020-01-28T05:47:02.475Z] 05:47:02 INFO - File "Z:\task_1580187855\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 642, in run_func
[task 2020-01-28T05:47:02.475Z] 05:47:02 INFO - self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2020-01-28T05:47:02.475Z] 05:47:02 INFO - File "Z:\task_1580187855\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 762, in do_testharness
[task 2020-01-28T05:47:02.475Z] 05:47:02 INFO - self.protocol.coverage.dump()
[task 2020-01-28T05:47:02.475Z] 05:47:02 INFO - File "Z:\task_1580187855\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 484, in dump
[task 2020-01-28T05:47:02.476Z] 05:47:02 INFO - error = self.marionette.execute_async_script(script)
[task 2020-01-28T05:47:02.476Z] 05:47:02 INFO - File "Z:\task_1580187855\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1656, in execute_async_script
[task 2020-01-28T05:47:02.476Z] 05:47:02 INFO - rv = self._send_message("WebDriver:ExecuteAsyncScript", body, key="value")
[task 2020-01-28T05:47:02.476Z] 05:47:02 INFO - File "Z:\task_1580187855\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2020-01-28T05:47:02.476Z] 05:47:02 INFO - return func(*args, **kwargs)
[task 2020-01-28T05:47:02.476Z] 05:47:02 INFO - File "Z:\task_1580187855\build\venv\lib\site-packages\marionette_driver\marionette.py", line 590, in _send_message
[task 2020-01-28T05:47:02.476Z] 05:47:02 INFO - msg = self.client.request(name, params)
[task 2020-01-28T05:47:02.476Z] 05:47:02 INFO - File "Z:\task_1580187855\build\venv\lib\site-packages\marionette_driver\transport.py", line 273, in request
[task 2020-01-28T05:47:02.476Z] 05:47:02 INFO - return self.receive()
[task 2020-01-28T05:47:02.476Z] 05:47:02 INFO - File "Z:\task_1580187855\build\venv\lib\site-packages\marionette_driver\transport.py", line 153, in receive
[task 2020-01-28T05:47:02.476Z] 05:47:02 INFO - chunk = self._sock.recv(bytes_to_recv)
[task 2020-01-28T05:47:02.476Z] 05:47:02 INFO - TEST-INFO took 20021ms
[task 2020-01-28T05:47:02.476Z] 05:47:02 INFO - No more tests
[task 2020-01-28T05:47:03.418Z] 05:47:03 INFO - PID 3988 | [CodeCoverage] flush completed.
[task 2020-01-28T05:47:03.448Z] 05:47:03 INFO - PID 3988 | [CodeCoverage] JS flush completed.
[task 2020-01-28T05:47:07.346Z] 05:47:07 INFO - PID 3988 | [CodeCoverage] flush completed.
[task 2020-01-28T05:47:07.371Z] 05:47:07 INFO - PID 3988 | [CodeCoverage] JS flush completed.
[task 2020-01-28T05:47:11.126Z] 05:47:11 INFO - PID 3988 | [CodeCoverage] flush completed.
[task 2020-01-28T05:47:11.168Z] 05:47:11 INFO - PID 3988 | [CodeCoverage] JS flush completed.
[task 2020-01-28T05:47:14.892Z] 05:47:14 INFO - PID 3988 | [CodeCoverage] flush completed.
[task 2020-01-28T05:47:14.911Z] 05:47:14 INFO - PID 3988 | [CodeCoverage] JS flush completed.
[task 2020-01-28T05:48:13.597Z] 05:48:13 INFO - Browser exited with return code 572
[task 2020-01-28T05:48:13.598Z] 05:48:13 INFO - PROCESS LEAKS None
[task 2020-01-28T05:48:13.598Z] 05:48:13 INFO - PROCESS LEAKS None
[task 2020-01-28T05:48:13.598Z] 05:48:13 INFO - Closing logging queue
[task 2020-01-28T05:48:13.598Z] 05:48:13 WARNING - Traceback (most recent call last):
[task 2020-01-28T05:48:13.598Z] 05:48:13 WARNING - File "Z:\task_1580187855\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 642, in run_func
[task 2020-01-28T05:48:13.598Z] 05:48:13 WARNING - self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2020-01-28T05:48:13.598Z] 05:48:13 WARNING - File "Z:\task_1580187855\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 762, in do_testharness
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - self.protocol.coverage.dump()
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - File "Z:\task_1580187855\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 489, in dump
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - pass
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - File "c:\mozilla-build\python\Lib\contextlib.py", line 35, in exit
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - self.gen.throw(type, value, traceback)
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - File "Z:\task_1580187855\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1332, in using_context
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - self.set_context(scope)
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - File "Z:\task_1580187855\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1309, in set_context
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - {"value": context})
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - File "Z:\task_1580187855\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - return func(*args, **kwargs)
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - File "Z:\task_1580187855\build\venv\lib\site-packages\marionette_driver\marionette.py", line 590, in _send_message
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - msg = self.client.request(name, params)
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - File "Z:\task_1580187855\build\venv\lib\site-packages\marionette_driver\transport.py", line 273, in request
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - return self.receive()
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - File "Z:\task_1580187855\build\venv\lib\site-packages\marionette_driver\transport.py", line 166, in receive
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - if len(remaining) == int(length):
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING - ValueError: invalid literal for int() with base 10: 'script timeout","message"'
[task 2020-01-28T05:48:13.599Z] 05:48:13 WARNING -
[task 2020-01-28T05:48:13.600Z] 05:48:13 INFO - queue closed
[task 2020-01-28T05:48:13.600Z] 05:48:13 INFO - PROCESS LEAKS None
[task 2020-01-28T05:48:13.600Z] 05:48:13 INFO - Got 1 unexpected results
[task 2020-01-28T05:48:13.600Z] 05:48:13 INFO - SUITE-END | took 100s
[task 2020-01-28T05:48:13.629Z] 05:48:13 INFO - Removed font: Ahem.ttf
[task 2020-01-28T05:48:13.648Z] 05:48:13 INFO - Closing logging queue
[task 2020-01-28T05:48:13.648Z] 05:48:13 INFO - queue closed
[task 2020-01-28T05:48:13.937Z] 05:48:13 ERROR - Return code: 1
[task 2020-01-28T05:48:13.976Z] Error: No objdir path for moz-extension://17c634d5-07f9-401e-ac17-245d436e4328/build/buildSettings.js.
[task 2020-01-28T05:48:13.976Z] Couldn't find source info for moz-extension://17c634d5-07f9-401e-ac17-245d436e4328/build/buildSettings.js, removing record

Whiteboard: [domsecurity-intermittent]

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

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.