[SR-Users] WebRTC to PSTN call, proxied through Kamailio

Tue Feb 10 18:01:20 CET 2015

Hello gents,

I was trying my hands on getting a successful RTCweb call (JSsip, since
Peter Dunkley mentioned that he's been using JSsip for most of the testing
scenarios..) to PSTN, making my kamailio as proxy + protocol converter (sip
over web-sockets to sip over udp).
And yes, I've referred Carlos' config; the main problem is I get 'Bad Media
Description' error in Google Chromium (Version 40.0.2214.111 m) & my SIP
server even sends 200 OK, but my phone doesn't ring. To make it worse, I
can see rtpengine throwing this error -
"SRTCP output wanted, but no crypto suite was negotiated"

BTW, I have -
[root at localhost log]# openssl version
OpenSSL 1.0.1j 15 Oct 2014

I even tried building kamailio & rtpengine using this openssl but in-vain.
One thing that baffles me is that, apparently kamailio has started
receiving RTP packets (perhaps early media) but the mobile phone hasn't
ringed :-(

I am attaching all possible logs & seek some guidance from the array of
experts in this list.

Files attached:
a) tcpdump on ext. interface
b) tcpdump on loopback
c) syslogs
d) Chromium JS logs

UAC (14.98.55.38), Kamailio (125.99.186.126), SIP Server (157.238.178.153),
Media Server (199.27.244.6)

-- 
Warm Regds.
MathuRahul
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20150210/0578981f/attachment.html>
-------------- next part --------------
Feb 10 21:03:14 localhost kernel: device eth0 entered promiscuous mode
Feb 10 21:03:18 localhost kernel: device lo entered promiscuous mode
Feb 10 21:03:23 localhost kamailio[1419]: INFO: <script>: HTTP Request Received
Feb 10 21:03:48 localhost kamailio[1416]: INFO: <script>: Request coming from WS
Feb 10 21:03:48 localhost rtpengine[12747]: Got valid command from 127.0.0.1:49850: offer - { "sdp": "v=0#015#012o=- 5760917034187860830 2 IN IP4 127.0.0.1#015#012s=-#015#012t=0 0#015#012a=group:BUNDLE audio video#015#012a=msid-semantic: WMS bDOP3T153XbBsmXY46HuSPCyUwzKcx2tgPjw#015#012m=audio 50991 RTP/SAVPF 111 103 104 9 0 8 106 105 13 126#015#012c=IN IP4 14.98.55.38#015#012a=rtcp:50991 IN IP4 14.98.55.38#015#012a=candidate:1210958840 1 udp 2122260223 14.98.55.38 50991 typ host generation 0#015#012a=candidate:1210958840 2 udp 2122260223 14.98.55.38 50991 typ host generation 0#015#012a=candidate:1120826 ...
Feb 10 21:03:48 localhost rtpengine[12747]: ... 96 1 tcp 1518280447 14.98.55.38 0 typ host tcptype active generation 0#015#012a=candidate:112082696 2 tcp 1518280447 14.98.55.38 0 typ host tcptype active generation 0#015#012a=ice-ufrag:enoRV16TroxOkh+G#015#012a=ice-pwd:0rqjkKImTjlcTjkNKb4K44xs#015#012a=ice-options:google-ice#015#012a=fingerprint:sha-256 C1:96:B0:69:7A:4C:D6:3B:DD:6C:4B:83:BF:F6:45:56:43:95:B4:46:E0:11:BF:AB:2A:42:8D:47:F7:DA:A8:66#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/experim ...
Feb 10 21:03:48 localhost rtpengine[12747]: ... ents/rtp-hdrext/abs-send-time#015#012a=sendrecv#015#012a=rtcp-mux#015#012a=rtpmap:111 opus/48000/2#015#012a=fmtp:111 minptime=10#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 CN/8000#015#012a=rtpmap:126 telephone-event/8000#015#012a=maxptime:60#015#012a=ssrc:1572919354 cname:AEwgZRKGd7k8aWft#015#012a=ssrc:1572919354 msid:bDOP3T153XbBsmXY46HuSPCyUwzKcx2tgPjw c4516b27-0713-4931-b5e3-42f6555b4e94#015#012a=ssrc:1572919354 msl ...
Feb 10 21:03:48 localhost rtpengine[12747]: ... abel:bDOP3T153XbBsmXY46HuSPCyUwzKcx2tgPjw#015#012a=ssrc:1572919354 label:c4516b27-0713-4931-b5e3-42f6555b4e94#015#012m=video 50991 RTP/SAVPF 100 116 117 96#015#012c=IN IP4 14.98.55.38#015#012a=rtcp:50991 IN IP4 14.98.55.38#015#012a=candidate:1210958840 1 udp 2122260223 14.98.55.38 50991 typ host generation 0#015#012a=candidate:1210958840 2 udp 2122260223 14.98.55.38 50991 typ host generation 0#015#012a=candidate:112082696 1 tcp 1518280447 14.98.55.38 0 typ host tcptype active generation 0#015#012a=candidate:112082696 2 tcp 1518280447 14.98.55 ...
Feb 10 21:03:48 localhost rtpengine[12747]: ... .38 0 typ host tcptype active generation 0#015#012a=ice-ufrag:enoRV16TroxOkh+G#015#012a=ice-pwd:0rqjkKImTjlcTjkNKb4K44xs#015#012a=ice-options:google-ice#015#012a=fingerprint:sha-256 C1:96:B0:69:7A:4C:D6:3B:DD:6C:4B:83:BF:F6:45:56:43:95:B4:46:E0:11:BF:AB:2A:42:8D:47:F7:DA:A8:66#015#012a=setup:actpass#015#012a=mid:video#015#012a=extmap:2 urn:ietf:params:rtp-hdrext:toffset#015#012a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time#015#012a=recvonly#015#012a=rtcp-mux#015#012a=rtpmap:100 VP8/90000#015#012a=rtcp-fb:100 ccm fir#015#012a=rtcp-fb:100 nack#015#012a=rtcp ...
Feb 10 21:03:48 localhost rtpengine[12747]: ... -fb:100 nack pli#015#012a=rtcp-fb:100 goog-remb#015#012a=rtpmap:116 red/90000#015#012a=rtpmap:117 ulpfec/90000#015#012a=rtpmap:96 rtx/90000#015#012a=fmtp:96 apt=100#015#012", "ICE": "remove", "flags": [ "force", "symmetric", "trust-address" ], "replace": [ "origin", "session-connection" ], "transport-protocol": "RTP/AVP", "rtcp-mux": [ "demux" ], "call-id": "4jai8q3sakqvb2b1dgbr", "received-from": [ "IP4", "14.98.55.38" ], "from-tag": "u1bhsv4lij", "command": "offer" }
Feb 10 21:03:48 localhost rtpengine[12747]: Unknown flag encountered: 'force'
Feb 10 21:03:48 localhost rtpengine[12747]: Unknown flag encountered: 'symmetric'
Feb 10 21:03:48 localhost rtpengine[12747]: [4jai8q3sakqvb2b1dgbr] Creating new call
Feb 10 21:03:48 localhost rtpengine[12747]: [4jai8q3sakqvb2b1dgbr] Returning to SIP proxy: d3:sdp1080:v=0#015#012o=- 5760917034187860830 2 IN IP4 125.99.186.126#015#012s=-#015#012t=0 0#015#012a=msid-semantic: WMS bDOP3T153XbBsmXY46HuSPCyUwzKcx2tgPjw#015#012m=audio 30486 RTP/AVP 111 103 104 9 0 8 106 105 13 126#015#012c=IN IP4 125.99.186.126#015#012a=rtpmap:111 opus/48000/2#015#012a=fmtp:111 minptime=10#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 CN/8000#015#012a=rtpmap:126 telephone-ev ...
Feb 10 21:03:48 localhost rtpengine[12747]: [4jai8q3sakqvb2b1dgbr] ... ent/8000#015#012a=maxptime:60#015#012a=ssrc:1572919354 cname:AEwgZRKGd7k8aWft#015#012a=ssrc:1572919354 msid:bDOP3T153XbBsmXY46HuSPCyUwzKcx2tgPjw c4516b27-0713-4931-b5e3-42f6555b4e94#015#012a=ssrc:1572919354 mslabel:bDOP3T153XbBsmXY46HuSPCyUwzKcx2tgPjw#015#012a=ssrc:1572919354 label:c4516b27-0713-4931-b5e3-42f6555b4e94#015#012a=sendrecv#015#012a=rtcp:30487#015#012m=video 30516 RTP/AVP 100 116 117 96#015#012c=IN IP4 125.99.186.126#015#012a=rtpmap:100 VP8/90000#015#012a=rtcp-fb:100 ccm fir#015#012a=rtcp-fb:100 nack#015#012a=rtcp-fb:100 nack pli#015#012a=rtcp-fb:100 goog-remb#015#012a=rt ...
Feb 10 21:03:48 localhost rtpengine[12747]: [4jai8q3sakqvb2b1dgbr] ... pmap:116 red/90000#015#012a=rtpmap:117 ulpfec/90000#015#012a=rtpmap:96 rtx/90000#015#012a=fmtp:96 apt=100#015#012a=recvonly#015#012a=rtcp:30517#015#0126:result2:oke
Feb 10 21:03:48 localhost kamailio[1406]: INFO: <script>: Reply from softphone: 100
Feb 10 21:03:49 localhost kamailio[1409]: INFO: <script>: Reply from softphone: 183
Feb 10 21:03:49 localhost rtpengine[12747]: Got valid command from 127.0.0.1:41308: answer - { "sdp": "v=0#015#012o=CiscoSystemsSIP-GW-UserAgent 5600 4664 IN IP4 199.27.244.6#015#012s=SIP Call#015#012c=IN IP4 199.27.244.6#015#012t=0 0#015#012m=audio 18256 RTP/AVP 0 126 100#015#012c=IN IP4 199.27.244.6#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:126 telephone-event/8000#015#012a=fmtp:126 0-16#015#012a=rtpmap:100 X-NSE/8000#015#012a=fmtp:100 192-194#015#012a=direction:active#015#012m=video 0 RTP/AVP#015#012c=IN IP4 199.27.244.6#015#012a=direction:active#015#012", "ICE": "force", "flags": [ "force", "trust-address" ], "replace": [ "ori ...
Feb 10 21:03:49 localhost rtpengine[12747]: ... gin", "session-connection" ], "transport-protocol": "RTP/SAVPF", "call-id": "4jai8q3sakqvb2b1dgbr", "received-from": [ "IP4", "157.238.178.153" ], "from-tag": "u1bhsv4lij", "to-tag": "C7FA5DA8-1564", "command": "answer" }
Feb 10 21:03:49 localhost rtpengine[12747]: Unknown flag encountered: 'force'
Feb 10 21:03:49 localhost rtpengine[12747]: [4jai8q3sakqvb2b1dgbr] Returning to SIP proxy: d3:sdp664:v=0#015#012o=CiscoSystemsSIP-GW-UserAgent 5600 4664 IN IP4 125.99.186.126#015#012s=SIP Call#015#012c=IN IP4 125.99.186.126#015#012t=0 0#015#012a=ice-lite#015#012m=audio 30500 RTP/SAVPF 0 126 100#015#012c=IN IP4 125.99.186.126#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:126 telephone-event/8000#015#012a=fmtp:126 0-16#015#012a=rtpmap:100 X-NSE/8000#015#012a=fmtp:100 192-194#015#012a=direction:active#015#012a=sendrecv#015#012a=rtcp:30500#015#012a=rtcp-mux#015#012a=setup:active#015#012a=fingerprint:sha-1 19:20:8B:A1:21:7A:F6:DC:CC:5F:93:1C:AD:87:B8:79:1D:82:CB:7F#015#012a=ice-u ...
Feb 10 21:03:49 localhost rtpengine[12747]: [4jai8q3sakqvb2b1dgbr] ... frag:fAEspN6B#015#012a=ice-pwd:fNZeKi82LXzgibdAgEjkXFHaejAB#015#012a=candidate:eXTcQtyPehJo7hZS 1 UDP 2130706431 125.99.186.126 30500 typ host#015#012m=video 0 RTP/SAVPF#015#012c=IN IP4 125.99.186.126#015#012a=direction:active#015#0126:result2:oke
Feb 10 21:03:49 localhost kamailio[1408]: INFO: <script>: Reply from softphone: 200
Feb 10 21:03:49 localhost rtpengine[12747]: Got valid command from 127.0.0.1:33805: answer - { "sdp": "v=0#015#012o=CiscoSystemsSIP-GW-UserAgent 5600 4664 IN IP4 199.27.244.6#015#012s=SIP Call#015#012c=IN IP4 199.27.244.6#015#012t=0 0#015#012m=audio 18256 RTP/AVP 0 126 100#015#012c=IN IP4 199.27.244.6#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:126 telephone-event/8000#015#012a=fmtp:126 0-16#015#012a=rtpmap:100 X-NSE/8000#015#012a=fmtp:100 192-194#015#012a=direction:active#015#012m=video 0 RTP/AVP#015#012c=IN IP4 199.27.244.6#015#012a=direction:active#015#012", "ICE": "force", "flags": [ "force", "trust-address" ], "replace": [ "ori ...
Feb 10 21:03:49 localhost rtpengine[12747]: ... gin", "session-connection" ], "transport-protocol": "RTP/SAVPF", "call-id": "4jai8q3sakqvb2b1dgbr", "received-from": [ "IP4", "157.238.178.153" ], "from-tag": "u1bhsv4lij", "to-tag": "C7FA5DA8-1564", "command": "answer" }
Feb 10 21:03:49 localhost rtpengine[12747]: Unknown flag encountered: 'force'
Feb 10 21:03:49 localhost rtpengine[12747]: [4jai8q3sakqvb2b1dgbr] Returning to SIP proxy: d3:sdp664:v=0#015#012o=CiscoSystemsSIP-GW-UserAgent 5600 4664 IN IP4 125.99.186.126#015#012s=SIP Call#015#012c=IN IP4 125.99.186.126#015#012t=0 0#015#012a=ice-lite#015#012m=audio 30500 RTP/SAVPF 0 126 100#015#012c=IN IP4 125.99.186.126#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:126 telephone-event/8000#015#012a=fmtp:126 0-16#015#012a=rtpmap:100 X-NSE/8000#015#012a=fmtp:100 192-194#015#012a=direction:active#015#012a=sendrecv#015#012a=rtcp:30500#015#012a=rtcp-mux#015#012a=setup:active#015#012a=fingerprint:sha-1 19:20:8B:A1:21:7A:F6:DC:CC:5F:93:1C:AD:87:B8:79:1D:82:CB:7F#015#012a=ice-u ...
Feb 10 21:03:49 localhost rtpengine[12747]: [4jai8q3sakqvb2b1dgbr] ... frag:fAEspN6B#015#012a=ice-pwd:fNZeKi82LXzgibdAgEjkXFHaejAB#015#012a=candidate:eXTcQtyPehJo7hZS 1 UDP 2130706431 125.99.186.126 30500 typ host#015#012m=video 0 RTP/SAVPF#015#012c=IN IP4 125.99.186.126#015#012a=direction:active#015#0126:result2:oke
Feb 10 21:03:49 localhost rtpengine[12747]: [4jai8q3sakqvb2b1dgbr port 30486] SRTP output wanted, but no crypto suite was negotiated
Feb 10 21:03:49 localhost kamailio[1407]: INFO: <script>: Reply from softphone: 200
Feb 10 21:03:49 localhost rtpengine[12747]: Got valid command from 127.0.0.1:35196: answer - { "sdp": "v=0#015#012o=CiscoSystemsSIP-GW-UserAgent 5600 4664 IN IP4 199.27.244.6#015#012s=SIP Call#015#012c=IN IP4 199.27.244.6#015#012t=0 0#015#012m=audio 18256 RTP/AVP 0 126 100#015#012c=IN IP4 199.27.244.6#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:126 telephone-event/8000#015#012a=fmtp:126 0-16#015#012a=rtpmap:100 X-NSE/8000#015#012a=fmtp:100 192-194#015#012a=direction:active#015#012m=video 0 RTP/AVP#015#012c=IN IP4 199.27.244.6#015#012a=direction:active#015#012", "ICE": "force", "flags": [ "force", "trust-address" ], "replace": [ "ori ...
Feb 10 21:03:49 localhost rtpengine[12747]: ... gin", "session-connection" ], "transport-protocol": "RTP/SAVPF", "call-id": "4jai8q3sakqvb2b1dgbr", "received-from": [ "IP4", "157.238.178.153" ], "from-tag": "u1bhsv4lij", "to-tag": "C7FA5DA8-1564", "command": "answer" }
Feb 10 21:03:49 localhost rtpengine[12747]: Unknown flag encountered: 'force'
Feb 10 21:03:49 localhost rtpengine[12747]: [4jai8q3sakqvb2b1dgbr] Returning to SIP proxy: d3:sdp664:v=0#015#012o=CiscoSystemsSIP-GW-UserAgent 5600 4664 IN IP4 125.99.186.126#015#012s=SIP Call#015#012c=IN IP4 125.99.186.126#015#012t=0 0#015#012a=ice-lite#015#012m=audio 30500 RTP/SAVPF 0 126 100#015#012c=IN IP4 125.99.186.126#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:126 telephone-event/8000#015#012a=fmtp:126 0-16#015#012a=rtpmap:100 X-NSE/8000#015#012a=fmtp:100 192-194#015#012a=direction:active#015#012a=sendrecv#015#012a=rtcp:30500#015#012a=rtcp-mux#015#012a=setup:active#015#012a=fingerprint:sha-1 19:20:8B:A1:21:7A:F6:DC:CC:5F:93:1C:AD:87:B8:79:1D:82:CB:7F#015#012a=ice-u ...
Feb 10 21:03:49 localhost rtpengine[12747]: [4jai8q3sakqvb2b1dgbr] ... frag:fAEspN6B#015#012a=ice-pwd:fNZeKi82LXzgibdAgEjkXFHaejAB#015#012a=candidate:eXTcQtyPehJo7hZS 1 UDP 2130706431 125.99.186.126 30500 typ host#015#012m=video 0 RTP/SAVPF#015#012c=IN IP4 125.99.186.126#015#012a=direction:active#015#0126:result2:oke
Feb 10 21:03:52 localhost kamailio[1406]: ERROR: <script>: ==> duri=[sip:14.98.55.38:65077;transport=ws]
Feb 10 21:03:53 localhost kamailio[1416]: ERROR: <script>: ==> duri=[sip:157.238.178.153;lr=on]
Feb 10 21:03:53 localhost kamailio[1409]: ERROR: <script>: ==> duri=[sip:14.98.55.38:65077;transport=ws]
Feb 10 21:03:54 localhost kamailio[1408]: ERROR: <script>: ==> duri=[sip:14.98.55.38:65077;transport=ws]
Feb 10 21:03:56 localhost rtpengine[12747]: [4jai8q3sakqvb2b1dgbr port 30487] SRTCP output wanted, but no crypto suite was negotiated
Feb 10 21:03:56 localhost rtpengine[12747]: [4jai8q3sakqvb2b1dgbr port 30487] Confirmed peer address as 199.27.244.6:18257
Feb 10 21:03:58 localhost kamailio[1416]: ERROR: <script>: ==> duri=[sip:157.238.178.153;lr=on]
Feb 10 21:04:03 localhost kernel: device eth0 left promiscuous mode
Feb 10 21:04:04 localhost kernel: device lo left promiscuous mode
Feb 10 21:11:52 localhost rtpengine[12747]: [4jai8q3sakqvb2b1dgbr] DTLS error: 1 (read timeout expired)
Feb 10 21:11:52 localhost rtpengine[12747]: [4jai8q3sakqvb2b1dgbr] DTLS error on local port 30500

^C
[root at localhost log]#
-------------- next part --------------
A non-text attachment was scrubbed...
Name: jssip.pcap
Type: application/octet-stream
Size: 83177 bytes
Desc: not available
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20150210/0578981f/attachment.obj>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: loopback.pcap
Type: application/octet-stream
Size: 8442 bytes
Desc: not available
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20150210/0578981f/attachment-0001.obj>
-------------- next part --------------
 JsSIP:RTCSession new +51s
jssip.js:21352 JsSIP:RTCSession connect() +3ms
jssip.js:21352 rtcninja:Connection new | original pcConfig: +8ms Object {iceServers: Array[1], gatheringTimeout: 2000}gatheringTimeout: 2000iceServers: Array[1]__proto__: Object
jssip.js:21352 rtcninja:Connection new | processed pcConfig: +7ms Object {iceServers: Array[1]}
jssip.js:21352 JsSIP:RTCSession newRTCSession +4ms
jssip.js:21352 rtcninja:Adapter getUserMedia() | constraints: +45ms Object {audio: true, video: false}
jssip.js:21352 rtcninja:Adapter getUserMedia() | success +2s
jssip.js:21352 rtcninja:Connection addStream() | stream: +2ms MediaStream {onremovetrack: null, onaddtrack: null, onended: null, ended: false, id: "RvT60NrREEMPtO8sMT1cxZHtl4jES3tTIw4Y"�}
jssip.js:21352 JsSIP:RTCSession session connecting +10ms
jssip.js:21352 JsSIP:RTCSession createLocalDescription() +34ms
jssip.js:21352 rtcninja:Connection createOffer() +2ms
jssip.js:21352 rtcninja:Connection createOffer() | success +17ms
jssip.js:21352 rtcninja:Connection setLocalDescription() +3ms
jssip.js:21352 rtcninja:Connection onnegotiationneeded() +4ms
jssip.js:21352 rtcninja:Connection onsignalingstatechange() | signalingState: have-local-offer +124ms
jssip.js:21352 rtcninja:Connection setLocalDescription() | success +4ms
jssip.js:21352 rtcninja:Connection setLocalDescription() | ending gathering in 2000 ms (gatheringTimeout option) +1ms
jssip.js:21352 rtcninja:Connection onicecandidate() | m0(audio) candidate:1210958840 1 udp 2122260223 14.98.55.38 55133 typ host generation 0 +3ms
jssip.js:21352 rtcninja:Connection onicecandidate() | m0(audio) candidate:1210958840 2 udp 2122260223 14.98.55.38 55133 typ host generation 0 +2ms
jssip.js:21352 rtcninja:Connection onicecandidate() | m1(video) candidate:1210958840 1 udp 2122260223 14.98.55.38 55133 typ host generation 0 +1ms
jssip.js:21352 rtcninja:Connection onicecandidate() | m1(video) candidate:1210958840 2 udp 2122260223 14.98.55.38 55133 typ host generation 0 +0ms
jssip.js:21352 rtcninja:Connection onicecandidate() | m0(audio) candidate:112082696 1 tcp 1518280447 14.98.55.38 0 typ host tcptype active generation 0 +108ms
jssip.js:21352 rtcninja:Connection onicecandidate() | m0(audio) candidate:112082696 2 tcp 1518280447 14.98.55.38 0 typ host tcptype active generation 0 +5ms
jssip.js:21352 rtcninja:Connection onicecandidate() | m1(video) candidate:112082696 1 tcp 1518280447 14.98.55.38 0 typ host tcptype active generation 0 +2ms
jssip.js:21352 rtcninja:Connection onicecandidate() | m1(video) candidate:112082696 2 tcp 1518280447 14.98.55.38 0 typ host tcptype active generation 0 +1ms
jssip.js:21352 rtcninja:Connection onicecandidate() | end of candidates +2s
jssip.js:21352 JsSIP:Transport sending WebSocket message:

INVITE sip:919650926333 at 157.238.178.153 SIP/2.0
Route: <sip:125.99.186.126:10080;transport=ws;lr>
Via: SIP/2.0/TCP hri628j4n5cm.invalid;branch=z9hG4bK1380799
Max-Forwards: 69
To: <sip:919650926333 at 157.238.178.153>
From: "1399004090" <sip:1399004090 at 157.238.178.153>;tag=jfgg6hl98u
Call-ID: s9b5v9g1im3d6o091ik4
CSeq: 227 INVITE
Contact: <sip:7qaa5cuh at hri628j4n5cm.invalid;transport=ws;ob>
Content-Type: application/sdp
Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS
Supported: ice,outbound
User-Agent: JsSIP 0.6.4
Content-Length: 2578

v=0
o=- 4334093953556381344 2 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE audio video
a=msid-semantic: WMS RvT60NrREEMPtO8sMT1cxZHtl4jES3tTIw4Y
m=audio 55133 RTP/SAVPF 111 103 104 9 0 8 106 105 13 126
c=IN IP4 14.98.55.38
a=rtcp:55133 IN IP4 14.98.55.38
a=candidate:1210958840 1 udp 2122260223 14.98.55.38 55133 typ host generation 0
a=candidate:1210958840 2 udp 2122260223 14.98.55.38 55133 typ host generation 0
a=candidate:112082696 1 tcp 1518280447 14.98.55.38 0 typ host tcptype active generation 0
a=candidate:112082696 2 tcp 1518280447 14.98.55.38 0 typ host tcptype active generation 0
a=ice-ufrag:gHzDeSdZiGNnghsv
a=ice-pwd:/tOv8QyfjquC7n/qHjQFg5JH
a=ice-options:google-ice
a=fingerprint:sha-256 C1:96:B0:69:7A:4C:D6:3B:DD:6C:4B:83:BF:F6:45:56:43:95:B4:46:E0:11:BF:AB:2A:42:8D:47:F7:DA:A8:66
a=setup:actpass
a=mid:audio
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=sendrecv
a=rtcp-mux
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10
a=rtpmap:103 ISAC/16000
a=rtpmap:104 ISAC/32000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:126 telephone-event/8000
a=maxptime:60
a=ssrc:4270178289 cname:H/y42GPRH2V6rGAs
a=ssrc:4270178289 msid:RvT60NrREEMPtO8sMT1cxZHtl4jES3tTIw4Y c0d7e44b-11e8-4fc7-a33e-1e8ee770ad9a
a=ssrc:4270178289 mslabel:RvT60NrREEMPtO8sMT1cxZHtl4jES3tTIw4Y
a=ssrc:4270178289 label:c0d7e44b-11e8-4fc7-a33e-1e8ee770ad9a
m=video 55133 RTP/SAVPF 100 116 117 96
c=IN IP4 14.98.55.38
a=rtcp:55133 IN IP4 14.98.55.38
a=candidate:1210958840 1 udp 2122260223 14.98.55.38 55133 typ host generation 0
a=candidate:1210958840 2 udp 2122260223 14.98.55.38 55133 typ host generation 0
a=candidate:112082696 1 tcp 1518280447 14.98.55.38 0 typ host tcptype active generation 0
a=candidate:112082696 2 tcp 1518280447 14.98.55.38 0 typ host tcptype active generation 0
a=ice-ufrag:gHzDeSdZiGNnghsv
a=ice-pwd:/tOv8QyfjquC7n/qHjQFg5JH
a=ice-options:google-ice
a=fingerprint:sha-256 C1:96:B0:69:7A:4C:D6:3B:DD:6C:4B:83:BF:F6:45:56:43:95:B4:46:E0:11:BF:AB:2A:42:8D:47:F7:DA:A8:66
a=setup:actpass
a=mid:video
a=extmap:2 urn:ietf:params:rtp-hdrext:toffset
a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=recvonly
a=rtcp-mux
a=rtpmap:100 VP8/90000
a=rtcp-fb:100 ccm fir
a=rtcp-fb:100 nack
a=rtcp-fb:100 nack pli
a=rtcp-fb:100 goog-remb
a=rtpmap:116 red/90000
a=rtpmap:117 ulpfec/90000
a=rtpmap:96 rtx/90000
a=fmtp:96 apt=100

 +12ms
jssip.js:21352 JsSIP:Transport received WebSocket text message:

SIP/2.0 100 trying -- your call is important to us
Via: SIP/2.0/TCP hri628j4n5cm.invalid;branch=z9hG4bK1380799;rport=65213;received=14.98.55.38
To: <sip:919650926333 at 157.238.178.153>
From: "1399004090" <sip:1399004090 at 157.238.178.153>;tag=jfgg6hl98u
Call-ID: s9b5v9g1im3d6o091ik4
CSeq: 227 INVITE
Server: kamailio (4.2.2 (x86_64/linux))
Content-Length: 0

 +11s
jssip.js:21352 JsSIP:RTCSession receiveInviteResponse() +14ms
jssip.js:21352 JsSIP:Transport received WebSocket text message:

SIP/2.0 183 Session Progress
Via: SIP/2.0/TCP hri628j4n5cm.invalid;rport=65213;received=14.98.55.38;branch=z9hG4bK1380799
From: "1399004090" <sip:1399004090 at 157.238.178.153>;tag=jfgg6hl98u
To: <sip:919650926333 at 157.238.178.153>;tag=C80AA6C8-10C2
Date: Thu, 06 Jul 2000 23:23:57 GMT
Call-ID: s9b5v9g1im3d6o091ik4
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 227 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
Allow-Events: telephone-event
Contact: <sip:8887999561919650926333 at 199.27.244.6:5060>
Record-Route: <sip:157.238.178.153;lr=on>,<sip:uTUdc+8P6hkyugV9Y7p+J2AOYjcm/r0=@125.99.186.126:5090;nat=yes;lr=on>
Content-Disposition: session;handling=required
Content-Type: application/sdp
Content-Length: 664

v=0
o=CiscoSystemsSIP-GW-UserAgent 1436 9079 IN IP4 125.99.186.126
s=SIP Call
c=IN IP4 125.99.186.126
t=0 0
a=ice-lite
m=audio 30628 RTP/SAVPF 0 126 100
c=IN IP4 125.99.186.126
a=rtpmap:0 PCMU/8000
a=rtpmap:126 telephone-event/8000
a=fmtp:126 0-16
a=rtpmap:100 X-NSE/8000
a=fmtp:100 192-194
a=direction:active
a=sendrecv
a=rtcp:30628
a=rtcp-mux
a=setup:active
a=fingerprint:sha-1 19:20:8B:A1:21:7A:F6:DC:CC:5F:93:1C:AD:87:B8:79:1D:82:CB:7F
a=ice-ufrag:bzc4dCbt
a=ice-pwd:hDIBK6ab3iGIDqN6H43xvaVEJ7IU
a=candidate:eXTcQtyPehJo7hZS 1 UDP 2130706431 125.99.186.126 30628 typ host
m=video 0 RTP/SAVPF
c=IN IP4 125.99.186.126
a=direction:active

 +2ms
jssip.js:21352 JsSIP:RTCSession receiveInviteResponse() +30ms
jssip.js:21352 JsSIP:Dialog new UAC dialog created with status EARLY +4ms
jssip.js:21352 JsSIP:RTCSession session progress +2ms
jssip.js:21352 rtcninja:Connection setRemoteDescription() +7ms
jssip.js:21510 rtcninja:ERROR:Connection setRemoteDescription() | error: +3ms Failed to parse SessionDescription. m=video 0 RTP/SAVPF Expects at least 4 fields.
jssip.js:21352 JsSIP:Dialog dialog s9b5v9g1im3d6o091ik4jfgg6hl98uC80AA6C8-10C2 deleted +3ms
jssip.js:21352 JsSIP:Transport received WebSocket text message:

SIP/2.0 200 OK
Via: SIP/2.0/TCP hri628j4n5cm.invalid;rport=65213;received=14.98.55.38;branch=z9hG4bK1380799
From: "1399004090" <sip:1399004090 at 157.238.178.153>;tag=jfgg6hl98u
To: <sip:919650926333 at 157.238.178.153>;tag=C80AA6C8-10C2
Date: Thu, 06 Jul 2000 23:23:57 GMT
Call-ID: s9b5v9g1im3d6o091ik4
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 227 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
Allow-Events: telephone-event
Contact: <sip:8887999561919650926333 at 199.27.244.6:5060>
Record-Route: <sip:157.238.178.153;lr=on>,<sip:uTUdc+8P6hkyugV9Y7p+J2AOYjcm/r0=@125.99.186.126:5090;nat=yes;lr=on>
Content-Type: application/sdp
Content-Length: 664

v=0
o=CiscoSystemsSIP-GW-UserAgent 1436 9079 IN IP4 125.99.186.126
s=SIP Call
c=IN IP4 125.99.186.126
t=0 0
a=ice-lite
m=audio 30628 RTP/SAVPF 0 126 100
c=IN IP4 125.99.186.126
a=rtpmap:0 PCMU/8000
a=rtpmap:126 telephone-event/8000
a=fmtp:126 0-16
a=rtpmap:100 X-NSE/8000
a=fmtp:100 192-194
a=direction:active
a=sendrecv
a=rtcp:30628
a=rtcp-mux
a=setup:active
a=fingerprint:sha-1 19:20:8B:A1:21:7A:F6:DC:CC:5F:93:1C:AD:87:B8:79:1D:82:CB:7F
a=ice-ufrag:bzc4dCbt
a=ice-pwd:hDIBK6ab3iGIDqN6H43xvaVEJ7IU
a=candidate:eXTcQtyPehJo7hZS 1 UDP 2130706431 125.99.186.126 30628 typ host
m=video 0 RTP/SAVPF
c=IN IP4 125.99.186.126
a=direction:active

 +2ms
jssip.js:21352 JsSIP:RTCSession receiveInviteResponse() +31ms
jssip.js:21352 JsSIP:Dialog dialog s9b5v9g1im3d6o091ik4jfgg6hl98uC80AA6C8-10C2  changed to CONFIRMED state +5ms
jssip.js:21352 rtcninja:Connection setRemoteDescription() +1ms
jssip.js:21510 rtcninja:ERROR:Connection setRemoteDescription() | error: +2ms Failed to parse SessionDescription. m=video 0 RTP/SAVPF Expects at least 4 fields.
jssip.js:21352 JsSIP:RTCSession acceptAndTerminate() +1ms
jssip.js:21352 JsSIP:RTCSession sendRequest() +2ms
jssip.js:21352 JsSIP:RTCSession:Request new | ACK +1ms
jssip.js:21352 JsSIP:Transport sending WebSocket message:

ACK sip:8887999561919650926333 at 199.27.244.6:5060 SIP/2.0
Route: <sip:uTUdc+8P6hkyugV9Y7p+J2AOYjcm/r0=@125.99.186.126:5090;nat=yes;lr=on>
Route: <sip:157.238.178.153;lr=on>
Via: SIP/2.0/TCP hri628j4n5cm.invalid;branch=z9hG4bK5306387
Max-Forwards: 69
To: <sip:919650926333 at 157.238.178.153>;tag=C80AA6C8-10C2
From: "1399004090" <sip:1399004090 at 157.238.178.153>;tag=jfgg6hl98u
Call-ID: s9b5v9g1im3d6o091ik4
CSeq: 227 ACK
Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS
Supported: outbound
User-Agent: JsSIP 0.6.4
Content-Length: 0

 +7ms
jssip.js:21352 JsSIP:RTCSession sendRequest() +2ms
jssip.js:21352 JsSIP:RTCSession:Request new | BYE +1ms
jssip.js:21352 JsSIP:Transport sending WebSocket message:

BYE sip:8887999561919650926333 at 199.27.244.6:5060 SIP/2.0
Route: <sip:uTUdc+8P6hkyugV9Y7p+J2AOYjcm/r0=@125.99.186.126:5090;nat=yes;lr=on>
Route: <sip:157.238.178.153;lr=on>
Via: SIP/2.0/TCP hri628j4n5cm.invalid;branch=z9hG4bK1957267
Max-Forwards: 69
To: <sip:919650926333 at 157.238.178.153>;tag=C80AA6C8-10C2
From: "1399004090" <sip:1399004090 at 157.238.178.153>;tag=jfgg6hl98u
Call-ID: s9b5v9g1im3d6o091ik4
CSeq: 228 BYE
Reason: SIP ;cause=488; text="Not Acceptable Here"
Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS
Supported: outbound
User-Agent: JsSIP 0.6.4
Content-Length: 0

 +8ms
jssip.js:21352 JsSIP:RTCSession session failed +2ms
jssip.js:21352 JsSIP:RTCSession close() +1ms
jssip.js:21352 JsSIP:Transport received WebSocket text message:

SIP/2.0 200 OK
Via: SIP/2.0/TCP hri628j4n5cm.invalid;rport=65213;received=14.98.55.38;branch=z9hG4bK1380799
From: "1399004090" <sip:1399004090 at 157.238.178.153>;tag=jfgg6hl98u
To: <sip:919650926333 at 157.238.178.153>;tag=C80AA6C8-10C2
Date: Thu, 06 Jul 2000 23:23:57 GMT
Call-ID: s9b5v9g1im3d6o091ik4
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 227 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
Allow-Events: telephone-event
Contact: <sip:8887999561919650926333 at 199.27.244.6:5060>
Record-Route: <sip:157.238.178.153;lr=on>,<sip:uTUdc+8P6hkyugV9Y7p+J2AOYjcm/r0=@125.99.186.126:5090;nat=yes;lr=on>
Content-Type: application/sdp
Content-Length: 664

v=0
o=CiscoSystemsSIP-GW-UserAgent 1436 9079 IN IP4 125.99.186.126
s=SIP Call
c=IN IP4 125.99.186.126
t=0 0
a=ice-lite
m=audio 30628 RTP/SAVPF 0 126 100
c=IN IP4 125.99.186.126
a=rtpmap:0 PCMU/8000
a=rtpmap:126 telephone-event/8000
a=fmtp:126 0-16
a=rtpmap:100 X-NSE/8000
a=fmtp:100 192-194
a=direction:active
a=sendrecv
a=rtcp:30628
a=rtcp-mux
a=setup:active
a=fingerprint:sha-1 19:20:8B:A1:21:7A:F6:DC:CC:5F:93:1C:AD:87:B8:79:1D:82:CB:7F
a=ice-ufrag:bzc4dCbt
a=ice-pwd:hDIBK6ab3iGIDqN6H43xvaVEJ7IU
a=candidate:eXTcQtyPehJo7hZS 1 UDP 2130706431 125.99.186.126 30628 typ host
m=video 0 RTP/SAVPF
c=IN IP4 125.99.186.126
a=direction:active

 +16ms
jssip.js:21352 JsSIP:RTCSession receiveInviteResponse() +32ms
jssip.js:21352 JsSIP:RTCSession sendRequest() +1ms
jssip.js:21352 JsSIP:RTCSession:Request new | ACK +3ms
jssip.js:16102 Uncaught INVALID_STATE_ERROR: Invalid status: 8
jssip.js:21352 JsSIP:Transport received WebSocket text message:

SIP/2.0 200 OK
Via: SIP/2.0/TCP hri628j4n5cm.invalid;rport=65213;received=14.98.55.38;branch=z9hG4bK1380799
From: "1399004090" <sip:1399004090 at 157.238.178.153>;tag=jfgg6hl98u
To: <sip:919650926333 at 157.238.178.153>;tag=C80AA6C8-10C2
Date: Thu, 06 Jul 2000 23:23:57 GMT
Call-ID: s9b5v9g1im3d6o091ik4
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 227 INVITE
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
Allow-Events: telephone-event
Contact: <sip:8887999561919650926333 at 199.27.244.6:5060>
Record-Route: <sip:157.238.178.153;lr=on>,<sip:uTUdc+8P6hkyugV9Y7p+J2AOYjcm/r0=@125.99.186.126:5090;nat=yes;lr=on>
Content-Type: application/sdp
Content-Length: 664

v=0
o=CiscoSystemsSIP-GW-UserAgent 1436 9079 IN IP4 125.99.186.126
s=SIP Call
c=IN IP4 125.99.186.126
t=0 0
a=ice-lite
m=audio 30628 RTP/SAVPF 0 126 100
c=IN IP4 125.99.186.126
a=rtpmap:0 PCMU/8000
a=rtpmap:126 telephone-event/8000
a=fmtp:126 0-16
a=rtpmap:100 X-NSE/8000
a=fmtp:100 192-194
a=direction:active
a=sendrecv
a=rtcp:30628
a=rtcp-mux
a=setup:active
a=fingerprint:sha-1 19:20:8B:A1:21:7A:F6:DC:CC:5F:93:1C:AD:87:B8:79:1D:82:CB:7F
a=ice-ufrag:bzc4dCbt
a=ice-pwd:hDIBK6ab3iGIDqN6H43xvaVEJ7IU
a=candidate:eXTcQtyPehJo7hZS 1 UDP 2130706431 125.99.186.126 30628 typ host
m=video 0 RTP/SAVPF
c=IN IP4 125.99.186.126
a=direction:active

 +4ms
jssip.js:21352 JsSIP:RTCSession receiveInviteResponse() +21ms
jssip.js:21352 JsSIP:RTCSession sendRequest() +2ms
jssip.js:21352 JsSIP:RTCSession:Request new | ACK +1ms
jssip.js:16102 Uncaught INVALID_STATE_ERROR: Invalid status: 8
jssip.js:21352 JsSIP:Transport received WebSocket text message:

BYE sip:7qaa5cuh at hri628j4n5cm.invalid:5060;alias=14.98.55.38~65213~5;transport=ws;ob SIP/2.0
Via: SIP/2.0/WS  125.99.186.126:10080;branch=z9hG4bK5e0d.40798727f0157b8102f498f8eeb9650c.0
Via: SIP/2.0/UDP 157.238.178.153;rport=5060;branch=z9hG4bK5e0d.36d266d1.0
Via: SIP/2.0/UDP  199.27.244.6:5060;branch=z9hG4bKFD381EC1
From: <sip:919650926333 at 157.238.178.153>;tag=C80AA6C8-10C2
To: "1399004090" <sip:1399004090 at 157.238.178.153>;tag=jfgg6hl98u
Date: Thu, 06 Jul 2000 23:23:57 GMT
Call-ID: s9b5v9g1im3d6o091ik4
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 68
Timestamp: 962925841
CSeq: 101 BYE
Content-Length: 0

 +3ms
jssip.js:21352 JsSIP:Transport sending WebSocket message:

SIP/2.0 481 Call/Transaction Does Not Exist
Via: SIP/2.0/WS  125.99.186.126:10080;branch=z9hG4bK5e0d.40798727f0157b8102f498f8eeb9650c.0
Via: SIP/2.0/UDP 157.238.178.153;rport=5060;branch=z9hG4bK5e0d.36d266d1.0
Via: SIP/2.0/UDP  199.27.244.6:5060;branch=z9hG4bKFD381EC1
To: "1399004090" <sip:1399004090 at 157.238.178.153>;tag=jfgg6hl98u
From: <sip:919650926333 at 157.238.178.153>;tag=C80AA6C8-10C2
Call-ID: s9b5v9g1im3d6o091ik4
CSeq: 101 BYE
Supported: outbound
Content-Length: 0

 +14ms
jssip.js:21352 JsSIP:NonInviteServerTransaction Timer J expired for transaction z9hG4bK5e0d.40798727f0157b8102f498f8eeb9650c.0 +13ms
jssip.js:21352 JsSIP:Transport received WebSocket text message:

SIP/2.0 481 Call Leg/Transaction Does Not Exist
Via: SIP/2.0/TCP hri628j4n5cm.invalid;rport=65213;received=14.98.55.38;branch=z9hG4bK1957267
From: "1399004090" <sip:1399004090 at 157.238.178.153>;tag=jfgg6hl98u
To: <sip:919650926333 at 157.238.178.153>;tag=C80AA6C8-10C2
Call-ID: s9b5v9g1im3d6o091ik4
CSeq: 228 BYE
Content-Length: 0

 +1s
jssip.js:21510 JsSIP:ERROR:RTCSession:Request onDialogError +7ms
jssip.js:21352 JsSIP:InviteClientTransaction Timer B expired for transaction z9hG4bK1380799 +20s
jssip.js:21352 JsSIP:InviteClientTransaction Timer M expired for transaction z9hG4bK1380799 +11s