2011-04-30 11:07:27.937500 [NOTICE] switch_loadable_module.c:185 Adding Codec PC MA 8 G.711 alaw 8000hz 30ms 64000bps 2011-04-30 11:07:27.937500 [NOTICE] switch_loadable_module.c:185 Adding Codec PC MA 8 G.711 alaw 8000hz 40ms 64000bps 2011-04-30 11:07:27.937500 [NOTICE] switch_loadable_module.c:185 Adding Codec PC MA 8 G.711 alaw 8000hz 50ms 64000bps 2011-04-30 11:07:27.937500 [NOTICE] switch_loadable_module.c:185 Adding Codec PC MA 8 G.711 alaw 8000hz 60ms 64000bps 2011-04-30 11:07:27.937500 [NOTICE] switch_loadable_module.c:185 Adding Codec PC MA 8 G.711 alaw 8000hz 70ms 64000bps 2011-04-30 11:07:27.937500 [NOTICE] switch_loadable_module.c:185 Adding Codec PC MA 8 G.711 alaw 8000hz 80ms 64000bps 2011-04-30 11:07:27.937500 [NOTICE] switch_loadable_module.c:185 Adding Codec PC MA 8 G.711 alaw 8000hz 90ms 64000bps 2011-04-30 11:07:27.937500 [NOTICE] switch_loadable_module.c:185 Adding Codec PC MA 8 G.711 alaw 8000hz 100ms 64000bps 2011-04-30 11:07:27.937500 [NOTICE] switch_loadable_module.c:185 Adding Codec PC MA 8 G.711 alaw 8000hz 110ms 64000bps 2011-04-30 11:07:27.937500 [NOTICE] switch_loadable_module.c:185 Adding Codec PC MA 8 G.711 alaw 8000hz 120ms 64000bps 2011-04-30 11:07:27.968750 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_console] 2011-04-30 11:07:27.968750 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_logfile] 2011-04-30 11:07:27.984375 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_xml_rpc] 2011-04-30 11:07:27.984375 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_event_socket] 2011-04-30 11:07:29.578125 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_sofia] 2011-04-30 11:07:29.593750 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_loopback] 2011-04-30 11:07:29.859375 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_commands] 2011-04-30 11:07:29.937500 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_conference] 2011-04-30 11:07:29.937500 [CONSOLE] sofia_presence.c:946 Event Thread Started 2011-04-30 11:07:29.937500 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_dptools] 2011-04-30 11:07:29.953125 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_esf] 2011-04-30 11:07:29.968750 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_expr] 2011-04-30 11:07:30.000000 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_spandsp] 2011-04-30 11:07:30.031250 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_fifo] 2011-04-30 11:07:30.046875 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_voicemail] 2011-04-30 11:07:30.078125 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_hash] 2011-04-30 11:07:30.078125 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_vmd] 2011-04-30 11:07:30.078125 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_valet_parking] 2011-04-30 11:07:30.093750 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_directory] 2011-04-30 11:07:30.093750 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_distributor] 2011-04-30 11:07:30.093750 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_dialplan_xml] 2011-04-30 11:07:30.109375 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_voipcodecs] 2011-04-30 11:07:30.125000 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_g723_1] 2011-04-30 11:07:30.125000 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_g729] 2011-04-30 11:07:30.125000 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_amr] 2011-04-30 11:07:30.140625 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_ilbc] 2011-04-30 11:07:30.140625 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_speex] 2011-04-30 11:07:30.140625 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_siren] 2011-04-30 11:07:30.156250 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_h26x] 2011-04-30 11:07:30.156250 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_sndfile] 2011-04-30 11:07:30.171875 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_native_file] 2011-04-30 11:07:30.171875 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_shout] 2011-04-30 11:07:30.187500 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_local_stream] 2011-04-30 11:07:30.187500 [CONSOLE] mod_local_stream.c:161 Can't open directory : C:\FreeSWITCH\sounds/music/16000 2011-04-30 11:07:30.187500 [CONSOLE] mod_local_stream.c:161 Can't open directory : C:\FreeSWITCH\sounds/music/32000 2011-04-30 11:07:30.187500 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_tone_stream] 2011-04-30 11:07:30.203125 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_lua] 2011-04-30 11:07:30.218750 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_flite] 2011-04-30 11:07:30.234375 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_pocketsphinx] 2011-04-30 11:07:30.234375 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_say_en] 2011-04-30 11:07:30.250000 [CONSOLE] switch_loadable_module.c:950 Successfully L oaded [mod_xml_cdr] 2011-04-30 11:07:30.250000 [CONSOLE] switch_loadable_module.c:123 Starting runti me thread for CORE_SOFTTIMER_MODULE 2011-04-30 11:07:30.250000 [CONSOLE] switch_loadable_module.c:123 Starting runti me thread for mod_event_socket 2011-04-30 11:07:30.250000 [CONSOLE] switch_loadable_module.c:123 Starting runti me thread for mod_xml_rpc Created Mutex ok 2011-04-30 11:07:30.250000 [CONSOLE] switch_core.c:1111 Created ip list lan defa ult (allow) 2011-04-30 11:07:30.250000 [CONSOLE] switch_core.c:1111 Created ip list domains default (deny) 2011-04-30 11:07:30.250000 [CONSOLE] switch_core.c:1829 ************************************************************ 2011-04-30 11:07:30.250000 [CONSOLE] switch_core.c:1832 FreeSWITCH Version 1.0.head (git-0f22877 2011-04-04 00-00-08 +0800) Started. Max Sessions[10] Session Rate[30] SQL [Enabled] FreeSWITCH@ibm> Name Type Da ta State ================================================================================ ================= external profile sip:mod_sofia@192.168.200.101:50 80 RUNNING (0) internal profile sip:mod_sofia@192.168.200.101:50 60 RUNNING (0) 192.168.200.101 alias intern al ALIASED ================================================================================ ================= 2 profiles 1 alias +OK console log level set to DEBUG 2011-04-30 11:08:23.296875 [INFO] mod_sofia.c:4785 EVENT_TRAP: IP change detecte d 2011-04-30 11:08:23.296875 [INFO] mod_sofia.c:4786 IP change detected [192.168.2 00.101]->[0.0.0.0] []->[] 2011-04-30 11:08:23.671875 [NOTICE] sofia_glue.c:5119 Reload XML [Success] 2011-04-30 11:08:23.671875 [INFO] mod_pocketsphinx.c:482 PocketSphinx Reloaded 2011-04-30 11:08:23.671875 [INFO] switch_time.c:999 Timezone reloaded 530 defini tions 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:1661 Write lock external 2011-04-30 11:08:24.156250 [NOTICE] sofia.c:1668 Waiting for worker thread 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:1725 Write unlock external 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 debug [0] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 sip-trace [no] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 rfc2833-pt [101] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 sip-port [5080] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 dialplan [XML] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:1661 Write lock internal 2011-04-30 11:08:24.156250 [NOTICE] sofia.c:1668 Waiting for worker thread 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 context [public] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 dtmf-duration [2000] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 inbound-codec-prefs [PCMA,PCMU,G 729,G723,G722,GSM] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 outbound-codec-prefs [PCMA,PCMU, G729,G723,G722,GSM] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 hold-music [local_stream://moh] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 rtp-timer-name [soft] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 local-network-acl [localnet.auto ] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 manage-presence [false] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:1725 Write unlock internal 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 context [default] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 sip-port [5060] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 rtp-ip [0.0.0.0] 2011-04-30 11:08:24.156250 [WARNING] sofia.c:3378 Invalid IP 0.0.0.0 replaced wi th 192.168.200.101 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 sip-ip [0.0.0.0] 2011-04-30 11:08:24.156250 [WARNING] sofia.c:3391 Invalid IP 0.0.0.0 replaced wi th 192.168.200.101 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 apply-inbound-acl [172.28.0.0/16 ] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 inbound-bypass-media [false] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 inbound-proxy-media [true] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 NDLB-received-in-nat-reg-contact [true] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 NDLB-force-rport [true] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 user-agent-string [FreeSWITCH] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 debug [0] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 inbound-codec-negotiation [gener ous] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 nonce-ttl [60] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 auth-calls [false] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 rtp-ip [0.0.0.0] 2011-04-30 11:08:24.156250 [WARNING] sofia.c:3378 Invalid IP 0.0.0.0 replaced wi th 192.168.200.101 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 sip-ip [0.0.0.0] 2011-04-30 11:08:24.156250 [WARNING] sofia.c:3391 Invalid IP 0.0.0.0 replaced wi th 192.168.200.101 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 ext-rtp-ip [auto-nat] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 ext-sip-ip [auto-nat] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 rtp-timeout-sec [300] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 rtp-hold-timeout-sec [1800] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 tls [false] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 tls-bind-params [transport=tls] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 tls-sip-port [5081] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 tls-cert-dir [C:\FreeSWITCH/conf/ss l] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 tls-version [tlsv1] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 sip-trace [no] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 watchdog-enabled [no] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 watchdog-step-timeout [30000] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 watchdog-event-timeout [30000] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 log-auth-failures [true] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 forward-unsolicited-mwi-notify [ false] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 rfc2833-pt [101] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 dtmf-duration [2000] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 dialplan [XML] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 inbound-codec-prefs [PCMA,PCMU,G 722,GSM] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 outbound-codec-prefs [PCMA,PCMU, G722,GSM] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 rtp-timer-name [soft] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 hold-music [local_stream://moh] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 apply-nat-acl [nat.auto] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 local-network-acl [localnet.auto ] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 record-path [C:\FreeSWITCH\recordin gs] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 record-template [${caller_id_num ber}.${target_domain}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:3087 manage-presence [false] 2011-04-30 11:08:24.156250 [DEBUG] sofia.c:1476 Creating agent for external 2011-04-30 11:08:24.156250 [NOTICE] sofia.c:4009 Started Profile external [sofia _reg_external] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 inbound-codec-negotiation [gener ous] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 tls [false] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 tls-bind-params [transport=tls] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 tls-sip-port [5061] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 tls-cert-dir [C:\FreeSWITCH/conf/ss l] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 tls-version [tlsv1] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 nonce-ttl [60] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 auth-calls [true] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 inbound-reg-force-matching-usern ame [true] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 auth-all-packets [false] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 rtp-timeout-sec [300] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 rtp-hold-timeout-sec [1800] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 force-register-domain [0.0.0.0] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 force-subscription-domain [0.0.0 .0] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 force-register-db-domain [0.0.0. 0] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 challenge-realm [auto_from] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 send-message-query-on-register [ false] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:3087 all-reg-options-ping [true] 2011-04-30 11:08:24.171875 [NOTICE] sofia.c:2265 Adding Alias [0.0.0.0] for prof ile [internal] 2011-04-30 11:08:24.171875 [NOTICE] sofia.c:3994 Adding Alias [internal] for pro file [internal] 2011-04-30 11:08:24.171875 [NOTICE] sofia.c:4009 Started Profile internal [sofia _reg_internal] 2011-04-30 11:08:24.171875 [DEBUG] sofia.c:1476 Creating agent for internal 2011-04-30 11:08:24.187500 [ERR] sofia.c:1539 Error Creating SIP UA for profile: external 2011-04-30 11:08:24.203125 [ERR] sofia.c:1539 Error Creating SIP UA for profile: internal Name Type Da ta State ================================================================================ ================= ================================================================================ ================= 0 profiles 0 aliases 2011-04-30 11:09:23.296875 [INFO] mod_sofia.c:4785 EVENT_TRAP: IP change detecte d 2011-04-30 11:09:23.296875 [INFO] mod_sofia.c:4786 IP change detected [0.0.0.0]- >[192.168.200.101] []->[] 2011-04-30 11:09:23.671875 [NOTICE] sofia_glue.c:5119 Reload XML [Success] 2011-04-30 11:09:23.671875 [INFO] mod_pocketsphinx.c:482 PocketSphinx Reloaded 2011-04-30 11:09:23.671875 [INFO] switch_time.c:999 Timezone reloaded 530 defini tions Name Type Da ta State ================================================================================ ================= ================================================================================ ================= 0 profiles 0 aliases