Closed Bug 1616504 Opened 4 years ago Closed 4 years ago

Intermittent TEST-UNEXPECTED-CRASH | /webrtc/RTCPeerConnection-transceivers.https.html | expected OK

Categories

(Core :: WebRTC, defect, P5)

Product:
Core
Component:
WebRTC
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Depends on 1 open bug)

Details

(Keywords: intermittent-failure)

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

[task 2020-02-19T07:31:31.033Z] 07:31:31 INFO - TEST-START | /dom/events/scrolling/overscroll-event-fired-to-document.html
[task 2020-02-19T07:31:31.058Z] 07:31:31 INFO - Setting pref dom.w3c_touch_events.legacy_apis.enabled (true)
[task 2020-02-19T07:31:31.073Z] 07:31:31 INFO - Setting pref privacy.reduceTimerPrecision (false)
[task 2020-02-19T07:31:31.496Z] 07:31:31 INFO - {'actions': [{u'type': u'none', u'id': u'0', u'actions': [{u'duration': 16, u'type': u'pause'}, {u'duration': 16, u'type': u'pause'}, {u'duration': 16, u'type': u'pause'}, {u'duration': 16, u'type': u'pause'}, {u'duration': 16, u'type': u'pause'}, {u'duration': 16, u'type': u'pause'}, {u'duration': 100, u'type': u'pause'}, {u'duration': 16, u'type': u'pause'}]}, {u'type': u'pointer', u'actions': [{u'y': 0, u'x': 0, u'frame': {u'frame': u'window'}, u'type': u'pointerMove', u'origin': {'element-6066-11e4-a52e-4f735466cecf': u'dd906118-0713-40ba-82cc-bb2a1e100907', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': u'dd906118-0713-40ba-82cc-bb2a1e100907'}}, {u'button': 0, u'type': u'pointerDown'}, {u'y': 60, u'x': 0, u'frame': {u'frame': u'window'}, u'type': u'pointerMove', u'origin': {'element-6066-11e4-a52e-4f735466cecf': u'dd906118-0713-40ba-82cc-bb2a1e100907', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': u'dd906118-0713-40ba-82cc-bb2a1e100907'}}, {u'y': 120, u'x': 0, u'frame': {u'frame': u'window'}, u'type': u'pointerMove', u'origin': {'element-6066-11e4-a52e-4f735466cecf': u'dd906118-0713-40ba-82cc-bb2a1e100907', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': u'dd906118-0713-40ba-82cc-bb2a1e100907'}}, {u'y': 180, u'x': 0, u'frame': {u'frame': u'window'}, u'type': u'pointerMove', u'origin': {'element-6066-11e4-a52e-4f735466cecf': u'dd906118-0713-40ba-82cc-bb2a1e100907', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': u'dd906118-0713-40ba-82cc-bb2a1e100907'}}, {u'y': 240, u'x': 0, u'frame': {u'frame': u'window'}, u'type': u'pointerMove', u'origin': {'element-6066-11e4-a52e-4f735466cecf': u'dd906118-0713-40ba-82cc-bb2a1e100907', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': u'dd906118-0713-40ba-82cc-bb2a1e100907'}}, {u'y': 300, u'x': 0, u'frame': {u'frame': u'window'}, u'type': u'pointerMove', u'origin': {'element-6066-11e4-a52e-4f735466cecf': u'dd906118-0713-40ba-82cc-bb2a1e100907', 'chromeelement-9fc5-4b51-a3c8-01716eedeb04': u'dd906118-0713-40ba-82cc-bb2a1e100907'}}, {u'button': 0, u'type': u'pointerUp'}], u'parameters': {u'pointerType': u'touch'}, u'id': u'pointer1'}]}
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - Action action_sequence failed
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - Traceback (most recent call last):
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/base.py", line 715, in process_action
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - result = action_handler(payload)
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/base.py", line 774, in call
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - self.protocol.action_sequence.send_actions({"actions": actions})
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - File "/builds/worker/workspace/build/tests/web-platform/tests/tools/wptrunner/wptrunner/executors/executormarionette.py", line 424, in send_actions
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - self.marionette._send_message("WebDriver:PerformActions", actions)
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - return func(args, **kwargs)
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 598, in _send_message
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - self._handle_error(err)
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 618, in _handle_error
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - InvalidArgumentException: Unknown pointerType: [object String] "touch"
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - stacktrace:
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - WebDriverError@chrome://marionette/content/error.js:175:5
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - InvalidArgumentError@chrome://marionette/content/error.js:304:5
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - assert.that/<@chrome://marionette/content/assert.js:448:13
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - assert.in@chrome://marionette/content/assert.js:405:47
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - action.PointerType.get@chrome://marionette/content/action.js:403:10
[task 2020-02-19T07:31:31.506Z] 07:31:31 WARNING - Pointer@chrome://marionette/content/action.js:593:39
[task 2020-02-19T07:31:31.507Z] 07:31:31 WARNING - fromJSON@chrome://marionette/content/action.js:481:14
[task 2020-02-19T07:31:31.507Z] 07:31:31 WARNING - fromJSON@chrome://marionette/content/action.js:847:39
[task 2020-02-19T07:31:31.507Z] 07:31:31 WARNING - fromJSON@chrome://marionette/content/action.js:810:48
[task 2020-02-19T07:31:31.507Z] 07:31:31 WARNING - performActions@chrome://marionette/content/listener.js:849:28
[task 2020-02-19T07:31:31.507Z] 07:31:31 WARNING - dispatch/</req<@chrome://marionette/content/listener.js:527:14
[task 2020-02-19T07:31:31.507Z] 07:31:31 WARNING - dispatch/<@chrome://marionette/content/listener.js:520:15
[task 2020-02-19T07:31:31.507Z] 07:31:31 WARNING - MessageListener.receiveMessagestartListeners@chrome://marionette/content/listener.js:600:21
[task 2020-02-19T07:31:31.507Z] 07:31:31 WARNING - registerSelf@chrome://marionette/content/listener.js:501:5
[task 2020-02-19T07:31:31.507Z] 07:31:31 WARNING - @chrome://marionette/content/listener.js:1854:1

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

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE

This is basically permaorange on android, but is masked by the ini file. Pretty often, I see this kind of thing in the logcat (on debug):

05-26 22:23:54.295 2824 2839 I Gecko : [Child 2824: Main Thread]: I/jsep [1590528234287575 (id=2147483750 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-transceivers.https.html)]: stable -> have-local-offer
05-26 22:23:54.296 2824 2839 I Gecko : [Child 2824: Main Thread]: I/signaling [main|sdp_config] sdp_config.c:86: SDP: Initialized config pointer: 0x73dd996fd580
05-26 22:23:54.297 2824 2839 I Gecko : [Child 2824: Main Thread]: I/jsep [1590528234288895 (id=2147483750 url=https://web-platform.test:8443/webrtc/RTCPeerConnection-transceivers.https.html)]: stable -> have-remote-offer
05-26 22:23:54.298 2824 2839 E rtc :
05-26 22:23:54.298 2824 2839 E rtc :
05-26 22:23:54.298 2824 2839 E rtc : #
05-26 22:23:54.298 2824 2839 E rtc : # Fatal error in /builds/worker/checkouts/gecko/media/webrtc/trunk/webrtc/rtc_base/task_queue_libevent.cc, line 287
05-26 22:23:54.298 2824 2839 E rtc : # last system error: 24
05-26 22:23:54.298 2824 2839 E rtc : # Check failed: pipe(fds) == 0
05-26 22:23:54.298 2824 2839 E rtc : #
05-26 22:23:54.298 2824 2839 E rtc : #

Sometimes, it is instead something like this:

05-27 15:02:36.862 2814 7783 I Gecko : [Child 2814, Unnamed thread 7bcb432ae9a0] WARNING: Unable to create pipe named "2814.175.970996532" in server mode error(Too many open files).: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 213
05-27 15:02:36.862 2814 7783 I Gecko : [Child 2814, Unnamed thread 7bcb432ae9a0] WARNING: Failed to create top level actor!: file /builds/worker/checkouts/gecko/ipc/glue/BackgroundImpl.cpp, line 789
05-27 15:02:36.862 2814 7783 I Gecko : [Child 2814, Unnamed thread 7bcb432ae9a0] WARNING: '!mBackgroundManager', file /builds/worker/checkouts/gecko/dom/network/UDPSocketChild.cpp, line 59
05-27 15:02:36.862 2814 7783 F MOZ_Assert: Assertion failure: false (Failed to create UDP socket), at /builds/worker/checkouts/gecko/media/mtransport/nr_socket_prsock.cpp:1514

It seems that we're running out of fds during this test. I predict that this problem goes away if we run this test in isolation, but let's see...

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Depends on: 1641237

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

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