[Spce-user] Incoming DID not being passed to Subscriber (Asterisk) on SPCE - coming up as s at xxx.xxx.xxx.xxx

Bob Fryer bob at netintegrity.com.au
Sun Feb 16 00:11:02 EST 2014 

Dear List,

First of all let me state that the SIPWise product is really fantastic.
5 months 100% uptime - really impressive. CDR's working well.
Rewrite rules fully understood and working. I believe we have a
reasonable handle on the product. Still on Sipwise 2.8 - stock standard
install (e.g. no changes or additions in terms of other software)

We have been primarily using it for outgoing for all accounts except
one, ourselves, and incoming has been working with no major issues for a
few months (we only use one incoming number). We knew since the time we
started with incoming that there was a fault in terms of the incoming
number was not passing the incoming DID to the Asterisk PBX. So if we to
use the following "diagram" we are calling from a Mobile 0418181818, and
calling the number 0271027500. We have spent a great deal of time
ourselves to diagnose the issue, but we have been unsuccessful.

+++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
Call from Mobile | ----> SIPWISE                |  ------> Asterisk PBX 
0418181818       |       113.99.10.86           |          113.99.10.83
Calling          |       AKA sip.company.com.au |
0271027500       |       0271027500 setup as    |
                         E.164 Number           |
+++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++

The CLID and the DID (Called Number) are passed all the way from our
carrier, through SIPWISE (e.g. the CDR records and logs in SIPWISE show
the DID and CLID correctly), but it does not pass through to the
Asterisk PBX.

As you can see in the Asterisk log below, the DID appears to be not
coming through. The SIP Header (captured on the Asterisk PBX) from the
SIPWISE system shows

>From : <sip:0418181818 at sip.company.com.au>
To: <sip:s at sip.company.com.au>

And as you can see, this is coming through to the Asterisk system as
"S", instead of the DID (incoming number).

As you can see from the Kamailio log, it the incoming DID (0271027500 -
E164-61271027500) seems to be recognised all the way through, but does
not get to the Asterisk PBX.

The Asterisk PBX is registered to the SIPWISE account successfully, so
no issues there

elastixUCSho*CLI> sip show registry
Host                                    dnsmgr Username       Refresh
State                Reg.Time
sip.company.com.au:5060             N      12501100           105
Registered           Sun, 16 Feb 2014 16:05:20

Any help or direction on looking to resolve this, if you need captures
or other logs, will be happy to provide.

Regards

Bob



kamilio-proxy log
=================

Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>: New
request - M=INVITE R=sip:0271027500 at 113.99.10.86:5060
F=sip:0418181818 at 113.99.10.83 T=sip:0271027500 at 113.99.10.86:5060
IP=113.99.10.83:5060 (127.0.0.1:5060)
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>: Call
from PSTN - R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>: Load
peer preferences for peer host '6' - R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>: Load
dialplan IDs for peer host '6' - R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>:
Fetched dialplan IDs caller_in='5', callee_in='6' -
R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>: Use
'P-NGCP-Src-Ip' for CDR IP - R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>: Dialog
set mark total - R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>: Dialog
set mark peer to '6' - R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>: IP
authorization for caller not provisioned, allow call -
R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>:
User-Provided CLI '0418181818' taken from From-User -
R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>:
Network-Provided CLI '0418181818' taken from From-User -
R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>:
Applying caller-in peer rewrite rules on user-provided CLI using dpid
'5' - R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>:
Rewriting user-provided CLI '0418181818' to '61418181818' -
R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>:
Applying caller-in peer rewrite rules on network-provided CLI using dpid
'5' - R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>:
Rewriting network-provided CLI '0418181818' to '61418181818' -
R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>:
Setting '61418181818 at 113.99.10.83' as initiating user-provided CLI -
R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>:
Setting '61418181818 at 113.99.10.83' as initiating network-provided CLI -
R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>:
Searching for callee - R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>:
Applying callee-in peer rewrite rules using dpid '6' -
R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>:
Rewriting callee '0271027500' to '61271027500' -
R=sip:0271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>:
Skipping internal services for unauthenticated call -
R=sip:61271027500 at 113.99.10.86:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>: Callee
was aliased with base '61271027500' -
R=sip:12501100 at sip.company.com.au:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>: Clear
callee preferences - R=sip:12501100 at sip.company.com.au:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060
Feb 15 16:16:52 bpsip01 /usr/sbin/kamailio[2362]: INFO: <script>: Callee
is local, uuid='f3882219-9937-43c1-8717-6826977e4cb4' -
R=sip:12501100 at sip.company.com.au:5060
ID=4e879911731d41c0355b7f506d819aae at 113.99.10.83:5060

Asterisk PBX log (Incoming call)
================================

[Feb 15 16:16:52] VERBOSE[20549][C-000000d2] pbx.c:     -- Executing
[s at from-trunk-sip-BPSIP:1] Set("SIP/BPSIP-000002be", "GROUP()=OUT_2") in
new stack
[Feb 15 16:16:52] VERBOSE[20549][C-000000d2] pbx.c:     -- Executing
[s at from-trunk-sip-BPSIP:2] Goto("SIP/BPSIP-000002be", "from-trunk,s,1")
in new stack
[Feb 15 16:16:52] VERBOSE[20549][C-000000d2] pbx.c:     -- Goto
(from-trunk,s,1)
[Feb 15 16:16:52] VERBOSE[20549][C-000000d2] pbx.c:     -- Executing
[s at from-trunk:1] Set("SIP/BPSIP-000002be", "__FROM_DID=s") in new stack
[Feb 15 16:16:52] VERBOSE[20549][C-000000d2] pbx.c:     -- Executing
[s at from-trunk:2] Gosub("SIP/BPSIP-000002be", "app-blacklist-check,s,1")
in new stack
[Feb 15 16:16:52] VERBOSE[20549][C-000000d2] pbx.c:     -- Executing
[s at app-blacklist-check:1] GotoIf("SIP/BPSIP-000002be", "0?blacklisted")
in new stack
[Feb 15 16:16:52] VERBOSE[20549][C-000000d2] pbx.c:     -- Executing
[s at app-blacklist-check:2] Set("SIP/BPSIP-000002be",
"CALLED_BLACKLIST=1") in new stack
[Feb 15 16:16:52] VERBOSE[20549][C-000000d2] pbx.c:     -- Executing
[s at app-blacklist-check:3] Return("SIP/BPSIP-000002be", "") in new stack
[Feb 15 16:16:52] VERBOSE[20549][C-000000d2] pbx.c:     -- Executing
[s at from-trunk:3] ExecIf("SIP/BPSIP-000002be", "1
?Set(CALLERID(name)=0418181818)") in new stack

More information about the Spce-user mailing list