[Spce-user] Inbound call fail

Daniel-Constantin Mierla miconda at gmail.com
Mon Apr 11 07:03:30 EDT 2011


Hello,

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.

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|sipwise.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

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sipwise.com/pipermail/spce-user_lists.sipwise.com/attachments/20110411/a8cc46ca/attachment-0001.html>


More information about the Spce-user mailing list