Closed Bug 1655214 Opened 5 years ago Closed 5 years ago

Intermittent TCw3 TEST-UNEXPECTED-TIMEOUT | /origin-isolation/insecure-http.sub.html | TestRunner hit external timeout (this may indicate a hang)

Categories

(Testing :: web-platform-tests, defect, P5)

Product:
Testing
Component:
web-platform-tests
Type:
defect

Tracking

(firefox-esr68 unaffected, firefox-esr78 unaffected, firefox79 unaffected, firefox80 wontfix, firefox81 wontfix)

Status:
RESOLVED INCOMPLETE
Tracking Flags:
Tracking Status
firefox-esr68 --- unaffected
firefox-esr78 --- unaffected
firefox79 --- unaffected
firefox80 --- wontfix
firefox81 --- wontfix

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

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

[task 2020-07-24T23:41:42.401Z] 23:41:42 INFO - TEST-START | /origin-isolation/insecure-http.sub.html
[task 2020-07-24T23:41:42.442Z] 23:41:42 INFO - PID 5316 | [CodeCoverage] Requested flush for 5984.
[task 2020-07-24T23:41:44.064Z] 23:41:44 INFO - PID 5316 | [CodeCoverage] flush completed.
[task 2020-07-24T23:41:44.447Z] 23:41:44 INFO - PID 5316 | [CodeCoverage] JS flush completed.
[task 2020-07-24T23:41:44.447Z] 23:41:44 INFO - PID 5316 | [CodeCoverage] Requested flush for 8536.
[task 2020-07-24T23:41:44.447Z] 23:41:44 INFO - PID 5316 | [CodeCoverage] Requested flush for 9680.
[task 2020-07-24T23:41:44.447Z] 23:41:44 INFO - PID 5316 | [CodeCoverage] Requested flush for 4732.
[task 2020-07-24T23:41:44.447Z] 23:41:44 INFO - PID 5316 | [CodeCoverage] Requested flush for 7788.
[task 2020-07-24T23:41:44.448Z] 23:41:44 INFO - PID 5316 | [CodeCoverage] Requested flush for 6720.
[task 2020-07-24T23:41:49.992Z] 23:41:49 INFO - PID 5316 | [CodeCoverage] flush completed.
[task 2020-07-24T23:41:50.013Z] 23:41:50 INFO - PID 5316 | [CodeCoverage] JS flush completed.
[task 2020-07-24T23:41:55.436Z] 23:41:55 INFO - PID 5316 | [CodeCoverage] flush completed.
[task 2020-07-24T23:41:55.467Z] 23:41:55 INFO - PID 5316 | [CodeCoverage] JS flush completed.
[task 2020-07-24T23:42:01.196Z] 23:42:01 INFO - PID 5316 | [CodeCoverage] flush completed.
[task 2020-07-24T23:42:01.215Z] 23:42:01 INFO - PID 5316 | [CodeCoverage] JS flush completed.
[task 2020-07-24T23:42:06.681Z] 23:42:06 INFO - PID 5316 | [CodeCoverage] flush completed.
[task 2020-07-24T23:42:06.740Z] 23:42:06 INFO - PID 5316 | [CodeCoverage] JS flush completed.
[task 2020-07-24T23:42:08.166Z] 23:42:08 INFO - PID 5316 | [CodeCoverage] Requested flush for 5984.
[task 2020-07-24T23:42:12.438Z] 23:42:12 INFO - PID 5316 | [CodeCoverage] flush completed.
[task 2020-07-24T23:42:12.448Z] 23:42:12 INFO - PID 5316 | [CodeCoverage] JS flush completed.
[task 2020-07-24T23:42:17.406Z] 23:42:17 INFO - Got timeout in harness
[task 2020-07-24T23:42:17.416Z] 23:42:17 INFO - TEST-UNEXPECTED-TIMEOUT | /origin-isolation/insecure-http.sub.html | TestRunner hit external timeout (this may indicate a hang)
[task 2020-07-24T23:42:17.416Z] 23:42:17 INFO - TEST-INFO took 35014ms
[task 2020-07-24T23:42:17.416Z] 23:42:17 INFO - No more tests
[task 2020-07-24T23:42:17.920Z] 23:42:17 INFO - PID 5316 | [CodeCoverage] flush completed.
[task 2020-07-24T23:42:18.202Z] 23:42:18 INFO - PID 5316 | [CodeCoverage] JS flush completed.
[task 2020-07-24T23:42:18.202Z] 23:42:18 INFO - PID 5316 | [CodeCoverage] Requested flush for 4732.
[task 2020-07-24T23:42:18.202Z] 23:42:18 INFO - PID 5316 | [CodeCoverage] Requested flush for 8536.
[task 2020-07-24T23:42:18.202Z] 23:42:18 INFO - PID 5316 | [CodeCoverage] Requested flush for 9680.
[task 2020-07-24T23:42:18.202Z] 23:42:18 INFO - PID 5316 | [CodeCoverage] Requested flush for 7788.
[task 2020-07-24T23:42:18.203Z] 23:42:18 INFO - PID 5316 | [CodeCoverage] Requested flush for 6720.
[task 2020-07-24T23:42:20.929Z] 23:42:20 INFO - PID 5316 | [CodeCoverage] flush completed.
[task 2020-07-24T23:42:20.959Z] 23:42:20 INFO - PID 5316 | [CodeCoverage] JS flush completed.
[task 2020-07-24T23:42:26.372Z] 23:42:26 INFO - PID 5316 | [CodeCoverage] flush completed.
[task 2020-07-24T23:42:26.391Z] 23:42:26 INFO - PID 5316 | [CodeCoverage] JS flush completed.
[task 2020-07-24T23:42:31.824Z] 23:42:31 INFO - PID 5316 | [CodeCoverage] flush completed.
[task 2020-07-24T23:42:31.846Z] 23:42:31 INFO - PID 5316 | [CodeCoverage] JS flush completed.
[task 2020-07-24T23:42:37.250Z] 23:42:37 INFO - PID 5316 | [CodeCoverage] flush completed.
[task 2020-07-24T23:42:37.301Z] 23:42:37 INFO - PID 5316 | [CodeCoverage] JS flush completed.
[task 2020-07-24T23:42:42.836Z] 23:42:42 INFO - PID 5316 | [CodeCoverage] flush completed.
[task 2020-07-24T23:42:42.846Z] 23:42:42 INFO - PID 5316 | [CodeCoverage] JS flush completed.
[task 2020-07-24T23:43:28.540Z] 23:43:28 INFO - Browser exited with return code 572
[task 2020-07-24T23:43:28.541Z] 23:43:28 INFO - PROCESS LEAKS None
[task 2020-07-24T23:43:28.541Z] 23:43:28 INFO - Browser not responding, setting status to CRASH
[task 2020-07-24T23:43:28.541Z] 23:43:28 WARNING - Command left in command_queue during cleanup: 'test_ended', (<wptrunner.wpttest.TestharnessTest /origin-isolation/insecure-http.sub.html>, (<wptrunner.wpttest.TestharnessResult CRASH>, []))
[task 2020-07-24T23:43:28.541Z] 23:43:28 INFO - Closing logging queue
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - Traceback (most recent call last):
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - File "Z:\task_1595632524\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 711, in run_func
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - File "Z:\task_1595632524\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 833, in do_testharness
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - self.protocol.coverage.dump()
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - File "Z:\task_1595632524\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 507, in dump
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - pass
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - File "c:\mozilla-build\python\Lib\contextlib.py", line 35, in exit
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - self.gen.throw(type, value, traceback)
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - File "Z:\task_1595632524\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1337, in using_context
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - self.set_context(scope)
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - File "Z:\task_1595632524\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1314, in set_context
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - {"value": context})
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - File "Z:\task_1595632524\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - return func(*args, **kwargs)
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - File "Z:\task_1595632524\build\venv\lib\site-packages\marionette_driver\marionette.py", line 594, in _send_message
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - msg = self.client.request(name, params)
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - File "Z:\task_1595632524\build\venv\lib\site-packages\marionette_driver\transport.py", line 276, in request
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - return self.receive()
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - File "Z:\task_1595632524\build\venv\lib\site-packages\marionette_driver\transport.py", line 169, in receive
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - if len(remaining) == int(length):
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING - ValueError: invalid literal for int() with base 10: 'script timeout","mesed out after 25000 ms","stacktrace"'
[task 2020-07-24T23:43:28.542Z] 23:43:28 WARNING -
[task 2020-07-24T23:43:28.542Z] 23:43:28 INFO - queue closed
[task 2020-07-24T23:43:28.542Z] 23:43:28 INFO - PROCESS LEAKS None
[task 2020-07-24T23:43:28.628Z] 23:43:28 INFO - Got 1 unexpected results
[task 2020-07-24T23:43:28.628Z] 23:43:28 INFO - SUITE-END | took 116s
[task 2020-07-24T23:43:28.667Z] 23:43:28 INFO - Removed font: Ahem.ttf
[task 2020-07-24T23:43:28.685Z] 23:43:28 INFO - Closing logging queue
[task 2020-07-24T23:43:28.685Z] 23:43:28 INFO - queue closed
[task 2020-07-24T23:43:28.875Z] 23:43:28 ERROR - Return code: 1

Set release status flags based on info from the regressing bug 1652840

status-firefox79: --- → unaffected
status-firefox80: --- → affected
status-firefox81: --- → affected
status-firefox-esr68: --- → unaffected
status-firefox-esr78: --- → unaffected

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

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