[Spce-user] Force CLI

Mon May 13 04:53:37 EDT 2013

Hi,

Unfortunately it didn't work out.

The PAI seems to keep on defaulting to the From User set ... this time
round, I did it from an X-lite ... if I set From user to blank, it picks up
the user_cli. When I put in a funny From user such
as somefromusersetfromasterisk in this case, in the sip trace it uses that
as the from user thus the end result is an anonymous user.

Is there a bug here?

root at km1:/var/log/ngcp# tail kamailio-proxy.log -f
May 13 18:45:10 km1 /usr/sbin/kamailio[1837]: INFO: <script>: Stop
mediaproxy for current branch using first Via -
R=sip:0123456789 at 1.2.3.4ID=N2YwNzY5NzUxMzhhZGQzMTlhZDUzMjIzN2E1MzIzOGM
May 13 18:45:10 km1 /usr/sbin/kamailio[1837]: INFO: <script>: Request
leaving server via local route -
R=sip:0123456789 at 1.2.3.4ID=N2YwNzY5NzUxMzhhZGQzMTlhZDUzMjIzN2E1MzIzOGM
May 13 18:45:10 km1 /usr/sbin/kamailio[1835]: INFO: <script>: NAT-Reply -
S=487 - Request terminated M=INVITE IP=58.96.95.191:59992 (127.0.0.1:5080)
ID=N2YwNzY5NzUxMzhhZGQzMTlhZDUzMjIzN2E1MzIzOGM
May 13 18:45:10 km1 /usr/sbin/kamailio[1835]: INFO: <script>: Failure route
for PSTN call -
R=sip:0123456789 at 1.2.3.4ID=N2YwNzY5NzUxMzhhZGQzMTlhZDUzMjIzN2E1MzIzOGM
May 13 18:45:10 km1 /usr/sbin/kamailio[1835]: INFO: <script>: Unset dialog
mark peer and peerout '2' -
R=sip:0123456789 at 1.2.3.4ID=N2YwNzY5NzUxMzhhZGQzMTlhZDUzMjIzN2E1MzIzOGM
May 13 18:45:10 km1 /usr/sbin/kamailio[1835]: INFO: <script>: Filter reply
code - R=sip:0123456789 at 1.2.3.4ID=N2YwNzY5NzUxMzhhZGQzMTlhZDUzMjIzN2E1MzIzOGM
May 13 18:45:10 km1 /usr/sbin/kamailio[1835]: INFO: <script>: No failover
routing needed for this response code -
R=sip:0123456789 at 1.2.3.4ID=N2YwNzY5NzUxMzhhZGQzMTlhZDUzMjIzN2E1MzIzOGM
May 13 18:45:10 km1 /usr/sbin/kamailio[1835]: INFO: <script>: Stop
mediaproxy for current branch using first Via -
R=sip:0123456789 at 1.2.3.4ID=N2YwNzY5NzUxMzhhZGQzMTlhZDUzMjIzN2E1MzIzOGM
May 13 18:45:10 km1 /usr/sbin/kamailio[1835]: ERROR: rtpproxy-ng
[rtpproxy.c:1318]: proxy replied with error: Call-ID not found or tags
didn't match
May 13 18:45:10 km1 /usr/sbin/kamailio[1828]: INFO: <script>: New request -
M=ACK R=sip:0123456789 at 1.2.3.4 F=sip:61390207909 at 1.2.3.4 T=
sip:0123456789 at 1.2.3.4 IP=58.96.95.191:59992 (127.0.0.1:5060)
ID=N2YwNzY5NzUxMzhhZGQzMTlhZDUzMjIzN2E1MzIzOGM
May 13 18:45:36 km1 /usr/sbin/kamailio[1828]: INFO: <script>: New request -
M=INVITE R=sip:0123456789 at 1.2.3.4 F=sip:61390207909 at 1.2.3.4 T=
sip:0123456789 at 1.2.3.4 IP=58.96.95.191:59992 (127.0.0.1:5060)
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1828]: INFO: <script>:
Authentication failed, no credentials -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: New request -
M=INVITE R=sip:0123456789 at 1.2.3.4 F=sip:61390207909 at 1.2.3.4 T=
sip:0123456789 at 1.2.3.4 IP=58.96.95.191:59992 (127.0.0.1:5060)
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Clear caller
preferences - R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Load caller
preferences for uuid '0a05d717-3927-43cc-94dc-12d8df5d1192' and domain part
of uri 'sip:61390207909 at 1.2.3.4' -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Load dialplan
IDs for domain 'sip:61390207909 at 1.2.3.4' -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Fetched
dialplan IDs caller_in='<null>', callee_in='<null>' - R=
sip:0123456789 at 1.2.3.4 ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Use
'P-NGCP-Src-Ip' for CDR IP -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Dialog set
total - R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1839]: INFO: <script>: New request -
M=REGISTER R=sip:1.2.3.4 F=sip:mwhome at 1.2.3.4 T=sip:mwhome at 1.2.3.4 IP=
58.96.95.189:5060 (127.0.0.1:5060) ID=2875315338 at 192_168_7_100
May 13 18:45:36 km1 /usr/sbin/kamailio[1839]: INFO: <script>:
Authentication failed, no credentials - R=sip:1.2.3.4
ID=2875315338 at 192_168_7_100
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Dialog set
user, userout, account, accountout to
'0a05d717-3927-43cc-94dc-12d8df5d1192/6' -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: IP
authorization for caller not provisioned, allow call - R=
sip:0123456789 at 1.2.3.4 ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: User-Provided
CLI '61390207909' taken from From-User -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Checking
User-provided CLI against allowed CLIs -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: User-provided
CLI '61390207909' rejected, using provisioned user-provided CLI
'61390207912' -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Setting '
61390207912 at 1.2.3.4' as initiating user-provided CLI - R=
sip:0123456789 at 1.2.3.4 ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Setting '
61390207912 at 1.2.3.4' as initiating network-provided CLI - R=
sip:0123456789 at 1.2.3.4 ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Searching for
callee - R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Clear callee
preferences - R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Callee is not
local - R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Check
Administrative NCOS level -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Check User
NCOS level - R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Checking URI '
0123456789 at 1.2.3.4' and CLI '0123456789' against block out lists - R=
sip:0123456789 at 1.2.3.4 ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Admin caller
block mode is 'open' -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Caller block
mode is 'open' -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Call to SIP
Peering - R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Load gws
matching calling part 'sip:61390207912 at 1.2.3.4' and called user
'0123456789' and called part 'sip:0123456789 at 1.2.3.4' - R=
sip:0123456789 at 1.2.3.4 ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Dialog set
mark type to outgoing -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Load peer
preferences for peer host '2' - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Load dialplan
IDs for peer host '2' - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Fetched
dialplan IDs caller_out='<null>', callee_out='<null>' -
R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Dialog set
mark peer and peerout to '2' - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Setting acc
source-leg for uuid '0a05d717-3927-43cc-94dc-12d8df5d1192':
'0a05d717-3927-43cc-94dc-12d8df5d1192|61390207909|1.2.3.4|61390207912||binaryelements|6|||0|call|58.96.95.191|1368434736.785815'
- R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Setting acc
destination-leg for uuid '0':
'0|||<null>|0123456789|0|0123456789|5.6.7.8|0123456789|1.2.3.4' -
R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Relaying
request, du='<null>' - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Use
mediaproxy for forward direction for IPv4/IPv4 -
R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Remove
existing ICE candidates (if any) for callee -
R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Setting
P-Caller-UUID to '0a05d717-3927-43cc-94dc-12d8df5d1192' -
R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Setting
P-Callee-UUID to '0' - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Sessions
Timers disabled for caller - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Sessions
Timers disabled for callee - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Prepare From
display-name setting - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: No selector
set, not setting CLI - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Prepare From
username setting - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Setting
display-name/caller_domain_userprov 'somefromusersetfromasterisk at 1.2.3.4'
for rcv_display - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Setting From
to '<sip:somefromusersetfromasterisk at 1.2.3.4>' -
R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Prepare PAI
username setting - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Setting
display-name/caller_domain_userprov 'somefromusersetfromasterisk at 1.2.3.4'
for rcv_display - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Setting PAI
to '<sip:somefromusersetfromasterisk at 1.2.3.4>' -
R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Prepare PPI
username setting - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: No selector
set, not setting CLI - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Prepare
Diversion setting - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: No selector
set, not setting CLI - R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Setting
P-Called-Party-ID '<sip:0123456789 at 5.6.7.8>' -
R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Writing sbc
parameters  ;aleg_sst_enable=no;sst_enable=no -
R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Setting 'sip:
5.6.7.8:5060' taken from R-URI as next hop after lb for PSTN call -
R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Appending
P-D-URI 'sip:127.0.0.1:5060;received='sip:5.6.7.8:5060;transport=udp'' -
R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Forcing
request via B2BUA 'sip:127.0.0.1:5080' -
R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Request
leaving server, D-URI='sip:127.0.0.1:5080' -
R=sip:0123456789 at 5.6.7.8:5060;transport=udp
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1830]: INFO: <script>: NAT-Reply -
S=100 - Connecting M=INVITE IP=58.96.95.191:59992 (127.0.0.1:5080)
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:36 km1 /usr/sbin/kamailio[1837]: INFO: <script>: New request -
M=REGISTER R=sip:1.2.3.4 F=sip:mwhome at 1.2.3.4 T=sip:mwhome at 1.2.3.4 IP=
58.96.95.189:5060 (127.0.0.1:5060) ID=2875315338 at 192_168_7_100
May 13 18:45:36 km1 /usr/sbin/kamailio[1837]: INFO: <script>: Load prefs
for uuid '12b76cd8-ff45-4c5a-8a26-31a287963c85' - R=sip:1.2.3.4
ID=2875315338 at 192_168_7_100
May 13 18:45:36 km1 /usr/sbin/kamailio[1837]: INFO: <script>: IP
authorization not provisioned, allow registration - R=sip:1.2.3.4
ID=2875315338 at 192_168_7_100
May 13 18:45:36 km1 /usr/sbin/kamailio[1837]: INFO: <script>: Load caller
preferences for uuid '12b76cd8-ff45-4c5a-8a26-31a287963c85' and domain part
of uri 'sip:mwhome at 1.2.3.4' - R=sip:1.2.3.4 ID=2875315338 at 192_168_7_100
May 13 18:45:36 km1 /usr/sbin/kamailio[1837]: INFO: <script>: Contacts
successfully updated - R=sip:1.2.3.4 ID=2875315338 at 192_168_7_100
May 13 18:45:40 km1 /usr/sbin/kamailio[1835]: INFO: <script>: New request -
M=REGISTER R=sip:1.2.3.4:5060 F=sip:61390207900 at 1.2.3.4:5060 T=
sip:61390207900 at 1.2.3.4:5060 IP=192.168.3.5:5067 (127.0.0.1:5060)
ID=NDQ4N2VlMWU1MWM4MjNkZmFiZDEyOGQwODZjNDZlZWQ.
May 13 18:45:40 km1 /usr/sbin/kamailio[1835]: INFO: <script>:
Authentication failed, stale nonce -
R=sip:1.2.3.4:5060ID=NDQ4N2VlMWU1MWM4MjNkZmFiZDEyOGQwODZjNDZlZWQ.
May 13 18:45:40 km1 /usr/sbin/kamailio[1828]: INFO: <script>: New request -
M=REGISTER R=sip:1.2.3.4:5060 F=sip:61390207900 at 1.2.3.4:5060 T=
sip:61390207900 at 1.2.3.4:5060 IP=192.168.3.5:5067 (127.0.0.1:5060)
ID=NDQ4N2VlMWU1MWM4MjNkZmFiZDEyOGQwODZjNDZlZWQ.
May 13 18:45:40 km1 /usr/sbin/kamailio[1828]: INFO: <script>: Load prefs
for uuid '7a21b5c7-20a2-42e9-a0a3-d5c4b49a9706' -
R=sip:1.2.3.4:5060ID=NDQ4N2VlMWU1MWM4MjNkZmFiZDEyOGQwODZjNDZlZWQ.
May 13 18:45:40 km1 /usr/sbin/kamailio[1828]: INFO: <script>: IP
authorization not provisioned, allow registration -
R=sip:1.2.3.4:5060ID=NDQ4N2VlMWU1MWM4MjNkZmFiZDEyOGQwODZjNDZlZWQ.
May 13 18:45:40 km1 /usr/sbin/kamailio[1828]: INFO: <script>: Load caller
preferences for uuid '7a21b5c7-20a2-42e9-a0a3-d5c4b49a9706' and domain part
of uri 'sip:61390207900 at 1.2.3.4' -
R=sip:1.2.3.4:5060ID=NDQ4N2VlMWU1MWM4MjNkZmFiZDEyOGQwODZjNDZlZWQ.
May 13 18:45:40 km1 /usr/sbin/kamailio[1828]: INFO: <script>: Contacts
successfully updated -
R=sip:1.2.3.4:5060ID=NDQ4N2VlMWU1MWM4MjNkZmFiZDEyOGQwODZjNDZlZWQ.
May 13 18:45:43 km1 /usr/sbin/kamailio[1826]: INFO: <script>: NAT-Reply -
S=183 - Session Progress M=INVITE IP=58.96.95.191:59992 (127.0.0.1:5080)
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:43 km1 /usr/sbin/kamailio[1826]: INFO: <script>: Remove
existing ICE candidates (if any) for caller -
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:43 km1 /usr/sbin/kamailio[1826]: INFO: <script>: Use
mediaproxy for backward direction -
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:45 km1 /usr/sbin/kamailio[1839]: INFO: <script>: New request -
M=REGISTER R=sip:1.2.3.4:5060 F=sip:61388041713 at 1.2.3.4:5060 T=
sip:61388041713 at 1.2.3.4:5060 IP=192.168.4.2:5060 (127.0.0.1:5060)
ID=MGYyNjY5N2MyNTE2MTM5YzIwMWIwODc5YmE3ZWMyMzk.
May 13 18:45:45 km1 /usr/sbin/kamailio[1839]: INFO: <script>:
Authentication failed, stale nonce -
R=sip:1.2.3.4:5060ID=MGYyNjY5N2MyNTE2MTM5YzIwMWIwODc5YmE3ZWMyMzk.
May 13 18:45:45 km1 /usr/sbin/kamailio[1843]: INFO: <script>: New request -
M=REGISTER R=sip:1.2.3.4:5060 F=sip:61388041713 at 1.2.3.4:5060 T=
sip:61388041713 at 1.2.3.4:5060 IP=192.168.4.2:5060 (127.0.0.1:5060)
ID=MGYyNjY5N2MyNTE2MTM5YzIwMWIwODc5YmE3ZWMyMzk.
May 13 18:45:45 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Load prefs
for uuid '7511213e-162b-4fc0-9451-d0711fc693ea' -
R=sip:1.2.3.4:5060ID=MGYyNjY5N2MyNTE2MTM5YzIwMWIwODc5YmE3ZWMyMzk.
May 13 18:45:45 km1 /usr/sbin/kamailio[1843]: INFO: <script>: IP
authorization not provisioned, allow registration -
R=sip:1.2.3.4:5060ID=MGYyNjY5N2MyNTE2MTM5YzIwMWIwODc5YmE3ZWMyMzk.
May 13 18:45:45 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Load caller
preferences for uuid '7511213e-162b-4fc0-9451-d0711fc693ea' and domain part
of uri 'sip:61388041713 at 1.2.3.4' -
R=sip:1.2.3.4:5060ID=MGYyNjY5N2MyNTE2MTM5YzIwMWIwODc5YmE3ZWMyMzk.
May 13 18:45:45 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Contacts
successfully updated -
R=sip:1.2.3.4:5060ID=MGYyNjY5N2MyNTE2MTM5YzIwMWIwODc5YmE3ZWMyMzk.
May 13 18:45:51 km1 /usr/sbin/kamailio[1837]: INFO: <script>: New request -
M=CANCEL R=sip:0123456789 at 1.2.3.4 F=sip:61390207909 at 1.2.3.4 T=
sip:0123456789 at 1.2.3.4 IP=58.96.95.191:59992 (127.0.0.1:5060)
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:51 km1 /usr/sbin/kamailio[1837]: INFO: <script>: Stop
mediaproxy for current branch using first Via -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:51 km1 /usr/sbin/kamailio[1837]: INFO: <script>: Request
leaving server via local route -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:51 km1 /usr/sbin/kamailio[1835]: INFO: <script>: NAT-Reply -
S=487 - Request terminated M=INVITE IP=58.96.95.191:59992 (127.0.0.1:5080)
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:51 km1 /usr/sbin/kamailio[1835]: INFO: <script>: Failure route
for PSTN call -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:51 km1 /usr/sbin/kamailio[1835]: INFO: <script>: Unset dialog
mark peer and peerout '2' -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:51 km1 /usr/sbin/kamailio[1835]: INFO: <script>: Filter reply
code - R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:51 km1 /usr/sbin/kamailio[1835]: INFO: <script>: No failover
routing needed for this response code -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:51 km1 /usr/sbin/kamailio[1835]: INFO: <script>: Stop
mediaproxy for current branch using first Via -
R=sip:0123456789 at 1.2.3.4ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:51 km1 /usr/sbin/kamailio[1835]: ERROR: rtpproxy-ng
[rtpproxy.c:1318]: proxy replied with error: Call-ID not found or tags
didn't match
May 13 18:45:51 km1 /usr/sbin/kamailio[1828]: INFO: <script>: New request -
M=ACK R=sip:0123456789 at 1.2.3.4 F=sip:61390207909 at 1.2.3.4 T=
sip:0123456789 at 1.2.3.4 IP=58.96.95.191:59992 (127.0.0.1:5060)
ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
May 13 18:45:53 km1 /usr/sbin/kamailio[1839]: INFO: <script>: New request -
M=REGISTER R=sip:1.2.3.4 F=sip:61390207909 at 1.2.3.4 T=sip:61390207909 at 1.2.3.4IP=
49.156.17.154:5060 (127.0.0.1:5060) ID=
2445d06c315701396b32f5652da5f50b at 127.0.0.1
May 13 18:45:53 km1 /usr/sbin/kamailio[1839]: INFO: <script>: Load prefs
for uuid '0a05d717-3927-43cc-94dc-12d8df5d1192' - R=sip:1.2.3.4 ID=
2445d06c315701396b32f5652da5f50b at 127.0.0.1
May 13 18:45:53 km1 /usr/sbin/kamailio[1839]: INFO: <script>: IP
authorization not provisioned, allow registration - R=sip:1.2.3.4 ID=
2445d06c315701396b32f5652da5f50b at 127.0.0.1
May 13 18:45:53 km1 /usr/sbin/kamailio[1839]: INFO: <script>: Load caller
preferences for uuid '0a05d717-3927-43cc-94dc-12d8df5d1192' and domain part
of uri 'sip:61390207909 at 1.2.3.4' - R=sip:1.2.3.4 ID=
2445d06c315701396b32f5652da5f50b at 127.0.0.1
May 13 18:45:53 km1 /usr/sbin/kamailio[1839]: INFO: <script>: Contacts
successfully updated - R=sip:1.2.3.4 ID=
2445d06c315701396b32f5652da5f50b at 127.0.0.1

Martin Wong
Director at Binary Elements
http://www.binaryelements.com.au
Tel: 03 9020 7900
DID: 03 9020 7907
Mob: 0405 817 796

On Sat, May 11, 2013 at 7:55 AM, Andrew Pogrebennyk <
apogrebennyk at sipwise.com> wrote:

> Martin,
>
> what is set for allowed_clis preference of the Asterisk subscriber?
> It should match the main E.164 number assigned to the subscriber plus
> everything behind it..
>
> That was if the Asterisk sends 9030 which is not permitted the system
> will override User-Provided Number (UPN) with value of user_cli. Please
> also check that outbound_from_user and outbound_pai_user are set to UPN.
>
> Hope this helps. Please send the kamailio-proxy log if this doesnt work.
>
> On 05/10/2013 10:45 AM, Martin Wong wrote:
> > Hi all,
> >
> > here're some sip traces.
> >
> > 1.1.1.1 -> Asterisk Box
> > 2.2.2.2 -> SPCE
> > 3.3.3.3 -> SIP PEER
> >
> > So I've registered 9030 extension on the Asterisk box. Made a call to an
> > external number (98769876), goes to 2.2.2.2 which is the SPCE.
> > 2.2.2.2 SPCE goes to 3.3.3.3 which is the sip peer.
> >
> > problem is ... even though user_cli or enum is set in the subscriber
> > details, that setting is not getting pulled through when the INVITE goes
> > to 3.3.3.3 (sip peer). The 9030 still goes through and replaces whatever
> > is setup in the subscriber.
> >
> > Can someone advise what can be done to rectify this or troubleshoot?
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sipwise.com/pipermail/spce-user_lists.sipwise.com/attachments/20130513/68fa7300/attachment-0001.html>