Closed Bug 1675271 Opened 5 years ago Closed 5 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /xhr/access-control-and-redirects-async-same-origin.any.html | TestRunner hit external timeout (this may indicate a hang)

Categories

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

Product:
Core
Component:
DOM: Networking
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

"INFO - TEST-START | /css/css-grid/alignment/grid-row-axis-alignment-positioned-items-014.html
[task 2020-11-04T11:11:44.657Z] 11:11:44     INFO - Closing window 298
[task 2020-11-04T11:12:18.062Z] 11:12:18     INFO - Got timeout in harness
[task 2020-11-04T11:12:18.063Z] 11:12:18     INFO - TEST-UNEXPECTED-TIMEOUT | /xhr/access-control-and-redirects-async-same-origin.any.html | TestRunner hit external timeout (this may indicate a hang)
[task 2020-11-04T11:12:18.063Z] 11:12:18     INFO - TEST-INFO took 35001ms
[task 2020-11-04T11:12:19.657Z] 11:12:19     INFO - Got timeout in harness
[task 2020-11-04T11:12:19.657Z] 11:12:19     INFO - TEST-UNEXPECTED-TIMEOUT | /css/css-grid/alignment/grid-row-axis-alignment-positioned-items-014.html | TestRunner hit external timeout (this may indicate a hang)
[task 2020-11-04T11:12:19.657Z] 11:12:19     INFO - TEST-INFO took 35002ms
[task 2020-11-04T11:13:29.111Z] 11:13:29     INFO - Browser exited with return code -15
[task 2020-11-04T11:13:29.112Z] 11:13:29     INFO - PROCESS LEAKS None
[task 2020-11-04T11:13:29.116Z] 11:13:29     INFO - Browser not responding, setting status to CRASH
[task 2020-11-04T11:13:29.116Z] 11:13:29  WARNING - Command left in command_queue during cleanup: 'test_ended', (<wptrunner.wpttest.TestharnessTest /xhr/access-control-and-redirects-async-same-origin.any.html>, (<wptrunner.wpttest.TestharnessResult CRASH>, []))
[task 2020-11-04T11:13:29.117Z] 11:13:29     INFO - Closing logging queue
[task 2020-11-04T11:13:29.117Z] 11:13:29     INFO - IOError on command, setting status to CRASH
[task 2020-11-04T11:13:29.117Z] 11:13:29     INFO - queue closed
[task 2020-11-04T11:13:29.146Z] 11:13:29     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpmi5Gss
[task 2020-11-04T11:13:29.174Z] 11:13:29     INFO - Starting runner
[task 2020-11-04T11:13:29.257Z] 11:13:29     INFO - PID 4868 | [CodeCoverage] Setting handlers for process 4868.
[task 2020-11-04T11:13:29.635Z] 11:13:29     INFO - PID 2612 | [CodeCoverage] Setting handlers for process 4861.
[task 2020-11-04T11:13:29.699Z] 11:13:29     INFO - PID 2612 | [CodeCoverage] Setting handlers for process 4793.
[task 2020-11-04T11:13:30.694Z] 11:13:30     INFO - Browser exited with return code -15
[task 2020-11-04T11:13:30.695Z] 11:13:30     INFO - PROCESS LEAKS None
[task 2020-11-04T11:13:30.695Z] 11:13:30     INFO - Browser not responding, setting status to CRASH
[task 2020-11-04T11:13:30.696Z] 11:13:30  WARNING - Command left in command_queue during cleanup: 'test_ended', (<wptrunner.wpttest.TestharnessTest /css/css-grid/alignment/grid-row-axis-alignment-positioned-items-014.html>, (<wptrunner.wpttest.TestharnessResult CRASH>, []))
[task 2020-11-04T11:13:30.697Z] 11:13:30  WARNING - Traceback (most recent call last):
[task 2020-11-04T11:13:30.697Z] 11:13:30  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 732, in run_func
[task 2020-11-04T11:13:30.697Z] 11:13:30  WARNING -     self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2020-11-04T11:13:30.697Z] 11:13:30  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 845, in do_testharness
[task 2020-11-04T11:13:30.697Z] 11:13:30  WARNING -     self.protocol.base.set_window(test_window)
[task 2020-11-04T11:13:30.697Z] 11:13:30  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 101, in set_window
[task 2020-11-04T11:13:30.697Z] 11:13:30  WARNING -     _switch_to_window(self.marionette, handle)
[task 2020-11-04T11:13:30.698Z] 11:13:30  WARNING -   File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 70, in _switch_to_window
[task 2020-11-04T11:13:30.698Z] 11:13:30  WARNING -     {"handle": handle, "name": handle, "focus": True})
[task 2020-11-04T11:13:30.698Z] 11:13:30  WARNING -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 27, in _
[task 2020-11-04T11:13:30.698Z] 11:13:30  WARNING -     return func(*args, **kwargs)
[task 2020-11-04T11:13:30.698Z] 11:13:30  WARNING -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 617, in _send_message
[task 2020-11-04T11:13:30.698Z] 11:13:30  WARNING -     msg = self.client.request(name, params)
[task 2020-11-04T11:13:30.698Z] 11:13:30  WARNING -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/transport.py", line 288, in request
[task 2020-11-04T11:13:30.698Z] 11:13:30  WARNING -     return self.receive()
[task 2020-11-04T11:13:30.698Z] 11:13:30  WARNING -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/transport.py", line 174, in receive
[task 2020-11-04T11:13:30.698Z] 11:13:30  WARNING -     if len(remaining) == int(length):
[task 2020-11-04T11:13:30.698Z] 11:13:30  WARNING - ValueError: invalid literal for int() with base 10: ',null,{"va,null,{"value"'
[task 2020-11-04T11:13:30.698Z] 11:13:30  WARNING - 
[task 2020-11-04T11:13:30.698Z] 11:13:30     INFO - Closing logging queue
[task 2020-11-04T11:13:30.699Z] 11:13:30     INFO - queue closed
[task 2020-11-04T11:13:30.720Z] 11:13:30     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpp6dcSn
[task 2020-11-04T11:13:30.747Z] 11:13:30     INFO - Starting runner
[task 2020-11-04T11:13:30.808Z] 11:13:30     INFO - PID 4964 | [CodeCoverage] Setting handlers for process 4964.
[task 2020-11-04T11:13:33.663Z] 11:13:33     INFO - PID 4868 | [CodeCoverage] Setting handlers for process 5041.
[task 2020-11-04T11:13:34.061Z] 11:13:34     INFO - PID 4868 | [CodeCoverage] Setting handlers for process 5052.
[task 2020-11-04T11:13:35.096Z] 11:13:35     INFO - PID 4964 | [CodeCoverage] Setting handlers for process 5104.
[task 2020-11-04T11:13:35.124Z] 11:13:35     INFO - PID 4868 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new Error("", "(unknown module)"))
[task 2020-11-04T11:13:35.562Z] 11:13:35     INFO - PID 4964 | [CodeCoverage] Setting handlers for process 5112.
[task 2020-11-04T11:13:36.727Z] 11:13:36     INFO - PID 4964 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new Error("", "(unknown module)"))
[task 2020-11-04T11:13:38.430Z] 11:13:38     INFO - PID 4868 | [CodeCoverage] Setting handlers for process 5209.
[task 2020-11-04T11:13:38.628Z] 11:13:38     INFO - PID 4868 | 1604488418620	Marionette	INFO	Listening on port 40027
[task 2020-11-04T11:13:39.372Z] 11:13:39     INFO - TEST-START | /xhr/access-control-and-redirects-async.any.html"```
Component: web-platform-tests → DOM: Networking
Product: Testing → Core

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.