1468905 - Intermittent dom/media/tests/mochitest/test_peerConnection_twoAudioTracksInOneStream.html | application terminated with exit code -11

Status: RESOLVED DUPLICATE of bug 1469838

(Core :: WebRTC, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: apavel [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=183297209&repo=autoland

https://queue.taskcluster.net/v1/task/dNq0uo9ETNirtrrnaE74Bw/runs/0/artifacts/public/logs/live_backing.log

[task 2018-06-15T04:50:20.694Z] 04:50:20     INFO - GECKO(2907) | [2952:Socket Thread]: D/mtransport Attempting to unprotect RTP...
[task 2018-06-15T04:50:20.694Z] 04:50:20     INFO - GECKO(2907) | [2952:Unnamed thread 0xe79f7100]: E/signaling [|WebrtcAudioSessionConduit] AudioConduit.cpp:803: A/V sync: sync delta: 0ms, audio jitter delay 50ms, playout delay 0ms
[task 2018-06-15T04:50:20.695Z] 04:50:20     INFO - GECKO(2907) | [2952:Socket Thread]: D/mtransport Attempting to protect RTP...
[task 2018-06-15T04:50:20.696Z] 04:50:20     INFO - GECKO(2907) | [2952:Socket Thread]: D/mtransport Attempting to protect RTP...
[task 2018-06-15T04:50:20.696Z] 04:50:20     INFO - GECKO(2907) | [2952:Socket Thread]: D/mtransport Attempting to protect RTP...
[task 2018-06-15T04:50:20.698Z] 04:50:20     INFO - GECKO(2907) | [2952:Socket Thread]: D/mtransport Attempting to protect RTP...
[task 2018-06-15T04:50:20.699Z] 04:50:20     INFO - GECKO(2907) | [2952:Socket Thread]: D/mtransport Attempting to unprotect RTP...
[task 2018-06-15T04:50:20.700Z] 04:50:20    ERROR - GECKO(2907) | thread '<unnamed>' panicked at 'assertion failed: `(left != right)`
[task 2018-06-15T04:50:20.701Z] 04:50:20     INFO - GECKO(2907) |   left: `0`,
[task 2018-06-15T04:50:20.701Z] 04:50:20     INFO - GECKO(2907) |  right: `0`', media/libcubeb/cubeb-pulse-rs/src/backend/stream.rs:905:5
[task 2018-06-15T04:50:20.701Z] 04:50:20     INFO - GECKO(2907) | stack backtrace:
[task 2018-06-15T04:50:20.702Z] 04:50:20     INFO - GECKO(2907) | [2952:Socket Thread]: D/mtransport Attempting to unprotect RTP...
[task 2018-06-15T04:50:20.703Z] 04:50:20     INFO - GECKO(2907) | [2952:Socket Thread]: D/mtransport Attempting to unprotect RTP...
[task 2018-06-15T04:50:20.704Z] 04:50:20     INFO - GECKO(2907) | [2952:Socket Thread]: D/mtransport Attempting to unprotect RTP...
[task 2018-06-15T04:50:20.866Z] 04:50:20     INFO - GECKO(2907) | [2952:Socket Thread]: D/mtransport Attempting to protect RTCP...
[task 2018-06-15T04:50:20.866Z] 04:50:20     INFO - GECKO(2907) | [2952:Socket Thread]: D/mtransport Attempting to unprotect RTCP...
[task 2018-06-15T04:50:20.867Z] 04:50:20     INFO - GECKO(2907) | [2952:Socket Thread]: W/signaling [Socket Thread|MediaPipeline] MediaPipeline.cpp:1176: Dropping incoming RTCP packet; filtered out
[task 2018-06-15T04:50:21.148Z] 04:50:21     INFO - GECKO(2907) |    0: 0xf2d1963d - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h1161623548489f53
[task 2018-06-15T04:50:21.151Z] 04:50:21     INFO - GECKO(2907) |    1: 0xf2d2ae63 - std::sys_common::backtrace::print::hf81721988fc483b5
[task 2018-06-15T04:50:21.151Z] 04:50:21     INFO - GECKO(2907) |    2: 0xf2d094f6 - std::panicking::default_hook::{{closure}}::h307410a777a3bd25
[task 2018-06-15T04:50:21.151Z] 04:50:21     INFO - GECKO(2907) |    3: 0xf2d090ff - std::panicking::default_hook::heb8feab19bd5fff3
[task 2018-06-15T04:50:21.151Z] 04:50:21     INFO - GECKO(2907) |    4: 0xf2642b40 - <unknown>
[task 2018-06-15T04:50:21.151Z] 04:50:21     INFO - GECKO(2907) | Redirecting call to abort() to mozalloc_abort
[task 2018-06-15T04:50:21.152Z] 04:50:21     INFO - GECKO(2907) | Hit MOZ_CRASH() at /builds/worker/workspace/build/src/memory/mozalloc/mozalloc_abort.cpp:34
[task 2018-06-15T04:50:21.177Z] 04:50:21     INFO - GECKO(2907) | Hit MOZ_CRASH(Aborting on channel error.) at /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2548
[task 2018-06-15T04:50:54.404Z] 04:50:54     INFO - GECKO(2907) | #01: mozilla::ipc::MessageChannel::OnChannelErrorFromLink() [ipc/glue/MessageChannel.cpp:2548]
[task 2018-06-15T04:50:54.404Z] 04:50:54     INFO - 
[task 2018-06-15T04:50:54.406Z] 04:50:54     INFO - GECKO(2907) | #02: mozilla::ipc::ProcessLink::OnChannelError() [ipc/glue/MessageLink.cpp:389]
[task 2018-06-15T04:50:54.406Z] 04:50:54     INFO - 
[task 2018-06-15T04:50:54.406Z] 04:50:54     INFO - GECKO(2907) | #03: IPC::Channel::ChannelImpl::OnFileCanReadWithoutBlocking(int) [ipc/chromium/src/chrome/common/ipc_channel_posix.cc:822]
[task 2018-06-15T04:50:54.407Z] 04:50:54     INFO - 
[task 2018-06-15T04:50:54.407Z] 04:50:54     INFO - GECKO(2907) | Hit MOZ_CRASH(Aborting on channel error.) at /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2548
[task 2018-06-15T04:50:54.408Z] 04:50:54     INFO - GECKO(2907) | #01: mozilla::ipc::MessageChannel::OnChannelErrorFromLink() [ipc/glue/MessageChannel.cpp:2548]
[task 2018-06-15T04:50:54.408Z] 04:50:54     INFO - 
[task 2018-06-15T04:50:54.409Z] 04:50:54     INFO - GECKO(2907) | #02: mozilla::ipc::ProcessLink::OnChannelError() [ipc/glue/MessageLink.cpp:389]
[task 2018-06-15T04:50:54.410Z] 04:50:54     INFO - 
[task 2018-06-15T04:50:54.410Z] 04:50:54     INFO - GECKO(2907) | #03: IPC::Channel::ChannelImpl::OnFileCanReadWithoutBlocking(int) [ipc/chromium/src/chrome/common/ipc_channel_posix.cc:822]
[task 2018-06-15T04:50:54.411Z] 04:50:54     INFO - 
[task 2018-06-15T04:50:54.411Z] 04:50:54     INFO - GECKO(2907) | #04: event_process_active_single_queue [ipc/chromium/src/third_party/libevent/event.c:1648]
[task 2018-06-15T04:50:54.412Z] 04:50:54     INFO - 
[task 2018-06-15T04:50:54.413Z] 04:50:54     INFO - GECKO(2907) | #05: event_base_loop [ipc/chromium/src/third_party/libevent/event.c:1741]

[task 2018-06-15T04:50:54.841Z] 04:50:54     INFO - CLINE-NO-DEFAULT-ADDR-SDP: "audio 59045 UDP/TLS/RTP/SAVPF 109 101\r\nc=IN IP4 172.17.0.4\r\na=candidate:0 1 UDP 2122187007 172.17.0.4 59045 typ host\r\na=candidate:1 1 UDP 2122252543 fd15:4ba5:5a2b:100a:0:242:ac11:4 45643 typ host\r\na=candidate:2 1 TCP 2105458943 172.17.0.4 9 typ host tcptype active\r\na=candidate:3 1 TCP 2105524479 fd15:4ba5:5a2b:100a:0:242:ac11:4 9 typ host tcptype active\r\na=sendrecv\r\na=end-of-candidates\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=fmtp:109 maxplaybackrate=48000;stereo=1;useinbandfec=1\r\na=fmtp:101 0-15\r\na=ice-pwd:97535533f2575d9873903735ef3f0aa4\r\na=ice-ufrag:f3d91853\r\na=mid:sdparta_0\r\na=msid:{769b38fb-e591-42b0-bdf9-8dbf96af6415} {e2477237-3170-406a-9142-629ccb925b5c}\r\na=rtcp-mux\r\na=rtpmap:109 opus/48000/2\r\na=rtpmap:101 telephone-event/8000\r\na=setup:active\r\na=ssrc:4036053184 cname:{c8e5f453-d00b-487f-9c2b-838de791bf5e}"
[task 2018-06-15T04:50:54.842Z] 04:50:54     INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_twoAudioTracksInOneStream.html | pcRemote: SDP contains non-zero IP c line 
[task 2018-06-15T04:50:54.843Z] 04:50:54     INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_twoAudioTracksInOneStream.html | SDP MLine Index needs to exist 
[task 2018-06-15T04:50:54.848Z] 04:50:54     INFO - Received: {"candidate":"candidate:3 1 TCP 2105524479 fd15:4ba5:5a2b:100a:0:242:ac11:4 9 typ host tcptype active","sdpMid":"sdparta_0","sdpMLineIndex":0} from pcRemote
[task 2018-06-15T04:50:54.849Z] 04:50:54     INFO - PeerConnectionWrapper (pcLocal): adding ICE candidate {"candidate":"candidate:3 1 TCP 2105524479 fd15:4ba5:5a2b:100a:0:242:ac11:4 9 typ host tcptype active","sdpMid":"sdparta_0","sdpMLineIndex":0}
[task 2018-06-15T04:50:54.850Z] 04:50:54     INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_twoAudioTracksInOneStream.html | PeerConnectionWrapper (pcLocal) successfully added an ICE candidate 
[task 2018-06-15T04:50:54.851Z] 04:50:54     INFO - Buffered messages finished
[task 2018-06-15T04:50:54.852Z] 04:50:54    ERROR - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_twoAudioTracksInOneStream.html | application terminated with exit code -11
[task 2018-06-15T04:50:54.853Z] 04:50:54     INFO - runtests.py | Application ran for: 0:27:32.019050
[task 2018-06-15T04:50:54.853Z] 04:50:54     INFO - zombiecheck | Reading PID log: /tmp/tmpNOukzipidlog
[task 2018-06-15T04:50:54.854Z] 04:50:54     INFO - ==> process 2907 launched child process 2927
[task 2018-06-15T04:50:54.855Z] 04:50:54     INFO - ==> process 2907 launched child process 2952
[task 2018-06-15T04:50:54.856Z] 04:50:54     INFO - ==> process 2907 launched child process 2994
[task 2018-06-15T04:50:54.857Z] 04:50:54     INFO - ==> process 2907 launched child process 4028
[task 2018-06-15T04:50:54.858Z] 04:50:54     INFO - ==> process 2907 launched child process 4471
[task 2018-06-15T04:50:54.859Z] 04:50:54     INFO - ==> process 2907 launched child process 4894
[task 2018-06-15T04:50:54.860Z] 04:50:54     INFO - zombiecheck | Checking for orphan process with PID: 2952
[task 2018-06-15T04:50:54.861Z] 04:50:54     INFO - zombiecheck | Checking for orphan process with PID: 2927
[task 2018-06-15T04:50:54.862Z] 04:50:54     INFO - zombiecheck | Checking for orphan process with PID: 2994
[task 2018-06-15T04:50:54.863Z] 04:50:54     INFO - zombiecheck | Checking for orphan process with PID: 4471
[task 2018-06-15T04:50:54.863Z] 04:50:54     INFO - zombiecheck | Checking for orphan process with PID: 4028
[task 2018-06-15T04:50:54.864Z] 04:50:54     INFO - zombiecheck | Checking for orphan process with PID: 4894
[task 2018-06-15T04:50:54.865Z] 04:50:54     INFO - Stopping web server
[task 2018-06-15T04:50:54.866Z] 04:50:54     INFO - Stopping web socket server
[task 2018-06-15T04:50:54.867Z] 04:50:54     INFO - Stopping ssltunnel
[task 2018-06-15T04:50:54.868Z] 04:50:54     INFO -  websocket/process bridge listening on port 8191
[task 2018-06-15T04:50:54.869Z] 04:50:54     INFO - Stopping websocket/process bridge
[task 2018-06-15T04:50:54.870Z] 04:50:54     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 0 bytes

Comment 1

[Intermittent Failures Robot]

2 failures in 408 pushes (0.005 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 1
* autoland: 1

Platform breakdown:
* linux32: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1468905&startday=2018-06-11&endday=2018-06-17&tree=trunk

Comment 2

[Intermittent Failures Robot]

2 failures in 521 pushes (0.004 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 1
* autoland: 1

Platform breakdown:
* linux32: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1468905&startday=2018-06-18&endday=2018-06-24&tree=trunk

Comment 3

[Intermittent Failures Robot]

4 failures in 718 pushes (0.006 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* try: 2
* mozilla-inbound: 2

Platform breakdown:
* linux32: 3
* linux64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1468905&startday=2018-06-25&endday=2018-07-01&tree=trunk

Comment 4

[Intermittent Failures Robot]

1 failures in 622 pushes (0.002 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* autoland: 1

Platform breakdown:
* linux32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1468905&startday=2018-07-02&endday=2018-07-08&tree=trunk

Comment 5

[Intermittent Failures Robot]

2 failures in 623 pushes (0.003 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 1
* autoland: 1

Platform breakdown:
* linux32: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1468905&startday=2018-07-09&endday=2018-07-15&tree=trunk

Comment 6

[Intermittent Failures Robot]

3 failures in 653 pushes (0.005 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* autoland: 2
* mozilla-central: 1

Platform breakdown:
* linux32: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1468905&startday=2018-07-16&endday=2018-07-22&tree=trunk

Comment 7

[Intermittent Failures Robot]

1 failures in 760 pushes (0.001 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-beta: 1

Platform breakdown:
* linux32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1468905&startday=2018-07-23&endday=2018-07-29&tree=trunk

Comment 9

[Intermittent Failures Robot]

5 failures in 2579 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* autoland: 1
* mozilla-central: 1
* try: 2
* mozilla-inbound: 1

Platform breakdown:
* windows10-64-nightly: 1
* linux32: 3
* linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1468905&startday=2018-08-06&endday=2018-08-12&tree=all

Comment 10

[Intermittent Failures Robot]

1 failures in 2568 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* autoland: 1

Platform breakdown:
* linux32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1468905&startday=2018-08-20&endday=2018-08-26&tree=all