[SR-Users] ack not transfer kamailio-3.3.4

dolphin dolphinctk at gmail.com
Fri Dec 13 02:22:35 CET 2013


hello,

I’m trying to build two kamailio-3.3.4 server  like this:

ua1 ----> kamailio1(k1)-----> kamailio2(k2) -----> ua2

ua1 (5978003 )  is register k1
ua2 (5001005)   is register k2

When ua1 callout to ua2 sip process through k1 and k2

The problem is k2 server ACK not transfer to ua2  and  k2 has received ack
signals from u1 through k1

I ngrep sip process for k2 and k2 /var/log/debug

see the attached File ngrep_k2server.txt, debug_k2

1.
First ACK uri have ua2 number sip:5001005 at xxx.xxx.xxx.xxx(k2):5003;ob  ,
is correct !!

2. Second ack not include 5001005  , again loop itself     ; Route:
<sip:xxx.xxx.xxx.xxx(k2):5003;lr;did=763.941>
loose_route  Next hop: 'sip:xxx.xxx.xxx.xxx(k2):5003;lr;did=763.941' is
loose router.

so  next  hop  Not found in usrloc db  5001005 user and not  transfer to
ua2

How to fix this problem, any suggestions ?
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20131213/e6640d49/attachment-0001.html>
-------------- next part --------------
ngrep debug in k2 server : k1 <---->  k2

U xxx.xxx.xxx.xxx(k1):5002 -> xxx.xxx.xxx.xxx(k2):5003
INVITE sip:5001005 at xxx.xxx.xxx.xxx(k2):5003 SIP/2.0.
Record-Route: <sip:xxx.xxx.xxx.xxx(k1):5002;lr=on;nat=yes;did=763.7861>.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k1):5002;branch=z9hG4bKe93a.851a9236.0.
Via: SIP/2.0/UDP 100.65.104.101:60496;received=122.116.37.55;branch=z9hG4bK-d8754z-8800d531eb69491a-1---d8754z-;rport=60496.
Max-Forwards: 69.
Contact: <sip:5978003 at 122.116.37.55:60496;transport=udp>.
To: "5001005"<sip:5001005 at xxx.xxx.xxx.xxx(k2):5003>.
From: "5978003"<sip:5978003 at xxx.xxx.xxx.xxx(k1):5002>;tag=1944d063.
Call-ID: NWI3MDllMTI1YmM1MjQ1MDM4ZGVlMmIyZDBiN2Q3ZjI..
CSeq: 1 INVITE.
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO.
Content-Type: application/sdp.
User-Agent: eyeBeam release 1100z stamp 47739.
Content-Length: 312.
P-hint: forward branch.
.
v=0.
o=- 5 2 IN IP4 100.65.104.101.
s=CounterPath eyeBeam 1.5.
c=IN IP4 xxx.xxx.xxx.xxx(k2).
t=0 0.
m=audio 35462 RTP/AVP 98 18 3 101.
a=fmtp:18 annexb=yes.
a=fmtp:101 0-15.
a=rtpmap:98 iLBC/8000.
a=rtpmap:18 G729/8000.
a=rtpmap:101 telephone-event/8000.
a=sendrecv.
a=x-rtp-session-id:A27021BC28EB48E8BAF43C5BE6389874.

#
U xxx.xxx.xxx.xxx(k2):5003 -> xxx.xxx.xxx.xxx(k1):5002
SIP/2.0 100 trying -- your call is important to us.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k1):5002;branch=z9hG4bKe93a.851a9236.0.
Via: SIP/2.0/UDP 100.65.104.101:60496;received=122.116.37.55;branch=z9hG4bK-d8754z-8800d531eb69491a-1---d8754z-;rport=60496.
To: "5001005"<sip:5001005 at xxx.xxx.xxx.xxx(k2):5003>.
From: "5978003"<sip:5978003 at xxx.xxx.xxx.xxx(k1):5002>;tag=1944d063.
Call-ID: NWI3MDllMTI1YmM1MjQ1MDM4ZGVlMmIyZDBiN2Q3ZjI..
CSeq: 1 INVITE.
Server: SIP Server.
Content-Length: 0.
.

#
U xxx.xxx.xxx.xxx(k2):5003 -> 122.116.37.55:35890
INVITE sip:5001005 at 122.116.37.55:35890;ob SIP/2.0.
Record-Route: <sip:xxx.xxx.xxx.xxx(k2):5003;lr=on;did=763.941>.
Record-Route: <sip:xxx.xxx.xxx.xxx(k1):5002;lr=on;nat=yes;did=763.7861>.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k2):5003;branch=z9hG4bKe93a.359d0da4.0.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k1):5002;branch=z9hG4bKe93a.851a9236.0.
Via: SIP/2.0/UDP 100.65.104.101:60496;received=122.116.37.55;branch=z9hG4bK-d8754z-8800d531eb69491a-1---d8754z-;rport=60496.
Max-Forwards: 68.
Contact: <sip:5978003 at 122.116.37.55:60496;transport=udp>.
To: "5001005"<sip:5001005 at xxx.xxx.xxx.xxx(k2):5003>.
From: "5978003"<sip:5978003 at xxx.xxx.xxx.xxx(k1):5002>;tag=1944d063.
Call-ID: NWI3MDllMTI1YmM1MjQ1MDM4ZGVlMmIyZDBiN2Q3ZjI..
CSeq: 1 INVITE.
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO.
Content-Type: application/sdp.
User-Agent: eyeBeam release 1100z stamp 47739.
Content-Length: 312.
.
v=0.
o=- 5 2 IN IP4 100.65.104.101.
s=CounterPath eyeBeam 1.5.
c=IN IP4 xxx.xxx.xxx.xxx(k2).
t=0 0.
m=audio 35462 RTP/AVP 98 18 3 101.
a=fmtp:18 annexb=yes.
a=fmtp:101 0-15.
a=rtpmap:98 iLBC/8000.
a=rtpmap:18 G729/8000.
a=rtpmap:101 telephone-event/8000.
a=sendrecv.
a=x-rtp-session-id:A27021BC28EB48E8BAF43C5BE6389874.

#
U 122.116.37.55:35890 -> xxx.xxx.xxx.xxx(k2):5003
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k2):5003;received=xxx.xxx.xxx.xxx(k2);branch=z9hG4bKe93a.359d0da4.0.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k1):5002;branch=z9hG4bKe93a.851a9236.0.
Via: SIP/2.0/UDP 100.65.104.101:60496;rport=60496;received=122.116.37.55;branch=z9hG4bK-d8754z-8800d531eb69491a-1---d8754z-.
Record-Route: <sip:xxx.xxx.xxx.xxx(k2):5003;lr;did=763.941>.
Record-Route: <sip:xxx.xxx.xxx.xxx(k1):5002;lr;nat=yes;did=763.7861>.
Call-ID: NWI3MDllMTI1YmM1MjQ1MDM4ZGVlMmIyZDBiN2Q3ZjI..
From: "5978003" <sip:5978003 at xxx.xxx.xxx.xxx(k1)>;tag=1944d063.
To: "5001005" <sip:5001005 at xxx.xxx.xxx.xxx(k2)>.
CSeq: 1 INVITE.
Content-Length:  0.
.

#
U 122.116.37.55:35890 -> xxx.xxx.xxx.xxx(k2):5003
SIP/2.0 180 Ringing.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k2):5003;received=xxx.xxx.xxx.xxx(k2);branch=z9hG4bKe93a.359d0da4.0.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k1):5002;branch=z9hG4bKe93a.851a9236.0.
Via: SIP/2.0/UDP 100.65.104.101:60496;rport=60496;received=122.116.37.55;branch=z9hG4bK-d8754z-8800d531eb69491a-1---d8754z-.
Record-Route: <sip:xxx.xxx.xxx.xxx(k2):5003;lr;did=763.941>.
Record-Route: <sip:xxx.xxx.xxx.xxx(k1):5002;lr;nat=yes;did=763.7861>.
Call-ID: NWI3MDllMTI1YmM1MjQ1MDM4ZGVlMmIyZDBiN2Q3ZjI..
From: "5978003" <sip:5978003 at xxx.xxx.xxx.xxx(k1)>;tag=1944d063.
To: "5001005" <sip:5001005 at xxx.xxx.xxx.xxx(k2)>;tag=cjLZLUdZz5lKpi1gtGak.otJWQzet.vf.
CSeq: 1 INVITE.
Contact: "5001005" <sip:5001005 at 122.116.37.55:35890;ob>.
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS.
Content-Length:  0.
.

#
U xxx.xxx.xxx.xxx(k2):5003 -> xxx.xxx.xxx.xxx(k1):5002
SIP/2.0 180 Ringing.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k1):5002;branch=z9hG4bKe93a.851a9236.0.
Via: SIP/2.0/UDP 100.65.104.101:60496;rport=60496;received=122.116.37.55;branch=z9hG4bK-d8754z-8800d531eb69491a-1---d8754z-.
Record-Route: <sip:xxx.xxx.xxx.xxx(k2):5003;lr;did=763.941>.
Record-Route: <sip:xxx.xxx.xxx.xxx(k1):5002;lr;nat=yes;did=763.7861>.
Call-ID: NWI3MDllMTI1YmM1MjQ1MDM4ZGVlMmIyZDBiN2Q3ZjI..
From: "5978003" <sip:5978003 at xxx.xxx.xxx.xxx(k1)>;tag=1944d063.
To: "5001005" <sip:5001005 at xxx.xxx.xxx.xxx(k2)>;tag=cjLZLUdZz5lKpi1gtGak.otJWQzet.vf.
CSeq: 1 INVITE.
Contact: "5001005" <sip:5001005 at 122.116.37.55:35890;ob>.
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS.
Content-Length:  0.
.

#
U 122.116.37.55:35890 -> xxx.xxx.xxx.xxx(k2):5003
SIP/2.0 200 OK.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k2):5003;received=xxx.xxx.xxx.xxx(k2);branch=z9hG4bKe93a.359d0da4.0.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k1):5002;branch=z9hG4bKe93a.851a9236.0.
Via: SIP/2.0/UDP 100.65.104.101:60496;rport=60496;received=122.116.37.55;branch=z9hG4bK-d8754z-8800d531eb69491a-1---d8754z-.
Record-Route: <sip:xxx.xxx.xxx.xxx(k2):5003;lr;did=763.941>.
Record-Route: <sip:xxx.xxx.xxx.xxx(k1):5002;lr;nat=yes;did=763.7861>.
Call-ID: NWI3MDllMTI1YmM1MjQ1MDM4ZGVlMmIyZDBiN2Q3ZjI..
From: "5978003" <sip:5978003 at xxx.xxx.xxx.xxx(k1)>;tag=1944d063.
To: "5001005" <sip:5001005 at xxx.xxx.xxx.xxx(k2)>;tag=cjLZLUdZz5lKpi1gtGak.otJWQzet.vf.
CSeq: 1 INVITE.
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS.
Contact: "5001005" <sip:5001005 at 122.116.37.55:35890;ob>.
Supported: replaces, 100rel, timer, norefersub.
Content-Type: application/sdp.
Content-Length:   230.
.
v=0.
o=- 3595766102 3595766103 IN IP4 122.116.37.55.
s=cpc_med.
c=IN IP4 122.116.37.55.
t=0 0.
m=audio 4008 RTP/AVP 98 101.
a=sendrecv.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=rtpmap:98 iLBC/8000.
a=fmtp:98 mode=20.

#
U xxx.xxx.xxx.xxx(k2):5003 -> xxx.xxx.xxx.xxx(k1):5002
SIP/2.0 200 OK.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k1):5002;branch=z9hG4bKe93a.851a9236.0.
Via: SIP/2.0/UDP 100.65.104.101:60496;rport=60496;received=122.116.37.55;branch=z9hG4bK-d8754z-8800d531eb69491a-1---d8754z-.
Record-Route: <sip:xxx.xxx.xxx.xxx(k2):5003;lr;did=763.941>.
Record-Route: <sip:xxx.xxx.xxx.xxx(k1):5002;lr;nat=yes;did=763.7861>.
Call-ID: NWI3MDllMTI1YmM1MjQ1MDM4ZGVlMmIyZDBiN2Q3ZjI..
From: "5978003" <sip:5978003 at xxx.xxx.xxx.xxx(k1)>;tag=1944d063.
To: "5001005" <sip:5001005 at xxx.xxx.xxx.xxx(k2)>;tag=cjLZLUdZz5lKpi1gtGak.otJWQzet.vf.
CSeq: 1 INVITE.
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS.
Contact: "5001005" <sip:5001005 at 122.116.37.55:35890;ob>.
Supported: replaces, 100rel, timer, norefersub.
Content-Type: application/sdp.
Content-Length:   230.
.
v=0.
o=- 3595766102 3595766103 IN IP4 122.116.37.55.
s=cpc_med.
c=IN IP4 122.116.37.55.
t=0 0.
m=audio 4008 RTP/AVP 98 101.
a=sendrecv.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=rtpmap:98 iLBC/8000.
a=fmtp:98 mode=20.

#
U xxx.xxx.xxx.xxx(k1):5002 -> xxx.xxx.xxx.xxx(k2):5003
ACK sip:5001005 at xxx.xxx.xxx.xxx(k2):5003;ob SIP/2.0.
Record-Route: <sip:xxx.xxx.xxx.xxx(k1):5002;lr=on>.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k1):5002;branch=z9hG4bKcydzigwkX.
Via: SIP/2.0/UDP 100.65.104.101:60496;received=122.116.37.55;branch=z9hG4bK-d8754z-5479c6361610102b-1---d8754z-;rport=60496.
Max-Forwards: 69.
Route: <sip:xxx.xxx.xxx.xxx(k2):5003;lr;did=763.941>.
Contact: <sip:5978003 at 122.116.37.55:60496;transport=udp>.
To: "5001005"<sip:5001005 at xxx.xxx.xxx.xxx(k2)>;tag=cjLZLUdZz5lKpi1gtGak.otJWQzet.vf.
From: "5978003"<sip:5978003 at xxx.xxx.xxx.xxx(k1)>;tag=1944d063.
Call-ID: NWI3MDllMTI1YmM1MjQ1MDM4ZGVlMmIyZDBiN2Q3ZjI..
CSeq: 1 ACK.
User-Agent: eyeBeam release 1100z stamp 47739.
Content-Length: 0.
.

#
U 122.116.37.55:35890 -> xxx.xxx.xxx.xxx(k2):5003
SIP/2.0 200 OK.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k2):5003;received=xxx.xxx.xxx.xxx(k2);branch=z9hG4bKe93a.359d0da4.0.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k1):5002;branch=z9hG4bKe93a.851a9236.0.
Via: SIP/2.0/UDP 100.65.104.101:60496;rport=60496;received=122.116.37.55;branch=z9hG4bK-d8754z-8800d531eb69491a-1---d8754z-.
Record-Route: <sip:xxx.xxx.xxx.xxx(k2):5003;lr;did=763.941>.
Record-Route: <sip:xxx.xxx.xxx.xxx(k1):5002;lr;nat=yes;did=763.7861>.
Call-ID: NWI3MDllMTI1YmM1MjQ1MDM4ZGVlMmIyZDBiN2Q3ZjI..
From: "5978003" <sip:5978003 at xxx.xxx.xxx.xxx(k1)>;tag=1944d063.
To: "5001005" <sip:5001005 at xxx.xxx.xxx.xxx(k2)>;tag=cjLZLUdZz5lKpi1gtGak.otJWQzet.vf.
CSeq: 1 INVITE.
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS.
Contact: "5001005" <sip:5001005 at 122.116.37.55:35890;ob>.
Supported: replaces, 100rel, timer, norefersub.
Content-Type: application/sdp.
Content-Length:   230.
.
v=0.
o=- 3595766102 3595766103 IN IP4 122.116.37.55.
s=cpc_med.
c=IN IP4 122.116.37.55.
t=0 0.
m=audio 4008 RTP/AVP 98 101.
a=sendrecv.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=rtpmap:98 iLBC/8000.
a=fmtp:98 mode=20.

#
U xxx.xxx.xxx.xxx(k2):5003 -> xxx.xxx.xxx.xxx(k1):5002
SIP/2.0 200 OK.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k1):5002;branch=z9hG4bKe93a.851a9236.0.
Via: SIP/2.0/UDP 100.65.104.101:60496;rport=60496;received=122.116.37.55;branch=z9hG4bK-d8754z-8800d531eb69491a-1---d8754z-.
Record-Route: <sip:xxx.xxx.xxx.xxx(k2):5003;lr;did=763.941>.
Record-Route: <sip:xxx.xxx.xxx.xxx(k1):5002;lr;nat=yes;did=763.7861>.
Call-ID: NWI3MDllMTI1YmM1MjQ1MDM4ZGVlMmIyZDBiN2Q3ZjI..
From: "5978003" <sip:5978003 at xxx.xxx.xxx.xxx(k1)>;tag=1944d063.
To: "5001005" <sip:5001005 at xxx.xxx.xxx.xxx(k2)>;tag=cjLZLUdZz5lKpi1gtGak.otJWQzet.vf.
CSeq: 1 INVITE.
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS.
Contact: "5001005" <sip:5001005 at 122.116.37.55:35890;ob>.
Supported: replaces, 100rel, timer, norefersub.
Content-Type: application/sdp.
Content-Length:   230.
.
v=0.
o=- 3595766102 3595766103 IN IP4 122.116.37.55.
s=cpc_med.
c=IN IP4 122.116.37.55.
t=0 0.
m=audio 4008 RTP/AVP 98 101.
a=sendrecv.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=rtpmap:98 iLBC/8000.
a=fmtp:98 mode=20.

#
U xxx.xxx.xxx.xxx(k1):5002 -> xxx.xxx.xxx.xxx(k2):5003
ACK sip:5001005 at xxx.xxx.xxx.xxx(k2):5003;ob SIP/2.0.
Record-Route: <sip:xxx.xxx.xxx.xxx(k1):5002;lr=on>.
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k1):5002;branch=z9hG4bKcydzigwkX.
Via: SIP/2.0/UDP 100.65.104.101:60496;received=122.116.37.55;branch=z9hG4bK-d8754z-5479c6361610102b-1---d8754z-;rport=60496.
Max-Forwards: 69.
Route: <sip:xxx.xxx.xxx.xxx(k2):5003;lr;did=763.941>.
Contact: <sip:5978003 at 122.116.37.55:60496;transport=udp>.
To: "5001005"<sip:5001005 at xxx.xxx.xxx.xxx(k2)>;tag=cjLZLUdZz5lKpi1gtGak.otJWQzet.vf.
From: "5978003"<sip:5978003 at xxx.xxx.xxx.xxx(k1)>;tag=1944d063.
Call-ID: NWI3MDllMTI1YmM1MjQ1MDM4ZGVlMmIyZDBiN2Q3ZjI..
CSeq: 1 ACK.
User-Agent: eyeBeam release 1100z stamp 47739.
Content-Length: 0.
.
-------------- next part --------------
/var/log/debug in k2 server :

: DEBUG: <core> [parser/msg_parser.c:624]: SIP Request:
: DEBUG: <core> [parser/msg_parser.c:626]:  method:  <ACK>
: DEBUG: <core> [parser/msg_parser.c:628]:  uri:     <sip:5001005 at xxx.xxx.xxx.xxx(k2):5003;ob>
: DEBUG: <core> [parser/msg_parser.c:630]:  version: <SIP/2.0>
: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bKcydzigwkX>; state=16
: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2
: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via
: DEBUG: <core> [receive.c:149]: After parse_msg...
: DEBUG: <core> [receive.c:190]: preparing to run routing scripts...
: DEBUG: sl [sl_funcs.c:396]: DEBUG : sl_filter_ACK: to late to be a local ACK!
: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 234, <received> = <122.116.37.55>; state=6
: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bK-d8754z-5479c6361610102b-1---d8754z->; state=6
: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <60496>; state=16
: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=10
: DEBUG: <core> [parser/msg_parser.c:524]: parse_headers: this is the second via
: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=cjLZLUdZz5lKpi1gtGak.otJWQzet.vf
: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [73]; uri=[sip:5001005 at xxx.xxx.xxx.xxx(k2)]
: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body ["5001005"<sip:5001005 at xxx.xxx.xxx.xxx(k2)>]
: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=1944d063
: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
: DEBUG: siptrace [siptrace.c:794]: storing info...
: DEBUG: maxfwd [mf_funcs.c:85]: value = 69
: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=42 global id=41 T start=0xffffffffffffffff
: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <1> <ACK>
: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=0
: DEBUG: <core> [parser/msg_parser.c:104]: found end of header
: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=41886, isACK=1
: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=42 global id=42 T end=(nil)
: DEBUG: rr [loose.c:88]: is_preloaded: No
: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 11==11 && [xxx.xxx.xxx.xxx(k2)] == [xxx.xxx.xxx.xxx(k2)]
: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5003 (advertise 0) matches port 5003
: DEBUG: rr [loose.c:597]: Next hop: 'sip:xxx.xxx.xxx.xxx(k2):5003;lr;did=763.941' is loose router
: DEBUG: rr [loose.c:647]: The last route URI: 'sip:xxx.xxx.xxx.xxx(k2):5003;lr;did=763.941'
: DEBUG: rr [rr_cb.c:97]: callback id 0 entered with <ob>
: DEBUG: dialog [dlg_handlers.c:1109]: Route param 'did' not found
: DEBUG: tm [t_lookup.c:1379]: DEBUG: t_newtran: msg id=42 , global msg id=42 , T on entrance=(nil)
: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=41886, isACK=1
: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
: DEBUG: tm [t_funcs.c:315]: SER: forwarding ACK  statelessly
: DEBUG: <core> [msg_translator.c:206]: check_via_address(xxx.xxx.xxx.xxx(k1), xxx.xxx.xxx.xxx(k1), 0)
: DEBUG: <core> [forward.c:609]: Sending:#012ACK sip:xxx.xxx.xxx.xxx(k2):5003;lr;did=763.941 SIP/2.0#015#012Record-Route: <sip:xxx.xxx.xxx.xxx(k2):5003;lr=on>#015#012Record-Route: <sip:xxx.xxx.xxx.xxx(k1):5002;lr=on>#015#012Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k2):5003;branch=z9hG4bKcydzigwkX#015#012Via: SIP/2.0/UDP xxx.xxx.xxx.xxx(k1):5002;branch=z9hG4bKcydzigwkX#015#012Via: SIP/2.0/UDP 100.65.104.101:60496;received=122.116.37.55;branch=z9hG4bK-d8754z-5479c6361610102b-1---d8754z-;rport=60496#015#012Max-Forwards: 68#015#012Contact: <sip:5978003 at 122.116.37.55:60496;transport=udp>#015#012To: "5001005"<sip:5001005 at xxx.xxx.xxx.xxx(k2)>;tag=cjLZLUdZz5lKpi1gtGak.otJWQzet.vf#015#012From: "5978003"<sip:5978003 at xxx.xxx.xxx.xxx(k1)>;tag=1944d063#015#012Call-ID: NWI3MDllMTI1YmM1MjQ1MDM4ZGVlMmIyZDBiN2Q3ZjI.#015#012CSeq: 1 ACK#015#012User-Agent: eyeBeam release 1100z stamp 47739#015#012Content-Length: 0#015#012#015#012.
: DEBUG: <core> [forward.c:611]: orig. len=669, new_len=730, proto=1
: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
: DEBUG: <core> [parser/msg_parser.c:624]: SIP Request:
: DEBUG: <core> [parser/msg_parser.c:626]:  method:  <ACK>
: DEBUG: <core> [parser/msg_parser.c:628]:  uri:     <sip:xxx.xxx.xxx.xxx(k2):5003;lr;did=763.941>
: DEBUG: <core> [parser/msg_parser.c:630]:  version: <SIP/2.0>
: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bKcydzigwkX>; state=16
: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2
: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via
: DEBUG: <core> [receive.c:149]: After parse_msg...
: DEBUG: <core> [receive.c:190]: preparing to run routing scripts...
: DEBUG: sl [sl_funcs.c:396]: DEBUG : sl_filter_ACK: to late to be a local ACK!
: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bKcydzigwkX>; state=16
: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=10
: DEBUG: <core> [parser/msg_parser.c:524]: parse_headers: this is the second via
: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 234, <received> = <122.116.37.55>; state=6
: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bK-d8754z-5479c6361610102b-1---d8754z->; state=6
: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <60496>; state=16
: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=10
: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=cjLZLUdZz5lKpi1gtGak.otJWQzet.vf
: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [73]; uri=[sip:5001005 at xxx.xxx.xxx.xxx(k2)]
: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body ["5001005"<sip:5001005 at xxx.xxx.xxx.xxx(k2)>]
: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=1944d063
: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
: DEBUG: siptrace [siptrace.c:794]: storing info...
: DEBUG: maxfwd [mf_funcs.c:85]: value = 68
: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=43 global id=42 T start=(nil)
: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <1> <ACK>
: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=0
: DEBUG: <core> [parser/msg_parser.c:104]: found end of header
: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=41886, isACK=1
: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=43 global id=43 T end=(nil)
: DEBUG: rr [loose.c:111]: No Route headers found
: DEBUG: rr [loose.c:835]: There is no Route HF
: DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f32b6d1cc30
: DEBUG: db_mysql [km_res.c:68]: 16 columns returned from the query
: DEBUG: <core> [db_res.c:155]: allocate 128 bytes for result names at 0x7f32b6d1ccc8
: DEBUG: <core> [db_res.c:165]: allocate 64 bytes for result types at 0x7f32b6d1bdb8
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f32b6d1cc10
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cc10)[0]=[contact]
: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[1] at 0x7f32b6d1cc78
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cc78)[1]=[expires]
: DEBUG: db_mysql [km_res.c:116]: use DB1_DATETIME result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[2] at 0x7f32b6d1c958
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1c958)[2]=[q]
: DEBUG: db_mysql [km_res.c:111]: use DB1_DOUBLE result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[3] at 0x7f32b6d1cda8
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cda8)[3]=[callid]
: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[4] at 0x7f32b6d1cdc8
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cdc8)[4]=[cseq]
: DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[5] at 0x7f32b6d1cde8
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cde8)[5]=[flags]
: DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[6] at 0x7f32b6d1ce08
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1ce08)[6]=[cflags]
: DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[7] at 0x7f32b6d1ce28
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1ce28)[7]=[user_agent]
: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[8] at 0x7f32b6d1ce48
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1ce48)[8]=[received]
: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[9] at 0x7f32b6d1ce68
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1ce68)[9]=[path]
: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[10] at 0x7f32b6d1ce88
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1ce88)[10]=[socket]
: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[11] at 0x7f32b6d1cea8
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cea8)[11]=[methods]
: DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[12] at 0x7f32b6d1cec8
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cec8)[12]=[last_modified]
: DEBUG: db_mysql [km_res.c:116]: use DB1_DATETIME result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[13] at 0x7f32b6d1cee8
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cee8)[13]=[ruid]
: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[14] at 0x7f32b6d1cf08
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cf08)[14]=[instance]
: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[15] at 0x7f32b6d1cf28
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cf28)[15]=[reg_id]
: DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
: DEBUG: db_mysql [km_res.c:169]: no rows returned from the query
: DEBUG: usrloc [udomain.c:599]: aor  not found in table aliases
: DEBUG: <core> [db_res.c:81]: freeing 16 columns
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f32b6d1cc10
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[1] at 0x7f32b6d1cc78
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[2] at 0x7f32b6d1c958
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[3] at 0x7f32b6d1cda8
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[4] at 0x7f32b6d1cdc8
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[5] at 0x7f32b6d1cde8
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[6] at 0x7f32b6d1ce08
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[7] at 0x7f32b6d1ce28
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[8] at 0x7f32b6d1ce48
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[9] at 0x7f32b6d1ce68
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[10] at 0x7f32b6d1ce88
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[11] at 0x7f32b6d1cea8
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[12] at 0x7f32b6d1cec8
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[13] at 0x7f32b6d1cee8
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[14] at 0x7f32b6d1cf08
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[15] at 0x7f32b6d1cf28
: DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f32b6d1ccc8
: DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f32b6d1bdb8
: DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f32b6d1cc30
: DEBUG: registrar [lookup.c:158]: '' Not found in usrloc
: DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f32b6d1cc30
: DEBUG: db_mysql [km_res.c:68]: 16 columns returned from the query
: DEBUG: <core> [db_res.c:155]: allocate 128 bytes for result names at 0x7f32b6d1ccc8
: DEBUG: <core> [db_res.c:165]: allocate 64 bytes for result types at 0x7f32b6d1bdb8
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f32b6d1cf28
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cf28)[0]=[contact]
: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[1] at 0x7f32b6d1cf08
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cf08)[1]=[expires]
: DEBUG: db_mysql [km_res.c:116]: use DB1_DATETIME result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[2] at 0x7f32b6d1cee8
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cee8)[2]=[q]
: DEBUG: db_mysql [km_res.c:111]: use DB1_DOUBLE result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[3] at 0x7f32b6d1cec8
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cec8)[3]=[callid]
: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[4] at 0x7f32b6d1cea8
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cea8)[4]=[cseq]
: DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[5] at 0x7f32b6d1ce88
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1ce88)[5]=[flags]
: DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[6] at 0x7f32b6d1ce68
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1ce68)[6]=[cflags]
: DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[7] at 0x7f32b6d1ce48
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1ce48)[7]=[user_agent]
: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[8] at 0x7f32b6d1ce28
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1ce28)[8]=[received]
: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[9] at 0x7f32b6d1ce08
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1ce08)[9]=[path]
: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[10] at 0x7f32b6d1cde8
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cde8)[10]=[socket]
: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[11] at 0x7f32b6d1cdc8
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cdc8)[11]=[methods]
: DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[12] at 0x7f32b6d1cda8
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cda8)[12]=[last_modified]
: DEBUG: db_mysql [km_res.c:116]: use DB1_DATETIME result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[13] at 0x7f32b6d1c958
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1c958)[13]=[ruid]
: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[14] at 0x7f32b6d1cc78
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cc78)[14]=[instance]
: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
: DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[15] at 0x7f32b6d1cc10
: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f32b6d1cc10)[15]=[reg_id]
: DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
: DEBUG: db_mysql [km_res.c:169]: no rows returned from the query
: DEBUG: usrloc [udomain.c:599]: aor  not found in table location
: DEBUG: <core> [db_res.c:81]: freeing 16 columns
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f32b6d1cf28
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[1] at 0x7f32b6d1cf08
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[2] at 0x7f32b6d1cee8
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[3] at 0x7f32b6d1cec8
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[4] at 0x7f32b6d1cea8
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[5] at 0x7f32b6d1ce88
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[6] at 0x7f32b6d1ce68
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[7] at 0x7f32b6d1ce48
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[8] at 0x7f32b6d1ce28
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[9] at 0x7f32b6d1ce08
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[10] at 0x7f32b6d1cde8
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[11] at 0x7f32b6d1cdc8
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[12] at 0x7f32b6d1cda8
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[13] at 0x7f32b6d1c958
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[14] at 0x7f32b6d1cc78
: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[15] at 0x7f32b6d1cc10
: DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f32b6d1ccc8
: DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f32b6d1bdb8
: DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f32b6d1cc30
: DEBUG: registrar [lookup.c:158]: '' Not found in usrloc
: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=43 global id=43 T start=(nil)
: DEBUG: tm [t_lookup.c:1154]: DEBUG: t_check_msg: T previously sought and not found
: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up


More information about the sr-users mailing list