[Spce-user] Force CLI

Tue May 14 07:11:44 EDT 2013

Hi Martin,

it is easy, you should change the outbound_from_user and
outbound_pai_user settings of your outbound peer. Currently they are set
to "Received Display-name" as we see from the log. You want to set
either UPN of NPN there. UPN is the user-provided number which is taken
from some header field which is set in inbound_upn preference of your
domain and then validated by checking against allowed_clis preference
(and replaced by user_cli or cli if it's found to be invalid) and NPN is
the main DID number of the subscriber. Since you have selected "Received
Display-name" the display-name is passed without any validation. Take
some time to learn how different inbound and outbound settings work.

On 05/13/2013 10:53 AM, Martin Wong wrote:
> 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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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
> <http://58.96.95.191:59992> (127.0.0.1:5080 <http://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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=N2YwNzY5NzUxMzhhZGQzMTlhZDUzMjIzN2E1MzIzOGM
> May 13 18:45:10 km1 /usr/sbin/kamailio[1835]: INFO: <script>: Filter
> reply code - R=sip:0123456789 at 1.2.3.4 <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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.4 <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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
> <mailto:sip%3A0123456789 at 1.2.3.4> F=sip:61390207909 at 1.2.3.4
> <mailto:sip%3A61390207909 at 1.2.3.4> T=sip:0123456789 at 1.2.3.4
> <mailto:sip%3A0123456789 at 1.2.3.4> IP=58.96.95.191:59992
> <http://58.96.95.191:59992> (127.0.0.1:5060 <http://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
> <mailto:sip%3A0123456789 at 1.2.3.4> F=sip:61390207909 at 1.2.3.4
> <mailto:sip%3A61390207909 at 1.2.3.4> T=sip:0123456789 at 1.2.3.4
> <mailto:sip%3A0123456789 at 1.2.3.4> IP=58.96.95.191:59992
> <http://58.96.95.191:59992> (127.0.0.1:5060 <http://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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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
> <mailto:sip%3A0123456789 at 1.2.3.4> F=sip:61390207909 at 1.2.3.4
> <mailto:sip%3A61390207909 at 1.2.3.4> T=sip:0123456789 at 1.2.3.4
> <mailto:sip%3A0123456789 at 1.2.3.4> IP=58.96.95.191:59992
> <http://58.96.95.191:59992> (127.0.0.1:5060 <http://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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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
> <mailto:sip%3A61390207909 at 1.2.3.4>' - R=sip:0123456789 at 1.2.3.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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
> <mailto:sip%3A61390207909 at 1.2.3.4>' - R=sip:0123456789 at 1.2.3.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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 <mailto:sip%3A0123456789 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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
> May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Dialog set
> total - R=sip:0123456789 at 1.2.3.4 <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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
> <mailto:sip%3Amwhome at 1.2.3.4> T=sip:mwhome at 1.2.3.4
> <mailto:sip%3Amwhome at 1.2.3.4> IP=58.96.95.189:5060
> <http://58.96.95.189:5060> (127.0.0.1:5060 <http://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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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 <mailto:sip%3A0123456789 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.4 <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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.4
> <mailto:sip%3A0123456789 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 <mailto:61390207912 at 1.2.3.4>' as initiating
> user-provided CLI - R=sip:0123456789 at 1.2.3.4
> <mailto:sip%3A0123456789 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 <mailto:61390207912 at 1.2.3.4>' as initiating
> network-provided CLI - R=sip:0123456789 at 1.2.3.4
> <mailto:sip%3A0123456789 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.4 <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
> May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Clear
> callee preferences - R=sip:0123456789 at 1.2.3.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
> May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Callee is
> not local - R=sip:0123456789 at 1.2.3.4 <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
> May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Check
> Administrative NCOS level - R=sip:0123456789 at 1.2.3.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
> May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Check User
> NCOS level - R=sip:0123456789 at 1.2.3.4 <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
> May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Checking
> URI '0123456789 at 1.2.3.4 <mailto:0123456789 at 1.2.3.4>' and CLI
> '0123456789' against block out lists - R=sip:0123456789 at 1.2.3.4
> <mailto:sip%3A0123456789 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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
> May 13 18:45:36 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Call to
> SIP Peering - R=sip:0123456789 at 1.2.3.4 <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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
> <mailto:sip%3A61390207912 at 1.2.3.4>' and called user '0123456789' and
> called part 'sip:0123456789 at 1.2.3.4 <mailto:sip%3A0123456789 at 1.2.3.4>' -
> R=sip:0123456789 at 1.2.3.4 <mailto:sip%3A0123456789 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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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
> <mailto: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
> <mailto:sip%3Asomefromusersetfromasterisk 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
> <mailto: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
> <mailto:sip%3Asomefromusersetfromasterisk 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
> <mailto:sip%3A0123456789 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 <http://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 <http://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 <http://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
> <http://58.96.95.191:59992> (127.0.0.1:5080 <http://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
> <mailto:sip%3Amwhome at 1.2.3.4> T=sip:mwhome at 1.2.3.4
> <mailto:sip%3Amwhome at 1.2.3.4> IP=58.96.95.189:5060
> <http://58.96.95.189:5060> (127.0.0.1:5060 <http://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 <mailto:sip%3Amwhome 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 <http://1.2.3.4:5060>
> F=sip:61390207900 at 1.2.3.4:5060 <http://sip:61390207900@1.2.3.4:5060>
> T=sip:61390207900 at 1.2.3.4:5060 <http://sip:61390207900@1.2.3.4:5060>
> IP=192.168.3.5:5067 <http://192.168.3.5:5067> (127.0.0.1:5060
> <http://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:5060
> <http://1.2.3.4:5060> ID=NDQ4N2VlMWU1MWM4MjNkZmFiZDEyOGQwODZjNDZlZWQ.
> May 13 18:45:40 km1 /usr/sbin/kamailio[1828]: INFO: <script>: New
> request - M=REGISTER R=sip:1.2.3.4:5060 <http://1.2.3.4:5060>
> F=sip:61390207900 at 1.2.3.4:5060 <http://sip:61390207900@1.2.3.4:5060>
> T=sip:61390207900 at 1.2.3.4:5060 <http://sip:61390207900@1.2.3.4:5060>
> IP=192.168.3.5:5067 <http://192.168.3.5:5067> (127.0.0.1:5060
> <http://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:5060
> <http://1.2.3.4:5060> ID=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:5060
> <http://1.2.3.4:5060> ID=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
> <mailto:sip%3A61390207900 at 1.2.3.4>' - R=sip:1.2.3.4:5060
> <http://1.2.3.4:5060> ID=NDQ4N2VlMWU1MWM4MjNkZmFiZDEyOGQwODZjNDZlZWQ.
> May 13 18:45:40 km1 /usr/sbin/kamailio[1828]: INFO: <script>: Contacts
> successfully updated - R=sip:1.2.3.4:5060 <http://1.2.3.4:5060>
> ID=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
> <http://58.96.95.191:59992> (127.0.0.1:5080 <http://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 <http://1.2.3.4:5060>
> F=sip:61388041713 at 1.2.3.4:5060 <http://sip:61388041713@1.2.3.4:5060>
> T=sip:61388041713 at 1.2.3.4:5060 <http://sip:61388041713@1.2.3.4:5060>
> IP=192.168.4.2:5060 <http://192.168.4.2:5060> (127.0.0.1:5060
> <http://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:5060
> <http://1.2.3.4:5060> ID=MGYyNjY5N2MyNTE2MTM5YzIwMWIwODc5YmE3ZWMyMzk.
> May 13 18:45:45 km1 /usr/sbin/kamailio[1843]: INFO: <script>: New
> request - M=REGISTER R=sip:1.2.3.4:5060 <http://1.2.3.4:5060>
> F=sip:61388041713 at 1.2.3.4:5060 <http://sip:61388041713@1.2.3.4:5060>
> T=sip:61388041713 at 1.2.3.4:5060 <http://sip:61388041713@1.2.3.4:5060>
> IP=192.168.4.2:5060 <http://192.168.4.2:5060> (127.0.0.1:5060
> <http://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:5060
> <http://1.2.3.4:5060> ID=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:5060
> <http://1.2.3.4:5060> ID=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
> <mailto:sip%3A61388041713 at 1.2.3.4>' - R=sip:1.2.3.4:5060
> <http://1.2.3.4:5060> ID=MGYyNjY5N2MyNTE2MTM5YzIwMWIwODc5YmE3ZWMyMzk.
> May 13 18:45:45 km1 /usr/sbin/kamailio[1843]: INFO: <script>: Contacts
> successfully updated - R=sip:1.2.3.4:5060 <http://1.2.3.4:5060>
> ID=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
> <mailto:sip%3A0123456789 at 1.2.3.4> F=sip:61390207909 at 1.2.3.4
> <mailto:sip%3A61390207909 at 1.2.3.4> T=sip:0123456789 at 1.2.3.4
> <mailto:sip%3A0123456789 at 1.2.3.4> IP=58.96.95.191:59992
> <http://58.96.95.191:59992> (127.0.0.1:5060 <http://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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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
> <http://58.96.95.191:59992> (127.0.0.1:5080 <http://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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=Y2Y4ODYzYjVmNDEzMTk0MDUxNWFhNzBlMzUyYTFlY2U
> May 13 18:45:51 km1 /usr/sbin/kamailio[1835]: INFO: <script>: Filter
> reply code - R=sip:0123456789 at 1.2.3.4 <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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.4 <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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.4
> <mailto:sip%3A0123456789 at 1.2.3.4>
> ID=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
> <mailto:sip%3A0123456789 at 1.2.3.4> F=sip:61390207909 at 1.2.3.4
> <mailto:sip%3A61390207909 at 1.2.3.4> T=sip:0123456789 at 1.2.3.4
> <mailto:sip%3A0123456789 at 1.2.3.4> IP=58.96.95.191:59992
> <http://58.96.95.191:59992> (127.0.0.1:5060 <http://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
> <mailto:sip%3A61390207909 at 1.2.3.4> T=sip:61390207909 at 1.2.3.4
> <mailto:sip%3A61390207909 at 1.2.3.4> IP=49.156.17.154:5060
> <http://49.156.17.154:5060> (127.0.0.1:5060 <http://127.0.0.1:5060>)
> ID=2445d06c315701396b32f5652da5f50b at 127.0.0.1
> <mailto: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
> <mailto: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
> <mailto: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
> <mailto:sip%3A61390207909 at 1.2.3.4>' - R=sip:1.2.3.4
> ID=2445d06c315701396b32f5652da5f50b at 127.0.0.1
> <mailto: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
> <mailto: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 <mailto: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?
> 
> 