Closed Bug 1709520 Opened 4 years ago Closed 3 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /referrer-policy/css-integration/svg/processing-instruction.html | Styling SVG from ProcessingInstruction mask - Test timed out

Categories

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

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

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

Filed by: dluca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=338772927&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/E8rlJkPLQWuUk-CQlSa-hQ/runs/0/artifacts/public/logs/live_backing.log


INFO - TEST-START | /pointerevents/pointerevent_movementxy.html?touch
[task 2021-05-05T03:32:08.896Z] 03:32:08     INFO - Closing window 76
[task 2021-05-05T03:32:09.766Z] 03:32:09 CRITICAL - Test harness output was not a valid structured log message: 
[task 2021-05-05T03:32:09.767Z] 03:32:09 CRITICAL - Traceback (most recent call last):
[task 2021-05-05T03:32:09.767Z] 03:32:09 CRITICAL -   File "Z:\task_1620184841\build\tests\web-platform\tests\tools\wptserve\wptserve\handlers.py", line 362, in __call__
[task 2021-05-05T03:32:09.767Z] 03:32:09 CRITICAL -     rv = self.func(request, response)
[task 2021-05-05T03:32:09.768Z] 03:32:09 CRITICAL -   File "Z:\task_1620184841\build\tests\web-platform\tests\common/security-features/subresource/svg.py", line 37, in main
[task 2021-05-05T03:32:09.768Z] 03:32:09 CRITICAL -     content_type = content_type)
[task 2021-05-05T03:32:09.768Z] 03:32:09 CRITICAL -   File "Z:\task_1620184841\build\tests\web-platform\tests\common\security-features\subresource\subresource.py", line 199, in respond
[task 2021-05-05T03:32:09.768Z] 03:32:09 CRITICAL -     response.writer.write(payload)
[task 2021-05-05T03:32:09.769Z] 03:32:09 CRITICAL -   File "Z:\task_1620184841\build\tests\web-platform\tests\tools\wptserve\wptserve\response.py", line 777, in write
[task 2021-05-05T03:32:09.769Z] 03:32:09 CRITICAL -     self._wfile.write(self.encode(data))
[task 2021-05-05T03:32:09.769Z] 03:32:09 CRITICAL -   File "Z:\task_1620184841\build\tests\web-platform\tests\tools\wptserve\wptserve\response.py", line 807, in encode
[task 2021-05-05T03:32:09.770Z] 03:32:09 CRITICAL -     raise ValueError("data %r should be text or binary, but is %s" % (data, type(data)))
[task 2021-05-05T03:32:09.770Z] 03:32:09 CRITICAL - ValueError: data None should be text or binary, but is <class 'NoneType'>
[task 2021-05-05T03:32:09.784Z] 03:32:09     INFO - 
[task 2021-05-05T03:32:09.785Z] 03:32:09     INFO - TEST-PASS | /referrer-policy/css-integration/svg/processing-instruction.html | Styling SVG from ProcessingInstruction fill 
[task 2021-05-05T03:32:09.785Z] 03:32:09     INFO - TEST-PASS | /referrer-policy/css-integration/svg/processing-instruction.html | Styling SVG from ProcessingInstruction stroke 
[task 2021-05-05T03:32:09.785Z] 03:32:09     INFO - TEST-PASS | /referrer-policy/css-integration/svg/processing-instruction.html | Styling SVG from ProcessingInstruction filter 
[task 2021-05-05T03:32:09.785Z] 03:32:09     INFO - TEST-PASS | /referrer-policy/css-integration/svg/processing-instruction.html | Styling SVG from ProcessingInstruction clip-path 
[task 2021-05-05T03:32:09.785Z] 03:32:09     INFO - TEST-PASS | /referrer-policy/css-integration/svg/processing-instruction.html | Styling SVG from ProcessingInstruction marker-start 
[task 2021-05-05T03:32:09.785Z] 03:32:09     INFO - TEST-PASS | /referrer-policy/css-integration/svg/processing-instruction.html | Styling SVG from ProcessingInstruction marker-mid 
[task 2021-05-05T03:32:09.785Z] 03:32:09     INFO - TEST-PASS | /referrer-policy/css-integration/svg/processing-instruction.html | Styling SVG from ProcessingInstruction marker-end 
[task 2021-05-05T03:32:09.786Z] 03:32:09     INFO - TEST-UNEXPECTED-TIMEOUT | /referrer-policy/css-integration/svg/processing-instruction.html | Styling SVG from ProcessingInstruction mask - Test timed out
[task 2021-05-05T03:32:09.786Z] 03:32:09     INFO - 
[task 2021-05-05T03:32:09.786Z] 03:32:09     INFO - TEST-UNEXPECTED-NOTRUN | /referrer-policy/css-integration/svg/processing-instruction.html | Styling SVG from ProcessingInstruction mask-image - expected PASS
[task 2021-05-05T03:32:09.787Z] 03:32:09     INFO - TEST-UNEXPECTED-TIMEOUT | /referrer-policy/css-integration/svg/processing-instruction.html | expected OK
[task 2021-05-05T03:32:09.787Z] 03:32:09     INFO - TEST-INFO took 10236ms
[task 2021-05-05T03:32:09.787Z] 03:32:09     INFO - Restarting browser for new test group
[task 2021-05-05T03:32:09.789Z] 03:32:09     INFO - PID 10684 | 1620185529789	Marionette	INFO	Stopped listening on port 56651
[task 2021-05-05T03:32:09.818Z] 03:32:09     INFO - PID 10684 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 575: NotFoundError: No such JSWindowActor 'MarionetteEvents'
[task 2021-05-05T03:32:09.829Z] 03:32:09     INFO - {'actions': [{'type': 'none', 'actions': [{'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}], 'id': '0'}, {'type': 'pointer', 'actions': [{'type': 'pointerMove', 'x': 0, 'y': 0, 'origin': {'element-6066-11e4-a52e-4f735466cecf': 'e8a10542-55c8-46c3-8576-00addc7ff588', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': 'e8a10542-55c8-46c3-8576-00addc7ff588'}}, {'type': 'pointerDown', 'button': 0}, {'type': 'pointerMove', 'x': 20, 'y': 30, 'origin': {'element-6066-11e4-a52e-4f735466cecf': 'e8a10542-55c8-46c3-8576-00addc7ff588', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': 'e8a10542-55c8-46c3-8576-00addc7ff588'}}, {'type': 'pointerMove', 'x': 50, 'y': 40, 'origin': {'element-6066-11e4-a52e-4f735466cecf': 'e8a10542-55c8-46c3-8576-00addc7ff588', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': 'e8a10542-55c8-46c3-8576-00addc7ff588'}}, {'type': 'pointerMove', 'x': 80, 'y': 30, 'origin': {'element-6066-11e4-a52e-4f735466cecf': 'e8a10542-55c8-46c3-8576-00addc7ff588', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': 'e8a10542-55c8-46c3-8576-00addc7ff588'}}, {'type': 'pointerMove', 'x': 110, 'y': 20, 'origin': {'element-6066-11e4-a52e-4f735466cecf': 'e8a10542-55c8-46c3-8576-00addc7ff588', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': 'e8a10542-55c8-46c3-8576-00addc7ff588'}}, {'type': 'pointerMove', 'x': 0, 'y': 0, 'origin': {'element-6066-11e4-a52e-4f735466cecf': '1b9d441a-15b9-4f8b-b0bc-d8dcfec9d189', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': '1b9d441a-15b9-4f8b-b0bc-d8dcfec9d189'}}, {'type': 'pointerUp', 'button': 0}], 'parameters': {'pointerType': 'touch'}, 'id': 'touchPointer1'}]}
[task 2021-05-05T03:32:09.832Z] 03:32:09  WARNING - Action action_sequence failed
[task 2021-05-05T03:32:09.835Z] 03:32:09  WARNING - Traceback (most recent call last):
[task 2021-05-05T03:32:09.835Z] 03:32:09  WARNING -   File "Z:\task_1620184841\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\base.py", line 803, in process_action
[task 2021-05-05T03:32:09.835Z] 03:32:09  WARNING -     result = action_handler(payload)
[task 2021-05-05T03:32:09.835Z] 03:32:09  WARNING -   File "Z:\task_1620184841\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\actions.py", line 59, in __call__
[task 2021-05-05T03:32:09.835Z] 03:32:09  WARNING -     self.protocol.action_sequence.send_actions({"actions": actions})
[task 2021-05-05T03:32:09.835Z] 03:32:09  WARNING -   File "Z:\task_1620184841\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 465, in send_actions
[task 2021-05-05T03:32:09.835Z] 03:32:09  WARNING -     self.marionette._send_message("WebDriver:PerformActions", actions)
[task 2021-05-05T03:32:09.835Z] 03:32:09  WARNING -   File "Z:\task_1620184841\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2021-05-05T03:32:09.835Z] 03:32:09  WARNING -     return func(*args, **kwargs)
[task 2021-05-05T03:32:09.835Z] 03:32:09  WARNING -   File "Z:\task_1620184841\build\venv\lib\site-packages\marionette_driver\marionette.py", line 629, in _send_message
[task 2021-05-05T03:32:09.835Z] 03:32:09  WARNING -     self._handle_error(err)
[task 2021-05-05T03:32:09.835Z] 03:32:09  WARNING -   File "Z:\task_1620184841\build\venv\lib\site-packages\marionette_driver\marionette.py", line 651, in _handle_error
[task 2021-05-05T03:32:09.835Z] 03:32:09  WARNING -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2021-05-05T03:32:09.836Z] 03:32:09  WARNING - marionette_driver.errors.InvalidArgumentException: Unknown pointerType: [object String] "touch"
[task 2021-05-05T03:32:09.836Z] 03:32:09  WARNING - stacktrace:
[task 2021-05-05T03:32:09.836Z] 03:32:09  WARNING - 	WebDriverError@chrome://marionette/content/error.js:181:5
[task 2021-05-05T03:32:09.836Z] 03:32:09  WARNING - 	InvalidArgumentError@chrome://marionette/content/error.js:310:5
[task 2021-05-05T03:32:09.836Z] 03:32:09  WARNING - 	assert.that/<@chrome://marionette/content/assert.js:460:13
[task 2021-05-05T03:32:09.836Z] 03:32:09  WARNING - 	assert.in@chrome://marionette/content/assert.js:413:47
[task 2021-05-05T03:32:09.836Z] 03:32:09  WARNING - 	action.PointerType.get@chrome://marionette/content/action.js:402:10
[task 2021-05-05T03:32:09.836Z] 03:32:09  WARNING - 	Pointer@chrome://marionette/content/action.js:588:39
[task 2021-05-05T03:32:09.836Z] 03:32:09  WARNING - 	fromJSON@chrome://marionette/content/action.js:476:14
[task 2021-05-05T03:32:09.836Z] 03:32:09  WARNING - 	fromJSON@chrome://marionette/content/action.js:842:39
[task 2021-05-05T03:32:09.836Z] 03:32:09  WARNING - 	fromJSON@chrome://marionette/content/action.js:805:48
[task 2021-05-05T03:32:09.836Z] 03:32:09  WARNING - 	performActions@chrome://marionette/content/actors/MarionetteCommandsChild.jsm:429:20
[task 2021-05-05T03:32:09.836Z] 03:32:09  WARNING - 	receiveMessage@chrome://marionette/content/actors/MarionetteCommandsChild.jsm:133:31
[task 2021-05-05T03:32:09.836Z] 03:32:09  WARNING - 
[task 2021-05-05T03:32:09.836Z] 03:32:09  WARNING - 
[task 2021-05-05T03:32:09.837Z] 03:32:09     INFO - PID 432 | JavaScript error: , line 0: uncaught exception: error: undefined
[task 2021-05-05T03:32:09.840Z] 03:32:09  WARNING - Traceback (most recent call last):
[task 2021-05-05T03:32:09.840Z] 03:32:09  WARNING -   File "Z:\task_1620184841\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 791, in run_func
[task 2021-05-05T03:32:09.840Z] 03:32:09  WARNING -     self.result = True, self.func(self.protocol, self.url, self.timeout)
[task 2021-05-05T03:32:09.840Z] 03:32:09  WARNING -   File "Z:\task_1620184841\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 919, in do_testharness
[task 2021-05-05T03:32:09.840Z] 03:32:09  WARNING -     done, rv = handler(result)
[task 2021-05-05T03:32:09.841Z] 03:32:09  WARNING -   File "Z:\task_1620184841\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\base.py", line 787, in __call__
[task 2021-05-05T03:32:09.841Z] 03:32:09  WARNING -     return callback(url, payload)
[task 2021-05-05T03:32:09.841Z] 03:32:09  WARNING -   File "Z:\task_1620184841\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\base.py", line 803, in process_action
[task 2021-05-05T03:32:09.841Z] 03:32:09  WARNING -     result = action_handler(payload)
[task 2021-05-05T03:32:09.841Z] 03:32:09  WARNING -   File "Z:\task_1620184841\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\actions.py", line 59, in __call__
[task 2021-05-05T03:32:09.841Z] 03:32:09  WARNING -     self.protocol.action_sequence.send_actions({"actions": actions})
[task 2021-05-05T03:32:09.841Z] 03:32:09  WARNING -   File "Z:\task_1620184841\build\tests\web-platform\tests\tools\wptrunner\wptrunner\executors\executormarionette.py", line 465, in send_actions
[task 2021-05-05T03:32:09.841Z] 03:32:09  WARNING -     self.marionette._send_message("WebDriver:PerformActions", actions)
[task 2021-05-05T03:32:09.841Z] 03:32:09  WARNING -   File "Z:\task_1620184841\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2021-05-05T03:32:09.841Z] 03:32:09  WARNING -     return func(*args, **kwargs)
[task 2021-05-05T03:32:09.841Z] 03:32:09  WARNING -   File "Z:\task_1620184841\build\venv\lib\site-packages\marionette_driver\marionette.py", line 629, in _send_message
[task 2021-05-05T03:32:09.841Z] 03:32:09  WARNING -     self._handle_error(err)
[task 2021-05-05T03:32:09.841Z] 03:32:09  WARNING -   File "Z:\task_1620184841\build\venv\lib\site-packages\marionette_driver\marionette.py", line 651, in _handle_error
[task 2021-05-05T03:32:09.842Z] 03:32:09  WARNING -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2021-05-05T03:32:09.842Z] 03:32:09  WARNING - marionette_driver.errors.InvalidArgumentException: Unknown pointerType: [object String] "touch"
[task 2021-05-05T03:32:09.842Z] 03:32:09  WARNING - stacktrace:
[task 2021-05-05T03:32:09.844Z] 03:32:09  WARNING - 	WebDriverError@chrome://marionette/content/error.js:181:5
[task 2021-05-05T03:32:09.844Z] 03:32:09  WARNING - 	InvalidArgumentError@chrome://marionette/content/error.js:310:5
[task 2021-05-05T03:32:09.844Z] 03:32:09  WARNING - 	assert.that/<@chrome://marionette/content/assert.js:460:13
[task 2021-05-05T03:32:09.844Z] 03:32:09  WARNING - 	assert.in@chrome://marionette/content/assert.js:413:47
[task 2021-05-05T03:32:09.845Z] 03:32:09  WARNING - 	action.PointerType.get@chrome://marionette/content/action.js:402:10
[task 2021-05-05T03:32:09.845Z] 03:32:09  WARNING - 	Pointer@chrome://marionette/content/action.js:588:39
[task 2021-05-05T03:32:09.845Z] 03:32:09  WARNING - 	fromJSON@chrome://marionette/content/action.js:476:14
[task 2021-05-05T03:32:09.845Z] 03:32:09  WARNING - 	fromJSON@chrome://marionette/content/action.js:842:39
[task 2021-05-05T03:32:09.845Z] 03:32:09  WARNING - 	fromJSON@chrome://marionette/content/action.js:805:48
[task 2021-05-05T03:32:09.845Z] 03:32:09  WARNING - 	performActions@chrome://marionette/content/actors/MarionetteCommandsChild.jsm:429:20
[task 2021-05-05T03:32:09.845Z] 03:32:09  WARNING - 	receiveMessage@chrome://marionette/content/actors/MarionetteCommandsChild.jsm:133:31
[task 2021-05-05T03:32:09.845Z] 03:32:09  WARNING - 
[task 2021-05-05T03:32:09.845Z] 03:32:09  WARNING - 
[task 2021-05-05T03:32:09.847Z] 03:32:09     INFO - TEST-ERROR | /pointerevents/pointerevent_movementxy.html?touch | took 945ms
[task 2021-05-05T03:32:09.849Z] 03:32:09     INFO - PID 432 | 1620185529842	Marionette	INFO	Stopped listening on port 57173
[task 2021-05-05T03:32:10.009Z] 03:32:10     INFO - PID 10684 | [Parent 2956, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2021-05-05T03:32:11.140Z] 03:32:11     INFO - PID 10684 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-05-05T03:32:11.222Z] 03:32:11     INFO - Browser exited with return code 0
[task 2021-05-05T03:32:11.223Z] 03:32:11     INFO - PROCESS LEAKS None
[task 2021-05-05T03:32:11.225Z] 03:32:11     INFO - Closing logging queue
[task 2021-05-05T03:32:11.225Z] 03:32:11     INFO - queue closed
[task 2021-05-05T03:32:11.239Z] 03:32:11     INFO - Browser exited with return code 0
[task 2021-05-05T03:32:11.240Z] 03:32:11     INFO - PROCESS LEAKS None
[task 2021-05-05T03:32:11.242Z] 03:32:11     INFO - Closing logging queue
[task 2021-05-05T03:32:11.242Z] 03:32:11     INFO - queue closed
[task 2021-05-05T03:32:11.311Z] 03:32:11     INFO - Application command: Z:\task_1620184841\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_1620184841\AppData\Local\Temp\tmps9of_p6y
[task 2021-05-05T03:32:11.314Z] 03:32:11     INFO - Starting runner
[task 2021-05-05T03:32:11.325Z] 03:32:11     INFO - Application command: Z:\task_1620184841\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_1620184841\AppData\Local\Temp\tmps_dazwah
[task 2021-05-05T03:32:11.327Z] 03:32:11     INFO - Starting runner
[task 2021-05-05T03:32:12.543Z] 03:32:12     INFO - PID 9608 | 1620185532542	Marionette	INFO	Marionette enabled
[task 2021-05-05T03:32:12.559Z] 03:32:12     INFO - PID 5868 | 1620185532558	Marionette	INFO	Marionette enabled
[task 2021-05-05T03:32:13.590Z] 03:32:13     INFO - PID 5868 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_1620184841\\AppData\\Local\\Temp\\tmps_dazwah\\search.json.mozlz4", (void 0)))
[task 2021-05-05T03:32:13.600Z] 03:32:13     INFO - PID 9608 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_1620184841\\AppData\\Local\\Temp\\tmps9of_p6y\\search.json.mozlz4", (void 0)))
[task 2021-05-05T03:32:14.557Z] 03:32:14     INFO - PID 5868 | 1620185534556	Marionette	INFO	Listening on port 57255
[task 2021-05-05T03:32:15.563Z] 03:32:15     INFO - PID 9608 | 1620185535562	Marionette	INFO	Listening on port 57254
[task 2021-05-05T03:32:15.666Z] 03:32:15     INFO - TEST-START | /referrer-policy/generic/iframe-src-change.html```
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.