Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/msg_parser.c:628]: SIP Request: Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/msg_parser.c:630]: method: Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/msg_parser.c:632]: uri: Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/msg_parser.c:634]: version: Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/parse_via.c:1288]: Found param type 232, = ; state=6 Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/parse_via.c:1288]: Found param type 235, = ; state=17 Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/parse_via.c:2563]: end of header reached, state=5 Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/msg_parser.c:515]: parse_headers: Via found, flags=2 Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/msg_parser.c:517]: parse_headers: this is the first via Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [receive.c:146]: After parse_msg... Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [receive.c:187]: preparing to run routing scripts... Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/parse_to.c:803]: end of header reached, state=10 Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/msg_parser.c:187]: DEBUG: get_hdr_field: [32]; uri=[sip:201@sip.flygroup.st] Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/msg_parser.c:189]: DEBUG: to body ["201"#015#012] Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/msg_parser.c:167]: get_hdr_field: cseq : <1> Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/msg_parser.c:201]: DEBUG: get_hdr_body : content_length=371 Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/msg_parser.c:103]: found end of header Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/parse_to.c:174]: DEBUG: add_param: tag=df2e65c2 Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [parser/parse_to.c:803]: end of header reached, state=29 Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: sanity [mod_sanity.c:251]: sanity checks result: 1 Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [msg_translator.c:204]: check_via_address(178.140.2.133, 192.168.1.2, 0) Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: siputils [checks.c:104]: no totag Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=36 global id=35 T start=(nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=45991, isACK=0 Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=36 global id=36 T end=(nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [socket_info.c:502]: grep_sock_info - checking if host==us: 13==13 && [88.198.48.183] == [88.198.48.183] Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [socket_info.c:505]: grep_sock_info - checking if port 5060 matches port 5060 Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: auth_db [authorize.c:271]: realm value [88.198.48.183] Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: auth [api.c:85]: auth:pre_auth: Credentials with realm '88.198.48.183' not found Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: auth_db [authorize.c:177]: no credentials Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: auth [challenge.c:102]: build_challenge_hf: realm='88.198.48.183' Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: auth [challenge.c:244]: auth: 'Proxy-Authenticate: Digest realm="88.198.48.183", nonce="T06A0k9Of6YNkz/gCQ39kNtH6XOCeWhM"#015#012' Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: sl [sl.c:278]: reply in stateless mode (sl) Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [msg_translator.c:204]: check_via_address(178.140.2.133, 192.168.1.2, 0) Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [xavp.c:365]: destroying xavp list (nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: [receive.c:290]: receive_msg: cleaning up Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [parser/msg_parser.c:628]: SIP Request: Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [parser/msg_parser.c:630]: method: Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [parser/msg_parser.c:632]: uri: Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [parser/msg_parser.c:634]: version: Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [parser/parse_via.c:1288]: Found param type 232, = ; state=6 Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [parser/parse_via.c:1288]: Found param type 235, = ; state=17 Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [parser/parse_via.c:2563]: end of header reached, state=5 Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [parser/msg_parser.c:515]: parse_headers: Via found, flags=2 Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [parser/msg_parser.c:517]: parse_headers: this is the first via Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [receive.c:146]: After parse_msg... Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [receive.c:187]: preparing to run routing scripts... Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [parser/parse_to.c:174]: DEBUG: add_param: tag=4cf90b7f894f6efcb610e24626b3f90c.d1e8 Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [parser/parse_to.c:803]: end of header reached, state=29 Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [parser/msg_parser.c:187]: DEBUG: get_hdr_field: [74]; uri=[sip:201@sip.flygroup.st] Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [parser/msg_parser.c:189]: DEBUG: to body ["201"] Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: sl [sl_funcs.c:326]: SL local ACK found -> dropping it! Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [xavp.c:365]: destroying xavp list (nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: [receive.c:290]: receive_msg: cleaning up Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/msg_parser.c:628]: SIP Request: Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/msg_parser.c:630]: method: Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/msg_parser.c:632]: uri: Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/msg_parser.c:634]: version: Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/parse_via.c:1288]: Found param type 232, = ; state=6 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/parse_via.c:1288]: Found param type 235, = ; state=17 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/parse_via.c:2563]: end of header reached, state=5 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/msg_parser.c:515]: parse_headers: Via found, flags=2 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/msg_parser.c:517]: parse_headers: this is the first via Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [receive.c:146]: After parse_msg... Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [receive.c:187]: preparing to run routing scripts... Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/parse_to.c:803]: end of header reached, state=10 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/msg_parser.c:187]: DEBUG: get_hdr_field: [32]; uri=[sip:201@sip.flygroup.st] Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/msg_parser.c:189]: DEBUG: to body ["201"#015#012] Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/msg_parser.c:167]: get_hdr_field: cseq : <2> Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/msg_parser.c:201]: DEBUG: get_hdr_body : content_length=371 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/msg_parser.c:103]: found end of header Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/parse_to.c:174]: DEBUG: add_param: tag=df2e65c2 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [parser/parse_to.c:803]: end of header reached, state=29 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: sanity [mod_sanity.c:251]: sanity checks result: 1 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [msg_translator.c:204]: check_via_address(178.140.2.133, 192.168.1.2, 0) Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: siputils [checks.c:104]: no totag Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=34 global id=33 T start=(nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=45988, isACK=0 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=34 global id=34 T end=(nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [socket_info.c:502]: grep_sock_info - checking if host==us: 13==13 && [88.198.48.183] == [88.198.48.183] Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [socket_info.c:505]: grep_sock_info - checking if port 5060 matches port 5060 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: auth_db [authorize.c:271]: realm value [88.198.48.183] Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: auth [api.c:95]: auth: digest-algo: MD5 parsed value: 1 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [db_res.c:118]: allocate 28 bytes for result set at 0xb721b528 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: db_mysql [km_res.c:68]: 2 columns returned from the query Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [db_res.c:155]: allocate 8 bytes for result names at 0xb722c1b0 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [db_res.c:165]: allocate 8 bytes for result types at 0xb721b574 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[0] at 0xb7231d4c Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7231d4c)[0]=[password] Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[1] at 0xb722c1e8 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb722c1e8)[1]=[rpid] Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [db_res.c:184]: allocate 8 bytes for rows at 0xb7219900 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [db_row.c:119]: allocate 40 bytes for row values at 0xb7230bc8 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [db_val.c:117]: converting STRING [1004] Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [db_val.c:56]: converting NULL value Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: auth_db [authorize.c:121]: HA1 string calculated: e0ed591727640aed8640e723e04772e7 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: auth [api.c:210]: check_response: Our result = '703637383073b56ac6447d4622649a93' Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: auth [api.c:217]: check_response: Authorization is OK Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [db_res.c:81]: freeing 2 columns Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [db_res.c:85]: freeing RES_NAMES[0] at 0xb7231d4c Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [db_res.c:85]: freeing RES_NAMES[1] at 0xb722c1e8 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [db_res.c:94]: freeing result names at 0xb722c1b0 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [db_res.c:99]: freeing result types at 0xb721b574 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [db_res.c:54]: freeing 1 rows Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [db_row.c:97]: freeing row values at 0xb7230bc8 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [db_res.c:62]: freeing rows at 0xb7219900 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [db_res.c:136]: freeing result set at 0xb721b528 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [socket_info.c:502]: grep_sock_info - checking if host==us: 15==13 && [sip.flygroup.st] == [88.198.48.183] Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [socket_info.c:505]: grep_sock_info - checking if port 5060 matches port 5060 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [forward.c:448]: check_self: host != me Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:1379]: DEBUG: t_newtran: msg id=34 , global msg id=34 , T on entrance=(nil) Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=45988, isACK=0 Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_funcs.c:351]: SER: new INVITE Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [msg_translator.c:204]: check_via_address(178.140.2.133, 192.168.1.2, 0) Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: [mem/shm_mem.c:111]: WARNING:vqm_resize: resize(0) called Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_reply.c:667]: DEBUG: reply sent out. buf=0xb7239670: SIP/2.0 100 trying -..., shmem=0xaeeaf824: SIP/2.0 100 trying - Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_reply.c:677]: DEBUG: _reply_light: finished Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: