Last login: Fri Jan 29 14:37:02 on ttys000 Bars-MacBook-Air:~ baris$ ssh kamailio@192.168.2.98 kamailio@192.168.2.98's password: The programs included with the Debian GNU/Linux system are free software; the exact distribution terms for each program are described in the individual files in /usr/share/doc/*/copyright. Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent permitted by applicable law. Last login: Fri Jan 29 14:36:37 2016 from 192.168.2.41 kamailio@didkamailio:~$ su Password: root@didkamailio:/home/kamailio# cat /var/log/daemon.log Jan 29 14:25:15 didkamailio kamailio[2210]: 6(2233) DEBUG: [udp_server.c:455]: udp_rcv_loop(): probing packet received from 192.168.2.152 50707 Jan 29 14:25:21 didkamailio kamailio[2210]: 8(2235) DEBUG: [udp_server.c:455]: udp_rcv_loop(): probing packet received from 192.168.2.25 50707 Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [parser/msg_parser.c:606]: parse_msg(): SIP Request: Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [parser/msg_parser.c:608]: parse_msg(): method: Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [parser/msg_parser.c:610]: parse_msg(): uri: Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [parser/msg_parser.c:612]: parse_msg(): version: Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, = ; state=16 Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [receive.c:134]: receive_msg(): After parse_msg... Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [receive.c:177]: receive_msg(): preparing to run routing scripts... Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=472 a=5 n=route Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=585 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=580 a=25 n=mf_process_maxfwd_header Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=10 Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: [24]; uri=[sip:102@192.168.2.98] Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [ Jan 29 14:25:25 didkamailio kamailio[2210]: ] Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq : <1> Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70 Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=590 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=585 a=25 n=is_method Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=594 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=590 a=26 n=sanity_check Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [parser/msg_parser.c:187]: get_hdr_field(): DEBUG: get_hdr_body : content_length=306 Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [parser/msg_parser.c:89]: get_hdr_field(): found end of header Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=2100069921 Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: sanity [mod_sanity.c:248]: w_sanity_check(): sanity checks result: 1 Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=475 a=5 n=route Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=781 a=2 n=return Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=486 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=478 a=25 n=is_method Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=486 a=5 n=route Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=602 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=598 a=24 n=has_totag Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: siputils [checks.c:97]: has_totag(): no totag Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=598 a=2 n=return Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=495 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=491 a=24 n=t_precheck_trans Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=495 a=24 n=t_check_trans Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=6 global id=5 T start=(nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=58684, isACK=0 Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=6 global id=6 T end=(nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=498 a=5 n=route Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=758 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=745 a=25 n=is_method Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [socket_info.c:563]: grep_sock_info(): checking if host==us: 12==9 && [192.168.2.98] == [127.0.0.1] Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [socket_info.c:563]: grep_sock_info(): checking if host==us: 12==12 && [192.168.2.98] == [192.168.2.98] Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=753 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=748 a=27 n=auth_check Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: auth_db [authorize.c:487]: auth_check(): realm [192.168.2.98] table [subscriber] flags [1] Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: auth [api.c:74]: pre_auth(): auth:pre_auth: Credentials with realm '192.168.2.98' not found Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: auth_db [authorize.c:253]: digest_authenticate_hdr(): no credentials Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=749 a=26 n=auth_challenge Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: auth [challenge.c:111]: get_challenge_hf(): build_challenge_hf: realm='192.168.2.98' Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: auth [challenge.c:253]: get_challenge_hf(): auth: 'Proxy-Authenticate: Digest realm="192.168.2.98", nonce="VqtbYVarWjVywFv+q7vMYBn05oP9n58R" Jan 29 14:25:25 didkamailio kamailio[2210]: ' Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: sl [sl.c:280]: send_reply(): reply in stateless mode (sl) Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [msg_translator.c:158]: check_via_address(): (192.168.2.25, 192.168.2.25, 0) Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=750 a=2 n=exit Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 5(2232) DEBUG: [receive.c:278]: receive_msg(): cleaning up Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [parser/msg_parser.c:606]: parse_msg(): SIP Request: Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [parser/msg_parser.c:608]: parse_msg(): method: Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [parser/msg_parser.c:610]: parse_msg(): uri: Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [parser/msg_parser.c:612]: parse_msg(): version: Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, = ; state=16 Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [receive.c:134]: receive_msg(): After parse_msg... Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [receive.c:177]: receive_msg(): preparing to run routing scripts... Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=b27e1a1d33761e85846fc98f5f3a7e58.d9f1 Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: [66]; uri=[sip:102@192.168.2.98] Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [] Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: sl [sl_funcs.c:407]: sl_filter_ACK(): SL local ACK found -> dropping it! Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [usr_avp.c:631]: destroy_avp_list(): destroying list (nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 7(2234) DEBUG: [receive.c:278]: receive_msg(): cleaning up Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [parser/msg_parser.c:606]: parse_msg(): SIP Request: Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [parser/msg_parser.c:608]: parse_msg(): method: Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [parser/msg_parser.c:610]: parse_msg(): uri: Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [parser/msg_parser.c:612]: parse_msg(): version: Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, = ; state=16 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [receive.c:134]: receive_msg(): After parse_msg... Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [receive.c:177]: receive_msg(): preparing to run routing scripts... Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=472 a=5 n=route Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=585 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=580 a=25 n=mf_process_maxfwd_header Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=10 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: [24]; uri=[sip:102@192.168.2.98] Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [ Jan 29 14:25:25 didkamailio kamailio[2210]: ] Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq : <2> Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=590 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=585 a=25 n=is_method Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=594 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=590 a=26 n=sanity_check Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [parser/msg_parser.c:187]: get_hdr_field(): DEBUG: get_hdr_body : content_length=306 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [parser/msg_parser.c:89]: get_hdr_field(): found end of header Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=2100069921 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: sanity [mod_sanity.c:248]: w_sanity_check(): sanity checks result: 1 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=475 a=5 n=route Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=781 a=2 n=return Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=486 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=478 a=25 n=is_method Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=486 a=5 n=route Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=602 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=598 a=24 n=has_totag Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: siputils [checks.c:97]: has_totag(): no totag Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=598 a=2 n=return Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=495 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=491 a=24 n=t_precheck_trans Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=495 a=24 n=t_check_trans Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=4 global id=3 T start=(nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=58681, isACK=0 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=4 global id=4 T end=(nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=498 a=5 n=route Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=758 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=745 a=25 n=is_method Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [socket_info.c:563]: grep_sock_info(): checking if host==us: 12==9 && [192.168.2.98] == [127.0.0.1] Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [socket_info.c:563]: grep_sock_info(): checking if host==us: 12==12 && [192.168.2.98] == [192.168.2.98] Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=753 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=748 a=27 n=auth_check Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: auth_db [authorize.c:487]: auth_check(): realm [192.168.2.98] table [subscriber] flags [1] Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: auth [api.c:84]: pre_auth(): auth: digest-algo: MD5 parsed value: 1 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [db_res.c:116]: db_new_result(): allocate 56 bytes for result set at 0x7f1e6fe3bef8 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: db_mysql [km_res.c:66]: db_mysql_get_columns(): 1 columns returned from the query Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [db_res.c:154]: db_allocate_columns(): allocate 8 bytes for result names at 0x7f1e6fe38408 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [db_res.c:165]: db_allocate_columns(): allocate 4 bytes for result types at 0x7f1e6fe383a0 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: db_mysql [km_res.c:84]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7f1e6fe3c110 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: db_mysql [km_res.c:91]: db_mysql_get_columns(): RES_NAMES(0x7f1e6fe3c110)[0]=[password] Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [db_res.c:184]: db_allocate_rows(): allocate 16 bytes for rows at 0x7f1e6fe3c038 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [db_row.c:117]: db_allocate_row(): allocate 32 bytes for row values at 0x7f1e6fe3c1f0 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [db_val.c:118]: db_str2val(): converting STRING [12345678] Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: auth_db [authorize.c:197]: get_ha1(): HA1 string calculated: 4ecf331a76bdd6c35a0569701456b70f Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: auth [api.c:199]: auth_check_response(): check_response: Our result = '34ea9dfdb3b63e651fca18c6e99ee2cb' Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: auth [api.c:206]: auth_check_response(): check_response: Authorization is OK Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [db_res.c:79]: db_free_columns(): freeing 1 columns Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [db_res.c:83]: db_free_columns(): freeing RES_NAMES[0] at 0x7f1e6fe3c110 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [db_res.c:92]: db_free_columns(): freeing result names at 0x7f1e6fe38408 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [db_res.c:97]: db_free_columns(): freeing result types at 0x7f1e6fe383a0 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [db_res.c:52]: db_free_rows(): freeing 1 rows Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [db_row.c:95]: db_free_row(): freeing row values at 0x7f1e6fe3c1f0 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [db_res.c:60]: db_free_rows(): freeing rows at 0x7f1e6fe3c038 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [db_res.c:134]: db_free_result(): freeing result set at 0x7f1e6fe3bef8 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=755 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=753 a=25 n=is_method Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=754 a=24 n=consume_credentials Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=764 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [socket_info.c:563]: grep_sock_info(): checking if host==us: 12==9 && [192.168.2.98] == [127.0.0.1] Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [socket_info.c:563]: grep_sock_info(): checking if host==us: 12==12 && [192.168.2.98] == [192.168.2.98] Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=764 a=2 n=return Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=502 a=25 n=remove_hf Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=507 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=503 a=25 n=is_method Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=504 a=24 n=record_route Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=512 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=507 a=25 n=is_method Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=508 a=39 n=setflag Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=512 a=5 n=route Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[SIPOUT] c=[/etc/kamailio/kamailio.cfg] l=830 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [socket_info.c:563]: grep_sock_info(): checking if host==us: 12==9 && [192.168.2.98] == [127.0.0.1] Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [socket_info.c:563]: grep_sock_info(): checking if host==us: 12==12 && [192.168.2.98] == [192.168.2.98] Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[SIPOUT] c=[/etc/kamailio/kamailio.cfg] l=828 a=2 n=return Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=517 a=5 n=route Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=703 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=700 a=25 n=is_method Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=701 a=2 n=return Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=520 a=5 n=route Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[REGISTRAR] c=[/etc/kamailio/kamailio.cfg] l=645 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[REGISTRAR] c=[/etc/kamailio/kamailio.cfg] l=643 a=25 n=is_method Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[REGISTRAR] c=[/etc/kamailio/kamailio.cfg] l=643 a=2 n=return Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=529 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=529 a=5 n=route Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[PSTN] c=[/etc/kamailio/kamailio.cfg] l=867 a=2 n=return Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=532 a=5 n=route Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=673 a=63 n=assign Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=690 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=674 a=25 n=lookup Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: registrar [lookup.c:197]: lookup_helper(): contact for [102] found by address Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=694 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=690 a=25 n=is_method Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=691 a=39 n=setflag Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[LOCATION] c=[/etc/kamailio/kamailio.cfg] l=694 a=5 n=route Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=543 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=540 a=25 n=is_method Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=542 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=541 a=25 n=t_is_set Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=541 a=25 n=t_on_branch Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=546 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=543 a=25 n=is_method Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=545 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=544 a=25 n=t_is_set Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=544 a=25 n=t_on_reply Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=550 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=546 a=25 n=is_method Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=548 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=547 a=25 n=t_is_set Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=547 a=25 n=t_on_failure Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=553 a=16 n=if Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:request_route=[RELAY] c=[/etc/kamailio/kamailio.cfg] l=550 a=24 n=t_relay Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg id=4 , global msg id=4 , T on entrance=(nil) Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=58681, isACK=0 Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: tm [t_hooks.c:358]: run_reqin_callbacks_internal(): DBG: trans=0x7f1e66795438, callback type 1, id 0 entered Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [md5utils.c:67]: MD5StringArray(): MD5 calculated: 1cc816fb3524842fb4bf67c2275b71be Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: tm [t_funcs.c:315]: t_relay_to(): SER: new INVITE Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [msg_translator.c:158]: check_via_address(): (192.168.2.25, 192.168.2.25, 0) Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: [mem/shm_mem.c:101]: _shm_resize(): WARNING:vqm_resize: resize(0) called Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: tm [t_reply.c:647]: _reply_light(): DEBUG: reply sent out. buf=0x7f1e6fe3d0f8: SIP/2.0 100 trying -..., shmem=0x7f1e667987b0: SIP/2.0 100 trying - Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: tm [t_reply.c:657]: _reply_light(): DEBUG: _reply_light: finished Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) exec: *** cfgtrace:branch_route=[MANAGE_BRANCH] c=[/etc/kamailio/kamailio.cfg] l=920 a=25 n=xdbg Jan 29 14:25:25 didkamailio kamailio[2210]: 6(2233) DEBUG: