Hi,
This seems to be caused by an additional media stream (second m= line)
appearing in the answer SDP, which is invalid according to RFC 3264.
I'd like to invite you to try the upcoming new version of mediaproxy-ng
instead, which has been renamed to rtpengine:
It's still being worked on (including finalizing the name change), but
it should handle those multi-stream cases much better (especially when
WebRTC clients are involved), even though I can't guarantee that it will
fix your problem in particular, as it's an RTC violation.
cheers
On 04/02/14 06:27, Olli Heiskanen wrote:
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 <http://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
<http://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 <http://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 <http://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 <http://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 <http://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=7067a267ea14d…
<http://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 <http://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 <http://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 <http://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 <http://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 <http://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 <http://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
<http://testers.com>;src_ip=2.2.2.2;dst_ouser=wsclient;dst_user=wsclient;dst_domain=testers.com
<http://testers.com>
2014-04-01 21:41 GMT+03:00 Richard Fuchs <rfuchs(a)sipwise.com
<mailto:rfuchs@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(a)testers.com <mailto:wsclient@testers.com>
<mailto:wsclient@testers.com <mailto:wsclient@testers.com>> and
gsclient(a)testers.com
<mailto:gsclient@testers.com>
<mailto:gsclient@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>
<http://1.1.1.1:5060> ->
2.2.2.2:5066 <http://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>
<mailto:sip%3Agsclient@testers.com
<mailto:sip%253Agsclient@testers.com>>>.
From: <sip:wsclient@testers.com
<mailto:sip%3Awsclient@testers.com>
<mailto:sip%3Awsclient@testers.com
<mailto:sip%253Awsclient@testers.com>>>;tag=hhcd99tmvm.
Call-ID: 1dluvk38g1j22fn96t4b.
CSeq: 7237 INVITE.
Contact: <sip:wsclient@testers.com <mailto:sip%3Awsclient@testers.com>
<mailto:sip%3Awsclient@testers.com
<mailto:sip%253Awsclient@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>
<http://2.2.2.2:38986> ->
1.1.1.1:5060 <http://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>
<http://2.2.2.2:5066> ->
1.1.1.1:5060 <http://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>
<mailto:sip%3Awsclient@testers.com
<mailto:sip%253Awsclient@testers.com>>>;tag=hhcd99tmvm.
To: <sip:gsclient@testers.com
<mailto:sip%3Agsclient@testers.com>
<mailto:sip%3Agsclient@testers.com
<mailto:sip%253Agsclient@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>
<http://1.1.1.1:5060> ->
> 2.2.2.2:5066 <http://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>
> <mailto:sip%3Agsclient@testers.com
<mailto:sip%253Agsclient@testers.com>>>;tag=7875f08763872c34.
From: <sip:wsclient@testers.com
<mailto:sip%3Awsclient@testers.com>
<mailto:sip%3Awsclient@testers.com
<mailto:sip%253Awsclient@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>
<http://1.1.1.1:5060> ->
2.2.2.2:5066 <http://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>
<mailto:sip%3Agsclient@testers.com
<mailto:sip%253Agsclient@testers.com>>>.
From: <sip:wsclient@testers.com
<mailto:sip%3Awsclient@testers.com>
<mailto:sip%3Awsclient@testers.com
<mailto:sip%253Awsclient@testers.com>>>;tag=hhcd99tmvm.
Call-ID: 1dluvk38g1j22fn96t4b.
CSeq: 7237 INVITE.
Contact: <sip:wsclient@testers.com <mailto:sip%3Awsclient@testers.com>
<mailto:sip%3Awsclient@testers.com
<mailto:sip%253Awsclient@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>
<http://2.2.2.2:5066> ->
1.1.1.1:5060 <http://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>
<mailto:sip%3Awsclient@testers.com
<mailto:sip%253Awsclient@testers.com>>>;tag=hhcd99tmvm.
To: <sip:gsclient@testers.com
<mailto:sip%3Agsclient@testers.com>
<mailto:sip%3Agsclient@testers.com
<mailto:sip%253Agsclient@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>
<http://1.1.1.1:5060> ->
2.2.2.2:38986 <http://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>
<mailto:sip%3Awsclient@testers.com
<mailto:sip%253Awsclient@testers.com>>>;tag=hhcd99tmvm.
To: <sip:gsclient@testers.com
<mailto:sip%3Agsclient@testers.com>
<mailto:sip%3Agsclient@testers.com
<mailto:sip%253Agsclient@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
<mailto:sr-users@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 <mailto:sr-users@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