[SR-Users] Kamailio with rtpproxy-ng and mediaproxy-ng: Error rewriting SDP
Olli Heiskanen
ohjelmistoarkkitehti at gmail.com
Wed Apr 2 12:27:00 CEST 2014
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 at sipwise.com>:
> 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 at testers.com <mailto:wsclient at testers.com> and
> > gsclient at testers.com <mailto:gsclient at 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 at 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 at testers.com <mailto:sip%3Agsclient at testers.com>>.
> > From: <sip:wsclient at testers.com
> > <mailto:sip%3Awsclient at testers.com>>;tag=hhcd99tmvm.
> > Call-ID: 1dluvk38g1j22fn96t4b.
> > CSeq: 7237 INVITE.
> > Contact: <sip:wsclient at testers.com
> > <mailto:sip%3Awsclient at 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 at testers.com
> > <mailto:sip%3Awsclient at testers.com>>;tag=hhcd99tmvm.
> > To: <sip:gsclient at testers.com
> > <mailto:sip%3Agsclient at 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 at 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 at testers.com
> > <mailto:sip%3Agsclient at testers.com>>;tag=7875f08763872c34.
> > From: <sip:wsclient at testers.com
> > <mailto:sip%3Awsclient at 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 at 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 at testers.com <mailto:sip%3Agsclient at testers.com>>.
> > From: <sip:wsclient at testers.com
> > <mailto:sip%3Awsclient at testers.com>>;tag=hhcd99tmvm.
> > Call-ID: 1dluvk38g1j22fn96t4b.
> > CSeq: 7237 INVITE.
> > Contact: <sip:wsclient at testers.com
> > <mailto:sip%3Awsclient at 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 at testers.com
> > <mailto:sip%3Awsclient at testers.com>>;tag=hhcd99tmvm.
> > To: <sip:gsclient at testers.com
> > <mailto:sip%3Agsclient at testers.com>>;tag=fb215901a251c9a0.
> > Call-ID: 1dluvk38g1j22fn96t4b.
> > CSeq: 7237 INVITE.
> > User-Agent: Grandstream GXP2000 1.2.2.26.
> > Contact: <sip:gsclient at 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 at testers.com
> > <mailto:sip%3Awsclient at testers.com>>;tag=hhcd99tmvm.
> > To: <sip:gsclient at testers.com
> > <mailto:sip%3Agsclient at testers.com>>;tag=fb215901a251c9a0.
> > Call-ID: 1dluvk38g1j22fn96t4b.
> > CSeq: 7237 INVITE.
> > User-Agent: Grandstream GXP2000 1.2.2.26.
> > Contact: <sip:gsclient at 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 at 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 at lists.sip-router.org
> http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20140402/ff3b7227/attachment.html>
More information about the sr-users
mailing list