[Spce-user] t.38 fax from client to upstream SIP prover

Matthew Ogden matthew at tenacit.net
Thu Sep 19 03:59:14 EDT 2013 

Hi



I guess that’s a fair question, though, we don’t normalise inbound calls
numbers, we accept them for whatever the other networks sent them as.



Would this only be a problem because of the REINVITE (whereas all other
voice calls are not requiring the REINVITE for t.38 negotiation).



Regards



*From:* Lorenzo Mangani [mailto:lorenzo.mangani at gmail.com]
*Sent:* 19 September 2013 09:57 AM
*To:* Matthew Ogden
*Cc:* spce-user at lists.sipwise.com
*Subject:* Re: [Spce-user] t.38 fax from client to upstream SIP prover



Matthew,



Are you sure the callee number is being normalized to E164 in both
directions? It appears you might be only processing outbound invites here.

If this is the case, just try copy the Rewrite rules for E164 normalization
you have for outbound for inbound and re-test the scenario,








Lorenzo Mangani



HOMER DEV TEAM

QXIP - Capture Engineering





On Thu, Sep 19, 2013 at 9:52 AM, Matthew Ogden <matthew at tenacit.net> wrote:

Hi Lorenzo,



J Sorry, I just meant SPCE is sending a 404.



The call starts from my subscriber, and is made to 086. During the t.38
handover process (or whatever we call it? Negotiation?), my upstream peer
is doing the re-invite back (to swap it to t.38).  And this is where the
call seems to fail. After sending the email, I tested it in reverse, the
fax machine calls the subscriber, and the subscriber agent does a reinvite,
and everything works fine.



In the outbound version that doesn’t work, If I look at a wireshark log,
spce doesn’t send the reinvite back to subscriber, it simply replies back
with 404 not found.

The intial reINVITE for the t.38 is occurring at:

Sep 18 23:15:56 spce /usr/sbin/kamailio[2649]: INFO: <script>: New request
- M=INVITE R=sip:<myclientIP>:5060 F=sip:086XXXXXXX@<mysipserverIP>:5060
T=sip:27XXXXX2533 at mysipdns IP=127.0.0.1:5080 (127.0.0.1:5080)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

My relatively poor understanding (hopefully soon to be improved over this
negotiation) is that When it’s the other way around, and it does work, its
seems to be because the REINVITE is coming from a subscriber and that’s
allowed. But when the REINVITE comes from the upstream provider, it doesn’t
allow it or not able to route it?



Kind Regards



*From:* Lorenzo Mangani [mailto:lorenzo.mangani at gmail.com]
*Sent:* 19 September 2013 09:39 AM
*To:* Matthew Ogden
*Cc:* spce-user at lists.sipwise.com
*Subject:* Re: [Spce-user] t.38 fax from client to upstream SIP prover



Matthew,



I don't think Sipwise ("the company") is rejecting the request, but maybe
your SPCE ("the product") box is not properly configured :)

Jokes aside, I might be wrong but apparently you're missing the proper
rewrite rules for 086xxxx to be handled inbound - is this the format your
fax re-invite is coming in?

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>:
Applying caller-out peer rewrite rules to first user-provided caller
CLI using dpid '3' -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: No
matching rewrite rules for '0' found -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd


Best,



Lorenzo Mangani



HOMER DEV TEAM

QXIP - Capture Engineering





On Thu, Sep 19, 2013 at 12:10 AM, Matthew Ogden <matthew at tenacit.net> wrote:

Hi All,



When trying to send a fax out from a subscriber to a fax machine, my
upstream provider does a reverse invite for T.38. But Sipwise is rejecting
this request.



The log file is attached, and is below from the proxy.



Inbound faxes in the reverse (though to a different client) work with the
reinvite. Why is spce saying 404 not found this way around?



Sep 18 23:15:50 spce /usr/sbin/kamailio[2657]: INFO: <script>: New request
- M=INVITE R=sip:086XXXXXXX@<mysipserverIP>:5060 F=sip:TN001FAX
T=sip:086XXXXXXX@<mysipserverIP>:5060 IP=<myclientIP>:5060 (127.0.0.1:5060)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2657]: INFO: <script>:
Authentication failed, no credentials - R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: New request
- M=INVITE R=sip:086XXXXXXX@<mysipserverIP>:5060 F=sip:TN001FAX
T=sip:086XXXXXXX@<mysipserverIP>:5060 IP=<myclientIP>:5060 (127.0.0.1:5060)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Clear caller
preferences - R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Load caller
preferences for uuid 'b2e626b6-6c30-480a-9171-8ea7e0db82de' and domain part
of uri 'sip:@mysipdns' - R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Load
dialplan IDs for domain 'sip:@mysipdns' - R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Fetched
dialplan IDs caller_in='<null>', callee_in='<null>' -
R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Use
'P-NGCP-Src-Ip' for CDR IP - R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Dialog set
total - R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Dialog set
user, userout, account, accountout to
'b2e626b6-6c30-480a-9171-8ea7e0db82de/2' -
R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: IP
authorization for caller not provisioned, allow call -
R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>:
User-Provided CLI '0' taken from From-User -
R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Setting
'0 at TN001FAX' as initiating user-provided CLI -
R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Setting
'27XXXXX2533 at mysipdns' as initiating network-provided CLI -
R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Searching
for callee - R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Clear callee
preferences - R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Callee is
not local - R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Check
Administrative NCOS level - R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Check User
NCOS level - R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Checking URI
'086XXXXXXX@<mysipserverIP>' and CLI '086XXXXXXX' against block out lists -
R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Admin caller
block mode is 'open' - R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Caller block
mode is 'open' - R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Call to SIP
Peering - R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Load gws
matching calling part 'sip:0 at mysipdns' and called user '086XXXXXXX' and
called part 'sip:086XXXXXXX@<mysipserverIP>:5060' -
R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Dialog set
mark type to outgoing - R=sip:086XXXXXXX@<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Load peer
preferences for peer host '2' -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Load
dialplan IDs for peer host '2' -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Fetched
dialplan IDs caller_out='3', callee_out='4' -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Dialog set
mark peer and peerout to '2' -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Setting acc
source-leg for uuid 'b2e626b6-6c30-480a-9171-8ea7e0db82de':
'b2e626b6-6c30-480a-9171-8ea7e0db82de|<null>|mysipdns|0|TN001FAX|TN001|2|||0|call|<myclientIP>|1379538950.578478'
- R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Setting acc
destination-leg for uuid '0':
'0|||<null>|086XXXXXXX|0|086XXXXXXX|<myUpstreamProviderIP>|086XXXXXXX|<mysipserverIP>'
- R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Relaying
request, du='<null>' -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Use
mediaproxy for forward direction for IPv4/IPv4 -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Remove
existing ICE candidates (if any) for callee -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Setting
P-Caller-UUID to 'b2e626b6-6c30-480a-9171-8ea7e0db82de' -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Setting
P-Callee-UUID to '0' -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Sessions
Timers enabled for caller with interval 300 and method
UPDATE_FALLBACK_INVITE -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Sessions
Timers enabled for callee with interval 300 and method
UPDATE_FALLBACK_INVITE -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Applying
caller-out peer rewrite rules to first user-provided caller CLI using dpid
'3' - R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: No matching
rewrite rules for '0' found -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Applying
caller-out peer rewrite rules to first network-provided caller CLI using
dpid '3' - R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: No matching
rewrite rules for '27XXXXX2533' found -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Prepare From
display-name setting -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: No selector
set, not setting CLI -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Prepare From
username setting - R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Setting
caller_cli_userprov/caller_domain_userprov '0 at TN001FAX' for upn -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Setting From
to '<sip:27XXXXX2533 at mysipdns>' -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Prepare PAI
username setting - R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: No selector
set, not setting CLI -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Prepare PPI
username setting - R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: No selector
set, not setting CLI -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Prepare
Diversion setting - R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: No selector
set, not setting CLI -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Applying
callee-out peer rewrite rules to called party using dpid '4' -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Rewriting
called party '086XXXXXXX' to '2786XXXXXXX' -
R=sip:086XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Setting
P-Called-Party-ID '<sip:2786XXXXXXX@<myUpstreamProviderIP>>' -
R=sip:2786XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Writing sbc
parameters
;aleg_sst_enable=yes;aleg_sst_expires=300;aleg_sst_min_timer=90;aleg_sst_max_timer=7200;aleg_sst_refresh_method=UPDATE_FALLBACK_INVITE;sst_enable=yes;sst_expires=300;sst_min_timer=90;sst_max_timer=7200;sst_refresh_method=UPDATE_FALLBACK_INVITE
- R=sip:2786XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Setting
'sip:<myUpstreamProviderIP>:5060' taken from R-URI as next hop after lb for
PSTN call - R=sip:2786XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Appending
P-D-URI 'sip:127.0.0.1:5060;received='sip:<myUpstreamProviderIP>:5060;transport=udp''
- R=sip:2786XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Forcing
request via B2BUA 'sip:127.0.0.1:5080' -
R=sip:2786XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2655]: INFO: <script>: Request
leaving server, D-URI='sip:127.0.0.1:5080' -
R=sip:2786XXXXXXX@<myUpstreamProviderIP>:5060;transport=udp
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:50 spce /usr/sbin/kamailio[2647]: INFO: <script>: NAT-Reply -
S=100 - Connecting M=INVITE IP=<myclientIP>:5060 (127.0.0.1:5080)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:52 spce /usr/sbin/kamailio[2653]: INFO: <script>: NAT-Reply -
S=183 - Session Progress M=INVITE IP=<myclientIP>:5060 (127.0.0.1:5080)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:52 spce /usr/sbin/kamailio[2653]: INFO: <script>: Remove
existing ICE candidates (if any) for caller -
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:52 spce /usr/sbin/kamailio[2653]: INFO: <script>: Use
mediaproxy for backward direction - ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:52 spce /usr/sbin/kamailio[2659]: INFO: <script>: NAT-Reply -
S=200 - OK M=INVITE IP=<myclientIP>:5060 (127.0.0.1:5080)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:52 spce /usr/sbin/kamailio[2659]: INFO: <script>: Remove
existing ICE candidates (if any) for caller -
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:52 spce /usr/sbin/kamailio[2659]: INFO: <script>: Use
mediaproxy for backward direction - ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:52 spce /usr/sbin/kamailio[2651]: INFO: <script>: New request
- M=ACK R=sip:127.0.0.1:5080 F=sip:TN001FAX
T=sip:086XXXXXXX@<mysipserverIP>:5060
IP=<myclientIP>:5060 (127.0.0.1:5060)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:52 spce /usr/sbin/kamailio[2651]: INFO: <script>: Relaying
request, du='<null>' -
R=sip:127.0.0.1:5080ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:56 spce /usr/sbin/kamailio[2649]: INFO: <script>: New request
- M=INVITE R=sip:<myclientIP>:5060 F=sip:086XXXXXXX@<mysipserverIP>:5060
T=sip:27XXXXX2533 at mysipdns IP=127.0.0.1:5080 (127.0.0.1:5080)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:56 spce /usr/sbin/kamailio[2649]: INFO: <script>: Remove
existing ICE candidates (if any) for caller - R=sip:<myclientIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:56 spce /usr/sbin/kamailio[2649]: INFO: <script>: Use
mediaproxy for forward direction for IPv4/IPv4 - R=sip:<myclientIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:56 spce /usr/sbin/kamailio[2649]: INFO: <script>: Relaying
request,
du='sip:127.0.0.1;r2=on;lr=on;ftag=acfc5ab0-0-13c4-55013-bd-5bb91ef2-bd;ngcplb=yes'
- R=sip:<myclientIP>:5060 ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:56 spce /usr/sbin/kamailio[2655]: INFO: <script>: NAT-Reply -
S=200 - OK M=INVITE IP=127.0.0.1:5080 (127.0.0.1:5060)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:56 spce /usr/sbin/kamailio[2655]: INFO: <script>: Remove
existing ICE candidates (if any) for callee -
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:56 spce /usr/sbin/kamailio[2655]: INFO: <script>: Use
mediaproxy for backward direction - ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:56 spce /usr/sbin/kamailio[2647]: INFO: <script>: New request
- M=ACK R=sip:<mysipserverIP>:5060 F=sip:2786XXXXXXX@<myUpstreamProviderIP>
T=sip:27XXXXX2533 at mysipdns IP=<myUpstreamProviderIP>:5060 (127.0.0.1:5060)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2647]: INFO: <script>: Dropping
mis-routed request - R=sip:<mysipserverIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: New request
- M=INVITE
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
F=sip:2786XXXXXXX@<myUpstreamProviderIP>
T=sip:27XXXXX2533 at mysipdnsIP=<myUpstreamProviderIP>:5060 (
127.0.0.1:5060) ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Call from
PSTN -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Load peer
preferences for peer host '2' -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Load
dialplan IDs for peer host '2' -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Fetched
dialplan IDs caller_in='1', callee_in='2' -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Use
'P-NGCP-Src-Ip' for CDR IP -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Dialog set
mark total -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Dialog set
mark peer to '2' -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: IP
authorization for caller not provisioned, allow call -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>:
User-Provided CLI '2786XXXXXXX' taken from From-User -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>:
Network-Provided CLI '2786XXXXXXX' taken from From-User -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Applying
caller-in peer rewrite rules on user-provided CLI using dpid '1' -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Rewriting
user-provided CLI '2786XXXXXXX' to '086XXXXXXX' -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Applying
caller-in peer rewrite rules on network-provided CLI using dpid '1' -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Rewriting
network-provided CLI '2786XXXXXXX' to '086XXXXXXX' -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Setting
'086XXXXXXX@<myUpstreamProviderIP>' as initiating user-provided CLI -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Setting
'086XXXXXXX@<myUpstreamProviderIP>' as initiating network-provided CLI -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Searching
for callee -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Applying
callee-in peer rewrite rules using dpid '2' -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: No matching
rewrite rules for '<null>' found -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Skipping
internal services for unauthenticated call -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Clear callee
preferences -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Callee is
not local -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Check
Administrative NCOS level -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Check User
NCOS level -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Checking URI
'@127.0.0.1' and CLI '<null>' against block out lists -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Admin caller
block mode is 'open' -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Caller block
mode is 'open' -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Call to
unknown user -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Accounting
failed request for uuid '0' -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Setting acc
source-leg for uuid '0':
'0|2786XXXXXXX|<myUpstreamProviderIP>|086XXXXXXX|||<null>|||0|call|<myUpstreamProviderIP>|1379538956.820574'
-
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2653]: INFO: <script>: Setting acc
destination-leg for uuid '0':
'0|||<null>|<null>|0|<null>|127.0.0.1|<null>|127.0.0.1' -
R=sip:127.0.0.1;r2=on;lr=on;ftag=49E8355A-523A18060008DB67-B1DA1700;ngcplb=yes
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2659]: INFO: <script>: New request
- M=ACK R=sip:<mysipserverIP>:5060 F=sip:2786XXXXXXX@<myUpstreamProviderIP>
T=sip:27XXXXX2533 at mysipdns IP=<myUpstreamProviderIP>:5060 (127.0.0.1:5060)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd_b2b-1

Sep 18 23:15:56 spce /usr/sbin/kamailio[2651]: INFO: <script>: NAT-Reply -
S=200 - OK M=INVITE IP=127.0.0.1:5080 (127.0.0.1:5060)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:56 spce /usr/sbin/kamailio[2651]: INFO: <script>: Remove
existing ICE candidates (if any) for callee -
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:56 spce /usr/sbin/kamailio[2651]: INFO: <script>: Use
mediaproxy for backward direction - ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:57 spce /usr/sbin/kamailio[2648]: INFO: <script>: NAT-Reply -
S=200 - OK M=INVITE IP=127.0.0.1:5080 (127.0.0.1:5060)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:57 spce /usr/sbin/kamailio[2648]: INFO: <script>: Remove
existing ICE candidates (if any) for callee -
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:57 spce /usr/sbin/kamailio[2648]: INFO: <script>: Use
mediaproxy for backward direction - ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:59 spce /usr/sbin/kamailio[2647]: INFO: <script>: NAT-Reply -
S=200 - OK M=INVITE IP=127.0.0.1:5080 (127.0.0.1:5060)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:59 spce /usr/sbin/kamailio[2647]: INFO: <script>: Remove
existing ICE candidates (if any) for callee -
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:15:59 spce /usr/sbin/kamailio[2647]: INFO: <script>: Use
mediaproxy for backward direction - ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:16:28 spce /usr/sbin/kamailio[2648]: INFO: <script>: New request
- M=ACK R=sip:<myclientIP>:5060 F=sip:086XXXXXXX@<mysipserverIP>:5060
T=sip:27XXXXX2533 at mysipdns IP=127.0.0.1:5080 (127.0.0.1:5080)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:16:28 spce /usr/sbin/kamailio[2657]: INFO: <script>: New request
- M=BYE R=sip:<myclientIP>:5060 F=sip:086XXXXXXX@<mysipserverIP>:5060
T=sip:27XXXXX2533 at mysipdns IP=127.0.0.1:5080 (127.0.0.1:5080)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:16:28 spce /usr/sbin/kamailio[2657]: INFO: <script>: Stop
mediaproxy for all branches - R=sip:<myclientIP>:5060
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:16:28 spce /usr/sbin/kamailio[2648]: INFO: <script>: Relaying
request,
du='sip:127.0.0.1;r2=on;lr=on;ftag=acfc5ab0-0-13c4-55013-bd-5bb91ef2-bd;ngcplb=yes'
- R=sip:<myclientIP>:5060 ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:16:28 spce /usr/sbin/kamailio[2657]: INFO: <script>: Relaying
request,
du='sip:127.0.0.1;r2=on;lr=on;ftag=acfc5ab0-0-13c4-55013-bd-5bb91ef2-bd;ngcplb=yes'
- R=sip:<myclientIP>:5060 ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:16:28 spce /usr/sbin/kamailio[2649]: INFO: <script>: New request
- M=BYE R=sip:127.0.0.1:5080 F=sip:TN001FAX
T=sip:086XXXXXXX@<mysipserverIP>:5060
IP=<myclientIP>:5060 (127.0.0.1:5060)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:16:28 spce /usr/sbin/kamailio[2649]: INFO: <script>: Stop
mediaproxy for all branches -
R=sip:127.0.0.1:5080ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:16:28 spce /usr/sbin/kamailio[2649]: INFO: <script>: Relaying
request, du='<null>' -
R=sip:127.0.0.1:5080ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:16:28 spce /usr/sbin/kamailio[2655]: INFO: <script>: NAT-Reply -
S=200 - OK M=BYE IP=<myclientIP>:5060 (127.0.0.1:5080)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd

Sep 18 23:16:28 spce /usr/sbin/kamailio[2647]: INFO: <script>: NAT-Reply -
S=200 - OK M=BYE IP=127.0.0.1:5080 (127.0.0.1:5060)
ID=acebb4d0-0-13c4-55013-bd-1a5a4d0c-bd


_______________________________________________
Spce-user mailing list
Spce-user at lists.sipwise.com
http://lists.sipwise.com/listinfo/spce-user
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sipwise.com/pipermail/spce-user_lists.sipwise.com/attachments/20130919/11485209/attachment-0001.html>

More information about the Spce-user mailing list