Skip to content

Instantly share code, notes, and snippets.

@sreimers

sreimers/ice_controlled

Created February 23, 2020 20:05
Show Gist options
  • Save sreimers/10c367e146b6f026359713cc9d0db501 to your computer and use it in GitHub Desktop.
Save sreimers/10c367e146b6f026359713cc9d0db501 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
ice_controlled
ua: sipsess connect via TCP 148.251.217.82:5060 --> 192.168.178.79:43774
ua: using AF from sdp offer: af=AF_INET
call: alloc with params laddr=192.168.178.79, af=AF_INET, use_rtp=1
ice: new session with TURN-server at stun.studio.link (username=turn200300)
stream: audio: starting mediaenc 'dtls_srtp' (wait_secure=1)
call: use_video=0
ice: resolved TURN-server to address 95.179.254.217:3478
audio: added interface: enp0s31f6:192.168.178.79 (local prio 10)
ice: relay gathered for comp 1 (0 OK)
ice: all components gathered.
audio: comp1 setting local: 95.179.254.217:61984
ice: audio: Default local candidates: 95.179.254.217:61984 / ?
call: media-nat 'ice' established/gathered
sip:[email protected]: Incoming call from: sip:[email protected] - (press 'a' to accept)
play: /usr/local/share/baresip/ring.wav: No such file or directory
sip:[email protected]: Answering incoming call
answering call from sip:[email protected] with 200
call: update media
audio: client-to-mixer audio levels enabled
stream: update 'audio'
audio: RTP/RTCP multiplexing enabled
chklist: audio: pruned candidate pairs: 3
call: stream start (active=1)
[email protected]: Call established: sip:[email protected]
ice: audio: connectivity check is complete (update=0)
audio: Dumping media state: ----- ICE Media <audio> -----
local_mode=Full, remote_mode=Full, local_role=Controlled
local_ufrag="GrNV80z" local_pwd="GHLDiHFfGzSty1m7hdxkOwuLXebg9iF"
Components: (1)
id=1 ldef=95.179.254.217:61984 rdef=95.179.254.217:53038 concluded=1
Local Candidates: (3)
{1} fnd=c0a8b24f prio=7e000aff enp0s31f6:host:192.168.178.79:12806
{1} fnd=5fb3feda prio=000000ff enp0s31f6:relay:95.179.254.217:61984 (rel-addr=192.168.178.79:12806)
{1} fnd=6d280225 prio=640000ff enp0s31f6:srflx:109.40.2.36:19712 (rel-addr=192.168.178.79:12806)
Remote Candidates: (3)
{1} fnd=0a0a0a70 prio=7e000aff host:10.10.10.112:44068
{1} fnd=5fb3feda prio=000000ff relay:95.179.254.217:53038 (rel-addr=10.10.10.112:44068)
{1} fnd=904c98f9 prio=640000ff srflx:144.76.152.248:44068 (rel-addr=10.10.10.112:44068)
Check list: [state=Completed] (4)
{comp=1} Failed { } enp0s31f6:host:192.168.178.79:12806 <---> host:10.10.10.112:44068 (Connecti
on timed out)
{comp=1} Failed { } enp0s31f6:host:192.168.178.79:12806 <---> srflx:144.76.152.248:44068 (Conne
ction timed out)
{comp=1} Failed { } enp0s31f6:relay:95.179.254.217:61984 <---> host:10.10.10.112:44068 (Connect
ion timed out)
{comp=1} Failed { } enp0s31f6:host:192.168.178.79:12806 <---> relay:95.179.254.217:53038 (Conne
ction timed out)
Valid list: (2)
* {comp=1} Succeeded { VN} enp0s31f6:relay:95.179.254.217:61984 <---> srflx:144.76.152.248:44068
{comp=1} Succeeded {DVN} enp0s31f6:relay:95.179.254.217:61984 <---> relay:95.179.254.217:53038
STUN debug:
STUN client transactions: (3)
method=Binding tid=900aa80b799947634d6205ff rto=100ms tmr=45 n=1 interval=100
method=Binding tid=5e53e126b427e6e73b14e90b rto=100ms tmr=99 n=1 interval=100
method=Binding tid=85509aa30a3c003316e1ec08 rto=100ms tmr=99 n=1 interval=100
stream: mnat 'ice' connected: raddr 144.76.152.248:44068
stream: audio: starting mediaenc 'dtls_srtp' (wait_secure=1)
dtls_srtp: media=audio -- start DTLS client
dtls_srtp: component start: RTP [raddr=144.76.152.248:44068]
dtls_srtp: 'audio,RTP' dtls connect to 144.76.152.248:44068
connchk: send_req: CONNCHECK already Pending!
stunsrv: triggered check failed
icecomp: {audio.1} set_selected: invalid state Failed
dtls_srtp: incoming DTLS connect from 95.179.254.217:53038
dtls_srtp: dtls already accepted (peer = 144.76.152.248:44068)
dtls_srtp: incoming DTLS connect from 95.179.254.217:53038
dtls_srtp: dtls already accepted (peer = 144.76.152.248:44068)
dtls_srtp: incoming DTLS connect from 95.179.254.217:53038
dtls_srtp: dtls already accepted (peer = 144.76.152.248:44068)
dtls_srtp: incoming DTLS connect from 95.179.254.217:53038
dtls_srtp: dtls already accepted (peer = 144.76.152.248:44068)
dtls_srtp: incoming DTLS connect from 95.179.254.217:53038
dtls_srtp: dtls already accepted (peer = 144.76.152.248:44068)
dtls_srtp: incoming DTLS connect from 95.179.254.217:53038
dtls_srtp: dtls already accepted (peer = 144.76.152.248:44068)
dtls_srtp: incoming DTLS connect from 95.179.254.217:53038
dtls_srtp: dtls already accepted (peer = 144.76.152.248:44068)
dtls_srtp: incoming DTLS connect from 95.179.254.217:53038
dtls_srtp: dtls already accepted (peer = 144.76.152.248:44068)
dtls_srtp: incoming DTLS connect from 95.179.254.217:53038
dtls_srtp: dtls already accepted (peer = 144.76.152.248:44068)
dtls_srtp: incoming DTLS connect from 95.179.254.217:53038
dtls_srtp: dtls already accepted (peer = 144.76.152.248:44068)
dtls_srtp: incoming DTLS connect from 95.179.254.217:53038
dtls_srtp: dtls already accepted (peer = 144.76.152.248:44068)
dtls_srtp: incoming DTLS connect from 95.179.254.217:53038
dtls_srtp: dtls already accepted (peer = 144.76.152.248:44068)
dtls_srtp: incoming DTLS connect from 95.179.254.217:53038
dtls_srtp: dtls already accepted (peer = 144.76.152.248:44068)
Raw
ice_controlling
[email protected]: {0/TCP/v4} 200 OK (kamailio (5.3.2 (x86_64/linux))) [1 binding] [91/9312]
All 1 useragent registered successfully! (3 ms)
> [email protected]
ua: using best effort AF: af=AF_INET
call: alloc with params laddr=10.10.10.112, af=AF_INET, use_rtp=1
ice: new session with TURN-server at stun.studio.link (username=turn200300)
stream: audio: starting mediaenc 'dtls_srtp' (wait_secure=1)
call: use_video=0
call: connecting to 'sip:[email protected];transport=tcp'..
ice: resolved TURN-server to address 95.179.254.217:3478
audio: added interface: eth0:10.10.10.112 (local prio 10)
ice: relay gathered for comp 1 (0 OK)
ice: all components gathered.
audio: comp1 setting local: 95.179.254.217:53038
ice: audio: Default local candidates: 95.179.254.217:53038 / ?
call: media-nat 'ice' established/gathered
call: SIP Progress: 100 trying -- your call is important to us (/)
call: SIP Progress: 180 Ringing (/)
play: /usr/local/share/baresip/ringback.wav: No such file or directory
call: got SDP answer (1009 bytes)
call: update media
audio: client-to-mixer audio levels enabled
stream: update 'audio'
audio: RTP/RTCP multiplexing enabled
{ audio.1} Set default remote candidate: relay:95.179.254.217:61984
{ audio.1} Add TURN Channel to peer 95.179.254.217:61984
chklist: audio: pruned candidate pairs: 3
{ audio. } starting connectivity checks with 6 candidate pairs
{ audio.1} host <---> host FSM: Frozen ===> Waiting
{ audio.1} host <---> srflx FSM: Frozen ===> Waiting
{ audio.1} host <---> relay FSM: Frozen ===> Waiting
{ audio.1} relay <---> host FSM: Frozen ===> Waiting
{ audio.1} relay <---> srflx FSM: Frozen ===> Waiting
{ audio.1} relay <---> relay FSM: Frozen ===> Waiting
call: stream start (active=1)
stream: Enable RTP timeout (300000 milliseconds)
[email protected]: Call established: sip:[email protected];transport=tcp
{ audio.1} host <---> host FSM: Waiting ===> InProgress
{ audio.1} Tx eth0:host:10.10.10.112:44068 ---> host:192.168.178.79:12806 (InProgress)
stream: last "audio" RTP packet: 11 milliseconds
stream: last "audio" RTP packet: 1011 milliseconds
stream: last "audio" RTP packet: 2011 milliseconds
{ audio.1} Rx eth0:host:10.10.10.112:44068 <--- host:192.168.178.79:12806 '0 ' (Connection timed out)
{ audio.1} host <---> host FSM: InProgress ===> Failed
{ audio.1} host <---> srflx FSM: Waiting ===> InProgress
{ audio.1} Tx eth0:host:10.10.10.112:44068 ---> srflx:109.40.2.36:19712 (InProgress)
stream: last "audio" RTP packet: 3012 milliseconds
stream: last "audio" RTP packet: 4012 milliseconds
{ audio.1} Rx eth0:host:10.10.10.112:44068 <--- srflx:109.40.2.36:19712 '0 ' (Connection timed out)
{ audio.1} host <---> srflx FSM: InProgress ===> Failed
{ audio.1} host <---> relay FSM: Waiting ===> InProgress
{ audio.1} Tx eth0:host:10.10.10.112:44068 ---> relay:95.179.254.217:61984 (InProgress)
stream: last "audio" RTP packet: 5012 milliseconds
stream: last "audio" RTP packet: 6012 milliseconds
{ audio.1} Rx eth0:host:10.10.10.112:44068 <--- relay:95.179.254.217:61984 '0 ' (Connection timed out)
{ audio.1} host <---> relay FSM: InProgress ===> Failed
{ audio.1} relay <---> host FSM: Waiting ===> InProgress
{ audio.1} Tx eth0:relay:95.179.254.217:53038 ---> host:192.168.178.79:12806 (InProgress)
stream: last "audio" RTP packet: 7012 milliseconds
stream: last "audio" RTP packet: 8012 milliseconds
stream: last "audio" RTP packet: 9012 milliseconds
{ audio.1} Tx eth0:host:10.10.10.112:44068 ---> relay:95.179.254.217:61984 (Failed) [Trigged]
{ audio.1} Rx Binding Request from 95.179.254.217:61984 via Socket (candpair=Failed)
{ audio.1} Rx eth0:relay:95.179.254.217:53038 <--- host:192.168.178.79:12806 '0 ' (Connection timed out)
{ audio.1} relay <---> host FSM: InProgress ===> Failed
{ audio.1} relay <---> srflx FSM: Waiting ===> InProgress
{ audio.1} Tx eth0:relay:95.179.254.217:53038 ---> srflx:109.40.2.36:19712 (InProgress)
{ audio.1} Rx eth0:host:10.10.10.112:44068 <--- relay:95.179.254.217:61984 '0 OK'
{ audio.1} New local candidate for mapped 144.76.152.248:44068
icecomp: {audio.1} set_selected: invalid state Failed
{ audio.1} relay <---> relay FSM: Waiting ===> InProgress
{ audio.1} Tx eth0:relay:95.179.254.217:53038 ---> relay:95.179.254.217:61984 (InProgress)
{ audio.1} Rx Binding Request from 95.179.254.217:61984 via Tunnel (candpair=InProgress)
{ audio.1} added PeerReflexive remote candidate with priority 1845494015 (109.40.2.36:24100)
stunsrv: {audio.1} candidate pair not found: source=109.40.2.36:24100
{ audio.1} Rx Binding Request from 95.179.254.217:61984 via Socket (candpair=InProgress) [USE]
{ audio.1} relay <---> relay FSM: InProgress ===> Succeeded
stunsrv: {audio.1} candidate pair not found: source=109.40.2.36:24100
stunsrv: {audio.1} candidate pair not found: source=109.40.2.36:24100
stunsrv: {audio.1} candidate pair not found: source=109.40.2.36:24100
stream: last "audio" RTP packet: 10012 milliseconds
stream: last "audio" RTP packet: 11012 milliseconds
{ audio.1} Rx eth0:relay:95.179.254.217:53038 <--- srflx:109.40.2.36:19712 '0 ' (Connection timed out)
{ audio.1} relay <---> srflx FSM: InProgress ===> Failed
{ audio.1} Tx eth0:relay:95.179.254.217:53038 ---> relay:95.179.254.217:61984 (Succeeded) [USE] [Trigged]
ice: audio: connectivity check is complete (update=1)
audio: Dumping media state: ----- ICE Media <audio> -----
local_mode=Full, remote_mode=Full, local_role=Controlling
local_ufrag="FhoNMnh" local_pwd="0HOkLHl8yvBJxCRNJj0otfATfGqi4Tc"
Components: (1)
id=1 ldef=95.179.254.217:53038 rdef=95.179.254.217:61984 concluded=1
Local Candidates: (3)
{1} fnd=0a0a0a70 prio=7e000aff eth0:host:10.10.10.112:44068
{1} fnd=5fb3feda prio=000000ff eth0:relay:95.179.254.217:53038 (rel-addr=10.10.10.112:44068)
{1} fnd=904c98f9 prio=640000ff eth0:srflx:144.76.152.248:44068 (rel-addr=10.10.10.112:44068)
Remote Candidates: (4)
{1} fnd=c0a8b24f prio=7e000aff host:192.168.178.79:12806
{1} fnd=5fb3feda prio=000000ff relay:95.179.254.217:61984 (rel-addr=192.168.178.79:12806)
{1} fnd=6d280225 prio=640000ff srflx:109.40.2.36:19712 (rel-addr=192.168.178.79:12806)
{1} fnd=c604f189 prio=6e0000ff prflx:109.40.2.36:24100
Check list: [state=Completed] (4)
{comp=1} Failed { } eth0:host:10.10.10.112:44068 <---> host:192.168.178.79:12806 (Connection timed out)
{comp=1} Failed { } eth0:host:10.10.10.112:44068 <---> srflx:109.40.2.36:19712 (Connection timed out)
{comp=1} Failed { } eth0:relay:95.179.254.217:53038 <---> host:192.168.178.79:12806 (Connection timed out)
{comp=1} Failed { } eth0:relay:95.179.254.217:53038 <---> srflx:109.40.2.36:19712 (Connection timed out)
Valid list: (3)
{comp=1} Failed { VN} eth0:srflx:144.76.152.248:44068 <---> relay:95.179.254.217:61984
{comp=1} Failed { V } eth0:host:10.10.10.112:44068 <---> relay:95.179.254.217:61984
* {comp=1} Succeeded {DV } eth0:relay:95.179.254.217:53038 <---> relay:95.179.254.217:61984
STUN debug:
STUN client transactions: (1)
method=Binding tid=9b9cd7cd4a317004b2ccae54 rto=100ms tmr=100 n=1 interval=100
stream: mnat 'ice' connected: raddr 95.179.254.217:61984
stream: audio: starting mediaenc 'dtls_srtp' (wait_secure=1)
dtls_srtp: media=audio -- start DTLS server
dtls_srtp: component start: RTP [raddr=95.179.254.217:61984]
{ audio.1} Rx eth0:relay:95.179.254.217:53038 <--- relay:95.179.254.217:61984 '0 OK'
stream: last "audio" RTP packet: 12013 milliseconds
dtls_srtp: incoming DTLS connect from 95.179.254.217:61984
stream: last "audio" RTP packet: 13013 milliseconds
stream: last "audio" RTP packet: 14013 milliseconds
stream: last "audio" RTP packet: 15014 milliseconds
stream: last "audio" RTP packet: 16015 milliseconds
stream: last "audio" RTP packet: 17015 milliseconds
stream: last "audio" RTP packet: 18016 milliseconds
stream: last "audio" RTP packet: 19016 milliseconds
stream: last "audio" RTP packet: 20017 milliseconds
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment