Sep 19 15:19:08 testServer /usr/sbin/kamailio[2031]: INFO: <core> [cfg/cfg_ctx.c:613]: INFO: cfg_set_now(): core.debug has been changed to 4
Sep 19 15:19:08 testServer /usr/sbin/kamailio[2024]: DEBUG: pike [timer.c:77]: splitting 0xaf5976d0(0xaf5094c8,0xaf5094c8)node=0xaf5976c0
Sep 19 15:19:08 testServer /usr/sbin/kamailio[2024]: DEBUG: pike [timer.c:102]: succ. to split (h=0xaf5094c8)(p=0xaf5094c8,n=0xaf5094c8)
Sep 19 15:19:08 testServer /usr/sbin/kamailio[2024]: DEBUG: pike [pike_funcs.c:225]: clean node 0xaf5976c0 (kids=(nil); hits=[0,0];leaf=[0,0])
Sep 19 15:19:08 testServer /usr/sbin/kamailio[2024]: DEBUG: pike [pike_funcs.c:256]: rmv node 0xaf5976c0[10] 
Sep 19 15:19:08 testServer /usr/sbin/kamailio[2024]: DEBUG: pike [ip_tree.c:350]: destroying node 0xaf5976c0
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/msg_parser.c:624]: SIP Request:
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/msg_parser.c:626]:  method:  <INVITE>
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/msg_parser.c:628]:  uri:     <sip:global.group@mydomain.com>
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/msg_parser.c:630]:  version: <SIP/2.0>
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <n/a>; state=6
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bKepkkqpen>; state=16
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [receive.c:149]: After parse_msg...
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts...
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: INFO: <script>: route: **** Route start here ***** 
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: pv [pv_core.c:900]: no destination URI
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=10
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [33]; uri=[sip:global.group@mydomain.com] 
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body [<sip:global.group@mydomain.com>#015#012]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: INFO: <script>: route: INVITE: set FLT_FORCE_MEDIAPROXY: ru=sip:global.group@mydomain.com, du=<null>, src=10.82.130.135:5060, callid=grxlcwjmtjephdu@yufei-laptop
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.135] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: last message repeated 2 times
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=gkrni
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <139> <INVITE>
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: INFO: <script>: REQINIT: method=INVITE, sip:y1@mydomain.com -> sip:global.group@mydomain.com, callid=grxlcwjmtjephdu@yufei-laptop, cseq=139
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.135] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: last message repeated 2 times
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: pike [ip_tree.c:279]: search on branch 10 (top=(nil))
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: pike [ip_tree.c:292]: only first 0 were matched!
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: pike [pike_funcs.c:97]: src IP [10.82.130.135],node=0xaf5976c0; hits=[0,1],[0,0] node_flags=0 func_flags=1
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: pike [timer.c:39]: 0xaf5976d0 in 0xaf5094c8(0xaf5094c8,0xaf5094c8)
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=311
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [parser/msg_parser.c:104]: found end of header
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: pv [pv_trans.c:300]: i=7 j=0
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: INFO: <script>: DROUTING: INVITE: b4 do_routing: ru=sip:global.group@mydomain.com
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: drouting [drouting.c:675]: empty ruting table
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: permissions [permissions.c:334]: no rules => allow any routing
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: INFO: <script>: NAT_fix_contact: method=INVITE, callid=grxlcwjmtjephdu@yufei-laptop, cseq=139
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.135, 10.82.130.135, 0)
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: INFO: <script>: WITHINDLG: method=INVITE, callid=grxlcwjmtjephdu@yufei-laptop, cseq=139
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=20 global id=19 T start=0xffffffff
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=36209, isACK=0
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=20 global id=20 T end=(nil)
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: INFO: <script>: AUTH: method=INVITE, sip:y1@mydomain.com -> sip:global.group@mydomain.com, ru=sip:global.group@mydomain.com, callid=grxlcwjmtjephdu@yufei-laptop, cseq=139
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: permissions [address.c:403]: looking for <1, 8782520a, 5060>
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.135] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: last message repeated 2 times
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: auth_db [authorize.c:274]: realm value [mydomain.com]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: auth [api.c:86]: auth:pre_auth: Credentials with realm 'mydomain.com' not found
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: auth_db [authorize.c:180]: no credentials
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: tm [t_lookup.c:1379]: DEBUG: t_newtran: msg id=20 , global msg id=20 , T on entrance=(nil)
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=36209, isACK=0
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: tm [t_hooks.c:374]: DBG: trans=0xaf59c3f0, callback type 1, id 0 entered
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: tm [t_hooks.c:374]: DBG: trans=0xaf59c3f0, callback type 1, id 0 entered
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: auth [challenge.c:128]: build_challenge_hf: realm='mydomain.com'
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: auth [challenge.c:270]: auth: 'Proxy-Authenticate: Digest realm="mydomain.com", nonce="UFnViVBZ1F3Ahab5cyszIBt4ZFVoG6n0"#015#012'
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=20 global id=20 T start=0xaf59c3f0
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: tm [t_lookup.c:1151]: DEBUG: t_check_msg: T already found!
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.135, 10.82.130.135, 0)
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [mem/shm_mem.c:111]: WARNING:vqm_resize: resize(0) called
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: tm [t_reply.c:1543]: DEBUG: cleanup_uac_timers: RETR/FR timers reset
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: tm [t_reply.c:703]: DEBUG: reply sent out. buf=0xb74243d8: SIP/2.0 407 Proxy Au..., shmem=0xaf59db78: SIP/2.0 407 Proxy Au
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: tm [t_reply.c:713]: DEBUG: _reply_light: finished
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: sl [sl.c:273]: reply in stateful mode (tm)
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/msg_parser.c:624]: SIP Request:
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/msg_parser.c:624]: SIP Request:
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/msg_parser.c:626]:  method:  <ACK>
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/msg_parser.c:626]:  method:  <INVITE>
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/msg_parser.c:628]:  uri:     <sip:global.group@mydomain.com>
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/msg_parser.c:628]:  uri:     <sip:global.group@mydomain.com>
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/msg_parser.c:630]:  version: <SIP/2.0>
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/msg_parser.c:630]:  version: <SIP/2.0>
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <n/a>; state=6
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <n/a>; state=6
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bKepkkqpen>; state=16
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bKsbthkwcq>; state=16
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2010]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [receive.c:149]: After parse_msg...
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [receive.c:149]: After parse_msg...
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts...
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts...
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2011]: DEBUG: sl [sl_funcs.c:396]: DEBUG : sl_filter_ACK: to late to be a local ACK!
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: INFO: <script>: route: **** Route start here ***** 
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: pv [pv_core.c:900]: no destination URI
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=10
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [33]; uri=[sip:global.group@mydomain.com] 
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body [<sip:global.group@mydomain.com>#015#012]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: INFO: <script>: route: INVITE: set FLT_FORCE_MEDIAPROXY: ru=sip:global.group@mydomain.com, du=<null>, src=10.82.130.135:5060, callid=grxlcwjmtjephdu@yufei-laptop
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.135] == [10.82.130.244]
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: last message repeated 2 times
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:10 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=gkrni
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <140> <INVITE>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: REQINIT: method=INVITE, sip:y1@mydomain.com -> sip:global.group@mydomain.com, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.135] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: last message repeated 2 times
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: pike [ip_tree.c:279]: search on branch 10 (top=0xaf5976c0)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: INFO: <script>: route: **** Route start here ***** 
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: pike [ip_tree.c:292]: only first 1 were matched!
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.135] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: pike [pike_funcs.c:97]: src IP [10.82.130.135],node=0xaf5976c0; hits=[0,2],[0,0] node_flags=2 func_flags=8
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.135] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.135] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=311
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [parser/msg_parser.c:104]: found end of header
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=f8e4b26bee3ae2696576016b7874d8bd-c26d
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [75]; uri=[sip:global.group@mydomain.com] 
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body [<sip:global.group@mydomain.com>]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=gkrni
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <139> <ACK>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: INFO: <script>: REQINIT: method=ACK, sip:y1@mydomain.com -> sip:global.group@mydomain.com, callid=grxlcwjmtjephdu@yufei-laptop, cseq=139
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.135] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.135] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.135] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: pike [ip_tree.c:279]: search on branch 10 (top=0xaf5976c0)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: pike [ip_tree.c:292]: only first 1 were matched!
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: pike [pike_funcs.c:97]: src IP [10.82.130.135],node=0xaf5976c0; hits=[0,3],[0,0] node_flags=2 func_flags=8
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: pv [pv_trans.c:300]: i=7 j=0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: DROUTING: INVITE: b4 do_routing: ru=sip:global.group@mydomain.com
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [parser/msg_parser.c:104]: found end of header
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: drouting [drouting.c:675]: empty ruting table
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: permissions [permissions.c:334]: no rules => allow any routing
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: siputils [checks.c:106]: totag found
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: siputils [checks.c:106]: totag found
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: INFO: <script>: NAT_fix_contact: method=ACK, callid=grxlcwjmtjephdu@yufei-laptop, cseq=139
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.135, 10.82.130.135, 0)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: INFO: <script>: WITHINDLG: method=ACK, callid=grxlcwjmtjephdu@yufei-laptop, cseq=139
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: siputils [checks.c:106]: totag found
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: pv [pv_core.c:900]: no destination URI
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: INFO: <script>: WITHINDLG: ACK: b4 loose_route: route=<null>, ru=sip:global.group@mydomain.com, du=<null>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: rr [loose.c:108]: No Route headers found
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: rr [loose.c:829]: There is no Route HF
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=23 global id=22 T start=(nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=36209, isACK=1
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: tm [t_lookup.c:470]: DEBUG: RFC3261 transaction matched, tid=epkkqpen
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: tm [t_lookup.c:726]: DEBUG: t_lookup_request: transaction found (T=0xaf59c3f0)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=23 global id=23 T end=0xaf59c3f0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: NAT_fix_contact: method=INVITE, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.135, 10.82.130.135, 0)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: WITHINDLG: method=INVITE, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=23 global id=22 T start=(nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=35528, isACK=0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: tm [t_reply.c:1543]: DEBUG: cleanup_uac_timers: RETR/FR timers reset
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=23 global id=23 T end=(nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [timer.c:595]: timer_add called on an active timer 0xaf59c438 (0xaf330258, 0xaf330258), flags 201
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: AUTH: method=INVITE, sip:y1@mydomain.com -> sip:global.group@mydomain.com, ru=sip:global.group@mydomain.com, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: tm [t_funcs.c:184]: tm: put_on_wait: transaction 0xaf59c3f0 already on wait
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: permissions [address.c:403]: looking for <1, 8782520a, 5060>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.135] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: last message repeated 2 times
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: auth_db [authorize.c:274]: realm value [mydomain.com]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: auth [api.c:96]: auth: digest-algo: MD5 parsed value: 1
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:118]: allocate 28 bytes for result set at 0xb7424338
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: db_mysql [km_res.c:68]: 2 columns returned from the query
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0xb7375298
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:165]: allocate 8 bytes for result types at 0xb7376618
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[0] at 0xb737d3f0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb737d3f0)[0]=[ha1]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: last message repeated 4 times
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2011]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[1] at 0xb736f708
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb736f708)[1]=[rpid]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:184]: allocate 8 bytes for rows at 0xb736f648
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_row.c:119]: allocate 40 bytes for row values at 0xb7423fb8
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_val.c:117]: converting STRING [15cbd3d8d95c7f7d95382a1076c6807d]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_val.c:56]: converting NULL value
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: auth [api.c:211]: check_response: Our result = 'fa63f73de8ff6bb8a4419cbbf4a3cbbe'
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: auth [api.c:218]: check_response: Authorization is OK
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:81]: freeing 2 columns
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0xb737d3f0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[1] at 0xb736f708
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:94]: freeing result names at 0xb7375298
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:99]: freeing result types at 0xb7376618
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:54]: freeing 1 rows
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_row.c:97]: freeing row values at 0xb7423fb8
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:62]: freeing rows at 0xb736f648
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:136]: freeing result set at 0xb7424338
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: INVITE: method=INVITE, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140, sip:y1@mydomain.com->sip:global.group@mydomain.com
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: SIPOUT: method=INVITE, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140, sip:y1@mydomain.com->sip:global.group@mydomain.com
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: LOCATION: method=INVITE, sip:y1@mydomain.com -> sip:global.group@mydomain.com, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:118]: allocate 28 bytes for result set at 0xb74247e0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: db_mysql [km_res.c:68]: 2 columns returned from the query
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0xb737d3f0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:165]: allocate 8 bytes for result types at 0xb7379f00
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[0] at 0xb741bcf0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb741bcf0)[0]=[username]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[1] at 0xb741bd10
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb741bd10)[1]=[domain]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:184]: allocate 16 bytes for rows at 0xb73c4aa8
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_row.c:119]: allocate 40 bytes for row values at 0xb74243d8
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_val.c:117]: converting STRING [y2]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_val.c:117]: converting STRING [mydomain.com]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_row.c:119]: allocate 40 bytes for row values at 0xb7424408
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_val.c:117]: converting STRING [y3]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_val.c:117]: converting STRING [mydomain.com]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: alias_db [alookup.c:192]: new URI [0] is [sip:y2@mydomain.com]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: alias_db [alookup.c:192]: new URI [1] is [sip:y3@mydomain.com]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:81]: freeing 2 columns
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0xb741bcf0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[1] at 0xb741bd10
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:94]: freeing result names at 0xb737d3f0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:99]: freeing result types at 0xb7379f00
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:54]: freeing 2 rows
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_row.c:97]: freeing row values at 0xb74243d8
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_row.c:97]: freeing row values at 0xb7424408
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:62]: freeing rows at 0xb73c4aa8
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [db_res.c:136]: freeing result set at 0xb74247e0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: LOCATION: About to do t_on_branch ...
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: LOCATION: INVITE - set missed flag: sip:y1@mydomain.com -> sip:global.group@mydomain.com
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: RELAY: method=INVITE, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: RTPPROXY: method=INVITE, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140, sip:y1@mydomain.com->sip:global.group@mydomain.com
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: >>>>>>>>>>>>>>>>>>>>>>>>>>>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: RTPPROXY:INVITE: callid=grxlcwjmtjephdu@yufei-laptop, cseq=140, sip:y1@mydomain.com->sip:global.group@mydomain.com
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: RELAY: INVITE: ru=sip:y2@mydomain.com, route: <null>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: pv [pv_core.c:900]: no destination URI
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: RELAY: INVITE: ru=sip:y2@mydomain.com, du=<null>, route: <null>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: tm [t_lookup.c:1379]: DEBUG: t_newtran: msg id=23 , global msg id=23 , T on entrance=(nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=35528, isACK=0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: tm [t_hooks.c:374]: DBG: trans=0xaf59ddc8, callback type 1, id 0 entered
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: dialog [dlg_hash.c:652]: no dialog callid='grxlcwjmtjephdu@yufei-laptop' found
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: dialog [dlg_hash.c:683]: no dialog callid='grxlcwjmtjephdu@yufei-laptop' found
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: dialog [dlg_hash.c:438]: new dialog on hash 3958
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: dialog [dlg_handlers.c:274]: route_set , contact sip:y1@10.82.130.135, cseq 140 and bind_addr udp:10.82.130.244:5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: dialog [dlg_hash.c:706]: linking dialog [3958:1311]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: dialog [dlg_hash.c:715]: ref dlg 0xaf597938 with 1 -> 1
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: dialog [dlg_cb.c:230]: dialog=0xaf597938
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: ERROR: mediaproxy [mediaproxy.c:1433]: failed to connect to /var/run/mediaproxy/dispatcher.sock: No such file or directory
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: dialog [dlg_hash.c:735]: ref dlg 0xaf597938 with 1 -> 2
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: dialog [dlg_profile.c:406]: setting current dialog [3958:1311]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: dialog [dlg_hash.c:753]: unref dlg 0xaf597938 with 1 -> 1
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: dialog [dlg_hash.c:588]: ref dlg 0xaf597938 with 1 -> 2
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: dialog [dlg_hash.c:590]: dialog id=1311 found on entry 3958
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: dialog [dlg_hash.c:753]: unref dlg 0xaf597938 with 1 -> 1
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: tm [t_hooks.c:374]: DBG: trans=0xaf59ddc8, callback type 1, id 0 entered
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: tm [t_funcs.c:351]: SER: new INVITE
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.135, 10.82.130.135, 0)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [mem/shm_mem.c:111]: WARNING:vqm_resize: resize(0) called
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: tm [t_reply.c:703]: DEBUG: reply sent out. buf=0xb7424498: SIP/2.0 100 trying -..., shmem=0xaf59f6c0: SIP/2.0 100 trying -
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: tm [t_reply.c:713]: DEBUG: _reply_light: finished
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: BRANCH_ALIASDB: INVITE: idx=1/1: ruir=y2@mydomain.com
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: LOCATION_BRANCH: INVITE: idx=1/1: b4 lookup:ruir=y2@mydomain.com; from=y1@mydomain.com
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: pv [pv_core.c:900]: no destination URI
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: LOCATION_BRANCH: method=INVITE, callid=grxlcwjmtjephdu@yufei-laptop,cseq=140, y1@mydomain.com -> global.group@mydomain.com, ru=sip:y2@mydomain.com, du=<null>: not dropped - look up location
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: registrar [lookup.c:181]: contact for [y2] found by address
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: pv [pv_core.c:900]: no destination URI
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: LOCATION_BRANCH: method=INVITE, callid=grxlcwjmtjephdu@yufei-laptop,cseq=140, y1@mydomain.com -> global.group@mydomain.com, ru=sip:y2@10.82.130.140:19322;rinstance=cf85dcd5a9916cac;transport=udp, du=<null>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: LOCATION_BRANCH: num branches dropped=0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.135, 10.82.130.135, 0)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: BRANCH_ALIASDB: INVITE: idx=2/1: ruir=y3@mydomain.com
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: LOCATION_BRANCH: INVITE: idx=2/1: b4 lookup:ruir=y3@mydomain.com; from=y1@mydomain.com
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: pv [pv_core.c:900]: no destination URI
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: LOCATION_BRANCH: method=INVITE, callid=grxlcwjmtjephdu@yufei-laptop,cseq=140, y1@mydomain.com -> global.group@mydomain.com, ru=sip:y3@mydomain.com, du=<null>: not dropped - look up location
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: registrar [lookup.c:181]: contact for [y3] found by address
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: pv [pv_core.c:900]: no destination URI
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: LOCATION_BRANCH: method=INVITE, callid=grxlcwjmtjephdu@yufei-laptop,cseq=140, y1@mydomain.com -> global.group@mydomain.com, ru=sip:y3@10.82.130.140:53444;rinstance=6ca01e6627a2cc61, du=<null>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: INFO: <script>: LOCATION_BRANCH: num branches dropped=0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [dns_cache.c:569]: dns_hash_find(_sip._udp.mydomain.com(22), 33), h=1022
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [resolve.c:727]: get_record: lookup(_sip._udp.mydomain.com, 33) failed
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [dns_cache.c:897]: dns_cache_mk_bad_entry(_sip._udp.mydomain.com, 33, 60, 1)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [dns_cache.c:830]: dns_cache_add: adding _sip._udp.mydomain.com(22) 33 (flags=1) at 1022
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [dns_cache.c:569]: dns_hash_find(mydomain.com(12), 1), h=818
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [resolve.c:924]: get_record: skipping 0 NS (p=0x825ac4e, end=0x825ac4e)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [resolve.c:940]: get_record: parsing 0 ARs (p=0x825ac4e, end=0x825ac4e)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [dns_cache.c:1779]: dns_get_related(0xaf589fa0 (mydomain.com, 1), 1, *(nil)) (0)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [dns_cache.c:872]: dns_cache_add: adding mydomain.com(12) 1 (flags=0) at 818
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.135, 10.82.130.135, 0)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: tm [t_funcs.c:388]: SER: new transaction fwd'ed
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: last message repeated 5 times
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/msg_parser.c:624]: SIP Request:
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/msg_parser.c:626]:  method:  <INVITE>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2012]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/msg_parser.c:628]:  uri:     <sip:y3@10.82.130.140:53444;rinstance=6ca01e6627a2cc61>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/msg_parser.c:630]:  version: <SIP/2.0>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bK8ca8.b21b77d6.1>; state=16
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [receive.c:149]: After parse_msg...
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts...
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: INFO: <script>: route: **** Route start here ***** 
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: pv [pv_core.c:900]: no destination URI
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <5060>; state=6
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bKsbthkwcq>; state=16
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=40
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/msg_parser.c:524]: parse_headers: this is the second via
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=10
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [33]; uri=[sip:global.group@mydomain.com] 
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body [<sip:global.group@mydomain.com>#015#012]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: INFO: <script>: route: INVITE: set FLT_FORCE_MEDIAPROXY: ru=sip:y3@10.82.130.140:53444;rinstance=6ca01e6627a2cc61, du=<null>, src=10.82.130.244:5060, callid=grxlcwjmtjephdu@yufei-laptop
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.244] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: INFO: <script>: route: INVITE: rU=y3, is from myself
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=gkrni
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <140> <INVITE>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: INFO: <script>: REQINIT: method=INVITE, sip:y1@mydomain.com -> sip:global.group@mydomain.com, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.244] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: maxfwd [mf_funcs.c:85]: value = 69 
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=311
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [parser/msg_parser.c:104]: found end of header
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.140] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 53444
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.140] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 53444
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.140] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 53444
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: pv [pv_trans.c:300]: i=3 j=0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: ERROR: pv [pv_trans.c:312]: substr out of range
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: ERROR: <core> [lvalue.c:347]: non existing right pvar
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: ERROR: <core> [lvalue.c:407]: assignment failed at pos: (2577,20-2577,57)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: INFO: <script>: DROUTING: INVITE: b4 do_routing: ru=sip:y3@10.82.130.140:53444;rinstance=6ca01e6627a2cc61
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: drouting [drouting.c:675]: empty ruting table
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: permissions [permissions.c:334]: no rules => allow any routing
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.140] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 53444
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.140] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 53444
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.140] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 53444
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: INFO: <script>: NAT_fix_contact: method=INVITE, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.244, 10.82.130.244, 0)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: INFO: <script>: WITHINDLG: method=INVITE, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=21 global id=20 T start=(nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=35528, isACK=0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=21 global id=21 T end=(nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: INFO: <script>: AUTH: method=INVITE, sip:y1@mydomain.com -> sip:global.group@mydomain.com, ru=sip:y3@10.82.130.140:53444;rinstance=6ca01e6627a2cc61, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: permissions [address.c:403]: looking for <1, f482520a, 5060>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.244] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: auth_db [authorize.c:274]: realm value [mydomain.com]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: auth [api.c:86]: auth:pre_auth: Credentials with realm 'mydomain.com' not found
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: auth_db [authorize.c:180]: no credentials
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: tm [t_lookup.c:1379]: DEBUG: t_newtran: msg id=21 , global msg id=21 , T on entrance=(nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=35528, isACK=0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: tm [t_hooks.c:374]: DBG: trans=0xaf5a0198, callback type 1, id 0 entered
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: tm [t_hooks.c:374]: DBG: trans=0xaf5a0198, callback type 1, id 0 entered
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: auth [challenge.c:128]: build_challenge_hf: realm='mydomain.com'
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: auth [challenge.c:270]: auth: 'Proxy-Authenticate: Digest realm="mydomain.com", nonce="UFnViVBZ1F3Ahab5cyszIBt4ZFVoG6n0"#015#012'
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=21 global id=21 T start=0xaf5a0198
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: tm [t_lookup.c:1151]: DEBUG: t_check_msg: T already found!
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.244, 10.82.130.244, 0)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [mem/shm_mem.c:111]: WARNING:vqm_resize: resize(0) called
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: tm [t_reply.c:1543]: DEBUG: cleanup_uac_timers: RETR/FR timers reset
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: tm [t_reply.c:703]: DEBUG: reply sent out. buf=0xb74245f8: SIP/2.0 407 Proxy Au..., shmem=0xaf5a1c88: SIP/2.0 407 Proxy Au
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/msg_parser.c:634]: SIP Reply  (status):
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: tm [t_reply.c:713]: DEBUG: _reply_light: finished
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/msg_parser.c:636]:  version: <SIP/2.0>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: sl [sl.c:273]: reply in stateful mode (tm)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/msg_parser.c:638]:  status:  <407>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/msg_parser.c:640]:  reason:  <Proxy Authentication Required>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bK8ca8.b21b77d6.1>; state=6
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <5060>; state=16
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [receive.c:149]: After parse_msg...
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2018]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=8 global id=7 T start=(nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <5060>; state=6
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bKsbthkwcq>; state=16
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=ffffffffffffffff
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/msg_parser.c:524]: parse_headers: this is the second via
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=f8e4b26bee3ae2696576016b7874d8bd-9bb6
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [75]; uri=[sip:global.group@mydomain.com] 
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body [<sip:global.group@mydomain.com>]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <140> <INVITE>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/msg_parser.c:104]: found end of header
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: tm [t_lookup.c:965]: DEBUG: t_reply_matching: hash 35528 label 1836560683 branch 1
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: tm [t_lookup.c:1016]: DEBUG: t_reply_matching: reply matched (T=0xaf59ddc8)!
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: tm [t_hooks.c:288]: DBG: trans=0xaf59ddc8, callback type 2, id 0 entered
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: acc [acc_logic.c:497]: acc callback called for t(0xaf59ddc8) event type 2, reply code 407
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=8 global id=8 T end=0xaf59ddc8
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: tm [t_reply.c:2081]: DEBUG: reply_received: org. status uas=100, uac[1]=0 local=0 is_invite=1)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=gkrni
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/msg_parser.c:624]: SIP Request:
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/msg_parser.c:626]:  method:  <ACK>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: INFO: <script>: REPLY_NAT_INVITE_FIX_CONTACT: (Proxy Authentication Required 407): cseq=140, srcIP=10.82.130.244:5060, y1->global.group
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/msg_parser.c:628]:  uri:     <sip:y3@10.82.130.140:53444;rinstance=6ca01e6627a2cc61>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: tm [t_reply.c:1204]: ->>>>>>>>> T_code=100, new_code=407
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/msg_parser.c:630]:  version: <SIP/2.0>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: tm [t_reply.c:1702]: DEBUG: relay_reply: branch=1, save=1, relay=-1
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bK8ca8.b21b77d6.1>; state=16
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [receive.c:149]: After parse_msg...
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts...
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: sl [sl_funcs.c:396]: DEBUG : sl_filter_ACK: to late to be a local ACK!
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: INFO: <script>: route: **** Route start here ***** 
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2023]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.244] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: INFO: <script>: route: INVITE: rU=y3, is from myself
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=f8e4b26bee3ae2696576016b7874d8bd-9bb6
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [75]; uri=[sip:global.group@mydomain.com] 
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body [<sip:global.group@mydomain.com>]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=gkrni
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <140> <ACK>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: INFO: <script>: REQINIT: method=ACK, sip:y1@mydomain.com -> sip:global.group@mydomain.com, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.244] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [parser/msg_parser.c:104]: found end of header
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: pv [pv_core.c:665]: no User-Agent header
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: pv [pv_core.c:665]: no User-Agent header
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: maxfwd [mf_funcs.c:85]: value = 69 
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: siputils [checks.c:106]: totag found
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: siputils [checks.c:106]: totag found
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: INFO: <script>: NAT_fix_contact: method=ACK, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.244, 10.82.130.244, 0)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: INFO: <script>: WITHINDLG: method=ACK, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: siputils [checks.c:106]: totag found
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: pv [pv_core.c:900]: no destination URI
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: INFO: <script>: WITHINDLG: ACK: b4 loose_route: route=<null>, ru=sip:y3@10.82.130.140:53444;rinstance=6ca01e6627a2cc61, du=<null>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: rr [loose.c:108]: No Route headers found
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: rr [loose.c:829]: There is no Route HF
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=14 global id=13 T start=(nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=35528, isACK=1
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: tm [t_lookup.c:470]: DEBUG: RFC3261 transaction matched, tid=8ca8.b21b77d6.1
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: tm [t_lookup.c:726]: DEBUG: t_lookup_request: transaction found (T=0xaf5a0198)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=14 global id=14 T end=0xaf5a0198
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: tm [t_reply.c:1543]: DEBUG: cleanup_uac_timers: RETR/FR timers reset
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [timer.c:595]: timer_add called on an active timer 0xaf5a01e0 (0xaf330268, 0xaf330268), flags 201
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: tm [t_funcs.c:184]: tm: put_on_wait: transaction 0xaf5a0198 already on wait
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: last message repeated 5 times
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2021]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/msg_parser.c:634]: SIP Reply  (status):
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/msg_parser.c:636]:  version: <SIP/2.0>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/msg_parser.c:638]:  status:  <180>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/msg_parser.c:640]:  reason:  <Ringing>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bK8ca8.b21b77d6.0>; state=16
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [receive.c:149]: After parse_msg...
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=15 global id=14 T start=(nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <5060>; state=6
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bKsbthkwcq>; state=16
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=22
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/msg_parser.c:524]: parse_headers: this is the second via
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=a10f0861
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [46]; uri=[sip:global.group@mydomain.com] 
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body [<sip:global.group@mydomain.com>]
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <140> <INVITE>
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: tm [t_lookup.c:965]: DEBUG: t_reply_matching: hash 35528 label 1836560683 branch 0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: tm [t_lookup.c:1016]: DEBUG: t_reply_matching: reply matched (T=0xaf59ddc8)!
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: tm [t_hooks.c:288]: DBG: trans=0xaf59ddc8, callback type 2, id 0 entered
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: acc [acc_logic.c:497]: acc callback called for t(0xaf59ddc8) event type 2, reply code 180
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=15 global id=15 T end=0xaf59ddc8
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: tm [t_reply.c:2081]: DEBUG: reply_received: org. status uas=100, uac[0]=0 local=0 is_invite=1)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=gkrni
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: INFO: <script>: REPLY_NAT_INVITE_FIX_CONTACT: (Ringing 180): cseq=140, srcIP=10.82.130.140:19322, y1->global.group
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: tm [t_reply.c:1204]: ->>>>>>>>> T_code=100, new_code=180
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: tm [t_reply.c:1702]: DEBUG: relay_reply: branch=0, save=0, relay=0
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: tm [t_hooks.c:288]: DBG: trans=0xaf59ddc8, callback type 32, id 0 entered
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: dialog [dlg_hash.c:588]: ref dlg 0xaf597938 with 1 -> 2
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: dialog [dlg_hash.c:590]: dialog id=1311 found on entry 3958
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: dialog [dlg_cb.c:256]: dialog=0xaf597938, type=512
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [parser/msg_parser.c:104]: found end of header
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: dialog [dlg_hash.c:753]: unref dlg 0xaf597938 with 1 -> 1
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [msg_translator.c:1933]:  old size: 496, new size: 434
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [msg_translator.c:1951]: build_res_from_sip_res: copied size: orig:83, new: 21, rest: 413 msg=#012SIP/2.0 180 Ringing#015#012Via: SIP/2.0/UDP 10.82.130.135;rport=5060;branch=z9hG4bKsbthkwcq#015#012Record-Route: <sip:10.82.130.244;lr;ftag=gkrni>#015#012Contact: <sip:y2@10.82.130.140:19322;rinstance=cf85dcd5a9916cac;transport=udp>#015#012To: <sip:global.group@mydomain.com>;tag=a10f0861#015#012From: "y1"<sip:y1@mydomain.com>;tag=gkrni#015#012Call-ID: grxlcwjmtjephdu@yufei-laptop#015#012CSeq: 140 INVITE#015#012User-Agent: eyeBeam release 1105a stamp 56793#015#012Content-Length: 0#015#012#015#012
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: tm [t_hooks.c:288]: DBG: trans=0xaf59ddc8, callback type 1048576, id 0 entered
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: dialog [dlg_hash.c:588]: ref dlg 0xaf597938 with 1 -> 2
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: dialog [dlg_hash.c:590]: dialog id=1311 found on entry 3958
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: dialog [dlg_hash.c:940]: dialog 0xaf597938 changed from state 1 to state 2, due event 2 (ref 2)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: dialog [dlg_hash.c:753]: unref dlg 0xaf597938 with 1 -> 1
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: tm [t_hooks.c:288]: DBG: trans=0xaf59ddc8, callback type 512, id 0 entered
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: acc [acc_logic.c:497]: acc callback called for t(0xaf59ddc8) event type 512, reply code 180
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: last message repeated 5 times
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Sep 19 15:19:11 testServer /usr/sbin/kamailio[2019]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: presence [publish.c:77]: cleaning expired presentity information
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: <core> [db_res.c:118]: allocate 28 bytes for result set at 0xb7416020
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: db_mysql [km_res.c:68]: 4 columns returned from the query
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: <core> [db_res.c:155]: allocate 16 bytes for result names at 0xb73c4aa8
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: <core> [db_res.c:165]: allocate 16 bytes for result types at 0xb73c01d8
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[0] at 0xb737d3f0
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb737d3f0)[0]=[username]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[1] at 0xb7379f00
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7379f00)[1]=[domain]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[2] at 0xb7376638
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7376638)[2]=[etag]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[3] at 0xb7376618
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7376618)[3]=[event]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: db_mysql [km_dbase.c:328]: no rows returned from the query
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: presence [publish.c:118]: found n= 0 expires messages#012 
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: <core> [db_res.c:81]: freeing 4 columns
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0xb737d3f0
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[1] at 0xb7379f00
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[2] at 0xb7376638
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[3] at 0xb7376618
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: <core> [db_res.c:94]: freeing result names at 0xb73c4aa8
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: <core> [db_res.c:99]: freeing result types at 0xb73c01d8
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: <core> [db_res.c:136]: freeing result set at 0xb7416020
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: presence [subscribe.c:718]: cleaning pending subscriptions
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: presence [subscribe.c:2156]: db_update timer
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2024]: DEBUG: presence [subscribe.c:1879]: update_db_subs_timer: start
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/msg_parser.c:624]: SIP Request:
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/msg_parser.c:626]:  method:  <CANCEL>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/msg_parser.c:628]:  uri:     <sip:global.group@mydomain.com>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/msg_parser.c:630]:  version: <SIP/2.0>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <n/a>; state=6
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bKsbthkwcq>; state=16
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [receive.c:149]: After parse_msg...
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts...
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: INFO: <script>: route: **** Route start here ***** 
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.135] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: last message repeated 2 times
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=10
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [33]; uri=[sip:global.group@mydomain.com] 
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body [<sip:global.group@mydomain.com>#015#012]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=gkrni
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <140> <CANCEL>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: INFO: <script>: REQINIT: method=CANCEL, sip:y1@mydomain.com -> sip:global.group@mydomain.com, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.135] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: last message repeated 2 times
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: pike [ip_tree.c:279]: search on branch 10 (top=0xaf5976c0)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: pike [ip_tree.c:292]: only first 1 were matched!
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: pike [pike_funcs.c:97]: src IP [10.82.130.135],node=0xaf5976c0; hits=[0,1],[0,0] node_flags=2 func_flags=8
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=0
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [parser/msg_parser.c:104]: found end of header
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: pv [pv_trans.c:300]: i=7 j=0
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: INFO: <script>: DROUTING: CANCEL: b4 do_routing: ru=sip:global.group@mydomain.com
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: drouting [drouting.c:675]: empty ruting table
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: permissions [permissions.c:334]: no rules => allow any routing
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: INFO: <script>: NAT_fix_contact: method=CANCEL, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.135, 10.82.130.135, 0)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: INFO: <script>: WITHINDLG: method=CANCEL, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_lookup.c:762]: DEBUG: t_lookupOriginalT: searching on hash entry 35528
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_lookup.c:470]: DEBUG: RFC3261 transaction matched, tid=sbthkwcq
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_lookup.c:859]: DEBUG: t_lookupOriginalT: canceled transaction found (0xaf59ddc8)! 
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_lookup.c:862]: DEBUG: t_lookupOriginalT completed
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [tm.c:1015]: lookup_original: t_lookupOriginalT returned: 0xaf59ddc8
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: INFO: <script>: route: CANCEL - t_check_trans true: to relay
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_lookup.c:1379]: DEBUG: t_newtran: msg id=16 , global msg id=15 , T on entrance=(nil)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=35528, isACK=0
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_hooks.c:374]: DBG: trans=0xaf5a3738, callback type 1, id 0 entered
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_hooks.c:374]: DBG: trans=0xaf5a3738, callback type 1, id 0 entered
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_lookup.c:762]: DEBUG: t_lookupOriginalT: searching on hash entry 35528
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_lookup.c:470]: DEBUG: RFC3261 transaction matched, tid=sbthkwcq
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_lookup.c:859]: DEBUG: t_lookupOriginalT: canceled transaction found (0xaf59ddc8)! 
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_lookup.c:862]: DEBUG: t_lookupOriginalT completed
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_cancel.c:328]: DEBUG: cancel_branch: sending cancel...
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_fwd.c:1243]: DEBUG: e2e_cancel: e2e cancel proceeding
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.135, 10.82.130.135, 0)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [mem/shm_mem.c:111]: WARNING:vqm_resize: resize(0) called
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_reply.c:1543]: DEBUG: cleanup_uac_timers: RETR/FR timers reset
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_reply.c:703]: DEBUG: reply sent out. buf=0xb74243d8: SIP/2.0 200 cancelin..., shmem=0xaf5a1aa0: SIP/2.0 200 cancelin
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_reply.c:713]: DEBUG: _reply_light: finished
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: tm [t_funcs.c:388]: SER: new transaction fwd'ed
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: INFO: <script>: route: CANCEL - t_check_trans false: to drop
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: last message repeated 5 times
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2015]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [parser/msg_parser.c:634]: SIP Reply  (status):
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [parser/msg_parser.c:636]:  version: <SIP/2.0>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [parser/msg_parser.c:638]:  status:  <200>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [parser/msg_parser.c:640]:  reason:  <OK>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bK8ca8.b21b77d6.0>; state=16
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [receive.c:149]: After parse_msg...
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=23 global id=22 T start=(nil)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=a10f0861
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/msg_parser.c:634]: SIP Reply  (status):
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [46]; uri=[sip:global.group@mydomain.com] 
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/msg_parser.c:636]:  version: <SIP/2.0>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body [<sip:global.group@mydomain.com>]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/msg_parser.c:638]:  status:  <487>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <140> <CANCEL>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: tm [t_lookup.c:965]: DEBUG: t_reply_matching: hash 35528 label 1836560683 branch 0
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: tm [t_lookup.c:1016]: DEBUG: t_reply_matching: reply matched (T=0xaf59ddc8)!
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: tm [t_hooks.c:288]: DBG: trans=0xaf59ddc8, callback type 2, id 0 entered
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: acc [acc_logic.c:497]: acc callback called for t(0xaf59ddc8) event type 2, reply code 200
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=23 global id=23 T end=0xaf59ddc8
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: tm [t_reply.c:2081]: DEBUG: reply_received: org. status uas=180, uac[0]=180 local=0 is_invite=1)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: tm [t_reply.c:2094]: DEBUG: reply to local CANCEL processed
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: last message repeated 3 times
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/msg_parser.c:640]:  reason:  <Request Terminated>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bK8ca8.b21b77d6.0>; state=16
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2022]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [receive.c:149]: After parse_msg...
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=26 global id=25 T start=(nil)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <5060>; state=6
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bKsbthkwcq>; state=16
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=22
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/msg_parser.c:524]: parse_headers: this is the second via
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=a10f0861
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [46]; uri=[sip:global.group@mydomain.com] 
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body [<sip:global.group@mydomain.com>]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <140> <INVITE>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: tm [t_lookup.c:965]: DEBUG: t_reply_matching: hash 35528 label 1836560683 branch 0
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: tm [t_lookup.c:1016]: DEBUG: t_reply_matching: reply matched (T=0xaf59ddc8)!
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: tm [t_hooks.c:288]: DBG: trans=0xaf59ddc8, callback type 2, id 0 entered
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: acc [acc_logic.c:497]: acc callback called for t(0xaf59ddc8) event type 2, reply code 487
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=26 global id=26 T end=0xaf59ddc8
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: tm [t_reply.c:2081]: DEBUG: reply_received: org. status uas=180, uac[0]=180 local=0 is_invite=1)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=gkrni
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: INFO: <script>: REPLY_NAT_INVITE_FIX_CONTACT: (Request Terminated 487): cseq=140, srcIP=10.82.130.140:19322, y1->global.group
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: tm [t_reply.c:1204]: ->>>>>>>>> T_code=180, new_code=487
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: tm [t_hooks.c:288]: DBG: trans=0xaf59ddc8, callback type 128, id 0 entered
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: acc [acc_logic.c:497]: acc callback called for t(0xaf59ddc8) event type 128, reply code 407
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: NOTICE: acc [acc.c:275]: ACC: call missed: timestamp=1348064353;method=INVITE;from_tag=gkrni;to_tag=f8e4b26bee3ae2696576016b7874d8bd-9bb6;call_id=grxlcwjmtjephdu@yufei-laptop;code=407;reason=Proxy Authentication Required;src_user=y1;src_domain=mydomain.com;dst_ouser=global.group;dst_user=y2;dst_domain=10.82.130.140
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: ERROR: db_mysql [km_dbase.c:122]: driver error on query: Unknown column 'src_user' in 'field list'
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: ERROR: <core> [db_query.c:210]: error while submitting query
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: ERROR: acc [acc.c:404]: failed to insert into database
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: INFO: <script>: FAIL_INVITE:INVITE: <null>-<null>: callid=grxlcwjmtjephdu@yufei-laptop, cseq=140, srcIP=10.82.130.135:5060, sip:y1@mydomain.com->sip:global.group@mydomain.com
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: INFO: <script>: RTPPROXY: method=INVITE, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140, sip:y1@mydomain.com->sip:global.group@mydomain.com
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: INFO: <script>: >>>>>>>>>>>>>>>>>>>>>>>>>>>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: INFO: <script>: RTPPROXY:INVITE: callid=grxlcwjmtjephdu@yufei-laptop, cseq=140, sip:y1@mydomain.com->sip:global.group@mydomain.com
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=22 global id=22 T start=0xaf59ddc8
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: tm [t_lookup.c:1151]: DEBUG: t_check_msg: T already found!
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: tm [t_reply.c:1702]: DEBUG: relay_reply: branch=0, save=0, relay=1
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [msg_translator.c:1933]:  old size: 497, new size: 424
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [msg_translator.c:1951]: build_res_from_sip_res: copied size: orig:116, new: 43, rest: 381 msg=#012SIP/2.0 407 Proxy Authentication Required#015#012Via: SIP/2.0/UDP 10.82.130.135;rport=5060;branch=z9hG4bKsbthkwcq#015#012To: <sip:global.group@mydomain.com>;tag=f8e4b26bee3ae2696576016b7874d8bd-9bb6#015#012From: "y1" <sip:y1@mydomain.com>;tag=gkrni#015#012Call-ID: grxlcwjmtjephdu@yufei-laptop#015#012CSeq: 140 INVITE#015#012Proxy-Authenticate: Digest realm="mydomain.com", nonce="UFnViVBZ1F3Ahab5cyszIBt4ZFVoG6n0"#015#012Server: YT-SIP-Server#015#012Content-Length: 0#015#012#015#012
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: tm [t_hooks.c:288]: DBG: trans=0xaf59ddc8, callback type 1048576, id 0 entered
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: dialog [dlg_hash.c:588]: ref dlg 0xaf597938 with 1 -> 2
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: dialog [dlg_hash.c:590]: dialog id=1311 found on entry 3958
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: dialog [dlg_hash.c:940]: dialog 0xaf597938 changed from state 2 to state 5, due event 4 (ref 2)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: dialog [dlg_handlers.c:543]: dialog 0xaf597938 failed (negative reply)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: dialog [dlg_cb.c:256]: dialog=0xaf597938, type=4
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: ERROR: mediaproxy [mediaproxy.c:1433]: failed to connect to /var/run/mediaproxy/dispatcher.sock: No such file or directory
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: dialog [dlg_hash.c:753]: unref dlg 0xaf597938 with 1 -> 1
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: dialog [dlg_handlers.c:937]: registering TMCB to wait for negative ACK
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: dialog [dlg_hash.c:735]: ref dlg 0xaf597938 with 1 -> 2
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: dialog [dlg_hash.c:753]: unref dlg 0xaf597938 with 1 -> 1
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: tm [t_hooks.c:288]: DBG: trans=0xaf59ddc8, callback type 512, id 0 entered
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: acc [acc_logic.c:497]: acc callback called for t(0xaf59ddc8) event type 512, reply code 487
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: tm [t_reply.c:1543]: DEBUG: cleanup_uac_timers: RETR/FR timers reset
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: last message repeated 5 times
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/msg_parser.c:624]: SIP Request:
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2006]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/msg_parser.c:626]:  method:  <ACK>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/msg_parser.c:628]:  uri:     <sip:global.group@mydomain.com>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/msg_parser.c:630]:  version: <SIP/2.0>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <n/a>; state=6
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bKsbthkwcq>; state=16
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [receive.c:149]: After parse_msg...
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts...
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: sl [sl_funcs.c:396]: DEBUG : sl_filter_ACK: to late to be a local ACK!
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: INFO: <script>: route: **** Route start here ***** 
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.135] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: last message repeated 2 times
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=f8e4b26bee3ae2696576016b7874d8bd-9bb6
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [75]; uri=[sip:global.group@mydomain.com] 
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body [<sip:global.group@mydomain.com>]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=gkrni
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <140> <ACK>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: INFO: <script>: REQINIT: method=ACK, sip:y1@mydomain.com -> sip:global.group@mydomain.com, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.135] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: last message repeated 2 times
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: pike [ip_tree.c:279]: search on branch 10 (top=0xaf5976c0)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: pike [ip_tree.c:292]: only first 1 were matched!
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: pike [pike_funcs.c:97]: src IP [10.82.130.135],node=0xaf5976c0; hits=[0,2],[0,0] node_flags=2 func_flags=8
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=0
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [parser/msg_parser.c:104]: found end of header
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: siputils [checks.c:106]: totag found
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: siputils [checks.c:106]: totag found
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: INFO: <script>: NAT_fix_contact: method=ACK, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.135, 10.82.130.135, 0)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: INFO: <script>: WITHINDLG: method=ACK, callid=grxlcwjmtjephdu@yufei-laptop, cseq=140
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: siputils [checks.c:106]: totag found
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: pv [pv_core.c:900]: no destination URI
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: INFO: <script>: WITHINDLG: ACK: b4 loose_route: route=<null>, ru=sip:global.group@mydomain.com, du=<null>
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: rr [loose.c:108]: No Route headers found
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: rr [loose.c:829]: There is no Route HF
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=20 global id=19 T start=(nil)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=35528, isACK=1
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: tm [t_lookup.c:470]: DEBUG: RFC3261 transaction matched, tid=sbthkwcq
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: tm [t_lookup.c:726]: DEBUG: t_lookup_request: transaction found (T=0xaf59ddc8)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=20 global id=20 T end=0xaf59ddc8
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: tm [t_reply.c:1543]: DEBUG: cleanup_uac_timers: RETR/FR timers reset
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [timer.c:595]: timer_add called on an active timer 0xaf59de10 (0xaf3303d0, 0xaf3303d0), flags 201
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: tm [t_funcs.c:184]: tm: put_on_wait: transaction 0xaf59ddc8 already on wait
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: last message repeated 5 times
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Sep 19 15:19:13 testServer /usr/sbin/kamailio[2007]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/msg_parser.c:624]: SIP Request:
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/msg_parser.c:626]:  method:  <REGISTER>
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/msg_parser.c:628]:  uri:     <sip:mydomain.com>
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/msg_parser.c:630]:  version: <SIP/2.0>
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bK-d8754z-ab321235cc72810e-1---d8754z->; state=6
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <n/a>; state=17
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [receive.c:149]: After parse_msg...
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts...
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: INFO: <script>: route: **** Route start here ***** 
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.140] == [10.82.130.244]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: last message repeated 2 times
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=10
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [27]; uri=[sip:y3@mydomain.com] 
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body ["y3"<sip:y3@mydomain.com>#015#012]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=a90eba48
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <64> <REGISTER>
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: INFO: <script>: REQINIT: method=REGISTER, sip:y3@mydomain.com -> sip:y3@mydomain.com, callid=MGFlZTZlMWM5ZmE5YzAzZTBkN2FkOWFjM2RmNzIwNDc., cseq=64
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.140] == [10.82.130.244]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: last message repeated 2 times
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: pike [ip_tree.c:279]: search on branch 10 (top=0xaf5976c0)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: pike [ip_tree.c:292]: only first 1 were matched!
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: pike [pike_funcs.c:97]: src IP [10.82.130.140],node=0xaf5976c0; hits=[0,3],[0,0] node_flags=2 func_flags=8
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=0
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [parser/msg_parser.c:104]: found end of header
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: INFO: <script>: NAT_fix_contact: method=REGISTER, callid=MGFlZTZlMWM5ZmE5YzAzZTBkN2FkOWFjM2RmNzIwNDc., cseq=64
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.140, 10.82.130.140, 0)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: INFO: <script>: WITHINDLG: method=REGISTER, callid=MGFlZTZlMWM5ZmE5YzAzZTBkN2FkOWFjM2RmNzIwNDc., cseq=64
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=20 global id=19 T start=(nil)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=45611, isACK=0
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=20 global id=20 T end=(nil)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: INFO: <script>: AUTH: method=REGISTER, sip:y3@mydomain.com -> sip:y3@mydomain.com, ru=sip:mydomain.com, callid=MGFlZTZlMWM5ZmE5YzAzZTBkN2FkOWFjM2RmNzIwNDc., cseq=64
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.140, 10.82.130.140, 0)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: auth_db [authorize.c:306]: realm value [mydomain.com]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: auth [api.c:96]: auth: digest-algo: MD5 parsed value: 1
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: auth_db [authorize.c:176]: stale nonce
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: auth [challenge.c:128]: build_challenge_hf: realm='mydomain.com'
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: auth [challenge.c:270]: auth: 'WWW-Authenticate: Digest realm="mydomain.com", nonce="UFnVjlBZ1GK9eWDa0vS1B+3Pqck7fqT6aa0Sp14hncuQ89WVPx4yAg=="#015#012'
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: sl [sl.c:278]: reply in stateless mode (sl)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.140, 10.82.130.140, 0)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: last message repeated 5 times
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2005]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/msg_parser.c:624]: SIP Request:
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/msg_parser.c:626]:  method:  <REGISTER>
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/msg_parser.c:628]:  uri:     <sip:mydomain.com>
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/msg_parser.c:630]:  version: <SIP/2.0>
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 232, <branch> = <z9hG4bK-d8754z-c9036e68a9194f60-1---d8754z->; state=6
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/parse_via.c:1286]: Found param type 235, <rport> = <n/a>; state=17
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/parse_via.c:2561]: end of header reached, state=5
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/msg_parser.c:511]: parse_headers: Via found, flags=2
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: this is the first via
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [receive.c:149]: After parse_msg...
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts...
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: INFO: <script>: route: **** Route start here ***** 
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.140] == [10.82.130.244]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: last message repeated 2 times
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=10
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/msg_parser.c:188]: DEBUG: get_hdr_field: <To> [27]; uri=[sip:y3@mydomain.com] 
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: to body ["y3"<sip:y3@mydomain.com>#015#012]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/parse_to.c:178]: DEBUG: add_param: tag=a90eba48
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/parse_to.c:802]: end of header reached, state=29
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/msg_parser.c:168]: get_hdr_field: cseq <CSeq>: <65> <REGISTER>
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: INFO: <script>: REQINIT: method=REGISTER, sip:y3@mydomain.com -> sip:y3@mydomain.com, callid=MGFlZTZlMWM5ZmE5YzAzZTBkN2FkOWFjM2RmNzIwNDc., cseq=65
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 13==13 && [10.82.130.140] == [10.82.130.244]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: last message repeated 2 times
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [forward.c:456]: check_self: host != me
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: pike [ip_tree.c:279]: search on branch 10 (top=0xaf5976c0)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: pike [ip_tree.c:292]: only first 1 were matched!
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: pike [pike_funcs.c:97]: src IP [10.82.130.140],node=0xaf5976c0; hits=[0,4],[0,0] node_flags=2 func_flags=8
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/msg_parser.c:202]: DEBUG: get_hdr_body : content_length=0
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [parser/msg_parser.c:104]: found end of header
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [socket_info.c:583]: grep_sock_info - checking if host==us: 12==13 && [mydomain.com] == [10.82.130.244]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [socket_info.c:587]: grep_sock_info - checking if port 5061 (advertise 0) matches port 5060
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: INFO: <script>: NAT_fix_contact: method=REGISTER, callid=MGFlZTZlMWM5ZmE5YzAzZTBkN2FkOWFjM2RmNzIwNDc., cseq=65
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.140, 10.82.130.140, 0)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: INFO: <script>: WITHINDLG: method=REGISTER, callid=MGFlZTZlMWM5ZmE5YzAzZTBkN2FkOWFjM2RmNzIwNDc., cseq=65
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: siputils [checks.c:103]: no totag
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=9 global id=8 T start=(nil)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=45612, isACK=0
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=9 global id=9 T end=(nil)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: INFO: <script>: AUTH: method=REGISTER, sip:y3@mydomain.com -> sip:y3@mydomain.com, ru=sip:mydomain.com, callid=MGFlZTZlMWM5ZmE5YzAzZTBkN2FkOWFjM2RmNzIwNDc., cseq=65
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.140, 10.82.130.140, 0)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: auth_db [authorize.c:306]: realm value [mydomain.com]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: auth [api.c:96]: auth: digest-algo: MD5 parsed value: 1
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [db_res.c:118]: allocate 28 bytes for result set at 0xb7424338
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: db_mysql [km_res.c:68]: 2 columns returned from the query
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0xb7376638
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [db_res.c:165]: allocate 8 bytes for result types at 0xb737d3f0
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[0] at 0xb7375298
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7375298)[0]=[ha1]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[1] at 0xb7376618
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7376618)[1]=[rpid]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [db_res.c:184]: allocate 8 bytes for rows at 0xb7379f00
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [db_row.c:119]: allocate 40 bytes for row values at 0xb7416340
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [db_val.c:117]: converting STRING [85228b115eea705962b34398628f2157]
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [db_val.c:56]: converting NULL value
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: auth [api.c:211]: check_response: Our result = 'fdece6bdcd4b4fbfb92bcc43846d71cb'
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: auth [api.c:218]: check_response: Authorization is OK
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [db_res.c:81]: freeing 2 columns
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0xb7375298
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[1] at 0xb7376618
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [db_res.c:94]: freeing result names at 0xb7376638
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [db_res.c:99]: freeing result types at 0xb737d3f0
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [db_res.c:54]: freeing 1 rows
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [db_row.c:97]: freeing row values at 0xb7416340
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [db_res.c:62]: freeing rows at 0xb7379f00
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [db_res.c:136]: freeing result set at 0xb7424338
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: INFO: <script>: SIPOUT: method=REGISTER, callid=MGFlZTZlMWM5ZmE5YzAzZTBkN2FkOWFjM2RmNzIwNDc., cseq=65, sip:y3@mydomain.com->sip:y3@mydomain.com
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [sruid.c:176]: new sruid is [uloc-5059c8c6-7d8-8] (8 / 19)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: registrar [reply.c:368]: created Contact HF: Contact: <sip:y3@10.82.130.140:53444;rinstance=6ca01e6627a2cc61>;expires=60#015#012
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: sl [sl.c:278]: reply in stateless mode (sl)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [msg_translator.c:206]: check_via_address(10.82.130.140, 10.82.130.140, 0)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: INFO: <script>: REGISTRAR: REGISTERED y3@mydomain.com, callid=MGFlZTZlMWM5ZmE5YzAzZTBkN2FkOWFjM2RmNzIwNDc., cseq=65, contact:<sip:y3@10.82.130.140:53444;rinstance=6ca01e6627a2cc61>, transport:UDP, origURI:sip:mydomain.com, rcvd:10.82.130.140:53444, user-agent:X-Lite release 1100l stamp 47546
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: last message repeated 5 times
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Sep 19 15:19:15 testServer /usr/sbin/kamailio[2008]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Sep 19 15:19:16 testServer /usr/sbin/kamailio[2031]: DEBUG: mi_fifo [mi_parser.c:245]: adding node <> ; val <2>
Sep 19 15:19:16 testServer /usr/sbin/kamailio[2031]: DEBUG: mi_fifo [mi_parser.c:84]: end of fifo input tree
Sep 19 15:19:16 testServer /usr/sbin/kamailio[2031]: DEBUG: mi_fifo [fifo_fnc.c:507]: done parsing the mi tree
Sep 19 15:19:16 testServer /usr/sbin/kamailio[2031]: INFO: <core> [cfg/cfg_ctx.c:613]: INFO: cfg_set_now(): core.debug has been changed to 2
Sep 19 15:19:27 testServer /usr/sbin/kamailio[2014]: INFO: <script>: route: **** Route start here ***** 
Sep 19 15:19:27 testServer /usr/sbin/kamailio[2014]: INFO: <script>: REQINIT: method=REGISTER, sip:y2@mydomain.com -> sip:y2@mydomain.com, callid=M2U2MWVmNDcxYWNmYWJhMjBhMzljYzZlZmYzZjdkMTU., cseq=64
Sep 19 15:19:27 testServer /usr/sbin/kamailio[2014]: INFO: <script>: NAT_fix_contact: method=REGISTER, callid=M2U2MWVmNDcxYWNmYWJhMjBhMzljYzZlZmYzZjdkMTU., cseq=64
Sep 19 15:19:27 testServer /usr/sbin/kamailio[2014]: INFO: <script>: WITHINDLG: method=REGISTER, callid=M2U2MWVmNDcxYWNmYWJhMjBhMzljYzZlZmYzZjdkMTU., cseq=64
Sep 19 15:19:27 testServer /usr/sbin/kamailio[2014]: INFO: <script>: AUTH: method=REGISTER, sip:y2@mydomain.com -> sip:y2@mydomain.com, ru=sip:mydomain.com, callid=M2U2MWVmNDcxYWNmYWJhMjBhMzljYzZlZmYzZjdkMTU., cseq=64
Sep 19 15:19:27 testServer /usr/sbin/kamailio[2013]: INFO: <script>: route: **** Route start here ***** 
Sep 19 15:19:27 testServer /usr/sbin/kamailio[2013]: INFO: <script>: REQINIT: method=REGISTER, sip:y2@mydomain.com -> sip:y2@mydomain.com, callid=M2U2MWVmNDcxYWNmYWJhMjBhMzljYzZlZmYzZjdkMTU., cseq=65
Sep 19 15:19:27 testServer /usr/sbin/kamailio[2013]: INFO: <script>: NAT_fix_contact: method=REGISTER, callid=M2U2MWVmNDcxYWNmYWJhMjBhMzljYzZlZmYzZjdkMTU., cseq=65
Sep 19 15:19:27 testServer /usr/sbin/kamailio[2013]: INFO: <script>: WITHINDLG: method=REGISTER, callid=M2U2MWVmNDcxYWNmYWJhMjBhMzljYzZlZmYzZjdkMTU., cseq=65
Sep 19 15:19:27 testServer /usr/sbin/kamailio[2013]: INFO: <script>: AUTH: method=REGISTER, sip:y2@mydomain.com -> sip:y2@mydomain.com, ru=sip:mydomain.com, callid=M2U2MWVmNDcxYWNmYWJhMjBhMzljYzZlZmYzZjdkMTU., cseq=65
Sep 19 15:19:27 testServer /usr/sbin/kamailio[2013]: INFO: <script>: SIPOUT: method=REGISTER, callid=M2U2MWVmNDcxYWNmYWJhMjBhMzljYzZlZmYzZjdkMTU., cseq=65, sip:y2@mydomain.com->sip:y2@mydomain.com
Sep 19 15:19:27 testServer /usr/sbin/kamailio[2013]: INFO: <script>: REGISTRAR: REGISTERED y2@mydomain.com, callid=M2U2MWVmNDcxYWNmYWJhMjBhMzljYzZlZmYzZjdkMTU., cseq=65, contact:<sip:y2@10.82.130.140:19322;rinstance=cf85dcd5a9916cac;transport=udp>, transport:UDP, origURI:sip:mydomain.com, rcvd:10.82.130.140:19322, user-agent:eyeBeam release 1105a stamp 56793
Sep 19 15:19:53 testServer /usr/sbin/kamailio[2012]: INFO: <script>: route: **** Route start here ***** 
Sep 19 15:19:53 testServer /usr/sbin/kamailio[2012]: INFO: <script>: REQINIT: method=REGISTER, sip:y1@mydomain.com -> sip:y1@mydomain.com, callid=cfyvtshebyrtjyd@yufei-laptop, cseq=421
Sep 19 15:19:53 testServer /usr/sbin/kamailio[2012]: INFO: <script>: NAT_fix_contact: method=REGISTER, callid=cfyvtshebyrtjyd@yufei-laptop, cseq=421
Sep 19 15:19:53 testServer /usr/sbin/kamailio[2012]: INFO: <script>: WITHINDLG: method=REGISTER, callid=cfyvtshebyrtjyd@yufei-laptop, cseq=421
Sep 19 15:19:53 testServer /usr/sbin/kamailio[2012]: INFO: <script>: AUTH: method=REGISTER, sip:y1@mydomain.com -> sip:y1@mydomain.com, ru=sip:mydomain.com, callid=cfyvtshebyrtjyd@yufei-laptop, cseq=421
Sep 19 15:19:53 testServer /usr/sbin/kamailio[2018]: INFO: <script>: route: **** Route start here ***** 
Sep 19 15:19:53 testServer /usr/sbin/kamailio[2018]: INFO: <script>: REQINIT: method=REGISTER, sip:y1@mydomain.com -> sip:y1@mydomain.com, callid=cfyvtshebyrtjyd@yufei-laptop, cseq=422
Sep 19 15:19:53 testServer /usr/sbin/kamailio[2018]: INFO: <script>: NAT_fix_contact: method=REGISTER, callid=cfyvtshebyrtjyd@yufei-laptop, cseq=422
Sep 19 15:19:53 testServer /usr/sbin/kamailio[2018]: INFO: <script>: WITHINDLG: method=REGISTER, callid=cfyvtshebyrtjyd@yufei-laptop, cseq=422
Sep 19 15:19:53 testServer /usr/sbin/kamailio[2018]: INFO: <script>: AUTH: method=REGISTER, sip:y1@mydomain.com -> sip:y1@mydomain.com, ru=sip:mydomain.com, callid=cfyvtshebyrtjyd@yufei-laptop, cseq=422
Sep 19 15:19:53 testServer /usr/sbin/kamailio[2018]: INFO: <script>: SIPOUT: method=REGISTER, callid=cfyvtshebyrtjyd@yufei-laptop, cseq=422, sip:y1@mydomain.com->sip:y1@mydomain.com
Sep 19 15:19:53 testServer /usr/sbin/kamailio[2018]: INFO: <script>: REGISTRAR: REGISTERED y1@mydomain.com, callid=cfyvtshebyrtjyd@yufei-laptop, cseq=422, contact:<sip:y1@10.82.130.135>;expires=60, transport:UDP, origURI:sip:mydomain.com, rcvd:10.82.130.135:5060, user-agent:Twinkle/1.4.2