2 Apr
2014
2 Apr
'14
6:27 a.m.
Hello,
Thanks Richard, I made a new test and below is the output from
/var/log/messages.
This is probably a relevant part:
Apr 2 14:10:52 u363id562 mediaproxy-ng[18742]: [oginba5h5r8h1hk3kngu - ]
Got LOOKUP, but no usable callstreams found
Apr 2 14:10:52 u363id562 mediaproxy-ng[18742]: Error rewriting SDP
Looks like the whole call gets somehow messed up, there are several BYE
messages before I end the call from either side. I wonder what causes the
BYE messages to be sent?
I'll get better sdp output soon...
Thanks again,
Olli
Apr 2 14:10:47 u363id562 mediaproxy-ng[18742]: Got valid command from
127.0.0.1:39795: offer - { "sdp": "v=0#015#012o=- 7083457405291314669 2 IN
IP4 127.0.0.1#015#012s=-#015#012t=0 0#015#012a=group:BUNDLE
audio#015#012a=msid-semantic: WMS
No46lGs0HIghRI0dBCknwdhJyR8IlJ3FPqI3#015#012m=audio 64860 RTP/SAVPF 111 103
104 0 8 106 105 13 126#015#012c=IN IP4 2.2.2.2#015#012a=rtcp:64860 IN IP4
2.2.2.2#015#012a=candidate:2999745851 1 udp 2113937151 192.168.56.1 64859
typ host generation 0#015#012a=candidate:2999745851 2 udp 2113937151
192.168.56.1 64859 typ host generation 0#015#012a=candidate:3350409123 1
udp 2113937151 192.168.0.101 64860 typ host generation
0#015#012a=candidate:3350409123 2 udp 2113937151 192.168.0.101 64860 typ
host generation 0#015#012a=candidate:4233069003 1 tcp 1509957375
192.168.56.1 0 typ host generation 0#015#012a=candidate:4233069003 2 tcp
1509957375 192.168.56.1 0 typ host generation
0#015#012a=candidate:2301678419 1 tcp 1509957375 192.168.0.101 0 typ host
generation 0#015#012a=candidate:2301678419 2 tcp 1509957375 192.168.0.101 0
typ host generation 0#015#012a=candidate:1190865175 1 udp 1845501695
2.2.2.2 64860 typ srflx raddr 192.168.0.101 rport 64860 generation
0#015#012a=candidate:1190865175 2 udp 1845501695 2.2.2.2 64860 typ srflx
raddr 192.168.0.101 rport 64860 generation
0#015#012a=ice-ufrag:Nuzos2pMurFvifge#015#012a=ice-pwd:AAcKZc38Sp21Y/kFzdYxFGjt#015#012a=ice-options:google-ice#015#012a=fingerprint:sha-256
72:54:87:EC:D2:4C:D1:70:C2:FE:69:08:20:5C:92:1D:E0:EA:BD:45:09:E0:90:62:27:B6:34:60:54:E2:99:28#015#012a=setup:actpass#015#012a=mid:audio#015#012a=extmap:1
urn:ietf:params:rtp-hdrext:ssrc-audio-level#015#012a=sendrecv#015#012a=rtcp-mux#015#012a=crypto:0
AES_CM_128_HMAC_SHA1_32
inline:ERj6OGgpgTMY1kD3wmxN3oIOD8L5vL5tKAtmHM0f#015#012a=crypto:1
AES_CM_128_HMAC_SHA1_80
inline:UT3fhVoULjUtaHJdfLkFHqTbAEfb3oKDWqBY3ahK#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:0 PCMU/8000#015
Apr 2 14:10:47 u363id562 mediaproxy-ng[18742]: [oginba5h5r8h1hk3kngu]
Creating new call
Apr 2 14:10:47 u363id562 mediaproxy-ng[18742]: Returning to SIP proxy:
d3:sdp2211:v=0#015#012o=- 7083457405291314669 2 IN IP4
1.1.1.1#015#012s=-#015#012t=0 0#015#012a=group:BUNDLE
audio#015#012a=msid-semantic: WMS
No46lGs0HIghRI0dBCknwdhJyR8IlJ3FPqI3#015#012m=audio 30032 RTP/SAVPF 111 103
104 0 8 106 105 13 126#015#012c=IN IP4
1.1.1.1#015#012a=candidate:2999745851 1 udp 2113937151 192.168.56.1 64859
typ host generation 0#015#012a=candidate:2999745851 2 udp 2113937151
192.168.56.1 64859 typ host generation 0#015#012a=candidate:3350409123 1
udp 2113937151 192.168.0.101 64860 typ host generation
0#015#012a=candidate:3350409123 2 udp 2113937151 192.168.0.101 64860 typ
host generation 0#015#012a=candidate:4233069003 1 tcp 1509957375
192.168.56.1 0 typ host generation 0#015#012a=candidate:4233069003 2 tcp
1509957375 192.168.56.1 0 typ host generation
0#015#012a=candidate:2301678419 1 tcp 1509957375 192.168.0.101 0 typ host
generation 0#015#012a=candidate:2301678419 2 tcp 1509957375 192.168.0.101 0
typ host generation 0#015#012a=candidate:1190865175 1 udp 1845501695
2.2.2.2 64860 typ srflx raddr 192.168.0.101 rport 64860 generation
0#015#012a=candidate:1190865175 2 udp 1845501695 2.2.2.2 64860 typ srflx
raddr 192.168.0.101 rport 64860 generation
0#015#012a=ice-ufrag:Nuzos2pMurFvifge#015#012a=ice-pwd:AAcKZc38Sp21Y/kFzdYxFGjt#015#012a=ice-options:google-ice#015#012a=fingerprint:sha-256
72:54:87:EC:D2:4C:D1:70:C2:FE:69:08:20:5C:92:1D:E0:EA:BD:45:09:E0:90:62:27:B6:34:60:54:E2:99:28#015#012a=setup:actpass#015#012a=mid:audio#015#012a=sendrecv#015#012a=crypto:0
AES_CM_128_HMAC_SHA1_32
inline:ERj6OGgpgTMY1kD3wmxN3oIOD8L5vL5tKAtmHM0f#015#012a=crypto:1
AES_CM_128_HMAC_SHA1_80
inline:UT3fhVoULjUtaHJdfLkFHqTbAEfb3oKDWqBY3ahK#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: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 telephon
Apr 2 14:10:47 u363id562 kamailio[27059]: NOTICE: acc [acc.c:315]:
acc_log_request(): ACC: call missed:
timestamp=1396433447;method=INVITE;from_tag=a5uk5lnjvf;to_tag=f7a91f721d721d45;call_id=oginba5h5r8h1hk3kngu;code=488;reason=Not
Acceptable Here;src_user=wsclient;src_domain=testers.com
;src_ip=2.2.2.2;dst_ouser=gsclient;dst_user=gsclient;dst_domain=192.168.0.106
Apr 2 14:10:47 u363id562 mediaproxy-ng[18742]: Got valid command from
127.0.0.1:55835: offer - { "sdp": "v=0#015#012o=- 7083457405291314669 2 IN
IP4 127.0.0.1#015#012s=-#015#012t=0 0#015#012a=group:BUNDLE
audio#015#012a=msid-semantic: WMS
No46lGs0HIghRI0dBCknwdhJyR8IlJ3FPqI3#015#012m=audio 64860 RTP/SAVPF 111 103
104 0 8 106 105 13 126#015#012c=IN IP4 2.2.2.2#015#012a=rtcp:64860 IN IP4
2.2.2.2#015#012a=candidate:2999745851 1 udp 2113937151 192.168.56.1 64859
typ host generation 0#015#012a=candidate:2999745851 2 udp 2113937151
192.168.56.1 64859 typ host generation 0#015#012a=candidate:3350409123 1
udp 2113937151 192.168.0.101 64860 typ host generation
0#015#012a=candidate:3350409123 2 udp 2113937151 192.168.0.101 64860 typ
host generation 0#015#012a=candidate:4233069003 1 tcp 1509957375
192.168.56.1 0 typ host generation 0#015#012a=candidate:4233069003 2 tcp
1509957375 192.168.56.1 0 typ host generation
0#015#012a=candidate:2301678419 1 tcp 1509957375 192.168.0.101 0 typ host
generation 0#015#012a=candidate:2301678419 2 tcp 1509957375 192.168.0.101 0
typ host generation 0#015#012a=candidate:1190865175 1 udp 1845501695
2.2.2.2 64860 typ srflx raddr 192.168.0.101 rport 64860 generation
0#015#012a=candidate:1190865175 2 udp 1845501695 2.2.2.2 64860 typ srflx
raddr 192.168.0.101 rport 64860 generation
0#015#012a=ice-ufrag:Nuzos2pMurFvifge#015#012a=ice-pwd:AAcKZc38Sp21Y/kFzdYxFGjt#015#012a=ice-options:google-ice#015#012a=fingerprint:sha-256
72:54:87:EC:D2:4C:D1:70:C2:FE:69:08:20:5C:92:1D:E0:EA:BD:45:09:E0:90:62:27:B6:34:60:54:E2:99:28#015#012a=setup:actpass#015#012a=mid:audio#015#012a=extmap:1
urn:ietf:params:rtp-hdrext:ssrc-audio-level#015#012a=sendrecv#015#012a=rtcp-mux#015#012a=crypto:0
AES_CM_128_HMAC_SHA1_32
inline:ERj6OGgpgTMY1kD3wmxN3oIOD8L5vL5tKAtmHM0f#015#012a=crypto:1
AES_CM_128_HMAC_SHA1_80
inline:UT3fhVoULjUtaHJdfLkFHqTbAEfb3oKDWqBY3ahK#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:0 PCMU/8000#015
Apr 2 14:10:47 u363id562 mediaproxy-ng[18742]: Returning to SIP proxy:
d3:sdp925:v=0#015#012o=- 7083457405291314669 2 IN IP4
1.1.1.1#015#012s=-#015#012t=0 0#015#012a=group:BUNDLE
audio#015#012a=msid-semantic: WMS
No46lGs0HIghRI0dBCknwdhJyR8IlJ3FPqI3#015#012m=audio 30032 RTP/AVP 111 103
104 0 8 106 105 13 126#015#012c=IN IP4 1.1.1.1#015#012a=fingerprint:sha-256
72:54:87:EC:D2:4C:D1:70:C2:FE:69:08:20:5C:92:1D:E0:EA:BD:45:09:E0:90:62:27:B6:34:60:54:E2:99:28#015#012a=setup:actpass#015#012a=mid:audio#015#012a=sendrecv#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: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:1883467027
cname:xLrdjUv0kmdPbie/#015#012a=ssrc:1883467027
msid:No46lGs0HIghRI0dBCknwdhJyR8IlJ3FPqI3
2cddb28d-832f-4b95-a39f-cc2e39cf620c#015#012a=ssrc:1883467027
mslabel:No46lGs0HIghRI0dBCknwdhJyR8IlJ3FPqI3#015#012a=ssrc:1883467027
label:2cddb28d-832f-4b95-a39f-cc2e39cf620c#015#012a=rtcp:30033#015#0126:result2:oke
Apr 2 14:10:47 u363id562 mediaproxy-ng[18742]: Got valid command from
127.0.0.1:55835: offer - { "sdp": "v=0#015#012o=- 7083457405291314669 2 IN
IP4 127.0.0.1#015#012s=-#015#012t=0 0#015#012a=group:BUNDLE
audio#015#012a=msid-semantic: WMS
No46lGs0HIghRI0dBCknwdhJyR8IlJ3FPqI3#015#012m=audio 64860 RTP/SAVPF 111 103
104 0 8 106 105 13 126#015#012c=IN IP4 2.2.2.2#015#012a=rtcp:64860 IN IP4
2.2.2.2#015#012a=candidate:2999745851 1 udp 2113937151 192.168.56.1 64859
typ host generation 0#015#012a=candidate:2999745851 2 udp 2113937151
192.168.56.1 64859 typ host generation 0#015#012a=candidate:3350409123 1
udp 2113937151 192.168.0.101 64860 typ host generation
0#015#012a=candidate:3350409123 2 udp 2113937151 192.168.0.101 64860 typ
host generation 0#015#012a=candidate:4233069003 1 tcp 1509957375
192.168.56.1 0 typ host generation 0#015#012a=candidate:4233069003 2 tcp
1509957375 192.168.56.1 0 typ host generation
0#015#012a=candidate:2301678419 1 tcp 1509957375 192.168.0.101 0 typ host
generation 0#015#012a=candidate:2301678419 2 tcp 1509957375 192.168.0.101 0
typ host generation 0#015#012a=candidate:1190865175 1 udp 1845501695
2.2.2.2 64860 typ srflx raddr 192.168.0.101 rport 64860 generation
0#015#012a=candidate:1190865175 2 udp 1845501695 2.2.2.2 64860 typ srflx
raddr 192.168.0.101 rport 64860 generation
0#015#012a=ice-ufrag:Nuzos2pMurFvifge#015#012a=ice-pwd:AAcKZc38Sp21Y/kFzdYxFGjt#015#012a=ice-options:google-ice#015#012a=fingerprint:sha-256
72:54:87:EC:D2:4C:D1:70:C2:FE:69:08:20:5C:92:1D:E0:EA:BD:45:09:E0:90:62:27:B6:34:60:54:E2:99:28#015#012a=setup:actpass#015#012a=mid:audio#015#012a=extmap:1
urn:ietf:params:rtp-hdrext:ssrc-audio-level#015#012a=sendrecv#015#012a=rtcp-mux#015#012a=crypto:0
AES_CM_128_HMAC_SHA1_32
inline:ERj6OGgpgTMY1kD3wmxN3oIOD8L5vL5tKAtmHM0f#015#012a=crypto:1
AES_CM_128_HMAC_SHA1_80
inline:UT3fhVoULjUtaHJdfLkFHqTbAEfb3oKDWqBY3ahK#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:0 PCMU/8000#015
Apr 2 14:10:47 u363id562 mediaproxy-ng[18742]: Returning to SIP proxy:
d3:sdp2211:v=0#015#012o=- 7083457405291314669 2 IN IP4
1.1.1.1#015#012s=-#015#012t=0 0#015#012a=group:BUNDLE
audio#015#012a=msid-semantic: WMS
No46lGs0HIghRI0dBCknwdhJyR8IlJ3FPqI3#015#012m=audio 30032 RTP/SAVPF 111 103
104 0 8 106 105 13 126#015#012c=IN IP4
1.1.1.1#015#012a=candidate:2999745851 1 udp 2113937151 192.168.56.1 64859
typ host generation 0#015#012a=candidate:2999745851 2 udp 2113937151
192.168.56.1 64859 typ host generation 0#015#012a=candidate:3350409123 1
udp 2113937151 192.168.0.101 64860 typ host generation
0#015#012a=candidate:3350409123 2 udp 2113937151 192.168.0.101 64860 typ
host generation 0#015#012a=candidate:4233069003 1 tcp 1509957375
192.168.56.1 0 typ host generation 0#015#012a=candidate:4233069003 2 tcp
1509957375 192.168.56.1 0 typ host generation
0#015#012a=candidate:2301678419 1 tcp 1509957375 192.168.0.101 0 typ host
generation 0#015#012a=candidate:2301678419 2 tcp 1509957375 192.168.0.101 0
typ host generation 0#015#012a=candidate:1190865175 1 udp 1845501695
2.2.2.2 64860 typ srflx raddr 192.168.0.101 rport 64860 generation
0#015#012a=candidate:1190865175 2 udp 1845501695 2.2.2.2 64860 typ srflx
raddr 192.168.0.101 rport 64860 generation
0#015#012a=ice-ufrag:Nuzos2pMurFvifge#015#012a=ice-pwd:AAcKZc38Sp21Y/kFzdYxFGjt#015#012a=ice-options:google-ice#015#012a=fingerprint:sha-256
72:54:87:EC:D2:4C:D1:70:C2:FE:69:08:20:5C:92:1D:E0:EA:BD:45:09:E0:90:62:27:B6:34:60:54:E2:99:28#015#012a=setup:actpass#015#012a=mid:audio#015#012a=sendrecv#015#012a=crypto:0
AES_CM_128_HMAC_SHA1_32
inline:ERj6OGgpgTMY1kD3wmxN3oIOD8L5vL5tKAtmHM0f#015#012a=crypto:1
AES_CM_128_HMAC_SHA1_80
inline:UT3fhVoULjUtaHJdfLkFHqTbAEfb3oKDWqBY3ahK#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: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 telephon
Apr 2 14:10:52 u363id562 mediaproxy-ng[18742]: Got valid command from
127.0.0.1:40131: answer - { "sdp": "v=0#015#012o=gsclient 8000 8000 IN IP4
192.168.0.106#015#012s=SIP Call#015#012c=IN IP4 192.168.0.106#015#012t=0
0#015#012m=audio 5030 RTP/AVP 0 13#015#012a=sendrecv#015#012a=rtpmap:0
PCMU/8000#015#012a=ptime:20#015#012m=audio 0 RTP/SAVPF 111 103 104 0 8 106
105 13 126#015#012", "ICE": "force", "flags": [
"force", "trust-address" ],
"replace": [ "origin", "session-connection" ],
"transport-protocol":
"RTP/SAVPF", "call-id": "oginba5h5r8h1hk3kngu",
"received-from": [ "IP4",
"2.2.2.2" ], "from-tag": "a5uk5lnjvf", "to-tag":
"7067a267ea14db11",
"command": "answer" }
Apr 2 14:10:52 u363id562 mediaproxy-ng[18742]: [oginba5h5r8h1hk3kngu - ]
Got LOOKUP, but no usable callstreams found
Apr 2 14:10:52 u363id562 mediaproxy-ng[18742]: Error rewriting SDP
Apr 2 14:10:52 u363id562 mediaproxy-ng[18742]: Protocol error in packet
from 127.0.0.1:40131: Error rewriting SDP [d3:sdp216:v=0#015#012o=gsclient
8000 8000 IN IP4 192.168.0.106#015#012s=SIP Call#015#012c=IN IP4
192.168.0.106#015#012t=0 0#015#012m=audio 5030 RTP/AVP 0
13#015#012a=sendrecv#015#012a=rtpmap:0
PCMU/8000#015#012a=ptime:20#015#012m=audio 0 RTP/SAVPF 111 103 104 0 8 106
105 13
126#015#0123:ICE5:force5:flagsl5:force13:trust-addresse7:replacel6:origin18:session-connectione18:transport-protocol9:RTP/SAVPF7:call-id20:oginba5h5r8h1hk3kngu13:received-froml3:IP412:2.2.2.2e8:from-tag10:a5uk5lnjvf6:to-tag16:7067a267ea14db117:command6:answere]
Apr 2 14:10:52 u363id562 mediaproxy-ng[18742]: Returning to SIP proxy:
d6:result5:error12:error-reason19:Error rewriting SDPe
Apr 2 14:10:52 u363id562 kamailio[27056]: ERROR: rtpproxy-ng
[rtpproxy.c:1346]: rtpp_function_call(): proxy replied with error: Error
rewriting SDP
Apr 2 14:10:52 u363id562 kamailio[27056]: NOTICE: acc [acc.c:315]:
acc_log_request(): ACC: transaction answered:
timestamp=1396433452;method=INVITE;from_tag=a5uk5lnjvf;to_tag=7067a267ea14db11;call_id=oginba5h5r8h1hk3kngu;code=200;reason=OK;src_user=wsclient;src_domain=
testers.com
;src_ip=2.2.2.2;dst_ouser=gsclient;dst_user=gsclient;dst_domain=192.168.0.106
Apr 2 14:10:52 u363id562 mediaproxy-ng[18742]: Got valid command from
127.0.0.1:39795: delete - { "call-id": "oginba5h5r8h1hk3kngu",
"received-from": [ "IP4", "2.2.2.2" ], "from-tag":
"a5uk5lnjvf", "command":
"delete" }
Apr 2 14:10:52 u363id562 mediaproxy-ng[18742]: [oginba5h5r8h1hk3kngu]
Deleting full call
Apr 2 14:10:52 u363id562 mediaproxy-ng[18742]: [oginba5h5r8h1hk3kngu]
Final packet stats:
Apr 2 14:10:52 u363id562 mediaproxy-ng[18742]: [oginba5h5r8h1hk3kngu] ---
side A: RTP[30032] 0 p, 0 b, 0 e; RTCP[0] 0 p, 0 b, 30033 e; side B: RTP[0]
0 p, 0 b, 0 e; RTCP[0] 0 p, 30034 b, 0 e
Apr 2 14:10:52 u363id562 mediaproxy-ng[18742]: [oginba5h5r8h1hk3kngu] ---
side A: RTP[0] 0 p, 0 b, 0 e; RTCP[0] 0 p, 0 b, 0 e; side B: RTP[0] 0 p, 0
b, 0 e; RTCP[0] 0 p, 0 b, 0 e
Apr 2 14:10:52 u363id562 mediaproxy-ng[18742]: [oginba5h5r8h1hk3kngu] ---
side A: RTP[0] 0 p, 0 b, 0 e; RTCP[0] 0 p, 0 b, 0 e; side B: RTP[0] 0 p, 0
b, 0 e; RTCP[0] 0 p, 0 b, 0 e
Apr 2 14:10:52 u363id562 mediaproxy-ng[18742]: Returning to SIP proxy:
d7:createdi1396433447e7:streamslld3:tag10:a5uk5lnjvf6:status34:known but
unconfirmed peer
address5:statsd3:rtpd8:countersd7:packetsi0e5:bytesi0e6:errorsi0ee12:peer
addressd6:family4:IPv47:address12:2.2.2.24:porti64860ee23:advertised peer
addressd6:family4:IPv47:address12:2.2.2.24:porti64860ee10:local
porti30032ee4:rtcpd8:countersd7:packetsi0e5:bytesi0e6:errorsi0ee12:peer
addressd6:family4:IPv47:address12:2.2.2.24:porti64861ee23:advertised peer
addressd6:family4:IPv47:address12:2.2.2.24:porti64861ee10:local
porti30033eeeed3:tag16:7067a267ea14db116:status34:known but unconfirmed
peer
address5:statsd3:rtpd8:countersd7:packetsi0e5:bytesi0e6:errorsi0ee12:peer
addressd6:family4:IPv47:address13:192.168.0.1064:porti5030ee23:advertised
peer addressd6:family4:IPv47:address13:192.168.0.1064:porti5030ee10:local
porti30034ee4:rtcpd8:countersd7:packetsi0e5:bytesi0e6:errorsi0ee12:peer
addressd6:family4:IPv47:address13:192.168.0.1064:porti5031ee23:advertised
peer addressd6:family4:IPv47:address13:192.168.0.1064:porti5031ee10:local
porti30035eeeeee6:totalsd5:inputd3:rtpd7:packetsi0e5:bytesi0e6:errorsi0ee4:rtcpd7:packetsi0e5:bytesi0e6:errorsi0eee6:outputd3:rtpd7:packetsi0e5:bytesi0e6:errorsi0ee4:rtcpd7:packetsi0e5:bytesi0e6:errorsi0eeee6:result2:oke
Apr 2 14:10:53 u363id562 mediaproxy-ng[18742]: Got valid command from
127.0.0.1:55835: answer - { "sdp": "v=0#015#012o=gsclient 8000 8001 IN IP4
192.168.0.106#015#012s=SIP Call#015#012c=IN IP4 192.168.0.106#015#012t=0
0#015#012m=audio 5030 RTP/AVP 0 13#015#012a=sendrecv#015#012a=rtpmap:0
PCMU/8000#015#012a=ptime:20#015#012m=audio 0 RTP/SAVPF 111 103 104 0 8 106
105 13 126#015#012", "ICE": "force", "flags": [
"force", "trust-address" ],
"replace": [ "origin", "session-connection" ],
"transport-protocol":
"RTP/SAVPF", "call-id": "oginba5h5r8h1hk3kngu",
"received-from": [ "IP4",
"2.2.2.2" ], "from-tag": "a5uk5lnjvf", "to-tag":
"7067a267ea14db11",
"command": "answer" }
Apr 2 14:10:53 u363id562 mediaproxy-ng[18742]: Protocol error in packet
from 127.0.0.1:55835: Unknown call-id [d3:sdp216:v=0#015#012o=gsclient 8000
8001 IN IP4 192.168.0.106#015#012s=SIP Call#015#012c=IN IP4
192.168.0.106#015#012t=0 0#015#012m=audio 5030 RTP/AVP 0
13#015#012a=sendrecv#015#012a=rtpmap:0
PCMU/8000#015#012a=ptime:20#015#012m=audio 0 RTP/SAVPF 111 103 104 0 8 106
105 13
126#015#0123:ICE5:force5:flagsl5:force13:trust-addresse7:replacel6:origin18:session-connectione18:transport-protocol9:RTP/SAVPF7:call-id20:oginba5h5r8h1hk3kngu13:received-froml3:IP412:2.2.2.2e8:from-tag10:a5uk5lnjvf6:to-tag16:7067a267ea14db117:command6:answere]
Apr 2 14:10:53 u363id562 mediaproxy-ng[18742]: Returning to SIP proxy:
d6:result5:error12:error-reason15:Unknown call-ide
Apr 2 14:10:53 u363id562 kamailio[27059]: ERROR: rtpproxy-ng
[rtpproxy.c:1346]: rtpp_function_call(): proxy replied with error: Unknown
call-id
Apr 2 14:10:55 u363id562 mediaproxy-ng[18742]: Got valid command from
127.0.0.1:34407: answer - { "sdp": "v=0#015#012o=gsclient 8000 8002 IN IP4
192.168.0.106#015#012s=SIP Call#015#012c=IN IP4 192.168.0.106#015#012t=0
0#015#012m=audio 5030 RTP/AVP 0 13#015#012a=sendrecv#015#012a=rtpmap:0
PCMU/8000#015#012a=ptime:20#015#012m=audio 0 RTP/SAVPF 111 103 104 0 8 106
105 13 126#015#012", "ICE": "force", "flags": [
"force", "trust-address" ],
"replace": [ "origin", "session-connection" ],
"transport-protocol":
"RTP/SAVPF", "call-id": "oginba5h5r8h1hk3kngu",
"received-from": [ "IP4",
"2.2.2.2" ], "from-tag": "a5uk5lnjvf", "to-tag":
"7067a267ea14db11",
"command": "answer" }
Apr 2 14:10:55 u363id562 mediaproxy-ng[18742]: Protocol error in packet
from 127.0.0.1:34407: Unknown call-id [d3:sdp216:v=0#015#012o=gsclient 8000
8002 IN IP4 192.168.0.106#015#012s=SIP Call#015#012c=IN IP4
192.168.0.106#015#012t=0 0#015#012m=audio 5030 RTP/AVP 0
13#015#012a=sendrecv#015#012a=rtpmap:0
PCMU/8000#015#012a=ptime:20#015#012m=audio 0 RTP/SAVPF 111 103 104 0 8 106
105 13
126#015#0123:ICE5:force5:flagsl5:force13:trust-addresse7:replacel6:origin18:session-connectione18:transport-protocol9:RTP/SAVPF7:call-id20:oginba5h5r8h1hk3kngu13:received-froml3:IP412:2.2.2.2e8:from-tag10:a5uk5lnjvf6:to-tag16:7067a267ea14db117:command6:answere]
Apr 2 14:10:55 u363id562 mediaproxy-ng[18742]: Returning to SIP proxy:
d6:result5:error12:error-reason15:Unknown call-ide
Apr 2 14:10:55 u363id562 kamailio[27058]: ERROR: rtpproxy-ng
[rtpproxy.c:1346]: rtpp_function_call(): proxy replied with error: Unknown
call-id
Apr 2 14:11:07 u363id562 mediaproxy-ng[18742]: Got valid command from
127.0.0.1:55835: delete - { "call-id": "oginba5h5r8h1hk3kngu",
"received-from": [ "IP4", "2.2.2.2" ], "from-tag":
"7067a267ea14db11",
"command": "delete" }
Apr 2 14:11:07 u363id562 mediaproxy-ng[18742]: [oginba5h5r8h1hk3kngu]
Call-ID to delete not found
Apr 2 14:11:07 u363id562 mediaproxy-ng[18742]: Returning to SIP proxy:
d7:warning38:Call-ID not found or tags didn't match6:result2:oke
Apr 2 14:11:07 u363id562 kamailio[27074]: NOTICE: acc [acc.c:315]:
acc_log_request(): ACC: transaction answered:
timestamp=1396433467;method=BYE;from_tag=7067a267ea14db11;to_tag=a5uk5lnjvf;call_id=oginba5h5r8h1hk3kngu;code=481;reason=Call/Transaction
Does Not Exist;src_user=gsclient;src_domain=testers.com
;src_ip=2.2.2.2;dst_ouser=wsclient;dst_user=wsclient;dst_domain=testers.com
2014-04-01 21:41 GMT+03:00 Richard Fuchs <rfuchs(a)sipwise.com>om>:
Hey,
Unfortunately your packet dumps are truncated and don't show the
complete SDP bodies. It would also be interesting to see which options
and parameters are passed to mediaproxy-ng when processing the SDP. You
would find this info in the log produced by mediaproxy-ng, which should
also include the full SDP bodies going in and out (unless your syslog
daemon also truncates those messages). So, the most useful way to debug
this is to post the complete log lines.
cheers
On 04/01/14 13:19, Olli Heiskanen wrote:
Hello,
I've been experimenting with Kamailio with ws and sip clients and could
need a hand in getting a call between those two to work.
I have Kamailio 4.1.2 (using rtpproxy-ng instead of rtpproxy) on a
CentOS 6.5 and a mediaproxy-ng running. I have clients
wsclient(a)testers.com <mailto:wsclient@testers.com> and
gsclient(a)testers.com <mailto:gsclient@testers.com> and I try to make
call from wsclient to gsclient. The wsclient is a jssip client running
on chrome and gsclient is a grandstream desk phone. My config file is
the default one enhanced by online examples.
I use a html5 <audio> element for the media streams, and configured my
jssip phone to accept audio options like this:
var options = {
'eventHandlers': eventHandlers,
'mediaConstraints': {'audio': true, 'video': false }
};
sipUA.call(callto, options);
I used the instructions from
here: http://www.slideshare.net/crocodilertc/webrtc-websockets
What I get is gsclient ringing, and as I answer there is no audio and
call hangs up in a few seconds. I guess this is a SDP problem, something
between Kamailio and Mediaproxy-ng but SDP is not my strong point so I'd
appreciate advice.
Question is where's my misconfiguration/problem? I would like to learn
why this problem occurs and how to fix it rather than getting a solution
right away, but please bear in mind I don't know much about SDP.
In Kamailio log I see:
kamailio[27059]: ERROR: rtpproxy-ng [rtpproxy.c:1346]:
rtpp_function_call(): proxy replied with error: Error rewriting SDP
kamailio[27058]: ERROR: rtpproxy-ng [rtpproxy.c:1346]:
rtpp_function_call(): proxy replied with error: Unknown call-id
kamailio[27057]: ERROR: rtpproxy-ng [rtpproxy.c:1346]:
rtpp_function_call(): proxy replied with error: Unknown call-id
Following are the INVITEs and 200 OKs from my SIP trace (1.1.1.1 is the
ip of my Kamailio & mediaproxy-ng box and 2.2.2.2 is the public ip
behind which both my clients are). The gsclient has port 5066.
******************************************************************************
U 2014/04/01 20:03:41.060009 1.1.1.1:5060 <http://1.1.1.1:5060> ->
2.2.2.2:5066 <http://2.2.2.2:5066>
INVITE sip:gsclient@192.168.0.106:5066;transport=udp SIP/2.0.
Record-Route: <sip:1.1.1.1;r2=on;lr=on;nat=yes>.
Record-Route: <sip:1.1.1.1;transport=ws;r2=on;lr=on;nat=yes>.
Via: SIP/2.0/UDP
1.1.1.1;branch=z9hG4bKb703.fbb259c1d8c17e163876ec760e086145.0.
Via: SIP/2.0/WS
kj59uak271em.invalid;rport=38986;received=2.2.2.2;branch=z9hG4bK9891267.
Max-Forwards: 16.
To: <sip:gsclient@testers.com <mailto:sip%3Agsclient@testers.com>>.
From: <sip:wsclient@testers.com
<mailto:sip%3Awsclient@testers.com>>;tag=hhcd99tmvm.
Call-ID: 1dluvk38g1j22fn96t4b.
CSeq: 7237 INVITE.
Contact: <sip:wsclient@testers.com
<mailto:sip%3Awsclient@testers.com
;gr=urn:uuid:f6014564-88cb-4f57-9ae5-3b4336ef9db8;ob;alias=2.2.2.2~38986~5;alias=2.2.2.2~38986~5>.
Allow: ACK,CANCEL,BYE,OPTIONS,INVITE.
Content-Type: application/sdp.
Supported: path, outbound, gruu.
User-Agent: JsSIP 0.3.0.
Content-Length: 2211.
.
v=0.
o=- 4897716268503406223 2 IN IP4 1.1.1.1.
s=-.
t=0 0.
a=group:BUNDLE audio.
a=msid-semantic: WMS vMh5vhUEQzvVKJYdqRkAuCcXVa2blgbEXARZ.
m=audio 30028 RTP/SAVPF 111 103 104 0 8 106 105 13 126.
c=IN IP4 1.1.1.1.
a=candidate:2999745851 1 udp 2113937151 192.168.56.1 63341 typ host
generation 0.
a=candidate:2999745851 2 udp 2113937151 192.168.56.1 63341 typ host
generation 0.
a=candidate:3350409123 1 udp 2113937151 192.168.0.101 63342 typ host
generation 0.
a=candidate:3350409123 2 udp 2113937151 192.168.0.101 63342 typ host
generation 0.
a=candidate:4233069003 1 tcp 1509957375 192.168.56.1 0 typ host
generation 0.
a=candidate:4233069003 2 tcp 150995
T 2014/04/01 20:03:41.119806 2.2.2.2:38986 <http://2.2.2.2:38986> ->
1.1.1.1:5060 <http://1.1.1.1:5060> [A]
......
U 2014/04/01 20:03:41.159086 2.2.2.2:5066 <http://2.2.2.2:5066> ->
1.1.1.1:5060 <http://1.1.1.1:5060>
SIP/2.0 488 Not Acceptable Here.
Via: SIP/2.0/UDP
1.1.1.1;branch=z9hG4bKb703.fbb259c1d8c17e163876ec760e086145.0.
Via: SIP/2.0/WS
kj59uak271em.invalid;rport=38986;received=2.2.2.2;branch=z9hG4bK9891267.
Record-Route: <sip:1.1.1.1;r2=on;lr=on;nat=yes>.
Record-Route: <sip:1.1.1.1;transport=ws;r2=on;lr=on;nat=yes>.
From: <sip:wsclient@testers.com
<mailto:sip%3Awsclient@testers.com>>;tag=hhcd99tmvm.
To: <sip:gsclient@testers.com
<mailto:sip%3Agsclient@testers.com>>;tag=7875f08763872c34.
Call-ID: 1dluvk38g1j22fn96t4b.
CSeq: 7237 INVITE.
User-Agent: Grandstream GXP2000 1.2.2.26.
Warning: 304 GS "Media type not available".
Content-Length: 0.
.
U 2014/04/01 20:03:41.159392 1.1.1.1:5060 <http://1.1.1.1:5060> ->
2.2.2.2:5066 <http://2.2.2.2:5066>
ACK sip:gsclient@192.168.0.106:5066;transport=udp SIP/2.0.
Via: SIP/2.0/UDP
1.1.1.1;branch=z9hG4bKb703.fbb259c1d8c17e163876ec760e086145.0.
Max-Forwards: 16.
To: <sip:gsclient@testers.com
<mailto:sip%3Agsclient@testers.com>>;tag=7875f08763872c34.
From: <sip:wsclient@testers.com
<mailto:sip%3Awsclient@testers.com>>;tag=hhcd99tmvm.
Call-ID: 1dluvk38g1j22fn96t4b.
CSeq: 7237 ACK.
Content-Length: 0.
.
U 2014/04/01 20:03:41.161085 1.1.1.1:5060 <http://1.1.1.1:5060> ->
2.2.2.2:5066 <http://2.2.2.2:5066>
INVITE sip:gsclient@192.168.0.106:5066;transport=udp SIP/2.0.
Record-Route: <sip:1.1.1.1;r2=on;lr=on;nat=yes>.
Record-Route: <sip:1.1.1.1;transport=ws;r2=on;lr=on;nat=yes>.
Via: SIP/2.0/UDP
1.1.1.1;branch=z9hG4bKb703.fbb259c1d8c17e163876ec760e086145.1.
Via: SIP/2.0/WS
kj59uak271em.invalid;rport=38986;received=2.2.2.2;branch=z9hG4bK9891267.
Max-Forwards: 16.
To: <sip:gsclient@testers.com <mailto:sip%3Agsclient@testers.com>>.
From: <sip:wsclient@testers.com
<mailto:sip%3Awsclient@testers.com>>;tag=hhcd99tmvm.
Call-ID: 1dluvk38g1j22fn96t4b.
CSeq: 7237 INVITE.
Contact: <sip:wsclient@testers.com
<mailto:sip%3Awsclient@testers.com
;gr=urn:uuid:f6014564-88cb-4f57-9ae5-3b4336ef9db8;ob;alias=2.2.2.2~38986~5;alias=2.2.2.2~38986~5>.
Allow: ACK,CANCEL,BYE,OPTIONS,INVITE.
Content-Type: application/sdp.
Supported: path, outbound, gruu.
User-Agent: JsSIP 0.3.0.
Content-Length: 3136.
.
v=0.
o=- 4897716268503406223 2 IN IP4 1.1.1.1.
s=-.
t=0 0.
a=group:BUNDLE audio.
a=msid-semantic: WMS vMh5vhUEQzvVKJYdqRkAuCcXVa2blgbEXARZ.
m=audio 30028 RTP/AVP 111 103 104 0 8 106 105 13 126.
c=IN IP4 1.1.1.1.
a=fingerprint:sha-256
72:54:87:EC:D2:4C:D1:70:C2:FE:69:08:20:5C:92:1D:E0:EA:BD:45:09:E0:90:62:27:B6:34:60:54:E2:99:28.
a=setup:actpass.
a=mid:audio.
a=sendrecv.
a=rtpmap:111 opus/48000/2.
a=fmtp:111 minptime=10.
a=rtpmap:103 ISAC/16000.
a=rtpmap:104 ISAC/32000.
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:3298511848 cnam
And here are the 200 OK messages when answering the call:
U 2014/04/01 20:03:46.049711 2.2.2.2:5066 <http://2.2.2.2:5066> ->
1.1.1.1:5060 <http://1.1.1.1:5060>
SIP/2.0 200 OK.
Via: SIP/2.0/UDP
1.1.1.1;branch=z9hG4bKb703.fbb259c1d8c17e163876ec760e086145.1.
Via: SIP/2.0/WS
kj59uak271em.invalid;rport=38986;received=2.2.2.2;branch=z9hG4bK9891267.
Record-Route: <sip:1.1.1.1;r2=on;lr=on;nat=yes>.
Record-Route: <sip:1.1.1.1;transport=ws;r2=on;lr=on;nat=yes>.
From: <sip:wsclient@testers.com
<mailto:sip%3Awsclient@testers.com>>;tag=hhcd99tmvm.
To: <sip:gsclient@testers.com
<mailto:sip%3Agsclient@testers.com>>;tag=fb215901a251c9a0.
Call-ID: 1dluvk38g1j22fn96t4b.
CSeq: 7237 INVITE.
User-Agent: Grandstream GXP2000 1.2.2.26.
Contact: <sip:gsclient@192.168.0.106:5066;transport=udp>.
Allow:
INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE.
Content-Type: application/sdp.
Supported: replaces, timer.
Content-Length: 216.
.
v=0.
o=gsclient 8000 8000 IN IP4 192.168.0.106.
s=SIP Call.
c=IN IP4 192.168.0.106.
t=0 0.
m=audio 5026 RTP/AVP 0 13.
a=sendrecv.
a=rtpmap:0 PCMU/8000.
a=ptime:20.
m=audio 0 RTP/SAVPF 111 103 104 0 8 106 105 13 126.
T 2014/04/01 20:03:46.051127 1.1.1.1:5060 <http://1.1.1.1:5060> ->
2.2.2.2:38986 <http://2.2.2.2:38986> [AP]
.~.dSIP/2.0 200 OK.
Via: SIP/2.0/WS
kj59uak271em.invalid;rport=38986;received=2.2.2.2;branch=z9hG4bK9891267.
Record-Route: <sip:1.1.1.1;r2=on;lr=on;nat=yes>.
Record-Route: <sip:1.1.1.1;transport=ws;r2=on;lr=on;nat=yes>.
From: <sip:wsclient@testers.com
<mailto:sip%3Awsclient@testers.com>>;tag=hhcd99tmvm.
To: <sip:gsclient@testers.com
<mailto:sip%3Agsclient@testers.com>>;tag=fb215901a251c9a0.
Call-ID: 1dluvk38g1j22fn96t4b.
CSeq: 7237 INVITE.
User-Agent: Grandstream GXP2000 1.2.2.26.
Contact: <sip:gsclient@192.168.0.106:5066;transport=udp>.
Allow:
INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK,MESSAGE.
Content-Type: application/sdp.
Supported: replaces, timer.
Content-Length: 216.
.
v=0.
o=gsclient 8000 8000 IN IP4 192.168.0.106.
s=SIP Call.
c=IN IP4 192.168.0.106.
t=0 0.
m=audio 5026 RTP/AVP 0 13.
a=sendrecv.
a=rtpmap:0 PCMU/8000.
a=ptime:20.
m=audio 0 RTP/SAVPF 111 103 104 0 8 106 105 13 126.
******************************************************************************
cheers,
Olli
_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
sr-users(a)lists.sip-router.org
http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
sr-users(a)lists.sip-router.org
http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users