[Spce-user] Inbound call fail
Carlos M. Perez
CPerez at cmpcs.com
Mon Apr 11 08:58:34 EDT 2011
Forgot to paste...
root at rundc1-switch01:~# ngrep -O /usr/src/trace1.pcap -d any -qt -W
byline port 5060
interface: any
filter: (ip or ip6) and ( port 5060 )
output: /usr/src/trace1.pcap
U 2011/04/11 11:46:26.896829 provider.ip:5060 -> sipwise.ip:5060
INVITE sip:atadid at sipwise.ip:5060 SIP/2.0.
Via: SIP/2.0/UDP provider.ip:5060;branch=z9hG4bK1sansay52301785rdb11876.
Record-Route:
<sip:sansay52301785rdb11876 at provider.ip:5060;lr;transport=udp>.
To: <sip:atadid at sipwise.ip>.
From: "CMP" <sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 1 INVITE.
Contact: <sip:E164caller at provider.ip:5060>.
Supported: timer.
Session-Expires: 1800;refresher=uac.
Min-SE: 90.
P-Asserted-Identity: "CMP" <sip:+E164caller at provider.ip3:5060>.
Max-Forwards: 15.
Content-Type: application/sdp.
Content-Length: 297.
.
v=0.
o=Sansay-VSXi 188 1 IN IP4 provider.ip.
s=Session Controller.
c=IN IP4 provider.ip4.
t=0 0.
m=audio 20196 RTP/AVP 0 8 18 101.
a=rtpmap:0 PCMU/8000.
a=rtpmap:8 PCMA/8000.
a=rtpmap:18 G729/8000.
a=fmtp:18 annexb=no.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=sendrecv.
a=maxptime:20.
U 2011/04/11 11:46:26.896964 sipwise.ip:5060 -> provider.ip:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP
provider.ip:5060;branch=z9hG4bK1sansay52301785rdb11876;rport=5060.
To: <sip:atadid at sipwise.ip>.
From: "CMP" <sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 1 INVITE.
Server: kamailio (3.0.4 (x86_64/linux)).
Content-Length: 0.
.
U 2011/04/11 11:46:26.899561 sipwise.ip:5060 -> ata.ip:5060
INVITE sip:atadid at 192.168.1.194:5060 SIP/2.0.
Record-Route:
<sip:sipwise.ip;lr=on;ftag=sansay52301785rdb11876;did=e74.a00e4cd2;vsf=c
zFwOldpemUhITExIXMxcDpXaXplISExMSFzMXA->.
Via: SIP/2.0/UDP sipwise.ip;branch=z9hG4bK5e91.7fab91d3.0.
Via: SIP/2.0/UDP
provider.ip:5060;rport=5060;branch=z9hG4bK1sansay52301785rdb11876.
Record-Route:
<sip:sansay52301785rdb11876 at provider.ip:5060;lr;transport=udp>.
To: <sip:atadid at sipwise.ip>.
From: "E164caller"
<sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 1 INVITE.
Contact: <sip:E164caller at provider.ip:5060>.
Supported: timer.
Session-Expires: 1800;refresher=uac.
Min-SE: 90.
Max-Forwards: 14.
Content-Type: application/sdp.
Content-Length: 317.
P-Asserted-Identity: <sip:E164caller at provider.ip>.
.
v=0.
o=Sansay-VSXi 188 1 IN IP4 sipwise.ip.
s=Session Controller.
c=IN IP4 sipwise.ip.
t=0 0.
m=audio 31946 RTP/AVP 0 8 18 101.
a=rtpmap:0 PCMU/8000.
a=rtpmap:8 PCMA/8000.
a=rtpmap:18 G729/8000.
a=fmtp:18 annexb=no.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=sendrecv.
a=maxptime:20.
a=nortpproxy:yes.
U 2011/04/11 11:46:26.981967 ata.ip:5060 -> sipwise.ip:5060
SIP/2.0 100 Trying.
To: <sip:atadid at sipwise.ip>.
From: "E164caller"
<sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 1 INVITE.
Via: SIP/2.0/UDP sipwise.ip;branch=z9hG4bK5e91.7fab91d3.0.
Via: SIP/2.0/UDP
provider.ip:5060;rport=5060;branch=z9hG4bK1sansay52301785rdb11876.
Record-Route:
<sip:sipwise.ip;lr=on;ftag=sansay52301785rdb11876;did=e74.a00e4cd2;vsf=c
zFwOldpemUhITExIXMxcDpXaXplISExMSFzMXA->.
Record-Route:
<sip:sansay52301785rdb11876 at provider.ip:5060;lr;transport=udp>.
Server: Linksys/SPA2102-5.2.12.
Content-Length: 0.
.
U 2011/04/11 11:46:26.988213 ata.ip:5060 -> sipwise.ip:5060
SIP/2.0 180 Ringing.
To: <sip:atadid at sipwise.ip>;tag=9eb4a6c4f87b606di0.
From: "E164caller"
<sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 1 INVITE.
Via: SIP/2.0/UDP sipwise.ip;branch=z9hG4bK5e91.7fab91d3.0.
Via: SIP/2.0/UDP
provider.ip:5060;rport=5060;branch=z9hG4bK1sansay52301785rdb11876.
Record-Route:
<sip:sipwise.ip;lr=on;ftag=sansay52301785rdb11876;did=e74.a00e4cd2;vsf=c
zFwOldpemUhITExIXMxcDpXaXplISExMSFzMXA->.
Record-Route:
<sip:sansay52301785rdb11876 at provider.ip:5060;lr;transport=udp>.
Contact: Fax <sip:atadid at 192.168.1.194:5060>.
Server: Linksys/SPA2102-5.2.12.
Remote-Party-ID: Fax <sip:atadid at sipwise.ip>;screen=yes;party=called.
Content-Length: 0.
.
U 2011/04/11 11:46:26.988390 sipwise.ip:5060 -> provider.ip:5060
SIP/2.0 180 Ringing.
To: <sip:atadid at sipwise.ip>;tag=9eb4a6c4f87b606di0.
From: "CMP" <sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 1 INVITE.
Via: SIP/2.0/UDP
provider.ip:5060;rport=5060;branch=z9hG4bK1sansay52301785rdb11876.
Record-Route:
<sip:sipwise.ip;lr=on;ftag=sansay52301785rdb11876;did=e74.a00e4cd2;vsf=c
zFwOldpemUhITExIXMxcDpXaXplISExMSFzMXA->.
Record-Route:
<sip:sansay52301785rdb11876 at provider.ip:5060;lr;transport=udp>.
Contact: Fax <sip:atadid at ata.ip:5060>.
Server: Linksys/SPA2102-5.2.12.
Remote-Party-ID: Fax <sip:atadid at sipwise.ip>;screen=yes;party=called.
Content-Length: 0.
.
U 2011/04/11 11:46:36.077220 sipwise.ip:5060 -> ata.ip:5060
OPTIONS sip:ata.ip:5060 SIP/2.0.
Via: SIP/2.0/UDP sipwise.ip:5060;branch=0.
From: sip:pinger at sipwise.local;tag=44d934a3.
To: sip:ata.ip:5060.
Call-ID: 1eb8e465-7ac88c04-6bdc855 at sipwise.ip.
CSeq: 1 OPTIONS.
Content-Length: 0.
.
U 2011/04/11 11:46:36.157821 ata.ip:5060 -> sipwise.ip:5060
SIP/2.0 404 Not Found.
To: sip:ata.ip:5060;tag=48fd0f6b29ba876i0.
From: sip:pinger at sipwise.local;tag=44d934a3.
Call-ID: 1eb8e465-7ac88c04-6bdc855 at sipwise.ip.
CSeq: 1 OPTIONS.
Via: SIP/2.0/UDP sipwise.ip:5060;branch=0.
Server: Linksys/SPA2102-5.2.12.
Content-Length: 0.
.
U 2011/04/11 11:46:36.368560 ata.ip:5060 -> sipwise.ip:5060
SIP/2.0 200 OK.
To: <sip:atadid at sipwise.ip>;tag=9eb4a6c4f87b606di0.
From: "E164caller"
<sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 1 INVITE.
Via: SIP/2.0/UDP sipwise.ip;branch=z9hG4bK5e91.7fab91d3.0.
Via: SIP/2.0/UDP
provider.ip:5060;rport=5060;branch=z9hG4bK1sansay52301785rdb11876.
Record-Route:
<sip:sipwise.ip;lr=on;ftag=sansay52301785rdb11876;did=e74.a00e4cd2;vsf=c
zFwOldpemUhITExIXMxcDpXaXplISExMSFzMXA->.
Record-Route:
<sip:sansay52301785rdb11876 at provider.ip:5060;lr;transport=udp>.
Contact: Fax <sip:atadid at 192.168.1.194:5060>.
Server: Linksys/SPA2102-5.2.12.
Remote-Party-ID: Fax <sip:atadid at sipwise.ip>;screen=yes;party=called.
Content-Length: 257.
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER.
Supported: x-sipura, replaces.
Content-Type: application/sdp.
.
v=0.
o=- 3294690 3294690 IN IP4 192.168.1.194.
s=-.
c=IN IP4 192.168.1.194.
t=0 0.
m=audio 16408 RTP/AVP 0 100 101.
a=rtpmap:0 PCMU/8000.
a=rtpmap:100 NSE/8000.
a=fmtp:100 192-193.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=ptime:20.
a=sendrecv.
U 2011/04/11 11:46:36.369078 sipwise.ip:5060 -> provider.ip:5060
SIP/2.0 200 OK.
To: <sip:atadid at sipwise.ip>;tag=9eb4a6c4f87b606di0.
From: "CMP" <sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 1 INVITE.
Via: SIP/2.0/UDP
provider.ip:5060;rport=5060;branch=z9hG4bK1sansay52301785rdb11876.
Record-Route:
<sip:sipwise.ip;lr=on;ftag=sansay52301785rdb11876;did=e74.a00e4cd2;vsf=c
zFwOldpemUhITExIXMxcDpXaXplISExMSFzMXA->.
Record-Route:
<sip:sansay52301785rdb11876 at provider.ip:5060;lr;transport=udp>.
Contact: Fax <sip:atadid at ata.ip:5060>.
Server: Linksys/SPA2102-5.2.12.
Remote-Party-ID: Fax <sip:atadid at sipwise.ip>;screen=yes;party=called.
Content-Length: 273.
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER.
Supported: x-sipura, replaces.
Content-Type: application/sdp.
.
v=0.
o=- 3294690 3294690 IN IP4 sipwise.ip.
s=-.
c=IN IP4 sipwise.ip.
t=0 0.
m=audio 35966 RTP/AVP 0 100 101.
a=rtpmap:0 PCMU/8000.
a=rtpmap:100 NSE/8000.
a=fmtp:100 192-193.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=ptime:20.
a=sendrecv.
a=nortpproxy:yes.
U 2011/04/11 11:46:36.436125 provider.ip:5060 -> sipwise.ip:5060
ACK sip:atadid at ata.ip:5060 SIP/2.0.
Via: SIP/2.0/UDP
provider.ip:5060;branch=z9hG4bK1sansay52301785rdb11876-200.
Route:
<sip:sipwise.ip;lr=on;ftag=sansay52301785rdb11876;did=e74.a00e4cd2;vsf=c
zFwOldpemUhITExIXMxcDpXaXplISExMSFzMXA->.
To: <sip:atadid at sipwise.ip>;tag=9eb4a6c4f87b606di0.
From: "CMP" <sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 1 ACK.
Max-Forwards: 70.
Content-Length: 0.
.
U 2011/04/11 11:46:36.436413 sipwise.ip:5060 -> ata.ip:5060
ACK sip:atadid at ata.ip:5060 SIP/2.0.
Record-Route: <sip:sipwise.ip;lr=on;ftag=sansay52301785rdb11876>.
Via: SIP/2.0/UDP sipwise.ip;branch=0.
Via: SIP/2.0/UDP
provider.ip:5060;rport=5060;branch=z9hG4bK1sansay52301785rdb11876-200.
To: <sip:atadid at sipwise.ip>;tag=9eb4a6c4f87b606di0.
From: "CMP" <sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 1 ACK.
Max-Forwards: 69.
Content-Length: 0.
.
U 2011/04/11 11:46:39.540246 ata.ip:5060 -> sipwise.ip:5060
INVITE sip:E164caller at provider.ip:5060 SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.194:5060;branch=z9hG4bK-cc3770ac.
From: <sip:atadid at sipwise.ip>;tag=9eb4a6c4f87b606di0.
To: "E164caller"
<sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Remote-Party-ID: Fax <sip:atadid at sipwise.ip>;screen=yes;party=called.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 101 INVITE.
Max-Forwards: 70.
Route:
<sip:sipwise.ip;lr=on;ftag=sansay52301785rdb11876;did=e74.a00e4cd2;vsf=c
zFwOldpemUhITExIXMxcDpXaXplISExMSFzMXA->,
<sip:sansay52301785rdb11876 at provider.ip:5060;lr;transport=udp>.
Contact: Fax <sip:atadid at 192.168.1.194:5060>.
Expires: 30.
User-Agent: Linksys/SPA2102-5.2.12.
Content-Length: 269.
Content-Type: application/sdp.
.
v=0.
o=- 3295945 3295945 IN IP4 192.168.1.194.
s=-.
c=IN IP4 192.168.1.194.
t=0 0.
m=image 16408 udptl t38.
a=T38FaxVersion:0.
a=T38MaxBitRate:14400.
a=T38FaxRateManagement:transferredTCF.
a=T38FaxMaxBuffer:200.
a=T38FaxMaxDatagram:200.
a=T38FaxUdpEC:t38UDPRedundancy.
U 2011/04/11 11:46:39.540356 sipwise.ip:5060 -> ata.ip:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP
192.168.1.194:5060;branch=z9hG4bK-cc3770ac;rport=5060;received=ata.ip.
From: <sip:atadid at sipwise.ip>;tag=9eb4a6c4f87b606di0.
To: "E164caller"
<sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 101 INVITE.
Server: kamailio (3.0.4 (x86_64/linux)).
Content-Length: 0.
.
U 2011/04/11 11:46:39.540645 sipwise.ip:5060 -> provider.ip:5060
INVITE sip:E164caller at provider.ip:5060 SIP/2.0.
Record-Route: <sip:sipwise.ip;lr=on;ftag=9eb4a6c4f87b606di0>.
Via: SIP/2.0/UDP sipwise.ip;branch=z9hG4bK1883.9df37cf6.0.
Via: SIP/2.0/UDP
192.168.1.194:5060;rport=5060;received=ata.ip;branch=z9hG4bK-cc3770ac.
From: <sip:atadid at sipwise.ip>;tag=9eb4a6c4f87b606di0.
To: "E164caller"
<sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 101 INVITE.
Max-Forwards: 69.
Route: <sip:sansay52301785rdb11876 at provider.ip:5060;lr;transport=udp>.
Contact: Fax <sip:atadid at ata.ip:5060>.
Expires: 30.
User-Agent: Linksys/SPA2102-5.2.12.
Content-Length: 269.
Content-Type: application/sdp.
.
v=0.
o=- 3295945 3295945 IN IP4 192.168.1.194.
s=-.
c=IN IP4 192.168.1.194.
t=0 0.
m=image 16408 udptl t38.
a=T38FaxVersion:0.
a=T38MaxBitRate:14400.
a=T38FaxRateManagement:transferredTCF.
a=T38FaxMaxBuffer:200.
a=T38FaxMaxDatagram:200.
a=T38FaxUdpEC:t38UDPRedundancy.
U 2011/04/11 11:46:39.606771 provider.ip:5060 -> sipwise.ip:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP sipwise.ip;branch=z9hG4bK1883.9df37cf6.0.
Via: SIP/2.0/UDP
192.168.1.194:5060;rport=5060;received=ata.ip;branch=z9hG4bK-cc3770ac.
To: "CMP" <sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
From: <sip:atadid at sipwise.ip>;tag=9eb4a6c4f87b606di0.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 101 INVITE.
Content-Length: 0.
.
U 2011/04/11 11:46:48.134483 sipwise.ip:5060 -> ata.ip:5060
SIP/2.0 408 Request Timeout.
Via: SIP/2.0/UDP
192.168.1.194:5060;branch=z9hG4bK-a0e6cd7c;rport=5060;received=ata.ip.
From: <sip:atadid at sipwise.ip>;tag=936e432e78bb1c1di0.
To: "E164caller"
<sip:E164caller at provider.ip>;tag=sansay52300430rdb11785.
Call-ID: 22013328-0-473892250 at provider.ip2.
CSeq: 101 INVITE.
Server: kamailio (3.0.4 (x86_64/linux)).
Content-Length: 0.
.
U 2011/04/11 11:46:48.134518 sipwise.ip:5060 -> provider.ip:5060
CANCEL sip:E164caller at provider.ip:5060 SIP/2.0.
Via: SIP/2.0/UDP sipwise.ip;branch=z9hG4bKa71d.81b6e495.0.
From: <sip:atadid at sipwise.ip>;tag=936e432e78bb1c1di0.
To: "E164caller"
<sip:E164caller at provider.ip>;tag=sansay52300430rdb11785.
Call-ID: 22013328-0-473892250 at provider.ip2.
CSeq: 101 CANCEL.
Max-Forwards: 69.
Route: <sip:sansay52300430rdb11785 at provider.ip:5060;lr;transport=udp>.
Content-Length: 0.
.
U 2011/04/11 11:46:48.197092 provider.ip:5060 -> sipwise.ip:5060
SIP/2.0 481 transaction does not exist.
Via: SIP/2.0/UDP sipwise.ip;branch=z9hG4bKa71d.81b6e495.0.
To: "E164caller"
<sip:E164caller at provider.ip>;tag=sansay52300430rdb11785.
From: <sip:atadid at sipwise.ip>;tag=936e432e78bb1c1di0.
Call-ID: 22013328-0-473892250 at provider.ip2.
CSeq: 101 CANCEL.
Content-Length: 0.
.
U 2011/04/11 11:46:48.634453 sipwise.ip:5060 -> ata.ip:5060
SIP/2.0 408 Request Timeout.
Via: SIP/2.0/UDP
192.168.1.194:5060;branch=z9hG4bK-a0e6cd7c;rport=5060;received=ata.ip.
From: <sip:atadid at sipwise.ip>;tag=936e432e78bb1c1di0.
To: "E164caller"
<sip:E164caller at provider.ip>;tag=sansay52300430rdb11785.
Call-ID: 22013328-0-473892250 at provider.ip2.
CSeq: 101 INVITE.
Server: kamailio (3.0.4 (x86_64/linux)).
Content-Length: 0.
.
U 2011/04/11 11:46:49.634458 sipwise.ip:5060 -> ata.ip:5060
SIP/2.0 408 Request Timeout.
Via: SIP/2.0/UDP
192.168.1.194:5060;branch=z9hG4bK-a0e6cd7c;rport=5060;received=ata.ip.
From: <sip:atadid at sipwise.ip>;tag=936e432e78bb1c1di0.
To: "E164caller"
<sip:E164caller at provider.ip>;tag=sansay52300430rdb11785.
Call-ID: 22013328-0-473892250 at provider.ip2.
CSeq: 101 INVITE.
Server: kamailio (3.0.4 (x86_64/linux)).
Content-Length: 0.
.
U 2011/04/11 11:46:51.436900 provider.ip:5060 -> sipwise.ip:5060
BYE sip:atadid at ata.ip:5060 SIP/2.0.
Via: SIP/2.0/UDP provider.ip:5060;branch=z9hG4bK2sansay52301785rdb11876.
Route:
<sip:sipwise.ip;lr=on;ftag=sansay52301785rdb11876;did=e74.a00e4cd2;vsf=c
zFwOldpemUhITExIXMxcDpXaXplISExMSFzMXA->.
To: <sip:atadid at sipwise.ip>;tag=9eb4a6c4f87b606di0.
From: "CMP" <sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 2 BYE.
Max-Forwards: 70.
Content-Length: 0.
.
U 2011/04/11 11:46:51.437007 sipwise.ip:5060 -> provider.ip:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP
provider.ip:5060;branch=z9hG4bK2sansay52301785rdb11876;rport=5060.
To: <sip:atadid at sipwise.ip>;tag=9eb4a6c4f87b606di0.
From: "CMP" <sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 2 BYE.
Server: kamailio (3.0.4 (x86_64/linux)).
Content-Length: 0.
.
U 2011/04/11 11:46:51.437383 sipwise.ip:5060 -> ata.ip:5060
BYE sip:atadid at ata.ip:5060 SIP/2.0.
Record-Route: <sip:sipwise.ip;lr=on;ftag=sansay52301785rdb11876>.
Via: SIP/2.0/UDP sipwise.ip;branch=z9hG4bK2e91.c8c21704.0.
Via: SIP/2.0/UDP
provider.ip:5060;rport=5060;branch=z9hG4bK2sansay52301785rdb11876.
To: <sip:atadid at sipwise.ip>;tag=9eb4a6c4f87b606di0.
From: "CMP" <sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 2 BYE.
Max-Forwards: 69.
Content-Length: 0.
.
U 2011/04/11 11:46:51.517971 ata.ip:5060 -> sipwise.ip:5060
SIP/2.0 200 OK.
To: <sip:atadid at sipwise.ip>;tag=9eb4a6c4f87b606di0.
From: "CMP" <sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 2 BYE.
Via: SIP/2.0/UDP sipwise.ip;branch=z9hG4bK2e91.c8c21704.0.
Via: SIP/2.0/UDP
provider.ip:5060;rport=5060;branch=z9hG4bK2sansay52301785rdb11876.
Record-Route: <sip:sipwise.ip;lr=on;ftag=sansay52301785rdb11876>.
Server: Linksys/SPA2102-5.2.12.
Content-Length: 0.
.
U 2011/04/11 11:46:51.518116 sipwise.ip:5060 -> provider.ip:5060
SIP/2.0 200 OK.
To: <sip:atadid at sipwise.ip>;tag=9eb4a6c4f87b606di0.
From: "CMP" <sip:E164caller at provider.ip>;tag=sansay52301785rdb11876.
Call-ID: 22013908-0-474063590 at provider.ip2.
CSeq: 2 BYE.
Via: SIP/2.0/UDP
provider.ip:5060;rport=5060;branch=z9hG4bK2sansay52301785rdb11876.
Record-Route: <sip:sipwise.ip;lr=on;ftag=sansay52301785rdb11876>.
Server: Linksys/SPA2102-5.2.12.
Content-Length: 0.
Carlos M. Perez
CMP Consulting Services
-----Original Message-----
From: spce-user-bounces at lists.sipwise.com
[mailto:spce-user-bounces at lists.sipwise.com] On Behalf Of Andreas Granig
Sent: Monday, April 11, 2011 8:45 AM
To: spce-user at lists.sipwise.com
Subject: Re: [Spce-user] Inbound call fail
On 04/11/2011 01:03 PM, Daniel-Constantin Mierla wrote:
> can you get a sip trace of such call?
>
> ngrep -d any -qt -W byline port 5060
>
> It will show what is the reply code for failed call, giving hints
> where it get stuck.
Obviously, the re-INVITE gets stuck somewhere, and therefore one party
sends BYE to hang-up the call.
Yes, a SIP trace could help to determine if the re-INVITE is crafted
properly.
Andreas
>
> Cheers,
> Daniel
>
> On 4/11/11 6:01 AM, Carlos M. Perez wrote:
>>
>> Hi,
>>
>>
>>
>> We have recently setup a SPCE server, and we can make outbound calls
>> correctly, but inbound calls fail. We followed the instructions as
>> best we could. The purpose of this test box is to get ATA's
>> successfully dialing out and receiving calls. These calls will be
>> used for both voice and fax.
>>
>>
>>
>> We have a domain setup with Public IP address. The Inbound rewrite
>> for caller/callee are blank, as our provider gives us E164 compliant
>> CLID without the + sign. We will always receive <cc><ac><sn> as
>> described in the instructions. Our outbound rewriting is handled by
>> the ATA to ensure that we always dial as 1<ac><sn> for North America
>> based calls. We will not be supporting international calls at this
>> time with our first provider. We are blocking these types of calls
>> at the ata level.
>>
>>
>>
>> Here's the context of a call inbound. I've sanitized it as follows:
>> (hopefully I haven't pasted too much)
>>
>>
>>
>> - atadid = 11 digit matching the DID 1786xxxxxxx
>>
>> - ata.ip = Public IP of NAT router. Private IP as seen is
>> 192.168.1.194
>>
>> - E164caller = the CID of the inbound call it is 1305xxxxxxx
>>
>> - sipwise.ip = public IP of the Sipwise server (no-nat)
>>
>> - provider.ip = IP of provider we register against
>>
>> - provider.ip2 = IP of provider media?
>>
>>
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: New request - M=INVITE R=sip:atadid at sipwise.ip:5060
>> F=sip:E164caller at provider.ip T=sip:atadid at sipwise.ip
>> IP=provider.ip:5060 ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Call from PSTN - M=INVITE R=sip:atadid at sipwise.ip:5060
>> F=sip:E164caller at provider.ip T=sip:atadid at sipwise.ip
>> IP=provider.ip:5060 ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Load dialplan IDs for peer host '1' - M=INVITE
>> R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Fetched dialplan IDs caller_in='9', callee_in='10' -
>> M=INVITE R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Clear caller preferences - M=INVITE
>> R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Load caller preferences for uuid '0' - M=INVITE
>> R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: IP authorization for caller not provisioned, allow call -
>> M=INVITE R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: User-provided CLI 'E164caller' in From found - M=INVITE
>> R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Applying caller-in peer rewrite rules on user-provided CLI
>> using dpid '9' - M=INVITE R=sip:atadid at sipwise.ip:5060
>> F=sip:E164caller at provider.ip T=sip:atadid at sipwise.ip
>> IP=provider.ip:5060 ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: No matching rewrite rules for 'E164caller' found - M=INVITE
>> R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Setting 'E164caller at provider.ip' as initiating CLI -
>> M=INVITE R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Searching for callee - M=INVITE
>> R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Applying callee-in peer rewrite rules using dpid '10' -
>> M=INVITE R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: No matching rewrite rules for 'atadid' found - M=INVITE
>> R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Callee was aliased with base 'atadid' - M=INVITE
>> R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Clear callee preferences - M=INVITE
>> R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Callee is local,
uuid='81a82ee6-ec49-4e17-8030-8912317852a8'
>> - M=INVITE R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Check Administrative NCOS level - M=INVITE
>> R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Check User NCOS level - M=INVITE
>> R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Admin caller block mode is 'open' - M=INVITE
>> R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Caller block mode is 'open' - M=INVITE
>> R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Load callee preferences for uuid
>> '81a82ee6-ec49-4e17-8030-8912317852a8' - M=INVITE
>> R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Admin callee block mode is 'open' - M=INVITE
>> R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Callee block mode is 'open' - M=INVITE
>> R=sip:atadid at sipwise.ip:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Local user online, using base as user - M=INVITE
>> R=sip:atadid at 192.168.1.194:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Setting acc source-leg for uuid '0':
>> '0|E164caller|provider.ip|E164caller|0|call' - M=INVITE
>> R=sip:atadid at 192.168.1.194:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Setting acc destination-leg for uuid
>> '81a82ee6-ec49-4e17-8030-8912317852a8':
>>
'atadid|81a82ee6-ec49-4e17-8030-8912317852a8|atadid|sipwise.ip|atadid|si
pwise.ip'
>> - M=INVITE R=sip:atadid at 192.168.1.194:5060
>> F=sip:E164caller at provider.ip T=sip:atadid at sipwise.ip
>> IP=provider.ip:5060 ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Relaying request - M=INVITE R=sip:atadid at 192.168.1.194:5060
>> F=sip:E164caller at provider.ip T=sip:atadid at sipwise.ip
>> IP=provider.ip:5060 ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Use mediaproxy for forward direction - M=INVITE
>> R=sip:atadid at 192.168.1.194:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: First caller CLI 'E164caller' found - M=INVITE
>> R=sip:atadid at 192.168.1.194:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Applying caller-out domain rewrite rules to first caller
>> CLI using dpid '7' - M=INVITE R=sip:atadid at 192.168.1.194:5060
>> F=sip:E164caller at provider.ip T=sip:atadid at sipwise.ip
>> IP=provider.ip:5060 ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: No matching rewrite rules for 'E164caller' found - M=INVITE
>> R=sip:atadid at 192.168.1.194:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Set caller CLI 'E164caller <sip:E164caller at provider.ip>' -
>> M=INVITE R=sip:atadid at 192.168.1.194:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21633]: INFO:
>> <script>: Request leaving server, D-URI='sip:ata.ip:5060' - M=INVITE
>> R=sip:atadid at 192.168.1.194:5060 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21634]: INFO:
>> <script>: NAT-Reply - S=100 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=ata.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:18 rundc1-switch01 /usr/sbin/kamailio[21638]: INFO:
>> <script>: NAT-Reply - S=180 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=ata.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:27 rundc1-switch01 /usr/sbin/kamailio[21628]: INFO:
>> <script>: NAT-Reply - S=200 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=ata.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:27 rundc1-switch01 /usr/sbin/kamailio[21628]: INFO:
>> <script>: Use mediaproxy for backward direction - S=200
>> F=sip:E164caller at provider.ip T=sip:atadid at sipwise.ip IP=ata.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:28 rundc1-switch01 /usr/sbin/kamailio[21637]: INFO:
>> <script>: New request - M=ACK R=sip:atadid at ata.ip:5060
>> F=sip:E164caller at provider.ip T=sip:atadid at sipwise.ip
>> IP=provider.ip:5060 ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:28 rundc1-switch01 /usr/sbin/kamailio[21637]: INFO:
>> <script>: Relaying request - M=ACK R=sip:atadid at ata.ip:5060
>> F=sip:E164caller at provider.ip T=sip:atadid at sipwise.ip
>> IP=provider.ip:5060 ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:31 rundc1-switch01 /usr/sbin/kamailio[21627]: INFO:
>> <script>: New request - M=INVITE R=sip:E164caller at provider.ip:5060
>> F=sip:atadid at sipwise.ip T=sip:E164caller at provider.ip IP=ata.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:31 rundc1-switch01 /usr/sbin/kamailio[21627]: INFO:
>> <script>: Relaying request - M=INVITE
>> R=sip:E164caller at provider.ip:5060 F=sip:atadid at sipwise.ip
>> T=sip:E164caller at provider.ip IP=ata.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:31 rundc1-switch01 /usr/sbin/kamailio[21629]: INFO:
>> <script>: NAT-Reply - S=100 F=sip:atadid at sipwise.ip
>> T=sip:E164caller at provider.ip IP=provider.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:43 rundc1-switch01 /usr/sbin/kamailio[21630]: INFO:
>> <script>: New request - M=BYE R=sip:atadid at ata.ip:5060
>> F=sip:E164caller at provider.ip T=sip:atadid at sipwise.ip
>> IP=provider.ip:5060 ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:43 rundc1-switch01 /usr/sbin/kamailio[21630]: INFO:
>> <script>: Stop mediaproxy - M=BYE R=sip:atadid at ata.ip:5060
>> F=sip:E164caller at provider.ip T=sip:atadid at sipwise.ip
>> IP=provider.ip:5060 ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:43 rundc1-switch01 /usr/sbin/kamailio[21630]: INFO:
>> <script>: Relaying request - M=BYE R=sip:atadid at ata.ip:5060
>> F=sip:E164caller at provider.ip T=sip:atadid at sipwise.ip
>> IP=provider.ip:5060 ID=21957837-0-443276480 at provider.ip2
>>
>> Apr 11 03:13:43 rundc1-switch01 /usr/sbin/kamailio[21636]: INFO:
>> <script>: NAT-Reply - S=200 F=sip:E164caller at provider.ip
>> T=sip:atadid at sipwise.ip IP=ata.ip:5060
>> ID=21957837-0-443276480 at provider.ip2
>>
>>
>>
>> Thanks in advance,
>>
>>
>>
>> Carlos M. Perez
>>
>> CMP Consulting Services
>>
>>
>>
>>
>> _______________________________________________
>> Spce-user mailing list
>> Spce-user at lists.sipwise.com
>> http://lists.sipwise.com/listinfo/spce-user
>
> --
> Daniel-Constantin Mierla
> http://www.asipto.com
>
>
>
> _______________________________________________
> Spce-user mailing list
> Spce-user at lists.sipwise.com
> http://lists.sipwise.com/listinfo/spce-user
More information about the Spce-user
mailing list