i have not been able to figure out, why i can't hear any audio when i call from webrtc client (using UDP/TLS/RTP/SAVPF profile) to sems echo server (using RTP/AVP profile):
1) webrtc client - UDP/TLS/RTP/SAVPF - rtpengine - RTP/AVP - sems echo server
if i call from the same webrtc client to baresip, audio works fine:
2) webrtc client - UDP/TLS/RTP/SAVPF - rtpengine - RTP/AVP - baresip
also, if i call from baresip using UDP/TLS/RTP/SAVPF profile to sems, audio works fine:
3) basesip - UDP/TLS/RTP/SAVPF - rtpengine - RTP/AVP - sems echo server
below is level 7 debug from the problem case (1). stats at the bottom tell that audio packets were flowing both ways fine. what does not seem correct is that the webrtc client is constantly sending "Successful STUN binding requests" to rtpengine.
any advise for further debugging this problem would be highly appreciated.
-- juha
------------------------------------------------------------------------------
May 18 11:47:34 box /usr/bin/sip-proxy[18299]: INFO: ===== rtpengine_offer(ICE=force replace-session-connection replace-origin via-branch=1 rtcp-mux-demux RTP/AVP) May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Received command 'offer' from 127.0.0.1:43378 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Dump for 'offer' from 127.0.0.1:43378: { "sdp": "v=0#015#012o=- 4880355588178147449 2 IN IP4 127.0.0.1#015#012s=-#015#012t=0 0#015#012a=group:BUNDLE audio#015#012a=msid-semantic: WMS k2qrqNs5yzQcMjup0H0E85yNYAP5aA0w4SeM#015#012m=audio 35551 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 126#015#012c=IN IP4 93.106.207.11#015#012a=rtcp:35551 IN IP4 93.106.207.11#015#012a=candidate:1603072777 1 udp 2122260223 192.168.43.146 35551 typ host generation 0#015#012a=candidate:1603072777 2 udp 2122260223 192.168.43.146 35551 typ host generation 0#015#012a=candidate:2861 ... May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] ... 99801 1 tcp 1518280447 192.168.43.146 0 typ host tcptype active generation 0#015#012a=candidate:286199801 2 tcp 1518280447 192.168.43.146 0 typ host tcptype active generation 0#015#012a=candidate:2466368666 1 udp 1686052607 93.106.207.11 35551 typ srflx raddr 192.168.43.146 rport 35551 generation 0#015#012a=candidate:2466368666 2 udp 1686052607 93.106.207.11 35551 typ srflx raddr 192.168.43.146 rport 35551 generation 0#015#012a=ice-ufrag:U+gG6hyUzfrt859a#015#012a=ice-pwd:FQwGMInhUjWl8bbYIbSJS1pz#015#012a=fingerprint:sha-256 09:D6: ... May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] ... AA:5B:F2:9E:D6:A7:CE:69:D3:76:67:EF:36:A6:F4:87:94:B7:37:82:AF:17:9E:4B:E8:B7:F4:F1:8C:A0#015#012a=setup:actpass#015#012a=mid:audio#015#012a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level#015#012a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time#015#012a=sendrecv... opus/48000/2#015#012a=fmtp:111 minptime=10; useinbandfec=1#015#012a=rtpmap:103 ISAC/16000#015#012a=rtpmap:104 ISAC/32000#015#012a=rtpmap:9 G722/8000#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:8 PCMA/8000#015#012a=rtpmap:106 CN/32000#015#012a=rtpmap:105 CN/16000#015 ... May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] ... #012a=rtpmap:13 CN/8000#015#012a=rtpmap:126 telephone-event/8000#015#012a=maxptime:60#015#012a=ssrc:2202238380 cname:zcLA/sUaZsuYHC73#015#012a=ssrc:2202238380 msid:k2qrqNs5yzQcMjup0H0E85yNYAP5aA0w4SeM 2053b0c2-35f2-48f8-ae79-419a078169f4#015#012a=ssrc:2202238380 mslabel:k2qrqNs5yzQcMjup0H0E85yNYAP5aA0w4SeM#015#012a=ssrc:2202238380 label:2053b0c2-35f2-48f8-ae79-419a078169f4#015#012", "ICE": "force", "replace": [ "session-connection", "origin" ], "transport-protocol": "RTP/AVP", "rtcp-mux": [ "demux" ], "call-id": "e91aj9gq39qr07b6dn6u", "vi ... May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] ... a-branch": "z9hG4bK1294663", "received-from": [ "IP4", "<sip-proxy-rtpengine-ip>" ], "from-tag": "0m9av72lp5", "command": "offer" } May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Creating new call May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Opened ports 8012..8013 for media relay May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Opened ports 8028..8029 for media relay May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Learning new ICE candidate 1603072777:1 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Created candidate pair MmPVRTRG3mIW6Esg:1603072777:1 between <sip-proxy-rtpengine-ip> and 192.168.43.146:35551, type host May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Learning new ICE candidate 1603072777:2 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Learning new ICE candidate 2466368666:1 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Created candidate pair MmPVRTRG3mIW6Esg:2466368666:1 between <sip-proxy-rtpengine-ip> and 93.106.207.11:35551, type srflx May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Learning new ICE candidate 2466368666:2 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Sending ICE/STUN request for candidate pair MmPVRTRG3mIW6Esg:1603072777:1 from <sip-proxy-rtpengine-ip> to 192.168.43.146:35551 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Replying to 'offer' from 127.0.0.1:43378 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Response dump for 'offer' to 127.0.0.1:43378: { "sdp": "v=0#015#012o=- 4880355588178147449 2 IN IP4 <sip-proxy-rtpengine-ip>#015#012s=-#015#012t=0 0#015#012a=msid-semantic: WMS k2qrqNs5yzQcMjup0H0E85yNYAP5aA0w4SeM#015#012m=audio 8012 RTP/AVP 111 103 104 9 0 8 106 105 13 126#015#012c=IN IP4 <sip-proxy-rtpengine-ip>#015#012a=rtpmap:111 opus/48000/2#015#012a=fmtp:111 minptime=10; useinbandfec=1#015#012a=rtpmap:103 ISAC/16000#015#012a=rtpmap:104 ISAC/32000#015#012a=rtpmap:9 G722/8000#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:8 PCMA/8000#015#012a=rtpmap:106 CN/32000#015#012a=rtpmap:105 CN/16000#015#012a=rtpmap:13 ... May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] ... CN/8000#015#012a=rtpmap:126 telephone-event/8000#015#012a=maxptime:60#015#012a=ssrc:2202238380 cname:zcLA/sUaZsuYHC73#015#012a=ssrc:2202238380 msid:k2qrqNs5yzQcMjup0H0E85yNYAP5aA0w4SeM 2053b0c2-35f2-48f8-ae79-419a078169f4#015#012a=ssrc:2202238380 mslabel:k2qrqNs5yzQcMjup0H0E85yNYAP5aA0w4SeM#015#012a=ssrc:2202238380 label:2053b0c2-35f2-48f8-ae79-419a078169f4#015#012a=sendrecv#015#012a=rtcp:8013#015#012a=ice-ufrag:aEaiKI4d#015#012a=ice-pwd:S0ET0fRwnGLZ6lYSdXuAfvCo9K#015#012a=candidate:MmPVRTRG3mIW6Esg 1 UDP 2130706431 <sip-proxy-rtpengine-ip> 8012 typ host#015#012a=candidate:MmP ... May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] ... VRTRG3mIW6Esg 2 UDP 2130706430 <sip-proxy-rtpengine-ip> 8013 typ host#015#012", "result": "ok" } May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u/0m9av72lp5/1] Sending ICE/STUN request for candidate pair MmPVRTRG3mIW6Esg:2466368666:1 from <sip-proxy-rtpengine-ip> to 93.106.207.11:35551 May 18 11:47:34 box /usr/bin/sip-proxy[18242]: INFO: ===== rtpengine_answer(ICE=force via-branch=2 trust-address) May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Received command 'answer' from 127.0.0.1:49396 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Dump for 'answer' from 127.0.0.1:49396: { "sdp": "v=0#015#012o=sems 1244512444 1372754 IN IP4 <sip-proxy-rtpengine-ip>#015#012s=sems#015#012c=IN IP4 <sip-proxy-rtpengine-ip>#015#012t=0 0#015#012m=audio 8064 RTP/AVP 0 8 126#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:8 PCMA/8000#015#012a=rtpmap:126 telephone-event/8000#015#012a=sendrecv#015#012a=direction:both#015#012", "ICE": "force", "flags": [ "trust-address" ], "call-id": "e91aj9gq39qr07b6dn6u", "via-branch": "z9hG4bK1294663", "received-from": [ "IP4", "127.0.0.1" ], "from-tag": "0m9av72lp5", "to-tag": "627A71DC-5559A726000163C1 ... May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] ... -AA759700", "command": "answer" } May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Shutting down ICE agent (nothing to do) May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Replying to 'answer' from 127.0.0.1:49396 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Response dump for 'answer' to 127.0.0.1:49396: { "sdp": "v=0#015#012o=sems 1244512444 1372754 IN IP4 <sip-proxy-rtpengine-ip>#015#012s=sems#015#012c=IN IP4 <sip-proxy-rtpengine-ip>#015#012t=0 0#015#012m=audio 8028 UDP/TLS/RTP/SAVPF 0 8 126#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:8 PCMA/8000#015#012a=rtpmap:126 telephone-event/8000#015#012a=direction:both#015#012a=sendrecv#015#012a=rtcp:8028#015#012a=rtcp-mux#015#012a=setup:active#015#012a=fingerprint:sha-1 B0:27:8A:76:DE:C4:2A:18:7D:C9:32:F6:78:79:5E:7B:1D:7C:DB:68#015#012a=ice-ufrag:joFSF896#015#012a=ice-pwd:AMtcYr30DYw7PhCsz1LU1Eoi12#015#012a=candidate:MmPVRTRG ... May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] ... 3mIW6Esg 1 UDP 2130706431 <sip-proxy-rtpengine-ip> 8028 typ host#015#012", "result": "ok" } May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8012] SRTP output wanted, but no crypto suite was negotiated May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u/0m9av72lp5/1] Sending ICE/STUN request for candidate pair MmPVRTRG3mIW6Esg:1603072777:1 from <sip-proxy-rtpengine-ip> to 192.168.43.146:35551 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u/0m9av72lp5/1] Sending ICE/STUN request for candidate pair MmPVRTRG3mIW6Esg:2466368666:1 from <sip-proxy-rtpengine-ip> to 93.106.207.11:35551 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Triggering check for MmPVRTRG3mIW6Esg:2466368666:1 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] ICE pair MmPVRTRG3mIW6Esg:2466368666:1 has been nominated by peer May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] ICE not completed yet May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u/0m9av72lp5/1] Sending ICE/STUN request for candidate pair MmPVRTRG3mIW6Esg:2466368666:1 from <sip-proxy-rtpengine-ip> to 93.106.207.11:35551 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Triggering check for MmPVRTRG3mIW6Esg:2466368666:1 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u/0m9av72lp5/1] Sending ICE/STUN request for candidate pair MmPVRTRG3mIW6Esg:2466368666:1 from <sip-proxy-rtpengine-ip> to 93.106.207.11:35551 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Received ICE/STUN response code 0 for candidate pair MmPVRTRG3mIW6Esg:2466368666:1 from 93.106.207.11:35551 to <sip-proxy-rtpengine-ip> May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Setting ICE candidate pair MmPVRTRG3mIW6Esg:2466368666:1 as succeeded May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Best succeeded ICE pair with all components is MmPVRTRG3mIW6Esg:2466368666:1 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] ICE completed, using pair MmPVRTRG3mIW6Esg:2466368666:1 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u/0m9av72lp5/1] Sending ICE/STUN request for candidate pair MmPVRTRG3mIW6Esg:1603072777:1 from <sip-proxy-rtpengine-ip> to 192.168.43.146:35551 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Received ICE/STUN response code 0 for candidate pair MmPVRTRG3mIW6Esg:2466368666:1 from 93.106.207.11:35551 to <sip-proxy-rtpengine-ip> May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] DTLS: Peer certificate accepted May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] DTLS handshake successful May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] DTLS-SRTP successfully negotiated May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] SRTP keys, incoming: May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] --- AES_CM_128_HMAC_SHA1_32 key jZlg8WlCjfEiFoilpz7BQg== salt gUcb/yOXBOIuNY1ItSw= May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] SRTP keys, outgoing: May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] --- AES_CM_128_HMAC_SHA1_32 key e3hQ8pCcc04HD8I7KWdl/g== salt mrNX5VDOk0+Tif2xv5I= May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] DTLS-SRTP successfully negotiated May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] SRTP keys, incoming: May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] --- AES_CM_128_HMAC_SHA1_32 key jZlg8WlCjfEiFoilpz7BQg== salt gUcb/yOXBOIuNY1ItSw= May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] SRTP keys, outgoing: May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] --- AES_CM_128_HMAC_SHA1_32 key e3hQ8pCcc04HD8I7KWdl/g== salt mrNX5VDOk0+Tif2xv5I= May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u/0m9av72lp5/1] Sending ICE/STUN request for candidate pair MmPVRTRG3mIW6Esg:1603072777:1 from <sip-proxy-rtpengine-ip> to 192.168.43.146:35551 May 18 11:47:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:35 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:35 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u/0m9av72lp5/1] Sending ICE/STUN request for candidate pair MmPVRTRG3mIW6Esg:1603072777:1 from <sip-proxy-rtpengine-ip> to 192.168.43.146:35551 May 18 11:47:35 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:36 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:36 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:37 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u/0m9av72lp5/1] Sending ICE/STUN request for candidate pair MmPVRTRG3mIW6Esg:1603072777:1 from <sip-proxy-rtpengine-ip> to 192.168.43.146:35551 May 18 11:47:37 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:37 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:38 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8012] Confirmed peer address as <sip-proxy-rtpengine-ip>:8064 May 18 11:47:38 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Confirmed peer address as 93.106.207.11:35551 May 18 11:47:38 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Kernelizing media stream May 18 11:47:38 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8012] Kernelizing media stream May 18 11:47:38 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:38 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:39 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:39 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:40 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:40 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u/0m9av72lp5/1] Sending ICE/STUN request for candidate pair MmPVRTRG3mIW6Esg:1603072777:1 from <sip-proxy-rtpengine-ip> to 192.168.43.146:35551 May 18 11:47:40 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:41 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:41 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:42 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:42 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:43 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:43 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:43 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:44 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:44 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:45 box proxy_monitor[24109]: SIP test succeeded with proxy 192.98.100.34! May 18 11:47:45 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:45 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:46 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:46 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u/0m9av72lp5/1] Sending ICE/STUN request for candidate pair MmPVRTRG3mIW6Esg:1603072777:1 from <sip-proxy-rtpengine-ip> to 192.168.43.146:35551 May 18 11:47:46 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:47 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:47 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:48 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:48 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:49 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:49 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:50 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:50 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:51 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:51 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:52 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:52 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:53 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:53 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:54 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:54 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:55 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:55 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:55 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:56 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:56 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:57 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:57 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:58 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:58 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:59 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:47:59 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u/0m9av72lp5/1] Sending ICE/STUN request for candidate pair MmPVRTRG3mIW6Esg:1603072777:1 from <sip-proxy-rtpengine-ip> to 192.168.43.146:35551 May 18 11:47:59 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:00 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:00 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:01 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:01 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:02 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:02 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:03 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:03 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:04 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:04 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:05 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:05 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:06 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:06 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:07 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:07 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:07 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:08 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:08 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:09 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:09 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:10 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:10 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:11 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:11 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:12 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:12 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:13 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:13 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:14 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:14 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:15 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:15 box proxy_monitor[24109]: SIP test succeeded with proxy 192.98.100.34! May 18 11:48:15 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:16 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:16 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:17 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:17 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:18 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:18 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:19 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:19 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:20 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:20 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:20 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:21 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:21 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:22 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:22 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:23 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:23 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:24 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:24 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:25 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u/0m9av72lp5/1] Setting ICE candidate pair MmPVRTRG3mIW6Esg:1603072777:1 as failed May 18 11:48:25 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:25 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:26 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:26 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:27 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:27 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:28 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:28 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:29 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:29 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:30 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:30 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:31 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:31 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:32 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:32 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:32 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:33 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:33 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:34 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:35 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:35 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u port 8028] Successful STUN binding request from 93.106.207.11:35551 May 18 11:48:36 box /usr/bin/sip-proxy[18297]: INFO: ===== rtpengine_delete(via-branch=1) May 18 11:48:36 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Received command 'delete' from 127.0.0.1:60722 May 18 11:48:36 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Dump for 'delete' from 127.0.0.1:60722: { "call-id": "e91aj9gq39qr07b6dn6u", "via-branch": "z9hG4bK3605983", "received-from": [ "IP4", "<sip-proxy-rtpengine-ip>" ], "from-tag": "0m9av72lp5", "command": "delete" } May 18 11:48:36 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Scheduling deletion of call branch '0m9av72lp5' in 30 seconds May 18 11:48:36 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Replying to 'delete' from 127.0.0.1:60722 May 18 11:48:36 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Response dump for 'delete' to 127.0.0.1:60722: { "created": 1431938854, "last signal": 1431938854, "tags": { "0m9av72lp5": { "tag": "0m9av72lp5", "created": 1431938854, "in dialogue with": "627A71DC-5559A726000163C1-AA759700", "medias": [ { "index": 1, "type": "audio", "protocol": "UDP/TLS/RTP/SAVPF", "streams": [ { "local port": 8028, "endpoint": { "family": "IPv4", "address": "93.106.207.11", "port": 35551 }, "advertised endpoint": { "family": "IPv4", "address": "93.106.207.11", "port": 35551 ... May 18 11:48:36 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] ... }, "crypto suite": "AES_CM_128_HMAC_SHA1_32", "last packet": 1431938916, "flags": [ "RTP", "RTCP", "filled", "confirmed", "kernelized", "DTLS fingerprint verified" ], "stats": { "packets": 3084, "bytes": 540700, "errors": 0 } }, { "local port": 8029, "endpoint": { "family": "IPv6", "address": "::", "port": 0 }, "advertised endpoint": { "family": "IPv6", "address": "::", "port": 0 }, "crypto suite": "AES_CM_128_HMAC_SHA1_32", "last packet": 1431938854, "flags": [ "RTCP", "fallback RTCP", "filled" ... May 18 11:48:36 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] ... ], "stats": { "packets": 0, "bytes": 0, "errors": 0 } } ], "flags": [ "initialized", "send", "recv", "rtcp-mux", "DTLS-SRTP", "DTLS role active", "ICE" ] } ] }, "627A71DC-5559A726000163C1-AA759700": { "tag": "627A71DC-5559A726000163C1-AA759700", "created": 1431938854, "in dialogue with": "0m9av72lp5", "medias": [ { "index": 1, "type": "audio", "protocol": "RTP/AVP", "streams": [ { "local port": 8012, "endpoint": { "family": "IPv4", "address": "<sip-proxy-rtpengine-ip>", "port": 8064 }, "advertised endpoin ... May 18 11:48:36 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] ... t": { "family": "IPv4", "address": "<sip-proxy-rtpengine-ip>", "port": 8064 }, "last packet": 1431938916, "flags": [ "RTP", "filled", "confirmed", "kernelized" ], "stats": { "packets": 3095, "bytes": 533040, "errors": 0 } }, { "local port": 8013, "endpoint": { "family": "IPv4", "address": "<sip-proxy-rtpengine-ip>", "port": 8065 }, "advertised endpoint": { "family": "IPv4", "address": "<sip-proxy-rtpengine-ip>", "port": 8065 }, "last packet": 1431938854, "flags": [ "RTCP", "filled" ], "stats": { "packets": 0, "bytes": 0, "error ... May 18 11:48:36 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] ... s": 0 } } ], "flags": [ "initialized", "send", "recv" ] } ] } }, "totals": { "RTP": { "packets": 6179, "bytes": 1073740, "errors": 0 }, "RTCP": { "packets": 0, "bytes": 0, "errors": 0 } }, "result": "ok" } May 18 11:49:06 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Call branch '0m9av72lp5' deleted, no more branches remaining May 18 11:49:06 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] Final packet stats: May 18 11:49:06 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] --- Tag '627A71DC-5559A726000163C1-AA759700', created 1:32 ago, in dialogue with '0m9av72lp5' May 18 11:49:06 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] ------ Media #1 (audio over RTP/AVP) using PCMU/8000 May 18 11:49:06 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] --------- Port 8012 <> <sip-proxy-rtpengine-ip>:8064 , 3110 p, 535620 b, 0 e, 1431938917 last_packet May 18 11:49:06 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] --------- Port 8013 <> <sip-proxy-rtpengine-ip>:8065 (RTCP), 0 p, 0 b, 0 e, 1431938854 last_packet May 18 11:49:06 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] --- Tag '0m9av72lp5', created 1:32 ago, in dialogue with '627A71DC-5559A726000163C1-AA759700' May 18 11:49:06 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] ------ Media #1 (audio over UDP/TLS/RTP/SAVPF) using PCMU/8000 May 18 11:49:06 box rtpengine[4025]: [e91aj9gq39qr07b6dn6u] --------- Port 8028 <> 93.106.207.11:35551, 3101 p, 543584 b, 0 e, 1431938917 last_packet