[Spce-user] Spce timeout for T.38 Reinvites
Matthew Ogden
matthew at tenacit.net
Tue Jan 21 03:42:29 EST 2014
*From:* Ivan Milivojevic [mailto:edesibe at gmail.com]
*Sent:* 20 January 2014 01:35 PM
*To:* Matthew Ogden
*Cc:* Spce-user
*Subject:* Re: [Spce-user] Spce timeout for T.38 Reinvites
Hi Mattwhew,
Can you paste logs from proxy and lb?
Regards,
Ivan
On Mon, Jan 20, 2014 at 11:54 AM, Matthew Ogden <matthew at tenacit.net> wrote:
As per http://kamailio.org/docs/modules/stable/modules/tm.html#tm.f.t_set_fr
(I see it is used else where in the proxy.tt2 file too).
I tried putting this in proxy.custom.tt2 line 1076 (part of ROUTE_PSTN)
#our fax testing is on subscriber with ext ID TN001FAX
if($avp(s:caller_ext_subscriber_id) == "TN001FAX")
{
t_set_fr(30000);
xlog("L_INFO", "Changed outbound timer for this call to
30000ms for faxing\n");
}
It logs it my proxy log, but 9 seconds is still the timeout of the INVITE
*From:* Matthew Ogden [mailto:matthew at tenacit.net]
*Sent:* 19 January 2014 06:36 PM
*To:* 'Ivan Milivojevic'
*Cc:* 'Spce-user'
*Subject:* RE: [Spce-user] Spce timeout for T.38 Reinvites
Thanks Ivan
That’s definitely what I’m looking for, but perhaps someone out there can
tell me in which section of the proxy/lb that I can have an override of the
timer based on the subscriber name only. (I only need one subscriber with a
much longer timeout).
Regards
*From:* Ivan Milivojevic [mailto:edesibe at gmail.com <edesibe at gmail.com>]
*Sent:* 19 January 2014 04:51 PM
*To:* Matthew Ogden
*Cc:* Spce-user
*Subject:* Re: [Spce-user] Spce timeout for T.38 Reinvites
Hi Matthew,
Maybe you can try with
fr_timer_avpcheck also http://www.iptel.org/faq/sertimers
Regards,
Ivan
On Sat, Jan 18, 2014 at 9:56 AM, Matthew Ogden <matthew at tenacit.net> wrote:
Thanks Ivan!
There is obviously no way to make kamailio have different timeouts for
different INVITE (T.38 vs the rest) types then?
*From:* Ivan Milivojevic [mailto:edesibe at gmail.com]
*Sent:* 17 January 2014 08:58 PM
*To:* Matthew Ogden
*Cc:* Spce-user
*Subject:* Re: [Spce-user] Spce timeout for T.38 Reinvites
HI Matthew,
You can check timeout on kamailio-proxy side...
# kamailio.cfg.tt2
modparam("tm", "fr_timer", 9000) ### based on doc ### Timer which hits if
no final reply for a request or ACK for a negative INVITE reply arrives (in
seconds).
modparam("tm", "fr_inv_timer", 180000)
Regards,
Ivan
On Fri, Jan 17, 2014 at 6:07 PM, Matthew Ogden <matthew at tenacit.net> wrote:
HI
What sets the timeout for a T38 reinvite on SPCE? My timeout is 4000ms for
Sems in config.yml, so clearly its not that, since this took exactly
9000ms.
What timer/timeout made my SIP server cancel the reinvite? The reason I
need a longer timeout is, the recipient company is answering the call, then
forwarding the through a few rings (extra time before the sync can actually
be caught for t.38).
in this wireshark call graph,
The left line is my subscriber,
The middle line is sipserver
Here is picture,
Below and attached are the text representation
Conv.| Time | subscriber |
Peer |
| | | my server |
2 |18:46:27.453879| INVITE SDP (g711A)
| |SIP From: <sip:2XXXXXXXXX at subscriberip:5060
To:<sip:0XXXXXXXXX at spceIP:5060
| |(5060) ------------------> (5060) | |
2 |18:46:27.454546| 100 Trying|
| |SIP Status
| |(5060) <------------------ (5060) | |
2 |18:46:27.456403| 407 Proxy Authentication Required
| |SIP Status
| |(5060) <------------------ (5060) | |
2 |18:46:27.510806| ACK |
| |SIP Request
| |(5060) ------------------> (5060) | |
2 |18:46:27.514119| INVITE SDP (g711A) |
|SIP From: <sip:2XXXXXXXXX at subscriberip:5060
To:<sip:0XXXXXXXXX at spceIP:5060
| |(5060) ------------------> (5060) | |
2 |18:46:27.514636| 100 Trying|
| |SIP Status
| |(5060) <------------------ (5060) | |
-----------------------------------------------------------------------------
3 |18:46:27.522117| | INVITE SDP
(g711A) |SIP From:
<sip:2XXXXXXXXX at subscriberipTo:<sip:2XXXXXXXXXX at peerip
| | |(5060) ------------------> (5060) |
3 |18:46:27.536103| | 100 Giving a
try |SIP Status
| | |(5060) <------------------ (5060) |
3 |18:46:28.648929| | 200 OK SDP
(g711A) |SIP Status
| | |(5060) <------------------ (5060) |
-----------------------------------------------------------------------------
2 |18:46:28.650490| 200 OK SDP (g711A)
| |SIP Status
| |(5060) <------------------ (5060) | |
2 |18:46:28.708976| ACK |
| |SIP Request
| |(5060) ------------------> (5060) | |
-----------------------------------------------------------------------------
3 |18:46:28.710179| | ACK
| |SIP Request
| | |(5060) ------------------> (5060) |
-----------------------------------------------------------------------------
2 |18:46:28.712202| INVITE SDP (t38)
| |SIP From: <sip:2XXXXXXXXX at subscriberip:5060
To:<sip:0 at spceIP:5060
| |(5060) ------------------> (5060) | |
2 |18:46:28.712970| 100 Trying|
| |SIP Status
| |(5060) <------------------ (5060) | |
-----------------------------------------------------------------------------
3 |18:46:28.713577| | INVITE SDP
(t38) |SIP From:
<sip:2XXXXXXXXX at subscriberipTo:<sip:2XXXXXXXXXX at peerip
| | |(5060) ------------------> (5060) |
3 |18:46:28.717171| | 100 Giving a
try |SIP Status
| | |(5060) <------------------ (5060) |
-----------------------------------------------------------------------------
2 |18:46:37.713078| 408 Request Timeout
| |SIP Status
| |(5060) <------------------ (5060) | |
2 |18:46:37.768451| ACK |
| |SIP Request
| |(5060) ------------------> (5060) | |
2 |18:46:37.770487| BYE |
| |SIP Request
| |(5060) ------------------> (5060) | |
2 |18:46:37.771110| 100 Trying|
| |SIP Status
| |(5060) <------------------ (5060) | |
2 |18:46:37.772098| 200 OK |
| |SIP Status
| |(5060) <------------------ (5060) | |
-----------------------------------------------------------------------------
3 |18:46:37.772169| | ACK
| |SIP Request
| | |(5060) ------------------> (5060) |
3 |18:46:37.772244| | BYE
| |SIP Request
| | |(5060) ------------------> (5060) |
3 |18:46:37.776329| | BYE
| |SIP Request
| | |(5060) <------------------ (5060) |
3 |18:46:37.776817| | 200 OK
| |SIP Status
| | |(5060) ------------------> (5060) |
3 |18:46:37.778354| | 200 OK
| |SIP Status
| | |(5060) <------------------ (5060) |
3 |18:48:28.920672| | 408 Request
Timeout |SIP Status
| | |(5060) <------------------ (5060) |
3 |18:48:28.921215| | ACK
| |SIP Request
| | |(5060) ------------------> (5060) |
_______________________________________________
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/20140121/c8f77805/attachment-0001.html>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: image001.png
Type: image/png
Size: 46251 bytes
Desc: not available
URL: <http://lists.sipwise.com/pipermail/spce-user_lists.sipwise.com/attachments/20140121/c8f77805/attachment-0001.png>
-------------- next part --------------
Proxy
Jan 21 09:57:45 spce /usr/sbin/kamailio[4431]: INFO: <script>: New request - M=INVITE R=sip:0<Destination#>@<SPCE-IP>:5060 F=sip:27<SourceNumber#>@<SubscriberIP>:5060 T=sip:0<Destination#>@<SPCE-IP>:5060 IP=<SubscriberIP>:5060 (127.0.0.1:5060) ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4431]: INFO: <script>: Authentication failed, no credentials - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: New request - M=INVITE R=sip:0<Destination#>@<SPCE-IP>:5060 F=sip:27<SourceNumber#>@<SubscriberIP>:5060 T=sip:0<Destination#>@<SPCE-IP>:5060 IP=<SubscriberIP>:5060 (127.0.0.1:5060) ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Clear caller preferences - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Load caller preferences for uuid 'b2e626b6-6c30-480a-9171-8ea7e0db82de' and domain part of uri 'sip:27<SourceNumber#>@sip.tenacit.net' - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Load dialplan IDs for domain 'sip:27<SourceNumber#>@sip.tenacit.net' - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Fetched dialplan IDs caller_in='1', callee_in='2' - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Use 'P-NGCP-Src-Ip' for CDR IP - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Dialog set total - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Dialog set user, userout, account, accountout to 'b2e626b6-6c30-480a-9171-8ea7e0db82de/2' - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: IP authorization for caller not provisioned, allow call - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: User-Provided CLI '27<SourceNumber#>' taken from From-User - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Applying caller-in domain rewrite rules on user-provided CLI using dpid '1' - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: No matching rewrite rules for '27<SourceNumber#>' found - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Checking User-provided CLI against allowed CLIs - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: User-provided CLI '27<SourceNumber#>' accepted - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Setting '27<SourceNumber#>@<SubscriberIP>' as initiating user-provided CLI - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Setting '27<SubscriberE164>@sip.tenacit.net' as initiating network-provided CLI - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Searching for callee - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Applying callee-in domain rewrite rules using dpid '2' - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Rewriting callee '0<Destination#>' to '27<Destination#>' - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Clear callee preferences - R=sip:27<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Callee is not local - R=sip:27<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Check Administrative NCOS level - R=sip:27<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Check User NCOS level - R=sip:27<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Checking URI '27<Destination#>@<SPCE-IP>' and CLI '27<Destination#>' against block out lists - R=sip:27<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Admin caller block mode is 'open' - R=sip:27<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Caller block mode is 'open' - R=sip:27<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Call to SIP Peering - R=sip:27<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Load gws matching calling part 'sip:27<SourceNumber#>@sip.tenacit.net' and called user '27<Destination#>' and called part 'sip:27<Destination#>@<SPCE-IP>:5060' - R=sip:27<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Dialog set mark type to outgoing - R=sip:27<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Load peer preferences for peer host '2' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Load dialplan IDs for peer host '2' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Fetched dialplan IDs caller_out='3', callee_out='4' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Dialog set mark peer and peerout to '2' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Setting acc source-leg for uuid 'b2e626b6-6c30-480a-9171-8ea7e0db82de': 'b2e626b6-6c30-480a-9171-8ea7e0db82de|27<SourceNumber#>|sip.tenacit.net|27<SourceNumber#>|TN001FAX|TN001|2|||0|call|<SubscriberIP>|1390291065.560533' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Setting acc destination-leg for uuid '0': '0|||<null>|0<Destination#>|0|27<Destination#>|<Tier1IP>|27<Destination#>|<SPCE-IP>' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Relaying request, du='<null>' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Use mediaproxy for forward direction for IPv4/IPv4 - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Remove existing ICE candidates (if any) for callee - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Setting P-Caller-UUID to 'b2e626b6-6c30-480a-9171-8ea7e0db82de' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Setting P-Callee-UUID to '0' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Sessions Timers disabled for caller - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Sessions Timers enabled for callee with interval 300 and method UPDATE_FALLBACK_INVITE - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Applying caller-out peer rewrite rules to first user-provided caller CLI using dpid '3' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: No matching rewrite rules for '27<SourceNumber#>' found - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Applying caller-out peer rewrite rules to first network-provided caller CLI using dpid '3' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: No matching rewrite rules for '27<SubscriberE164>' found - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Prepare From display-name setting - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: No selector set, not setting CLI - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Prepare From username setting - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Setting caller_cli_userprov/caller_domain_userprov '27<SourceNumber#>@<SubscriberIP>' for upn - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Setting From to '<sip:27<SourceNumber#>@<SubscriberIP>>' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Prepare PAI username setting - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: No selector set, not setting CLI - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Prepare PPI username setting - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: No selector set, not setting CLI - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Prepare Diversion setting - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: No selector set, not setting CLI - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Prepare History-Info setting - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: No selector set, not setting CLI - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Applying callee-out peer rewrite rules to called party using dpid '4' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: No matching rewrite rules for '27<Destination#>' found - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Setting P-Called-Party-ID '<sip:27<Destination#>@<Tier1IP>>' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Writing sbc parameters ;aleg_sst_enable=no;sst_enable=yes;sst_expires=300;sst_min_timer=90;sst_max_timer=7200;sst_refresh_method=UPDATE_FALLBACK_INVITE - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Setting 'sip:<Tier1IP>:5060' taken from R-URI as next hop after lb for PSTN call - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Appending P-D-URI 'sip:127.0.0.1:5060;received='sip:<Tier1IP>:5060;transport=udp'' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Forcing request via B2BUA 'sip:127.0.0.1:5080' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4430]: INFO: <script>: Request leaving server, D-URI='sip:127.0.0.1:5080' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[4429]: INFO: <script>: NAT-Reply - S=100 - Connecting M=INVITE IP=<SubscriberIP>:5060 (127.0.0.1:5080) ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[4425]: INFO: <script>: NAT-Reply - S=200 - OK M=INVITE IP=<SubscriberIP>:5060 (127.0.0.1:5080) ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[4425]: INFO: <script>: Remove existing ICE candidates (if any) for caller - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[4425]: INFO: <script>: Use mediaproxy for backward direction - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[4424]: INFO: <script>: New request - M=ACK R=sip:127.0.0.1:5080 F=sip:27<SourceNumber#>@<SubscriberIP>:5060 T=sip:0<Destination#>@<SPCE-IP>:5060 IP=<SubscriberIP>:5060 (127.0.0.1:5060) ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[4424]: INFO: <script>: Relaying request, du='<null>' - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[4431]: INFO: <script>: New request - M=INVITE R=sip:127.0.0.1:5080 F=sip:27<SourceNumber#>@<SubscriberIP>:5060 T=sip:0<Destination#>@<SPCE-IP>:5060 IP=<SubscriberIP>:5060 (127.0.0.1:5060) ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[4431]: INFO: <script>: Remove existing ICE candidates (if any) for callee - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[4431]: INFO: <script>: Use mediaproxy for forward direction for IPv4/IPv4 - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[4431]: INFO: <script>: Relaying request, du='<null>' - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[4427]: INFO: <script>: New request - M=ACK R=sip:127.0.0.1:5080 F=sip:27<SourceNumber#>@<SubscriberIP>:5060 T=sip:0<Destination#>@<SPCE-IP>:5060 IP=<SubscriberIP>:5060 (127.0.0.1:5060) ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[4427]: INFO: <script>: Relaying request, du='<null>' - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[4425]: INFO: <script>: New request - M=BYE R=sip:127.0.0.1:5080 F=sip:27<SourceNumber#>@<SubscriberIP>:5060 T=sip:0<Destination#>@<SPCE-IP>:5060 IP=<SubscriberIP>:5060 (127.0.0.1:5060) ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[4425]: INFO: <script>: Stop mediaproxy for all branches - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[4425]: INFO: <script>: Relaying request, du='<null>' - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[4424]: INFO: <script>: NAT-Reply - S=200 - OK M=BYE IP=<SubscriberIP>:5060 (127.0.0.1:5080) ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
LOAD BALANCER:
Jan 21 09:57:45 spce /usr/sbin/kamailio[23388]: INFO: <script>: New request - M=INVITE R=sip:0<Destination#>@<SPCE-IP>:5060 F=sip:27<SourceNumber#>@<SubscriberIP>:5060 T=sip:0<Destination#>@<SPCE-IP>:5060 IP=udp:<SubscriberIP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23388]: INFO: <script>: Relaying request, du='sip:127.0.0.1:5062', fs='udp:127.0.0.1:5060' - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23397]: INFO: <script>: Reply from Inbound - S=100 - Trying M=INVITE IP=udp:127.0.0.1:5062 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23397]: INFO: <script>: Forcing outbound socket 'udp:<SPCE-IP>:5060' recovered from P-Out-Socket - R=<null> ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23397]: INFO: <script>: Sending reply, fs='udp:<SPCE-IP>:5060' - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23395]: INFO: <script>: Reply from Inbound - S=407 - Proxy Authentication Required M=INVITE IP=udp:127.0.0.1:5062 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23395]: INFO: <script>: Forcing outbound socket 'udp:<SPCE-IP>:5060' recovered from P-Out-Socket - R=<null> ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23395]: INFO: <script>: Sending reply, fs='udp:<SPCE-IP>:5060' - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23385]: INFO: <script>: New request - M=ACK R=sip:0<Destination#>@<SPCE-IP>:5060 F=sip:27<SourceNumber#>@<SubscriberIP>:5060 T=sip:0<Destination#>@<SPCE-IP>:5060 IP=udp:<SubscriberIP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23385]: INFO: <script>: Perform normal inbound routing - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23385]: INFO: <script>: Relaying request, du='sip:127.0.0.1:5062', fs='udp:127.0.0.1:5060' - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23390]: INFO: <script>: New request - M=INVITE R=sip:0<Destination#>@<SPCE-IP>:5060 F=sip:27<SourceNumber#>@<SubscriberIP>:5060 T=sip:0<Destination#>@<SPCE-IP>:5060 IP=udp:<SubscriberIP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23390]: INFO: <script>: Relaying request, du='sip:127.0.0.1:5062', fs='udp:127.0.0.1:5060' - R=sip:0<Destination#>@<SPCE-IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23393]: INFO: <script>: Reply from Inbound - S=100 - Trying M=INVITE IP=udp:127.0.0.1:5062 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23393]: INFO: <script>: Forcing outbound socket 'udp:<SPCE-IP>:5060' recovered from P-Out-Socket - R=<null> ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23393]: INFO: <script>: Sending reply, fs='udp:<SPCE-IP>:5060' - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23394]: INFO: <script>: Reply from Inbound - S=101 - Connecting M=INVITE IP=udp:127.0.0.1:5062 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:45 spce /usr/sbin/kamailio[23399]: INFO: <script>: New request - M=INVITE R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp F=sip:27<SourceNumber#>@<SubscriberIP> T=sip:27<Destination#>@<Tier1IP> IP=udp:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:45 spce /usr/sbin/kamailio[23399]: INFO: <script>: Mask local B2BUA contact - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:45 spce /usr/sbin/kamailio[23399]: INFO: <script>: Recovering D-URI - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:45 spce /usr/sbin/kamailio[23399]: INFO: <script>: Recovered rcv 'sip:<Tier1IP>:5060;transport=udp' from D-URI - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:45 spce /usr/sbin/kamailio[23399]: INFO: <script>: Relaying request, du='sip:<Tier1IP>:5060;transport=udp', fs='udp:<SPCE-IP>:5060' - R=sip:27<Destination#>@<Tier1IP>:5060;transport=udp ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:45 spce /usr/sbin/kamailio[23386]: INFO: <script>: Reply from Outbound - S=100 - Giving a try M=INVITE IP=udp:<Tier1IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:45 spce /usr/sbin/kamailio[23386]: INFO: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:51 spce /usr/sbin/kamailio[23388]: INFO: <script>: Reply from Outbound - S=200 - OK M=INVITE IP=udp:<Tier1IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:51 spce /usr/sbin/kamailio[23388]: INFO: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:51 spce /usr/sbin/kamailio[23395]: INFO: <script>: Reply from Inbound - S=200 - OK M=INVITE IP=udp:127.0.0.1:5062 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[23395]: INFO: <script>: Mask B2BUA contact - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[23395]: INFO: <script>: Forcing outbound socket 'udp:<SPCE-IP>:5060' recovered from P-Out-Socket - R=<null> ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[23395]: INFO: <script>: Sending reply, fs='udp:<SPCE-IP>:5060' - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[23385]: INFO: <script>: New request - M=ACK R=sip:ngcp-lb@<SPCE-IP>:5060;ngcpct=c2lwOjEyNy4wLjAuMTo1MDgw F=sip:27<SourceNumber#>@<SubscriberIP>:5060 T=sip:0<Destination#>@<SPCE-IP>:5060 IP=udp:<SubscriberIP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[23385]: INFO: <script>: Unmasked internal contact - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[23385]: INFO: <script>: Perform loose-routing, du='sip:127.0.0.1:5062;lr=on;ftag=acf7b730-0-13c4-55013-37f36-33bcac20-37f36;did=ac4.5c4;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=N052NGJIdExXSlZWTFBVaGg0aEE3TnY0YkhOeWd8Zg--' - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[23385]: INFO: <script>: Relaying request, du='sip:127.0.0.1:5062;lr=on;ftag=acf7b730-0-13c4-55013-37f36-33bcac20-37f36;did=ac4.5c4;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=N052NGJIdExXSlZWTFBVaGg0aEE3TnY0YkhOeWd8Zg--', fs='udp:127.0.0.1:5060' - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[23393]: INFO: <script>: New request - M=ACK R=sip:27<Destination#>@41.221.2.27:5070 F=sip:27<SourceNumber#>@<SubscriberIP> T=sip:27<Destination#>@<Tier1IP> IP=udp:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:51 spce /usr/sbin/kamailio[23393]: INFO: <script>: Mask local B2BUA contact - R=sip:27<Destination#>@41.221.2.27:5070 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:51 spce /usr/sbin/kamailio[23393]: INFO: <script>: Perform loose-routing, du='sip:<Tier1IP>;lr;ftag=0CD738CB-52DE2879000894EE-12D05700;vsf=AAAAAGZSWhxkWkIfBQMEAAAAAAAAAAAAAAA-' - R=sip:27<Destination#>@41.221.2.27:5070 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:51 spce /usr/sbin/kamailio[23393]: INFO: <script>: Relaying request, du='sip:<Tier1IP>;lr;ftag=0CD738CB-52DE2879000894EE-12D05700;vsf=AAAAAGZSWhxkWkIfBQMEAAAAAAAAAAAAAAA-', fs='udp:<SPCE-IP>:5060' - R=sip:27<Destination#>@41.221.2.27:5070 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:51 spce /usr/sbin/kamailio[23390]: INFO: <script>: New request - M=INVITE R=sip:ngcp-lb@<SPCE-IP>:5060;ngcpct=c2lwOjEyNy4wLjAuMTo1MDgw F=sip:27<SourceNumber#>@<SubscriberIP>:5060 T=sip:0<Destination#>@<SPCE-IP>:5060 IP=udp:<SubscriberIP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[23390]: INFO: <script>: Unmasked internal contact - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[23390]: INFO: <script>: Perform loose-routing, du='sip:127.0.0.1:5062;lr=on;ftag=acf7b730-0-13c4-55013-37f36-33bcac20-37f36;did=ac4.5c4;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=N052NGJIdExXSlZWTFBVaGg0aEE3TnY0YkhOeWd8Zg--' - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[23390]: INFO: <script>: Relaying request, du='sip:127.0.0.1:5062;lr=on;ftag=acf7b730-0-13c4-55013-37f36-33bcac20-37f36;did=ac4.5c4;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=N052NGJIdExXSlZWTFBVaGg0aEE3TnY0YkhOeWd8Zg--', fs='udp:127.0.0.1:5060' - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[23394]: INFO: <script>: Reply from Inbound - S=100 - Trying M=INVITE IP=udp:127.0.0.1:5062 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[23394]: INFO: <script>: Forcing outbound socket 'udp:<SPCE-IP>:5060' recovered from P-Out-Socket - R=<null> ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[23394]: INFO: <script>: Sending reply, fs='udp:<SPCE-IP>:5060' - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:57:51 spce /usr/sbin/kamailio[23399]: INFO: <script>: New request - M=INVITE R=sip:27<Destination#>@41.221.2.27:5070 F=sip:27<SourceNumber#>@<SubscriberIP> T=sip:27<Destination#>@<Tier1IP> IP=udp:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:51 spce /usr/sbin/kamailio[23399]: INFO: <script>: Mask local B2BUA contact - R=sip:27<Destination#>@41.221.2.27:5070 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:51 spce /usr/sbin/kamailio[23399]: INFO: <script>: Perform loose-routing, du='sip:<Tier1IP>;lr;ftag=0CD738CB-52DE2879000894EE-12D05700;vsf=AAAAAGZSWhxkWkIfBQMEAAAAAAAAAAAAAAA-' - R=sip:27<Destination#>@41.221.2.27:5070 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:51 spce /usr/sbin/kamailio[23399]: INFO: <script>: Relaying request, du='sip:<Tier1IP>;lr;ftag=0CD738CB-52DE2879000894EE-12D05700;vsf=AAAAAGZSWhxkWkIfBQMEAAAAAAAAAAAAAAA-', fs='udp:<SPCE-IP>:5060' - R=sip:27<Destination#>@41.221.2.27:5070 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:51 spce /usr/sbin/kamailio[23386]: INFO: <script>: Reply from Outbound - S=100 - Giving a try M=INVITE IP=udp:<Tier1IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:57:51 spce /usr/sbin/kamailio[23386]: INFO: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:58:00 spce /usr/sbin/kamailio[23394]: INFO: <script>: Reply from Inbound - S=408 - Request Timeout M=INVITE IP=udp:127.0.0.1:5062 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23394]: INFO: <script>: Forcing outbound socket 'udp:<SPCE-IP>:5060' recovered from P-Out-Socket - R=<null> ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23394]: INFO: <script>: Sending reply, fs='udp:<SPCE-IP>:5060' - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23387]: INFO: <script>: New request - M=ACK R=sip:ngcp-lb@<SPCE-IP>:5060;ngcpct=c2lwOjEyNy4wLjAuMTo1MDgw F=sip:27<SourceNumber#>@<SubscriberIP>:5060 T=sip:0<Destination#>@<SPCE-IP>:5060 IP=udp:<SubscriberIP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23387]: INFO: <script>: Unmasked internal contact - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23387]: INFO: <script>: Perform loose-routing, du='sip:127.0.0.1:5062;lr=on;ftag=acf7b730-0-13c4-55013-37f36-33bcac20-37f36;did=ac4.5c4;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=N052NGJIdExXSlZWTFBVaGg0aEE3TnY0YkhOeWd8Zg--' - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23387]: INFO: <script>: Relaying request, du='sip:127.0.0.1:5062;lr=on;ftag=acf7b730-0-13c4-55013-37f36-33bcac20-37f36;did=ac4.5c4;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=N052NGJIdExXSlZWTFBVaGg0aEE3TnY0YkhOeWd8Zg--', fs='udp:127.0.0.1:5060' - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23388]: INFO: <script>: New request - M=BYE R=sip:ngcp-lb@<SPCE-IP>:5060;ngcpct=c2lwOjEyNy4wLjAuMTo1MDgw F=sip:27<SourceNumber#>@<SubscriberIP>:5060 T=sip:0<Destination#>@<SPCE-IP>:5060 IP=udp:<SubscriberIP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23388]: INFO: <script>: Unmasked internal contact - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23388]: INFO: <script>: Perform loose-routing, du='sip:127.0.0.1:5062;lr=on;ftag=acf7b730-0-13c4-55013-37f36-33bcac20-37f36;did=ac4.5c4;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=N052NGJIdExXSlZWTFBVaGg0aEE3TnY0YkhOeWd8Zg--' - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23388]: INFO: <script>: Relaying request, du='sip:127.0.0.1:5062;lr=on;ftag=acf7b730-0-13c4-55013-37f36-33bcac20-37f36;did=ac4.5c4;mpd=ii;ice_caller=strip;ice_callee=strip;rtpprx=yes;vsf=N052NGJIdExXSlZWTFBVaGg0aEE3TnY0YkhOeWd8Zg--', fs='udp:127.0.0.1:5060' - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23399]: INFO: <script>: Reply from Inbound - S=100 - Trying M=BYE IP=udp:127.0.0.1:5062 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23399]: INFO: <script>: Forcing outbound socket 'udp:<SPCE-IP>:5060' recovered from P-Out-Socket - R=<null> ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23399]: INFO: <script>: Sending reply, fs='udp:<SPCE-IP>:5060' - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23396]: INFO: <script>: New request - M=ACK R=sip:27<Destination#>@41.221.2.27:5070 F=sip:27<SourceNumber#>@<SubscriberIP> T=sip:27<Destination#>@<Tier1IP> IP=udp:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:58:00 spce /usr/sbin/kamailio[23398]: INFO: <script>: Reply from Inbound - S=200 - OK M=BYE IP=udp:127.0.0.1:5062 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23396]: INFO: <script>: Mask local B2BUA contact - R=sip:27<Destination#>@41.221.2.27:5070 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:58:00 spce /usr/sbin/kamailio[23398]: INFO: <script>: Forcing outbound socket 'udp:<SPCE-IP>:5060' recovered from Record-Route - R=<null> ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23398]: INFO: <script>: Sending reply, fs='udp:<SPCE-IP>:5060' - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36
Jan 21 09:58:00 spce /usr/sbin/kamailio[23396]: INFO: <script>: Perform loose-routing, du='sip:<Tier1IP>;lr;ftag=0CD738CB-52DE2879000894EE-12D05700;vsf=AAAAAGZSWhxkWkIfBQMEAAAAAAAAAAAAAAA-' - R=sip:27<Destination#>@41.221.2.27:5070 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:58:00 spce /usr/sbin/kamailio[23396]: INFO: <script>: Relaying request, du='sip:<Tier1IP>;lr;ftag=0CD738CB-52DE2879000894EE-12D05700;vsf=AAAAAGZSWhxkWkIfBQMEAAAAAAAAAAAAAAA-', fs='udp:<SPCE-IP>:5060' - R=sip:27<Destination#>@41.221.2.27:5070 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:58:00 spce /usr/sbin/kamailio[23392]: INFO: <script>: New request - M=BYE R=sip:27<Destination#>@41.221.2.27:5070 F=sip:27<SourceNumber#>@<SubscriberIP> T=sip:27<Destination#>@<Tier1IP> IP=udp:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:58:00 spce /usr/sbin/kamailio[23392]: INFO: <script>: Perform loose-routing, du='sip:<Tier1IP>;lr;ftag=0CD738CB-52DE2879000894EE-12D05700;vsf=AAAAAGZSWhxkWkIfBQMEAAAAAAAAAAAAAAA-' - R=sip:27<Destination#>@41.221.2.27:5070 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:58:00 spce /usr/sbin/kamailio[23392]: INFO: <script>: Relaying request, du='sip:<Tier1IP>;lr;ftag=0CD738CB-52DE2879000894EE-12D05700;vsf=AAAAAGZSWhxkWkIfBQMEAAAAAAAAAAAAAAA-', fs='udp:<SPCE-IP>:5060' - R=sip:27<Destination#>@41.221.2.27:5070 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:58:00 spce /usr/sbin/kamailio[23385]: INFO: <script>: New request - M=BYE R=sip:ngcp-lb@<SPCE-IP>:5060;ngcpct=c2lwOjEyNy4wLjAuMTo1MDgw F=sip:27<Destination#>@<Tier1IP> T=sip:27<SourceNumber#>@<SubscriberIP> IP=udp:<Tier1IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:58:00 spce /usr/sbin/kamailio[23385]: INFO: <script>: Unmasked internal contact - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:58:00 spce /usr/sbin/kamailio[23385]: INFO: <script>: Perform loose-routing, du='<null>' - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:58:00 spce /usr/sbin/kamailio[23385]: INFO: <script>: Relaying request, du='<null>', fs='udp:127.0.0.1:5060' - R=sip:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:58:00 spce /usr/sbin/kamailio[23397]: INFO: <script>: Reply from Inbound - S=200 - OK M=BYE IP=udp:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:58:00 spce /usr/sbin/kamailio[23397]: INFO: <script>: Sending reply, fs='udp:<SPCE-IP>:5060' - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:58:00 spce /usr/sbin/kamailio[23390]: INFO: <script>: Reply from Outbound - S=200 - OK M=BYE IP=udp:<Tier1IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:58:00 spce /usr/sbin/kamailio[23390]: INFO: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:59:52 spce /usr/sbin/kamailio[23390]: INFO: <script>: Reply from Outbound - S=408 - Request Timeout M=INVITE IP=udp:<Tier1IP>:5060 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:59:52 spce /usr/sbin/kamailio[23390]: INFO: <script>: Sending reply, fs='udp:127.0.0.1:5060' - ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:59:52 spce /usr/sbin/kamailio[23398]: INFO: <script>: New request - M=ACK R=sip:27<Destination#>@41.221.2.27:5070 F=sip:27<SourceNumber#>@<SubscriberIP> T=sip:27<Destination#>@<Tier1IP> IP=udp:127.0.0.1:5080 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:59:52 spce /usr/sbin/kamailio[23398]: INFO: <script>: Perform loose-routing, du='sip:<Tier1IP>;lr;ftag=0CD738CB-52DE2879000894EE-12D05700;vsf=AAAAAGZSWhxkWkIfBQMEAAAAAAAAAAAAAAA-' - R=sip:27<Destination#>@41.221.2.27:5070 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
Jan 21 09:59:52 spce /usr/sbin/kamailio[23398]: INFO: <script>: Relaying request, du='sip:<Tier1IP>;lr;ftag=0CD738CB-52DE2879000894EE-12D05700;vsf=AAAAAGZSWhxkWkIfBQMEAAAAAAAAAAAAAAA-', fs='udp:<SPCE-IP>:5060' - R=sip:27<Destination#>@41.221.2.27:5070 ID=ace566c0-0-13c4-55013-37f36-74fa9a62-37f36_b2b-1
More information about the Spce-user
mailing list