[Spce-user] cfu being logged when a fallback was used

Matthew Ogden matthew at tenacit.net
Tue Mar 24 16:11:30 EDT 2015 

Hi All

I've sanitized this with <callerIP>, etc

Using 2.8 LTS templates are 2.8.18

This call logged a CFU, the subcriber preferences arent even set to use
e164.

I'm not sure why it chose to use the e164, and I'm really not sure why it
logged a CFU in the CDRs.

Any ideas... a possible bug?

root at spce:~# zgrep 72bdddc80a3ab1d276e95957559e07e8
/var/log/ngcp/old/kamailio-proxy.log-20150224.gz
Feb 24 11:17:24 spce /usr/sbin/kamailio[28667]: INFO: <script>: New request
- M=INVITE R=sip:<callee-e.164>@<SPCE-IP>:5060
F=sip:<caller-e.164>@<callerIP> T=sip:<callee-e.164>@<SPCE-IP>:5060
IP=<callerIP>:5060 (127.0.0.1:5060) ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28667]: INFO: <script>:
Authentication failed, no credentials - R=sip:<callee-e.164>@<SPCE-IP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: New request
- M=INVITE R=sip:<callee-e.164>@<SPCE-IP>:5060
F=sip:<caller-e.164>@<callerIP> T=sip:<callee-e.164>@<SPCE-IP>:5060
IP=<callerIP>:5060 (127.0.0.1:5060) ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Clear
caller preferences - R=sip:<callee-e.164>@<SPCE-IP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Load caller
preferences for uuid 'd7450f06-aced-4c89-9de8-85c21bed3423' and domain part
of uri 'sip:<caller-e.164>@<spce-DNSname>' -
R=sip:<callee-e.164>@<SPCE-IP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Load
dialplan IDs for domain 'sip:<caller-e.164>@<spce-DNSname>' -
R=sip:<callee-e.164>@<SPCE-IP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Fetched
dialplan IDs caller_in='1', callee_in='2' -
R=sip:<callee-e.164>@<SPCE-IP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Use
'P-NGCP-Src-Ip' for CDR IP - R=sip:<callee-e.164>@<SPCE-IP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Dialog set
total - R=sip:<callee-e.164>@<SPCE-IP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Dialog set
user, userout, account, accountout to
'd7450f06-aced-4c89-9de8-85c21bed3423/2' -
R=sip:<callee-e.164>@<SPCE-IP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: IP
authorization for caller not provisioned, allow call -
R=sip:<callee-e.164>@<SPCE-IP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>:
User-Provided CLI '<caller-e.164>' taken from From-User -
R=sip:<callee-e.164>@<SPCE-IP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Applying
caller-in domain rewrite rules on user-provided CLI using dpid '1' -
R=sip:<callee-e.164>@<SPCE-IP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: No matching
rewrite rules for '<caller-e.164>' found -
R=sip:<callee-e.164>@<SPCE-IP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Checking
User-provided CLI against allowed CLIs -
R=sip:<callee-e.164>@<SPCE-IP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>:
User-provided CLI '<caller-e.164>' accepted -
R=sip:<callee-e.164>@<SPCE-IP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Setting
'<caller-e.164>@<callerIP>' as initiating user-provided CLI -
R=sip:<callee-e.164>@<SPCE-IP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Setting
'<caller-e.164>@<spce-DNSname>' as initiating network-provided CLI -
R=sip:<callee-e.164>@<SPCE-IP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Searching
for callee - R=sip:<callee-e.164>@<SPCE-IP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Applying
callee-in domain rewrite rules using dpid '2' -
R=sip:<callee-e.164>@<SPCE-IP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: No matching
rewrite rules for '<callee-e.164>' found -
R=sip:<callee-e.164>@<SPCE-IP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Callee was
aliased with base '<callee-e.164>' - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Clear
callee preferences - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Callee is
local, uuid='9acdb7f9-8d12-47ca-a182-9e507fd6402e' -
R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Check
Administrative NCOS level - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Check User
NCOS level - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Checking
URI 'ST001A001@<spce-DNSname>' and CLI '<callee-e.164>' against block out
lists - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Admin
caller block mode is 'open' - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Caller
block mode is 'open' - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Load callee
preferences for uuid '9acdb7f9-8d12-47ca-a182-9e507fd6402e' -
R=sip:ST001A001@<spce-DNSname>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Load
dialplan IDs for domain '<spce-DNSname>' - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Fetched
dialplan IDs caller_out='3', callee_out='4' -
R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Dialog set
user, account to '9acdb7f9-8d12-47ca-a182-9e507fd6402e/13' -
R=sip:ST001A001@<spce-DNSname>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Checking
UPN '<caller-e.164>' and NPN '<caller-e.164>' against block in lists -
R=sip:ST001A001@<spce-DNSname>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Admin
callee block mode is 'open' - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Callee
block mode is 'open' - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Dialog set
mark type to local - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Setting acc
source-leg for uuid 'd7450f06-aced-4c89-9de8-85c21bed3423':
'd7450f06-aced-4c89-9de8-85c21bed3423|<caller-e.164>|<spce-DNSname>|<caller-e.164>|TN001HOSTED|TN001|2|||0|call|<callerIP>|1424769444.404111'
- R=sip:ST001A001@<calleeIP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Setting acc
destination-leg for uuid '9acdb7f9-8d12-47ca-a182-9e507fd6402e':
'0|ST001A001|ST001|13|<callee-e.164>|9acdb7f9-8d12-47ca-a182-9e507fd6402e|ST001A001|<spce-DNSname>|<callee-e.164>|<SPCE-IP>'
- R=sip:ST001A001@<calleeIP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Relaying
request, du='sip:lb at 127.0.0.1;lr;socket='sip:<SPCE-IP>:5060'' -
R=sip:ST001A001@<calleeIP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Usage of
mediaproxy forced by preference - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Use
mediaproxy for forward direction for IPv4/IPv4 -
R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Remove
existing ICE candidates (if any) for callee - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Setting
P-Caller-UUID to 'd7450f06-aced-4c89-9de8-85c21bed3423' -
R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Setting
P-Callee-UUID to '9acdb7f9-8d12-47ca-a182-9e507fd6402e' -
R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Local user
online, using alias as user - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Sessions
Timers disabled for caller - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Sessions
Timers disabled for callee - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Applying
caller-out domain rewrite rules to first user-provided caller CLI using
dpid '3' - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: No matching
rewrite rules for '<caller-e.164>' found -
R=sip:<callee-e.164>@<calleeIP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Applying
caller-out domain rewrite rules to first network-provided caller CLI using
dpid '3' - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: No matching
rewrite rules for '<caller-e.164>' found -
R=sip:<callee-e.164>@<calleeIP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Setting
userprovided caller domain - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Use first
userprov caller domain '<callerIP>' - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Prepare
>From display-name setting - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: No selector
set, not setting CLI - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Prepare
>From username setting - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Setting
caller_cli_netprov/caller_domain_netprov '<caller-e.164>@<spce-DNSname>'
for npn - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Setting
>From to '<sip:<caller-e.164>@<spce-DNSname>>' -
R=sip:<callee-e.164>@<calleeIP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Prepare PAI
username setting - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: No selector
set, not setting CLI - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Prepare PPI
username setting - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: No selector
set, not setting CLI - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Prepare
Diversion setting - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: No selector
set, not setting CLI - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Prepare
History-Info setting - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: No selector
set, not setting CLI - R=sip:<callee-e.164>@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Applying
callee-out domain rewrite rules using dpid '4' -
R=sip:<callee-e.164>@<calleeIP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: No matching
rewrite rules for '<callee-e.164>' found -
R=sip:<callee-e.164>@<calleeIP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Setting
P-Called-Party-ID '<sip:<callee-e.164>@<spce-DNSname>>' -
R=sip:<callee-e.164>@<calleeIP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Writing sbc
parameters  ;aleg_sst_enable=no;sst_enable=no -
R=sip:<callee-e.164>@<calleeIP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Appending
P-D-URI 'sip:lb at 127.0.0.1;lr;socket='sip:<SPCE-IP>:5060'' -
R=sip:<callee-e.164>@<calleeIP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Forcing
request via B2BUA 'sip:127.0.0.1:5080' -
R=sip:<callee-e.164>@<calleeIP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28666]: INFO: <script>: Request
leaving server, D-URI='sip:127.0.0.1:5080' -
R=sip:<callee-e.164>@<calleeIP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28664]: INFO: <script>: NAT-Reply -
S=100 - Connecting M=INVITE IP=<callerIP>:5060 (127.0.0.1:5080)
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: NAT-Reply -
S=404 - Not Found M=INVITE IP=<callerIP>:5060 (127.0.0.1:5080)
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Failure
route for local call - R=sip:<callee-e.164>@<SPCE-IP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Stop
mediaproxy for current branch using first Via -
R=sip:<callee-e.164>@<SPCE-IP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Fallback
from E164 number to user - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Searching
for callee - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Setting
network-provided info as user-provided info for call-forward -
R=sip:ST001A001@<spce-DNSname>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Clear
callee preferences - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Callee is
local, uuid='9acdb7f9-8d12-47ca-a182-9e507fd6402e' -
R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Check
Administrative NCOS level - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Check User
NCOS level - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Checking
URI 'ST001A001@<spce-DNSname>' and CLI 'ST001A001' against block out lists
- R=sip:ST001A001@<spce-DNSname>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Admin
caller block mode is 'open' - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Caller
block mode is 'open' - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Load callee
preferences for uuid '9acdb7f9-8d12-47ca-a182-9e507fd6402e' -
R=sip:ST001A001@<spce-DNSname>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Load
dialplan IDs for domain '<spce-DNSname>' - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Fetched
dialplan IDs caller_out='3', callee_out='4' -
R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Dialog set
user, account to '9acdb7f9-8d12-47ca-a182-9e507fd6402e/13' -
R=sip:ST001A001@<spce-DNSname>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Checking
UPN '<caller-e.164>' and NPN '<caller-e.164>' against block in lists -
R=sip:ST001A001@<spce-DNSname>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Admin
callee block mode is 'open' - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Callee
block mode is 'open' - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Dialog set
mark type to local - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Setting acc
source-leg for uuid 'd7450f06-aced-4c89-9de8-85c21bed3423':
'd7450f06-aced-4c89-9de8-85c21bed3423|ST001A001|<spce-DNSname>|<caller-e.164>|TN001HOSTED|TN001|2|||0|cfu|<callerIP>|1424769444.535528'
- R=sip:ST001A001@<calleeIP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Setting acc
destination-leg for uuid '9acdb7f9-8d12-47ca-a182-9e507fd6402e':
'0|ST001A001|ST001|13|ST001A001|9acdb7f9-8d12-47ca-a182-9e507fd6402e|ST001A001|<spce-DNSname>|<callee-e.164>|<SPCE-IP>'
- R=sip:ST001A001@<calleeIP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Relaying
request, du='sip:lb at 127.0.0.1;lr;socket='sip:<SPCE-IP>:5060'' -
R=sip:ST001A001@<calleeIP>:5060 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Usage of
mediaproxy forced by preference - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Use
mediaproxy for forward direction for IPv4/IPv4 -
R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Remove
existing ICE candidates (if any) for callee - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Setting
P-Caller-UUID to 'd7450f06-aced-4c89-9de8-85c21bed3423' -
R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Setting
P-Callee-UUID to '9acdb7f9-8d12-47ca-a182-9e507fd6402e' -
R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Local user
online, using registered contact as user - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Sessions
Timers disabled for caller - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Sessions
Timers disabled for callee - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Applying
caller-out domain rewrite rules to first user-provided caller CLI using
dpid '3' - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: No matching
rewrite rules for '<caller-e.164>' found - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Applying
caller-out domain rewrite rules to first network-provided caller CLI using
dpid '3' - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: No matching
rewrite rules for '<caller-e.164>' found - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Setting
userprovided caller domain - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Use first
userprov caller domain '<callerIP>' - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Prepare
>From display-name setting - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: No selector
set, not setting CLI - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Prepare
>From username setting - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Setting
caller_cli_netprov/caller_domain_netprov '<caller-e.164>@<spce-DNSname>'
for npn - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Setting
>From to '<sip:<caller-e.164>@<spce-DNSname>>' - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Prepare PAI
username setting - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: No selector
set, not setting CLI - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Prepare PPI
username setting - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: No selector
set, not setting CLI - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Prepare
Diversion setting - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: No selector
set, not setting CLI - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Prepare
History-Info setting - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: No selector
set, not setting CLI - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Applying
callee-out domain rewrite rules using dpid '4' -
R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: No matching
rewrite rules for 'ST001A001' found - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Setting
P-Called-Party-ID '<sip:ST001A001@<spce-DNSname>>' -
R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Writing sbc
parameters  ;aleg_sst_enable=no;sst_enable=no - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Appending
P-D-URI 'sip:lb at 127.0.0.1;lr;socket='sip:<SPCE-IP>:5060'' -
R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Forcing
request via B2BUA 'sip:127.0.0.1:5080' - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Request
leaving server, D-URI='sip:127.0.0.1:5080' - R=sip:ST001A001@<calleeIP>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28670]: INFO: <script>: Dropping
local branch - R=sip:ST001A001@<spce-DNSname>:5060
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:24 spce /usr/sbin/kamailio[28663]: INFO: <script>: NAT-Reply -
S=100 - Connecting M=INVITE IP=<callerIP>:5060 (127.0.0.1:5080)
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:25 spce /usr/sbin/kamailio[28666]: INFO: <script>: NAT-Reply -
S=180 - Ringing M=INVITE IP=<callerIP>:5060 (127.0.0.1:5080)
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:31 spce /usr/sbin/kamailio[28667]: INFO: <script>: NAT-Reply -
S=200 - OK M=INVITE IP=<callerIP>:5060 (127.0.0.1:5080)
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:31 spce /usr/sbin/kamailio[28667]: INFO: <script>: Remove
existing ICE candidates (if any) for caller -
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:31 spce /usr/sbin/kamailio[28667]: INFO: <script>: Use
mediaproxy for backward direction - ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:17:31 spce /usr/sbin/kamailio[28666]: INFO: <script>: New request
- M=ACK R=sip:127.0.0.1:5080 F=sip:<caller-e.164>@<callerIP>
T=sip:<callee-e.164>@<SPCE-IP>:5060 IP=<callerIP>:5060 (127.0.0.1:5060)
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:31 spce /usr/sbin/kamailio[28666]: INFO: <script>: debug
variables for additional info>>> $ua=Asterisk PBX 12.6.0,
$fU=<caller-e.164>, $rU=<null>, $ru=sip:127.0.0.1:5080, $ou=sip:
127.0.0.1:5080, $oU=<null>, $au=<null>, $aU=<null>, 0,<null>   <<< - R=sip:
127.0.0.1:5080 ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:17:31 spce /usr/sbin/kamailio[28666]: INFO: <script>: Relaying
request, du='<null>' - R=sip:127.0.0.1:5080
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:18:22 spce /usr/sbin/kamailio[28670]: INFO: <script>: New request
- M=BYE R=sip:127.0.0.1:5080 F=sip:<caller-e.164>@<callerIP>
T=sip:<callee-e.164>@<SPCE-IP>:5060 IP=<callerIP>:5060 (127.0.0.1:5060)
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:18:22 spce /usr/sbin/kamailio[28670]: INFO: <script>: Stop
mediaproxy for all branches - R=sip:127.0.0.1:5080
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:18:22 spce /usr/sbin/kamailio[28670]: INFO: <script>: debug
variables for additional info>>> $ua=Asterisk PBX 12.6.0,
$fU=<caller-e.164>, $rU=<null>, $ru=sip:127.0.0.1:5080, $ou=sip:
127.0.0.1:5080, $oU=<null>, $au=TN001HOSTED, $aU=TN001HOSTED, 0,<null>
<<< - R=sip:127.0.0.1:5080 ID=72bdddc80a3ab1d276e95957559e07e8@
<callerIP>:5060
Feb 24 11:18:22 spce /usr/sbin/kamailio[28670]: INFO: <script>: Relaying
request, du='<null>' - R=sip:127.0.0.1:5080
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060
Feb 24 11:18:22 spce /usr/sbin/kamailio[28667]: INFO: <script>: NAT-Reply -
S=200 - OK M=BYE IP=<callerIP>:5060 (127.0.0.1:5080)
ID=72bdddc80a3ab1d276e95957559e07e8@<callerIP>:5060



-- 





*Matthew Ogden*

Management

TenacIT





*Strategic IT Consulting *•* Advanced Networking *• *Virtualisation*

*Custom Development *• *Hosting *• *Syspro Support  *• *MS Licensing*

National Tel: 041 10 10 100 | Cell: 084 205 4445 | Email:
matthew at tenacit.net

CT Tel: 021 201 0333 | Skype Name: matthew.ogden | Web:
http://www.tenacit.net
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sipwise.com/mailman/private/spce-user_lists.sipwise.com/attachments/20150324/552d6749/attachment.html>

More information about the Spce-user mailing list