/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@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@xxx.xxx.xxx.xxx(k2)]
: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body ["5001005"<sip:5001005@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@122.116.37.55:60496;transport=udp>#015#012To: "5001005"<sip:5001005@xxx.xxx.xxx.xxx(k2)>;tag=cjLZLUdZz5lKpi1gtGak.otJWQzet.vf#015#012From: "5978003"<sip:5978003@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@xxx.xxx.xxx.xxx(k2)]
: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body ["5001005"<sip:5001005@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