[Spce-user] Inbound call fail

Andreas Granig agranig at sipwise.com
Mon Apr 11 08:45:07 EDT 2011 

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|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
> 
> 
> 
> _______________________________________________
> Spce-user mailing list
> Spce-user at lists.sipwise.com
> http://lists.sipwise.com/listinfo/spce-user

-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 900 bytes
Desc: OpenPGP digital signature
URL: <http://lists.sipwise.com/pipermail/spce-user_lists.sipwise.com/attachments/20110411/04f3b7ac/attachment-0001.asc>

More information about the Spce-user mailing list