Listening on 10.0.0.32 [10.0.0.32]:5060 WARNING: no fork mode 0(2315) DEBUG: init_mod: sl_module stateless - initializing 0(2315) DEBUG: register_fifo_cmd: new command (sl_stats) registered 0(2315) DEBUG: MD5 calculated: 2f8a75b015520947f50ab939181a7caf 0(2315) DEBUG: init_mod: tm 0(2315) TM - initializing... 0(2315) Call-ID initialization: '657fe6a7' 0(2315) DEBUG: register_fifo_cmd: new command (t_uac_dlg) registered 0(2315) DEBUG: register_fifo_cmd: new command (t_hash) registered 0(2315) DEBUG: lock_initialize: lock initialization started 0(2315) DEBUG: register_fifo_cmd: new command (t_stats) registered 0(2315) DEBUG: MD5 calculated: 614ce9f04536daa2eb03c7dbafc221ee 0(2315) DEBUG: MD5 calculated: f3105287012fae1d6536f5ac2d7e85bc 0(2315) DEBUG: init_mod: rr 0(2315) rr - initializing 0(2315) DEBUG: init_mod: maxfwd_module Maxfwd module- initializing 0(2315) DEBUG: init_mod: usrloc 0(2315) usrloc - initializing 0(2315) DEBUG: register_fifo_cmd: new command (ul_stats) registered 0(2315) DEBUG: register_fifo_cmd: new command (ul_rm) registered 0(2315) DEBUG: register_fifo_cmd: new command (ul_rm_contact) registered 0(2315) DEBUG: register_fifo_cmd: new command (ul_dump) registered 0(2315) DEBUG: register_fifo_cmd: new command (ul_flush) registered 0(2315) DEBUG: register_fifo_cmd: new command (ul_add) registered 0(2315) DEBUG: register_fifo_cmd: new command (ul_show_contact) registered 0(2315) DEBUG: init_mod: registrar 0(2315) registrar - initializing 0(2315) find_export: found in module sl_module [/usr/local/lib/ser/modules/sl.so] 0(2315) find_export: found <~ul_register_udomain> in module usrloc [/usr/local/lib/ser/modules/usrloc.so] 0(2315) find_export: found <~ul_insert_urecord> in module usrloc [/usr/local/lib/ser/modules/usrloc.so] 0(2315) find_export: found <~ul_delete_urecord> in module usrloc [/usr/local/lib/ser/modules/usrloc.so] 0(2315) find_export: found <~ul_get_urecord> in module usrloc [/usr/local/lib/ser/modules/usrloc.so] 0(2315) find_export: found <~ul_lock_udomain> in module usrloc [/usr/local/lib/ser/modules/usrloc.so] 0(2315) find_export: found <~ul_unlock_udomain> in module usrloc [/usr/local/lib/ser/modules/usrloc.so] 0(2315) find_export: found <~ul_release_urecord> in module usrloc [/usr/local/lib/ser/modules/usrloc.so] 0(2315) find_export: found <~ul_insert_ucontact> in module usrloc [/usr/local/lib/ser/modules/usrloc.so] 0(2315) find_export: found <~ul_delete_ucontact> in module usrloc [/usr/local/lib/ser/modules/usrloc.so] 0(2315) find_export: found <~ul_get_ucontact> in module usrloc [/usr/local/lib/ser/modules/usrloc.so] 0(2315) find_export: found <~ul_update_ucontact> in module usrloc [/usr/local/lib/ser/modules/usrloc.so] 0(2315) DEBUG: init_mod: auth 0(2315) auth module - initializing 0(2315) find_export: found in module sl_module [/usr/local/lib/ser/modules/sl.so] 0(2315) DEBUG: init_mod: auth_radius 0(2315) auth_radius - Initializing 0(2315) find_export: found in module auth [/usr/local/lib/ser/modules/auth.so] 0(2315) find_export: found in module auth [/usr/local/lib/ser/modules/auth.so] 0(0) fixing /usr/local/lib/ser/modules/maxfwd.so mf_process_maxfwd_header 0(0) fixing /usr/local/lib/ser/modules/sl.so sl_send_reply 0(0) fixing /usr/local/lib/ser/modules/sl.so sl_send_reply 0(0) fixing /usr/local/lib/ser/modules/rr.so record_route 0(0) fixing /usr/local/lib/ser/modules/rr.so loose_route 0(0) fixing /usr/local/lib/ser/modules/tm.so t_relay 0(0) fixing /usr/local/lib/ser/modules/auth_radius.so radius_www_authorize 0(0) fixing /usr/local/lib/ser/modules/auth.so www_challenge 0(0) fixing /usr/local/lib/ser/modules/registrar.so save 0(0) fixing /usr/local/lib/ser/modules/registrar.so lookup 0(0) fixing /usr/local/lib/ser/modules/sl.so sl_send_reply 0(0) fixing /usr/local/lib/ser/modules/tm.so t_relay 0(0) fixing /usr/local/lib/ser/modules/sl.so sl_reply_error 0(0) INFO: udp_init: SO_RCVBUF is initially 65535 0(0) DEBUG: udp_init: trying SO_RCVBUF: 131070 0(0) DEBUG: setting SO_RCVBUF; set=131070,verify=131070 0(0) DEBUG: udp_init: trying SO_RCVBUF: 262140 0(0) DEBUG: setting SO_RCVBUF; set=262140,verify=131070 0(0) DEBUG: setting SO_RCVBUF has no effect 0(0) DEBUG: udp_init: trying SO_RCVBUF: 133118 0(0) DEBUG: setting SO_RCVBUF; set=133118,verify=131070 0(0) DEBUG: setting SO_RCVBUF has no effect 0(0) INFO: udp_init: SO_RCVBUF is finally 131070 1(0) DEBUG: init_mod_child (-1): tm 1(0) DEBUG: callid: '657fe6a7-0@10.0.0.32' 1(0) DEBUG: init_mod_child (-1): usrloc 1(2316) DBG: open_uac_fifo: opening fifo... 1(2316) DEBUG: fifo /tmp/ser_fifo opened, mode=438 2(0) INFO: fifo process starting: 2317 2(0) DEBUG: init_mod_child (-2): tm 2(0) DEBUG: callid: '657fe6a7-0@10.0.0.32' 2(0) DEBUG: init_mod_child (-2): usrloc 0(2315) DEBUG: init_mod_child (1): tm 0(2315) DEBUG: callid: '657fe6a7-2315@10.0.0.32' 0(2315) DEBUG: init_mod_child (1): usrloc 2(2317) SER: open_uac_fifo: fifo server up at /tmp/ser_fifo... 0(2315) SIP Request: 0(2315) method: 0(2315) uri: 0(2315) version: 0(2315) parse_headers: flags=1 0(2315) end of header reached, state=2 0(2315) parse_headers: Via found, flags=1 0(2315) parse_headers: this is the first via 0(2315) After parse_msg... 0(2315) preparing to run routing scripts... 0(2315) DEBUG : is_maxfwd_present: searching for max_forwards header 0(2315) parse_headers: flags=128 0(2315) get_hdr_field: cseq : <2017> 0(2315) end of header reached, state=9 0(2315) DEBUG: get_hdr_field: [31]; uri=[sip:test@pildo.com] 0(2315) DEBUG: to body ["Floris" ] 0(2315) DEBUG: get_hdr_body : content_length=0 0(2315) found end of header 0(2315) DEBUG: is_maxfwd_present: max_forwards header not found! 0(2315) end of header reached, state=9 0(2315) parse_headers: flags=256 0(2315) find_first_route(): No Route headers found 0(2315) loose_route(): There is no Route HF 0(2315) check_self - checking if host==us: 9==9 && [pildo.com] == [10.0.0.32] 0(2315) parse_headers: flags=4096 0(2315) pre_auth(): Credentials with given realm not found 0(2315) build_auth_hf(): 'WWW-Authenticate: Digest realm="pildo.com", nonce="3f8484d3c58cba0a3540edd7c338d99284aa37ab" ' 0(2315) parse_headers: flags=-1 0(2315) check_via_address(10.0.0.33, 10.0.0.33, 0) 0(2315) receive_msg: cleaning up 0(2315) SIP Request: 0(2315) method: 0(2315) uri: 0(2315) version: 0(2315) parse_headers: flags=1 0(2315) end of header reached, state=2 0(2315) parse_headers: Via found, flags=1 0(2315) parse_headers: this is the first via 0(2315) After parse_msg... 0(2315) preparing to run routing scripts... 0(2315) DEBUG : is_maxfwd_present: searching for max_forwards header 0(2315) parse_headers: flags=128 0(2315) get_hdr_field: cseq : <2018> 0(2315) end of header reached, state=9 0(2315) DEBUG: get_hdr_field: [31]; uri=[sip:test@pildo.com] 0(2315) DEBUG: to body ["Floris" ] 0(2315) DEBUG: get_hdr_body : content_length=0 0(2315) found end of header 0(2315) DEBUG: is_maxfwd_present: max_forwards header not found! 0(2315) end of header reached, state=9 0(2315) parse_headers: flags=256 0(2315) find_first_route(): No Route headers found 0(2315) loose_route(): There is no Route HF 0(2315) check_self - checking if host==us: 9==9 && [pildo.com] == [10.0.0.32] 0(2315) check_nonce(): comparing [3f8484d3c58cba0a3540edd7c338d99284aa37ab] and [3f8484d3c58cba0a3540edd7c338d99284aa37ab] 0(2315) res: -1 0(2315) radius_authorize_sterman(): Failure 0(2315) build_auth_hf(): 'WWW-Authenticate: Digest realm="pildo.com", nonce="3f8484d7858c9c70569404a8a5a087ab8501e663" ' 0(2315) parse_headers: flags=-1 0(2315) check_via_address(10.0.0.33, 10.0.0.33, 0) 0(2315) receive_msg: cleaning up