2009-01-17 16:09:35 [INFO] switch_core_sqldb.c:487 switch_core_sqldb_start() Opening DB 2009-01-17 16:09:35 [DEBUG] switch_scheduler.c:214 switch_scheduler_add_task() Added task 1 heartbeat (core) to run at 1232204975 2009-01-17 16:09:35 [NOTICE] switch_scheduler.c:166 switch_scheduler_task_thread() Starting task thread 2009-01-17 16:09:35 [CONSOLE] switch_core.c:1270 switch_core_init_and_modload() Bringing up environment. 2009-01-17 16:09:35 [CONSOLE] switch_core.c:1271 switch_core_init_and_modload() Loading Modules. 2009-01-17 16:09:35 [INFO] switch_time.c:656 switch_load_timezones() Timezone loaded 530 definitions 2009-01-17 16:09:35 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [CORE_SOFTTIMER_MODULE] 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:221 switch_loadable_module_process() Adding Timer 'soft' 2009-01-17 16:09:35 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [CORE_PCM_MODULE] 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PROXY-VID' (PROXY VIDEO PASS-THROUGH) 90000hz 0ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PROXY' (PROXY PASS-THROUGH) 8000hz 20ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 11025hz 40ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 22050hz 20ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 48000hz 20ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 8ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 6ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 4ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 2ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 8ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 6ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 4ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 2ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 8ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 6ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 4ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 2ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 48000hz 10ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 48000hz 8ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 48000hz 6ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 48000hz 4ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 48000hz 2ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 10ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 20ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 30ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 40ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 50ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 60ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 10ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 20ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 30ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 40ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 50ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 60ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 10ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 20ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 30ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 40ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 50ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 60ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 70ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 80ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 90ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 100ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 110ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 120ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMU' (G.711 ulaw) 8000hz 10ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMU' (G.711 ulaw) 8000hz 20ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMU' (G.711 ulaw) 8000hz 30ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMU' (G.711 ulaw) 8000hz 40ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMU' (G.711 ulaw) 8000hz 50ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMU' (G.711 ulaw) 8000hz 60ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMU' (G.711 ulaw) 8000hz 70ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMU' (G.711 ulaw) 8000hz 80ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMU' (G.711 ulaw) 8000hz 90ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMU' (G.711 ulaw) 8000hz 100ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMU' (G.711 ulaw) 8000hz 110ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMU' (G.711 ulaw) 8000hz 120ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMA' (G.711 alaw) 8000hz 10ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMA' (G.711 alaw) 8000hz 20ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMA' (G.711 alaw) 8000hz 30ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMA' (G.711 alaw) 8000hz 40ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMA' (G.711 alaw) 8000hz 50ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMA' (G.711 alaw) 8000hz 60ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMA' (G.711 alaw) 8000hz 70ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMA' (G.711 alaw) 8000hz 80ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMA' (G.711 alaw) 8000hz 90ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMA' (G.711 alaw) 8000hz 100ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMA' (G.711 alaw) 8000hz 110ms 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'PCMA' (G.711 alaw) 8000hz 120ms 2009-01-17 16:09:35 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_console] 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'console' 2009-01-17 16:09:35 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_logfile] 2009-01-17 16:09:35 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_enum] 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:203 switch_loadable_module_process() Adding Dialplan 'enum' 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'enum' 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'enum' 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'enum_auto' 2009-01-17 16:09:35 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_cdr_csv] 2009-01-17 16:09:35 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_event_socket] 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'socket' 2009-01-17 16:09:35 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'event_sink' 2009-01-17 16:09:35 [NOTICE] sofia_reg.c:1872 sofia_reg_add_gateway() Added gateway 'messagenet.it' to profile 'external' 2009-01-17 16:09:35 [NOTICE] sofia.c:2234 config_sofia() Adding Alias [outbound] for profile [external] 2009-01-17 16:09:35 [NOTICE] sofia.c:2234 config_sofia() Adding Alias [nat] for profile [external] 2009-01-17 16:09:35 [NOTICE] sofia.c:2244 config_sofia() Started Profile external [sofia_reg_external] 2009-01-17 16:09:35 [NOTICE] sofia.c:1181 parse_domain_tag() Adding Alias [192.168.0.74] for profile [internal] 2009-01-17 16:09:35 [NOTICE] sofia.c:2234 config_sofia() Adding Alias [default] for profile [internal] 2009-01-17 16:09:35 [NOTICE] sofia.c:2244 config_sofia() Started Profile internal [sofia_reg_internal] su_socket_port_init(00DE3B58, 02491E00) called su_pthread_port_init(00DE3B58, 02491E00) called su_socket_port_init(0137A468, 02491E00) called su_pthread_port_init(0137A468, 02491E00) called soa_create("default", 0137A8D8, 00DD7F90) called soa_set_params(static::01ADEDC8, ...) called soa_set_params(static::01ADEDC8, ...) called nta_agent_create: initialized hash tables nta_agent_create: initialized transports nta_agent_create: initialized random identifiers nta_agent_create: initialized timer su_socket_port_init(01ADEFF8, 02491E00) called su_pthread_port_init(01ADEFF8, 02491E00) called nta_agent_create: initialized resolver tport_create(): 00DEC198 nta: master transport created tport(00DEC198) to */10.0.3.6:5080/sip tport(00DEC198): calling tport_listen for udp tport(00DEC198): new primary tport 00DD8D20 su_socket_port_init(01AE9DD8, 02491E00) called su_pthread_port_init(01AE9DD8, 02491E00) called soa_create("default", 01AE28C0, 01AE5580) called soa_set_params(static::01AE57C0, ...) called soa_set_params(static::01AE57C0, ...) called nta_agent_create: initialized hash tables nta_agent_create: initialized transports nta_agent_create: initialized random identifiers nta_agent_create: initialized timer nta_agent_create: initialized resolver tport_create(): 01AE9368 nta: master transport created tport(01AE9368) to */192.168.0.74:5060/sip tport(01AE9368): calling tport_listen for udp tport(01AE9368): new primary tport 01AE9650 tport(00DD8D20): listening at udp/10.0.3.6:5080/sip tport(00DEC198): calling tport_listen for tcp tport(00DEC198): new primary tport 01AE1540 tport(01AE9650): listening at udp/192.168.0.74:5060/sip tport(01AE9368): calling tport_listen for tcp tport(01AE9368): new primary tport 01AE17F8 tport(01AE17F8): listening at tcp/192.168.0.74:5060/sip nta: bound to (192.168.0.74:5060;transport=*) nta: agent_init_via: SIP/2.0/udp 192.168.0.74 (sip) nta: agent_init_via: SIP/2.0/tcp 192.168.0.74 (sip) nta: Via fields initialized nta: Contact header created nua_register: Adding contact URL '192.168.0.74' to list. tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 01AE5FB8 from (udp/192.168.0.74:5060) has 4 bytes, veclen = 1 tport(01AE9650): bad msg 01AE5FB8 (0 bytes) from udp/192.168.0.74:5060/sip next=00000000 tport(01AE1540): listening at tcp/10.0.3.6:5080/sip nta: bound to (212.001.001.001:5080;transport=*;maddr=10.0.3.6) nta: agent_init_via: SIP/2.0/udp 212.001.001.001:5080 (sip) nta: agent_init_via: SIP/2.0/tcp 212.001.001.001:5080 (sip) nta: Via fields initialized nta: Contact header created nua_register: Adding contact URL '212.001.001.001' to list. nua(00000000): sent signal r_set_params nua(00000000): recv signal r_set_params soa_set_params(static::01AE57C0, ...) called nua(00000000): event r_set_params 200 OK tport_wakeup_pri(00DD8D20): events IN tport_recv_event(00DD8D20) tport(00DD8D20) msg 01AF0170 from (udp/10.0.3.6:5080) has 4 bytes, veclen = 1 tport(00DD8D20): bad msg 01AF0170 (0 bytes) from udp/10.0.3.6:5080/sip next=00000000 nua(00000000): sent signal r_set_params nua(00000000): recv signal r_set_params soa_set_params(static::01ADEDC8, ...) called nua(00000000): event r_set_params 200 OK 2009-01-17 16:09:36 [NOTICE] sofia_reg.c:265 sofia_reg_check_gateway() Registering messagenet.it nua(01AE32E0): sent signal r_register nua(01AE32E0): recv signal r_register soa_clone(static::01ADEDC8, 0137A8D8, 01AE32E0) called soa_set_params(static::01AEC380, ...) called soa_set_params(static::01AEC380, ...) called nta_leg_tcreate(01AED9C0) nua(01AE32E0): adding register usage nta: selecting scheme sip nta: for "sip.messagenet.it" query "_sip._udp.sip.messagenet.it" SRV nta: for "sip.messagenet.it" query "sip.messagenet.it" A nta: sip.messagenet.it. IN A 212.97.59.76 tport_tsend(00DD8D20) tpn = udp/212.97.59.76:5060 tport_resolve addrinfo = 212.97.59.76:5060 tport(00DD8D20): not found by name udp/212.97.59.76:5060 tport_vsend(00DD8D20): 639 bytes of 639 to udp/212.97.59.76:5060 tport_vsend returned 639 send 639 bytes to udp/[212.97.59.76]:5060 at 15:09:36.803764: ------------------------------------------------------------------------ REGISTER sip:sip.messagenet.it;transport=udp SIP/2.0 Via: SIP/2.0/UDP 212.001.001.001:5080;rport;branch=z9hG4bKmD9FZQBy2FKXp Max-Forwards: 70 From: ;tag=mypD78F0vQSjc To: Call-ID: c66dcbcd-e144-354e-b169-0723760063cf CSeq: 109984152 REGISTER Contact: Expires: 3600 User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO Supported: timer, precondition, path, replaces Content-Length: 0 ------------------------------------------------------------------------ nta: sent REGISTER (109984152) to udp/212.97.59.76:5060 tport_pend(00DD8D20): pending 01AF0170 for udp/10.0.3.6:5080 (already 0) nta: timer set to 32000 ms nta: timer shortened to 500 ms tport_wakeup_pri(00DD8D20): events IN tport_recv_event(00DD8D20) tport(00DD8D20) msg 01B5D978 from (udp/10.0.3.6:5080) has 1278 bytes, veclen = 1 recv 564 bytes from udp/[212.97.59.76]:5060 at 15:09:36.803764: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 212.001.001.001:5080;rport=5080;branch=z9hG4bKmD9FZQBy2FKXp From: ;tag=mypD78F0vQSjc To: Call-ID: c66dcbcd-e144-354e-b169-0723760063cf CSeq: 109984152 REGISTER Server: OpenSer (1.1.0-notls (i386/linux)) Content-Length: 0 Warning: 392 212.97.59.76:5060 "Noisy feedback tells: pid=15517 req_src_ip=212.001.001.001 req_src_port=5080 in_uri=sip:sip.messagenet.it;transport=udp out_uri=sip:sip.messagenet.it;transport=udp via_cnt==1" ------------------------------------------------------------------------ tport(00DD8D20): msg 01B5D978 (564 bytes) from udp/212.97.59.76:5080/sip next=00000000 nta: received 100 Trying for REGISTER (109984152) nta: 100 Trying is going to a transaction nta_outgoing: RTT is 0 ms tport(00DD8D20): 01AF0170 by 01AED108 with 01B5D978 (preliminary) tport_wakeup_pri(00DD8D20): events IN tport_recv_event(00DD8D20) tport(00DD8D20) msg 01B5D978 from (udp/10.0.3.6:5080) has 714 bytes, veclen = 1 recv 714 bytes from udp/[212.97.59.76]:5060 at 15:09:36.803764: ------------------------------------------------------------------------ SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 212.001.001.001:5080;rport=5080;branch=z9hG4bKmD9FZQBy2FKXp From: ;tag=mypD78F0vQSjc To: ;tag=797b035a1d6b0049ed33903c5ef0eddf.062a Call-ID: c66dcbcd-e144-354e-b169-0723760063cf CSeq: 109984152 REGISTER WWW-Authenticate: Digest realm="sip.messagenet.it", nonce="4971f5dfd4d7b34dd71873b0bc184126b45c1700" Server: OpenSer (1.1.0-notls (i386/linux)) Content-Length: 0 Warning: 392 212.97.59.76:5060 "Noisy feedback tells: pid=15517 req_src_ip=212.001.001.001 req_src_port=5080 in_uri=sip:sip.messagenet.it;transport=udp out_uri=sip:sip.messagenet.it;transport=udp via_cnt==1" ------------------------------------------------------------------------ tport(00DD8D20): msg 01B5D978 (714 bytes) from udp/212.97.59.76:5080/sip next=00000000 nta: received 401 Unauthorized for REGISTER (109984152) nta: 401 Unauthorized is going to a transaction tport(00DD8D20): 01AF0170 by 01AED108 with 01B5D978 auth_digest_challenge_get(): got 2 nua(01AE32E0): event r_register 401 Unauthorized nua(01AE32E0): recv signal r_authenticate auth_digest_a1() has A1 = MD5(5343504:sip.messagenet.it:zanebap) = 7d86e12ae1b9db5b55b0653441352198 A2 = MD5(REGISTER:sip:sip.messagenet.it;transport=udp) auth_response: 937ac9aceb3126881d30de0ff6c9bcea = MD5(7d86e12ae1b9db5b55b0653441352198:4971f5dfd4d7b34dd71873b0bc184126b45c1700:c7eb2509efe71a9f29aa95f4d4019686) (qop=NONE) nta: selecting scheme sip nta: for "sip.messagenet.it" query "_sip._udp.sip.messagenet.it" SRV (cached) nta: for "sip.messagenet.it" query "sip.messagenet.it" A (cached) nta: sip.messagenet.it. IN A 212.97.59.76 tport_tsend(00DD8D20) tpn = udp/212.97.59.76:5060 tport_resolve addrinfo = 212.97.59.76:5060 tport(00DD8D20): not found by name udp/212.97.59.76:5060 tport_vsend(00DD8D20): 861 bytes of 861 to udp/212.97.59.76:5060 tport_vsend returned 861 send 861 bytes to udp/[212.97.59.76]:5060 at 15:09:36.803764: ------------------------------------------------------------------------ REGISTER sip:sip.messagenet.it;transport=udp SIP/2.0 nua(01AE32E0): sent signal r_authenticate Via: SIP/2.0/UDP 212.001.001.001:5080;rport;branch=z9hG4bKNp280jv1Zr9Fj Max-Forwards: 70 From: ;tag=mypD78F0vQSjc To: Call-ID: c66dcbcd-e144-354e-b169-0723760063cf CSeq: 109984153 REGISTER Contact: Expires: 3600 User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO Supported: timer, precondition, path, replaces Authorization: Digest username="5343504", realm="sip.messagenet.it", nonce="4971f5dfd4d7b34dd71873b0bc184126b45c1700", algorithm=MD5, uri="sip:sip.messagenet.it;transport=udp", response="937ac9aceb3126881d30de0ff6c9bcea" Content-Length: 0 ------------------------------------------------------------------------ nta: sent REGISTER (109984153) to udp/212.97.59.76:5060 tport_pend(00DD8D20): pending 01B58858 for udp/10.0.3.6:5080 (already 0) tport_wakeup_pri(00DD8D20): events IN tport_recv_event(00DD8D20) tport(00DD8D20) msg 01B59988 from (udp/10.0.3.6:5080) has 564 bytes, veclen = 1 recv 564 bytes from udp/[212.97.59.76]:5060 at 15:09:36.819385: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 212.001.001.001:5080;rport=5080;branch=z9hG4bKNp280jv1Zr9Fj From: ;tag=mypD78F0vQSjc To: Call-ID: c66dcbcd-e144-354e-b169-0723760063cf CSeq: 109984153 REGISTER Server: OpenSer (1.1.0-notls (i386/linux)) Content-Length: 0 Warning: 392 212.97.59.76:5060 "Noisy feedback tells: pid=15519 req_src_ip=212.001.001.001 req_src_port=5080 in_uri=sip:sip.messagenet.it;transport=udp out_uri=sip:sip.messagenet.it;transport=udp via_cnt==1" ------------------------------------------------------------------------ tport(00DD8D20): msg 01B59988 (564 bytes) from udp/212.97.59.76:5080/sip next=00000000 nta: received 100 Trying for REGISTER (109984153) nta: 100 Trying is going to a transaction nta_outgoing: RTT is 15.621 ms tport(00DD8D20): 01B58858 by 01B5C388 with 01B59988 (preliminary) tport_wakeup_pri(00DD8D20): events IN tport_recv_event(00DD8D20) tport(00DD8D20) msg 01B59988 from (udp/10.0.3.6:5080) has 715 bytes, veclen = 1 recv 715 bytes from udp/[212.97.59.76]:5060 at 15:09:36.819385: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 212.001.001.001:5080;rport=5080;branch=z9hG4bKNp280jv1Zr9Fj From: ;tag=mypD78F0vQSjc To: ;tag=797b035a1d6b0049ed33903c5ef0eddf.bdba Call-ID: c66dcbcd-e144-354e-b169-0723760063cf CSeq: 109984153 REGISTER Date: Sat, 17 Jan 2009 15:09:39 GMT Contact: ;q=0.5;expires=3600 Server: OpenSer (1.1.0-notls (i386/linux)) Content-Length: 0 Warning: 392 212.97.59.76:5060 "Noisy feedback tells: pid=15519 req_src_ip=212.001.001.001 req_src_port=5080 in_uri=sip:sip.messagenet.it;transport=udp out_uri=sip:sip.messagenet.it;transport=udp via_cnt==1" ------------------------------------------------------------------------ tport(00DD8D20): msg 01B59988 (715 bytes) from udp/212.97.59.76:5080/sip next=00000000 nta: received 200 OK for REGISTER (109984153) nta: 200 OK is going to a transaction tport(00DD8D20): 01B58858 by 01B5C388 with 01B59988 nua(): refresh register after 2568 seconds (in [900..2700]) nua(): refresh register after 2568 seconds nua(01AE32E0): event r_register 200 OK 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_sofia] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:141 switch_loadable_module_process() Adding Endpoint 'sofia' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'sofia' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'sofia_contact' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:354 switch_loadable_module_process() Adding Chat interface 'sip' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:394 switch_loadable_module_process() Adding Management interface 'mod_sofia' OID[.1.3.6.1.4.1.27880.1] 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_loopback] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:141 switch_loadable_module_process() Adding Endpoint 'loopback' 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_commands] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'group_call' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'in_group' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_flush_dtmf' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'md5' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'hupall' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'strftime_tz' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'originate' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'tone_detect' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_kill' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_park' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'reloadacl' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'reloadxml' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'unload' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'reload' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'load' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_transfer' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'pause' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'break' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'show' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'complete' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'alias' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'status' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_session_heartbeat' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_bridge' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_setvar' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_setvar_multi' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_getvar' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_dump' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'global_setvar' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'global_getvar' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_displace' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_record' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_broadcast' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_hold' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_display' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_media' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'fsctl' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'help' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'version' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'sched_hangup' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'sched_broadcast' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'sched_transfer' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'create_uuid' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'sched_api' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'unsched_api' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'bgapi' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'sched_del' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'xml_wrap' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'is_lan_addr' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'cond' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'regex' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'acl' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_chat' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_deflect' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'find_user_xml' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'user_exists' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'xml_locate' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'user_data' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'url_encode' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'url_decode' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'module_exists' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'domain_exists' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'uuid_send_dtmf' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'eval' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'system' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'time_test' 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_conference] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'conference' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'conference_set_auto_outcall' 2009-01-17 16:09:37 [CONSOLE] sofia_presence.c:621 sofia_presence_event_thread_run() Event Thread Started 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'conference' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:354 switch_loadable_module_process() Adding Chat interface 'conf' 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_dptools] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:141 switch_loadable_module_process() Adding Endpoint 'error' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:141 switch_loadable_module_process() Adding Endpoint 'group' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:141 switch_loadable_module_process() Adding Endpoint 'user' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:203 switch_loadable_module_process() Adding Dialplan 'inline' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'privacy' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'flush_dtmf' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'hold' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'unhold' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'transfer' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'check_acl' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'verbose_events' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'sleep' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'delay_echo' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'strftime' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'phrase' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'eval' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'pre_answer' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'answer' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'hangup' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'set_name' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'presence' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'log' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'info' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'event' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'export' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'set' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'set_global' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'set_profile_var' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'unset' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'ring_ready' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'remove_bugs' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'break' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'detect_speech' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'ivr' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'redirect' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'send_display' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'respond' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'deflect' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'queue_dtmf' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'send_dtmf' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'sched_hangup' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'sched_broadcast' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'sched_transfer' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'execute_extension' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'mkdir' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'soft_hold' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'bind_meta_app' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'unbind_meta_app' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'intercept' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'eavesdrop' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'three_way' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'set_user' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'stop_dtmf' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'start_dtmf' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'stop_dtmf_generate' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'start_dtmf_generate' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'stop_tone_detect' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'fax_detect' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'tone_detect' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'echo' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'park' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'park_state' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'gentones' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'playback' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'att_xfer' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'read' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'stop_record_session' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'record_session' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'record' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'stop_displace_session' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'displace_session' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'speak' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'clear_speech_cache' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'bridge' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'system' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'say' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'wait_for_silence' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'strepoch' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'chat' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'strftime' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'presence' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:354 switch_loadable_module_process() Adding Chat interface 'event' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:354 switch_loadable_module_process() Adding Chat interface 'api' 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_expr] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'expr' 2009-01-17 16:09:37 [INFO] mod_fifo.c:1811 load_config() cool_fifo@192.168.0.74 configured 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_fifo] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'fifo' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'fifo' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'fifo_member' 2009-01-17 16:09:37 [INFO] mod_voicemail.c:765 load_config() Added Profile default 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_voicemail] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'voicemail' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'voicemail' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'voicemail_inject' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'vm_boxcount' 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_limit] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'limit' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'limit_hash' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'db' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'hash' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'group' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'limit_hash_usage' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'limit_usage' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'db' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'hash' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'group' 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_esf] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'esf_page_group' 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_fsv] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'play_fsv' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'record_fsv' 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_dialplan_xml] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:203 switch_loadable_module_process() Adding Dialplan 'XML' nta: timer set next to 4485 ms 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_dialplan_asterisk] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:141 switch_loadable_module_process() Adding Endpoint 'SIP' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:141 switch_loadable_module_process() Adding Endpoint 'IAX2' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:203 switch_loadable_module_process() Adding Dialplan 'asterisk' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'Dial' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'Goto' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'AvoidingDeadlock' 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_voipcodecs] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 16000hz 10ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 16000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 16000hz 30ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 16000hz 40ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 16000hz 50ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 16000hz 60ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 10ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 30ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 40ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 50ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 60ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 70ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 80ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 90ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 100ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 110ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'DVI4' (ADPCM (IMA)) 8000hz 120ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 10ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 30ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 40ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 50ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 60ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 70ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 80ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 90ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 100ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 110ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-16' (G.726 16k (AAL2)) 8000hz 120ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-16' (G.726 16k) 8000hz 10ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-16' (G.726 16k) 8000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-16' (G.726 16k) 8000hz 30ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-16' (G.726 16k) 8000hz 40ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-16' (G.726 16k) 8000hz 50ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-16' (G.726 16k) 8000hz 60ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-16' (G.726 16k) 8000hz 70ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-16' (G.726 16k) 8000hz 80ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-16' (G.726 16k) 8000hz 90ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-16' (G.726 16k) 8000hz 100ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-16' (G.726 16k) 8000hz 110ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-16' (G.726 16k) 8000hz 120ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 10ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 30ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 40ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 50ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 60ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 70ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 80ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 90ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 100ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 110ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-24' (G.726 24k (AAL2)) 8000hz 120ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 10ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 30ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 40ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 50ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 60ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 70ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 80ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 90ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 100ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 110ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-32' (G.726 32k (AAL2)) 8000hz 120ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-32' (G.726 32k) 8000hz 10ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-32' (G.726 32k) 8000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-32' (G.726 32k) 8000hz 30ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-32' (G.726 32k) 8000hz 40ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-32' (G.726 32k) 8000hz 50ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-32' (G.726 32k) 8000hz 60ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-32' (G.726 32k) 8000hz 70ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-32' (G.726 32k) 8000hz 80ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-32' (G.726 32k) 8000hz 90ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-32' (G.726 32k) 8000hz 100ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-32' (G.726 32k) 8000hz 110ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-32' (G.726 32k) 8000hz 120ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 10ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 30ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 40ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 50ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 60ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 70ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 80ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 90ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 100ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 110ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AAL2-G726-40' (G.726 40k (AAL2)) 8000hz 120ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-40' (G.726 40k) 8000hz 10ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-40' (G.726 40k) 8000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-40' (G.726 40k) 8000hz 30ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-40' (G.726 40k) 8000hz 40ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-40' (G.726 40k) 8000hz 50ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-40' (G.726 40k) 8000hz 60ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-40' (G.726 40k) 8000hz 70ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-40' (G.726 40k) 8000hz 80ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-40' (G.726 40k) 8000hz 90ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-40' (G.726 40k) 8000hz 100ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-40' (G.726 40k) 8000hz 110ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G726-40' (G.726 40k) 8000hz 120ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G722' (G.722) 16000hz 10ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G722' (G.722) 16000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G722' (G.722) 16000hz 30ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G722' (G.722) 16000hz 40ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G722' (G.722) 16000hz 50ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G722' (G.722) 16000hz 60ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'GSM' (GSM) 8000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'GSM' (GSM) 8000hz 40ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'GSM' (GSM) 8000hz 60ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'GSM' (GSM) 8000hz 80ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'GSM' (GSM) 8000hz 100ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'GSM' (GSM) 8000hz 120ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'LPC' (LPC-10) 8000hz 90ms 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_g723_1] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G723' (G.723.1 6.3k) 8000hz 30ms 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_g729] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G729' (G.729) 8000hz 10ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G729' (G.729) 8000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G729' (G.729) 8000hz 30ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G729' (G.729) 8000hz 40ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G729' (G.729) 8000hz 50ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G729' (G.729) 8000hz 60ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G729' (G.729) 8000hz 70ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G729' (G.729) 8000hz 80ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G729' (G.729) 8000hz 90ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G729' (G.729) 8000hz 100ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G729' (G.729) 8000hz 110ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'G729' (G.729) 8000hz 120ms 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_amr] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'AMR' (AMR) 8000hz 20ms 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_ilbc] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'iLBC' (iLBC) 8000hz 30ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'iLBC' (iLBC) 8000hz 30ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'iLBC20ms' (iLBC) 8000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'iLBC102' (iLBC) 8000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'iLBC' (iLBC) 8000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'iLBC' (iLBC) 8000hz 20ms 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_speex] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'SPEEX' (Speex) 32000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'SPEEX' (Speex) 16000hz 20ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'SPEEX' (Speex) 8000hz 20ms 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_h26x] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'H264' (H.264 Video (passthru)) 90000hz 0ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'H263' (H.263 Video (passthru)) 90000hz 0ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'H263-1998' (H.263+ Video (passthru)) 90000hz 0ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'H263-2000' (H.263++ Video (passthru)) 90000hz 0ms 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Codec 'H261' (H.261 Video (passthru)) 90000hz 0ms 2009-01-17 16:09:37 [INFO] mod_sndfile.c:308 setup_formats() LibSndFile Version : libsndfile-1.0.12 Supported Formats ================================================================================ AIFF (Apple/SGI) (extension "aiff") AU (Sun/NeXT) (extension "au") AVR (Audio Visual Research) (extension "avr") CAF (Apple Core Audio File) (extension "caf") HTK (HMM Tool Kit) (extension "htk") IFF (Amiga IFF/SVX8/SV16) (extension "iff") MAT4 (GNU Octave 2.0 / Matlab 4.2) (extension "mat") MAT5 (GNU Octave 2.1 / Matlab 5.0) (extension "mat") PAF (Ensoniq PARIS) (extension "paf") PVF (Portable Voice Format) (extension "pvf") RAW (header-less) (extension "raw") SD2 (Sound Designer II) (extension "sd2") SDS (Midi Sample Dump Standard) (extension "sds") SF (Berkeley/IRCAM/CARL) (extension "sf") VOC (Creative Labs) (extension "voc") W64 (SoundFoundry WAVE 64) (extension "w64") WAV (Microsoft) (extension "wav") WAV (NIST Sphere) (extension "wav") WAVEX (Microsoft) (extension "wav") XI (FastTracker 2) (extension "xi") ================================================================================ 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_sndfile] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'aiff' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'au' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'avr' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'caf' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'htk' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'iff' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'mat' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'mat' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'paf' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'pvf' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'raw' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'sd2' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'sds' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'sf' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'voc' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'w64' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'wav' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'wav' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'wav' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'xi' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'r8' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'r16' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'r24' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'r32' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'gsm' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'ul' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'al' 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_native_file] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'H263' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'iLBC20ms' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'AMR' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'H263-1998' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'SPEEX' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'G729' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'G723' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'G726-16' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'H261' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'AAL2-G726-16' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'DVI4' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'PCMA' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'PCMU' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'L16' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'iLBC20ms' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'PROXY' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'PROXY-VID' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'AAL2-G726-24' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'AAL2-G726-32' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'H263-2000' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'H264' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'G726-32' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'iLBC20ms' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'G722' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'AAL2-G726-40' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'G726-40' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'GSM' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'LPC' 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_local_stream] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'stop_local_stream' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'start_local_stream' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'local_stream' 2009-01-17 16:09:37 [CONSOLE] mod_local_stream.c:142 read_stream_thread() Can't open directory: C:\Program Files\FreeSwitch/sounds/music/16000 2009-01-17 16:09:37 [CONSOLE] mod_local_stream.c:142 read_stream_thread() Can't open directory: C:\Program Files\FreeSwitch/sounds/music/32000 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_tone_stream] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'tone_stream' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:281 switch_loadable_module_process() Adding File Format 'silence_stream' 2009-01-17 16:09:37 [CONSOLE] mod_spidermonkey.c:948 sm_load_file() Successfully Loaded [C:\Program Files\FreeSwitch\mod\mod_spidermonkey_teletone.dll] 2009-01-17 16:09:37 [CONSOLE] mod_spidermonkey.c:948 sm_load_file() Successfully Loaded [C:\Program Files\FreeSwitch\mod\mod_spidermonkey_core_db.dll] 2009-01-17 16:09:37 [CONSOLE] mod_spidermonkey.c:948 sm_load_file() Successfully Loaded [C:\Program Files\FreeSwitch\mod\mod_spidermonkey_socket.dll] 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_spidermonkey] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'javascript' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'jsrun' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'jsapi' 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_lua] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:239 switch_loadable_module_process() Adding Application 'lua' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'luarun' 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:259 switch_loadable_module_process() Adding API Function 'lua' 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:857 switch_loadable_module_load_file() Successfully Loaded [mod_say_en] 2009-01-17 16:09:37 [NOTICE] switch_loadable_module.c:372 switch_loadable_module_process() Adding Say interface 'en' 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:119 switch_loadable_module_runtime() Starting runtime thread for CORE_SOFTTIMER_MODULE 2009-01-17 16:09:37 [CONSOLE] switch_loadable_module.c:119 switch_loadable_module_runtime() Starting runtime thread for mod_event_socket 2009-01-17 16:09:37 [CONSOLE] switch_core.c:865 switch_load_network_lists() Created ip list dl-candidates default (allow) 2009-01-17 16:09:37 [NOTICE] switch_core.c:948 switch_load_network_lists() Adding 10.0.0.0/8 (deny) to list dl-candidates 2009-01-17 16:09:37 [NOTICE] switch_core.c:948 switch_load_network_lists() Adding 172.16.0.0/12 (deny) to list dl-candidates 2009-01-17 16:09:37 [NOTICE] switch_core.c:948 switch_load_network_lists() Adding 192.168.0.0/16 (deny) to list dl-candidates 2009-01-17 16:09:37 [CONSOLE] switch_core.c:865 switch_load_network_lists() Created ip list rfc1918 default (deny) 2009-01-17 16:09:37 [NOTICE] switch_core.c:948 switch_load_network_lists() Adding 10.0.0.0/8 (allow) to list rfc1918 2009-01-17 16:09:37 [NOTICE] switch_core.c:948 switch_load_network_lists() Adding 172.16.0.0/12 (allow) to list rfc1918 2009-01-17 16:09:37 [NOTICE] switch_core.c:948 switch_load_network_lists() Adding 192.168.0.0/16 (allow) to list rfc1918 2009-01-17 16:09:37 [CONSOLE] switch_core.c:865 switch_load_network_lists() Created ip list lan default (allow) 2009-01-17 16:09:37 [NOTICE] switch_core.c:948 switch_load_network_lists() Adding 192.168.42.0/24 (deny) to list lan 2009-01-17 16:09:37 [NOTICE] switch_core.c:948 switch_load_network_lists() Adding 192.168.42.42/32 (allow) to list lan 2009-01-17 16:09:37 [CONSOLE] switch_core.c:865 switch_load_network_lists() Created ip list strict default (deny) 2009-01-17 16:09:37 [NOTICE] switch_core.c:948 switch_load_network_lists() Adding 208.102.123.124/32 (allow) to list strict 2009-01-17 16:09:37 [CONSOLE] switch_core.c:865 switch_load_network_lists() Created ip list domains default (deny) 2009-01-17 16:09:37 [CONSOLE] switch_core.c:1287 switch_core_init_and_modload() FreeSWITCH Version 1.0.trunk (UNKNOWN) Started. Crash Protection [Disabled] Max Sessions[1000] Session Rate[30] SQL [Enabled] freeswitch@inetmail2> nta: timer K fired, terminate REGISTER (109984152) outgoing_reclaim_all(00000000, 00000000, 027FFEC0) nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free nta: timer set next to 1 ms nta: timer K fired, terminate REGISTER (109984153) outgoing_reclaim_all(00000000, 00000000, 027FFEC0) nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free nta: timer not set tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 01B59988 from (udp/192.168.0.74:5060) has 543 bytes, veclen = 1 recv 543 bytes from udp/[192.168.0.52]:63214 at 15:09:57.844578: ------------------------------------------------------------------------ REGISTER sip:192.168.0.74 SIP/2.0 Via: SIP/2.0/UDP 192.168.0.52:63214;branch=z9hG4bK-d8754z-10626c213e281d27-1---d8754z-;rport Max-Forwards: 70 Contact: To: "Alberto" From: "Alberto";tag=544adf7f Call-ID: ZjNlNGY5ZGE5OGI5MjFkNTc3Y2RjMzhhMDc4MmM1NDc. CSeq: 1 REGISTER Expires: 3600 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO User-Agent: X-Lite release 1100l stamp 47546 Content-Length: 0 ------------------------------------------------------------------------ tport(01AE9650): msg 01B59988 (543 bytes) from udp/192.168.0.52:5060/sip next=00000000 nta: received REGISTER sip:192.168.0.74 SIP/2.0 (CSeq 1) nta: canonizing sip:192.168.0.74 with contact nta: REGISTER (1) going to a default leg soa_clone(static::01AE57C0, 01AE28C0, 01B6FBD8) called soa_set_params(static::01B5EF70, ...) called nua(01B6FBD8): event i_register 100 Trying nua(01B6FBD8): sent signal r_respond nua(01B6FBD8): recv signal r_respond 401 Unauthorized soa_set_params(static::01B5EF70, ...) called nua(01B6FBD8): sent signal r_destroy tport_tsend(01AE9650) tpn = UDP/192.168.0.52:63214 tport_resolve addrinfo = 192.168.0.52:63214 tport(01AE9650): not found by name UDP/192.168.0.52:63214 tport_vsend(01AE9650): 667 bytes of 667 to udp/192.168.0.52:63214 tport_vsend returned 667 send 667 bytes to udp/[192.168.0.52]:63214 at 15:09:57.844578: ------------------------------------------------------------------------ SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.0.52:63214;branch=z9hG4bK-d8754z-10626c213e281d27-1---d8754z-;rport=63214 From: "Alberto";tag=544adf7f To: "Alberto" ;tag=mypD78F0vQSjc Call-ID: ZjNlNGY5ZGE5OGI5MjFkNTc3Y2RjMzhhMDc4MmM1NDc. CSeq: 1 REGISTER User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces WWW-Authenticate: Digest realm="192.168.0.74", nonce="8d55768a-5992-1c4d-912e-92961f1f4e5e", algorithm=MD5, qop="auth" Content-Length: 0 ------------------------------------------------------------------------ nta: sent 401 Unauthorized for REGISTER (1) nta: timer set to 32000 ms nua(01B6FBD8): recv signal r_destroy nta_leg_destroy(00000000) soa_destroy(static::01B5EF70) called tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 01B5D978 from (udp/192.168.0.74:5060) has 792 bytes, veclen = 1 recv 792 bytes from udp/[192.168.0.52]:63214 at 15:09:58.047644: ------------------------------------------------------------------------ REGISTER sip:192.168.0.74 SIP/2.0 Via: SIP/2.0/UDP 192.168.0.52:63214;branch=z9hG4bK-d8754z-53075b0a800fd422-1---d8754z-;rport Max-Forwards: 70 Contact: To: "Alberto" From: "Alberto";tag=544adf7f Call-ID: ZjNlNGY5ZGE5OGI5MjFkNTc3Y2RjMzhhMDc4MmM1NDc. CSeq: 2 REGISTER Expires: 3600 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO User-Agent: X-Lite release 1100l stamp 47546 Authorization: Digest username="1008",realm="192.168.0.74",nonce="8d55768a-5992-1c4d-912e-92961f1f4e5e",uri="sip:192.168.0.74",response="55d0982a97d14c1b35e41e9617710f4f",cnonce="d4204f159a249cc80d1ea728bac88ad2",nc=00000001,qop=auth,algorithm=MD5 Content-Length: 0 ------------------------------------------------------------------------ tport(01AE9650): msg 01B5D978 (792 bytes) from udp/192.168.0.52:5060/sip next=00000000 nta: received REGISTER sip:192.168.0.74 SIP/2.0 (CSeq 2) nta: canonizing sip:192.168.0.74 with contact nta: REGISTER (2) going to a default leg soa_clone(static::01AE57C0, 01AE28C0, 01B6FBD8) called soa_set_params(static::01B75A20, ...) called nua(01B6FBD8): event i_register 100 Trying nua(01B6FBD8): sent signal r_respond nua(01B6FBD8): recv signal r_respond 200 OK soa_set_params(static::01B75A20, ...) called tport_tsend(01AE9650) tpn = UDP/192.168.0.52:63214 tport_resolve addrinfo = 192.168.0.52:63214 tport(01AE9650): not found by name UDP/192.168.0.52:63214 tport_vsend(01AE9650): 654 bytes of 654 to udp/192.168.0.52:63214 tport_vsend returned 654 nua(01B6FBD8): sent signal r_destroy send 654 bytes to udp/[192.168.0.52]:63214 at 15:09:58.047644: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.52:63214;branch=z9hG4bK-d8754z-53075b0a800fd422-1---d8754z-;rport=63214 From: "Alberto";tag=544adf7f To: "Alberto" ;tag=N7F68303S0F5Q Call-ID: ZjNlNGY5ZGE5OGI5MjFkNTc3Y2RjMzhhMDc4MmM1NDc. CSeq: 2 REGISTER Contact: ;expires=3600 Date: Sat, 17 Jan 2009 15:09:58 GMT User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces Content-Length: 0 ------------------------------------------------------------------------ nta: sent 200 OK for REGISTER (2) nua(01B6FBD8): recv signal r_destroy nta_leg_destroy(00000000) soa_destroy(static::01B75A20) called nua(01B6FBD8): sent signal r_notify nua(01B6FBD8): recv signal r_notify soa_clone(static::01AE57C0, 01AE28C0, 01B6FBD8) called soa_set_params(static::01B75A20, ...) called soa_set_params(static::01B75A20, ...) called nta_leg_tcreate(00DD7010) nua(01B6FBD8): adding notify usage with event message-summary nta: selecting scheme sip tport_tsend(01AE9650) tpn = */192.168.0.52:63214 tport_resolve addrinfo = 192.168.0.52:63214 tport(01AE9650): not found by name */192.168.0.52:63214 tport_vsend(01AE9650): 887 bytes of 887 to udp/192.168.0.52:63214 tport_vsend returned 887 send 887 bytes to udp/[192.168.0.52]:63214 at 15:09:58.141367: ------------------------------------------------------------------------ NOTIFY sip:1008@192.168.0.52:63214;rinstance=a8691dfbc11775d5 SIP/2.0 Via: SIP/2.0/UDP 192.168.0.74;rport;branch=z9hG4bKmD9FZQBy2FKXp Max-Forwards: 70 From: ;tag=pg9yaZH7p95QK To: Call-ID: beeb792e-5f4b-122c-2780-39a48cb53b8d CSeq: 109984163 NOTIFY Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces Event: message-summary Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer Subscription-State: terminated;timeout Content-Type: application/simple-message-summary Content-Length: 64 Messages-Waiting: no Message-Account: sip:1008@192.168.0.74 ------------------------------------------------------------------------ nta: sent NOTIFY (109984163) to */192.168.0.52:63214 tport_pend(01AE9650): pending 01B72C38 for udp/192.168.0.74:5060 (already 0) nta: timer shortened to 500 ms tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 02F2FC50 from (udp/192.168.0.74:5060) has 546 bytes, veclen = 1 recv 546 bytes from udp/[192.168.0.52]:63214 at 15:09:58.141367: ------------------------------------------------------------------------ SUBSCRIBE sip:1008@192.168.0.74 SIP/2.0 Via: SIP/2.0/UDP 192.168.0.52:63214;branch=z9hG4bK-d8754z-f3490746570af404-1---d8754z-;rport Max-Forwards: 70 Contact: To: "Alberto" From: "Alberto";tag=d11f6f44 Call-ID: ZDY2ZWMwN2M0MTkwZDA1NDJkOGNhZmNmYTg5NDgyNGY. CSeq: 1 SUBSCRIBE Expires: 300 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO User-Agent: X-Lite release 1100l stamp 47546 Event: message-summary Content-Length: 0 ------------------------------------------------------------------------ tport(01AE9650): msg 02F2FC50 (546 bytes) from udp/192.168.0.52:5060/sip next=00000000 nta: received SUBSCRIBE sip:1008@192.168.0.74 SIP/2.0 (CSeq 1) nta: canonizing sip:1008@192.168.0.74 with contact nta: SUBSCRIBE (1) going to a default leg soa_clone(static::01AE57C0, 01AE28C0, 01B72720) called soa_set_params(static::01B5EF70, ...) called nta_leg_tcreate(01B6F530) nua(01B72720): adding notify usage with event message-summary nua(01B72720): event i_subscribe 100 Trying nua(): refresh notify after 600 seconds (in [600..600]) nua(): refresh notify after 600 seconds nua(01B72720): recv signal r_respond 202 Accepted soa_set_params(static::01B5EF70, ...) called tport_tsend(01AE9650) tpn = UDP/192.168.0.52:63214 tport_resolve addrinfo = 192.168.0.52:63214 tport(01AE9650): not found by name UDP/192.168.0.52:63214 tport_vsend(01AE9650): 771 bytes of 771 to udp/192.168.0.52:63214 tport_vsend returned 771 send 771 bytes to udp/[192.168.0.52]:63214 at 15:09:58.141367: ------------------------------------------------------------------------ SIP/2.0 202 Accepted Via: SIP/2.0/UDP 192.168.0.52:63214;branch=z9hG4bK-d8754z-f3490746570af404-1---d8754z-;rport=63214 From: "Alberto";tag=d11f6f44 To: "Alberto" ;tag=QS2Qct2amjvaF Call-ID: ZDY2ZWMwN2M0MTkwZDA1NDJkOGNhZmNmYTg5NDgyNGY.nua(01B72720): sent signal r_respond CSeq: 1 SUBSCRIBE Contact: "Alberto" Expires: 300 User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer Subscription-State: active;expires=300 Content-Length: 0 ------------------------------------------------------------------------ nta: sent 202 Accepted for SUBSCRIBE (1) tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 01D71768 from (udp/192.168.0.74:5060) has 350 bytes, veclen = 1 recv 350 bytes from udp/[192.168.0.52]:63214 at 15:09:58.141367: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.74;rport=5060;branch=z9hG4bKmD9FZQBy2FKXp Contact: To: ;tag=321bea76 From: ;tag=pg9yaZH7p95QK Call-ID: beeb792e-5f4b-122c-2780-39a48cb53b8d CSeq: 109984163 NOTIFY User-Agent: X-Lite release 1100l stamp 47546 Content-Length: 0 ------------------------------------------------------------------------ tport(01AE9650): msg 01D71768 (350 bytes) from udp/192.168.0.52:5060/sip next=00000000 nta: received 200 OK for NOTIFY (109984163) nta: 200 OK is going to a transaction nta_outgoing: RTT is 15.621 ms tport(01AE9650): 01B72C38 by 01AED108 with 01D71768 nua(01B6FBD8): event r_notify 200 OK nua(01B6FBD8): removing notify usage with event message-summary nta_leg_destroy(00DD7010) nua(01B6FBD8): sent signal r_destroy nua(01B6FBD8): recv signal r_destroy nta_leg_destroy(00000000) soa_destroy(static::01B75A20) called nta: timer set next to 4484 ms tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 02F4BC48 from (udp/192.168.0.74:5060) has 4 bytes, veclen = 1 tport(01AE9650): bad msg 02F4BC48 (4 bytes) from udp/192.168.0.38:5060/sip next=00000000 nta: timer K fired, terminate NOTIFY (109984163) outgoing_reclaim_all(00000000, 00000000, 028FFEC0) nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free nta: timer set next to 26689 ms tport_wakeup_pri(00DD8D20): events IN tport_recv_event(00DD8D20) tport(00DD8D20) msg 01D71768 from (udp/10.0.3.6:5080) has 1168 bytes, veclen = 1 recv 1168 bytes from udp/[212.97.59.76]:5060 at 15:10:08.076005: ------------------------------------------------------------------------ INVITE sip:5343504@212.001.001.001:5080;transport=udp SIP/2.0 Record-Route: Record-Route: Via: SIP/2.0/UDP 212.97.59.76;branch=z9hG4bKe61f.f220cd02.0 Via: SIP/2.0/UDP 212.97.59.87:5060;branch=z9hG4bK57267a99;rport=5060 From: "+39020000000" ;tag=as302aa103 To: Contact: Call-ID: 0991575e53c716557b287c0f4e34a842@sip.messagenet.it CSeq: 102 INVITE User-Agent: alpha Max-Forwards: 69 Date: Sat, 17 Jan 2009 15:10:11 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Type: application/sdp Content-Length: 395 v=0 o=root 3859 3859 IN IP4 212.97.59.87 s=session c=IN IP4 212.97.59.91 t=0 0 m=audio 38196 RTP/AVP 18 3 97 8 0 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:3 GSM/8000 a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=30 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv a=nortpproxy:yes ------------------------------------------------------------------------ tport(00DD8D20): msg 01D71768 (1168 bytes) from udp/212.97.59.76:5080/sip next=00000000 nta: received INVITE sip:5343504@212.001.001.001:5080;transport=udp SIP/2.0 (CSeq 102) nta: canonizing sip:5343504@212.001.001.001:5080 with contact nta: INVITE (102) going to a default leg nta: timer set to 200 ms soa_clone(static::01ADEDC8, 0137A8D8, 02EDF6E0) called soa_set_params(static::01B75A20, ...) called nta_leg_tcreate(02F004E0) soa_init_offer_answer(static::01B75A20) called soa_set_remote_sdp(static::01B75A20, 00000000, 02F47CAD, 395) called nua(02EDF6E0): adding session usage tport_tsend(00DD8D20) tpn = UDP/212.97.59.76:5060 tport_resolve addrinfo = 212.97.59.76:5060 tport(00DD8D20): not found by name UDP/212.97.59.76:5060 tport_vsend(00DD8D20): 546 bytes of 546 to udp/212.97.59.76:5060 tport_vsend returned 546 send 546 bytes to udp/[212.97.59.76]:5060 at 15:10:08.076005: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 212.97.59.76;branch=z9hG4bKe61f.f220cd02.0 Via: SIP/2.0/UDP 212.97.59.87:5060;branch=z9hG4bK57267a99;rport=5060 Record-Route: Record-Route: From: "+39020000000" ;tag=as302aa103 To: Call-ID: 0991575e53c716557b287c0f4e34a842@sip.messagenet.it CSeq: 102 INVITE User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Content-Length: 0 ------------------------------------------------------------------------ nta: sent 100 Trying for INVITE (102) nua(02EDF6E0): event i_invite 100 Trying nua(02EDF6E0): call state changed: init -> received, received offer soa_get_remote_sdp(static::01B75A20, [027FFC14], [027FFC10], [00000000]) called nua(02EDF6E0): event i_state 100 Trying 2009-01-17 16:10:08 [NOTICE] switch_channel.c:565 switch_channel_set_name() New Channel sofia/external/+39020000000@sip.messagenet.it [711a2124-077d-f442-ae20-e63b2273f326] 2009-01-17 16:10:08 [INFO] mod_dialplan_xml.c:233 dialplan_hunt() Processing +39020000000->5343504 in context public 2009-01-17 16:10:08 [NOTICE] switch_ivr.c:1255 switch_ivr_session_transfer() Transfer sofia/external/+39020000000@sip.messagenet.it to XML[1008@default] 2009-01-17 16:10:08 [INFO] mod_dialplan_xml.c:233 dialplan_hunt() Processing +39020000000->1008 in context default 2009-01-17 16:10:08 [ERR] switch_regex.c:94 switch_regex_perform() COMPILE ERROR: 1 [nothing to repeat][^+39020000000$] 2009-01-17 16:10:08 [INFO] switch_ivr_async.c:1644 switch_ivr_bind_dtmf_meta_session() Bound B-Leg: 1 execute_extension::dx XML features 2009-01-17 16:10:08 [INFO] switch_ivr_async.c:1644 switch_ivr_bind_dtmf_meta_session() Bound B-Leg: 2 record_session::C:Program FilesFreeSwitch/recordings/+39020000000.2009-01-17-16-10-08.wav 2009-01-17 16:10:08 [INFO] switch_ivr_async.c:1644 switch_ivr_bind_dtmf_meta_session() Bound B-Leg: 3 execute_extension::cf XML features 2009-01-17 16:10:08 [NOTICE] switch_channel.c:565 switch_channel_set_name() New Channel sofia/internal/sip:1008@192.168.0.52:63214;rinstance=a8691dfbc11775d5 [698623ef-686c-9c4d-93c7-3cc6db803902] nua(02EDF468): sent signal r_invite nua(02EDF468): recv signal r_invite soa_clone(static::01AE57C0, 01AE28C0, 02EDF468) called soa_set_params(static::01D7FE78, ...) called soa_set_params(static::01D7FE78, ...) called soa_set_user_sdp(static::01D7FE78, 00000000, 012733C2, -1) called soa_set_capability_sdp(static::01D7FE78, 00000000, 012733C2, -1) called nta_leg_tcreate(02F47280) nua(02EDF468): adding session usage soa_init_offer_answer(static::01D7FE78) called soa_generate_offer(static::01D7FE78, 0) called soa_static_offer_answer_action(01D7FE78, soa_generate_offer): called soa_static(01D7FE78, soa_generate_offer): generating local description soa_static(01D7FE78, soa_generate_offer): upgrade with local description soa_sdp_mode_set(028FDCAC, 00000000, ""): called soa_static(01D7FE78, soa_generate_offer): storing local description soa_get_local_sdp(static::01D7FE78, [00000000], [028FFDC4], [028FFDC0]) called nta: selecting scheme sip tport_tsend(01AE9650) tpn = */192.168.0.52:63214 tport_resolve addrinfo = 192.168.0.52:63214 tport(01AE9650): not found by name */192.168.0.52:63214 tport_vsend(01AE9650): 1261 bytes of 1261 to udp/192.168.0.52:63214 tport_vsend returned 1261 send 1261 bytes to udp/[192.168.0.52]:63214 at 15:10:08.216590: ------------------------------------------------------------------------ INVITE sip:1008@192.168.0.52:63214;rinstance=a8691dfbc11775d5 SIP/2.0 Via: SIP/2.0/UDP 192.168.0.74;rport;branch=z9hG4bKNp280jv1Zr9Fj Max-Forwards: 67 From: "+39020000000" ;tag=r2UgeNKeHUjXa To: Call-ID: c4ecd490-5f4b-122c-2780-39a48cb53b8d CSeq: 109984168 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer Min-SE: 120 Content-Type: application/sdp Content-Disposition: session Content-Length: 333 Remote-Party-ID: "+39020000000" ;screen=yes;privacy=off v=0 o=FreeSWITCH 6699757194367626025 7696645903169343292 IN IP4 192.168.0.74 s=FreeSWITCH c=IN IP4 192.168.0.74 t=0 0 m=audio 29688 RTP/AVP 3 9 0 8 101 13 a=rtpmap:3 GSM/8000 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=ptime:20 ------------------------------------------------------------------------ nta: sent INVITE (109984168) to */192.168.0.52:63214 tport_pend(01AE9650): pending 02FC1940 for udp/192.168.0.74:5060 (already 0) nta: timer shortened to 500 ms nua(02EDF468): call state changed: init -> calling, sent offer soa_get_local_sdp(static::01D7FE78, [028FFDC4], [028FFDC0], [00000000]) called nua(02EDF468): event i_state INVITE sent nta: timer not set 2009-01-17 16:10:08 [INFO] mod_sofia.c:1294 sofia_receive_message() Asked to send early media by sofia/external/+39020000000@sip.messagenet.it 2009-01-17 16:10:08 [INFO] mod_sofia.c:1335 sofia_receive_message() Ring SDP: v=0 o=FreeSWITCH 1232175320 1232175321 IN IP4 212.001.001.001 s=FreeSWITCH c=IN IP4 212.001.001.001 t=0 0 m=audio 29688 RTP/AVP 3 101 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv 2009-01-17 16:10:08 [NOTICE] mod_sofia.c:1338 sofia_receive_message() Ring-Ready sofia/external/+39020000000@sip.messagenet.it! 2009-01-17 16:10:08 [NOTICE] mod_sofia.c:1338 sofia_receive_message() Pre-Answer sofia/external/+39020000000@sip.messagenet.it! nua(02EDF6E0): sent signal r_respond nua(02EDF6E0): recv signal r_respond 183 Session Progress soa_set_params(static::01B75A20, ...) called soa_set_user_sdp(static::01B75A20, 00000000, 00DECA53, -1) called soa_set_capability_sdp(static::01B75A20, 00000000, 00DECA53, -1) called soa_generate_answer(static::01B75A20) called soa_static_offer_answer_action(01B75A20, soa_generate_answer): called soa_static(01B75A20, soa_generate_answer): generating local description soa_static(01B75A20, soa_generate_answer): upgrade with remote description soa_sdp_mode_set(027FDCF8, 01274870, ""): called soa_static(01B75A20, soa_generate_answer): storing local description soa_activate(static::01B75A20, (nil)) called soa_get_local_sdp(static::01B75A20, [00000000], [027FFE0C], [027FFE08]) called tport_tsend(00DD8D20) tpn = UDP/212.97.59.76:5060 tport_resolve addrinfo = 212.97.59.76:5060 tport(00DD8D20): not found by name UDP/212.97.59.76:5060 tport_vsend(00DD8D20): 1170 bytes of 1170 to udp/212.97.59.76:5060 tport_vsend returned 1170 send 1170 bytes to udp/[212.97.59.76]:5060 at 15:10:08.310313: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 212.97.59.76;branch=z9hG4bKe61f.f220cd02.0 Via: SIP/2.0/UDP 212.97.59.87:5060;branch=z9hG4bK57267a99;rport=5060 Record-Route: Record-Route: From: "+39020000000" ;tag=as302aa103 To: ;tag=N7F68303S0F5Q Call-ID: 0991575e53c716557b287c0f4e34a842@sip.messagenet.it CSeq: 102 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO Supported: timer, precondition, path, replaces Allow-Events: talk, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 266 v=0 o=FreeSWITCH 1110445663877587962 6324161778166975444 IN IP4 212.001.001.001 s=FreeSWITCH c=IN IP4 212.001.001.001 t=0 0 m=audio 29688 RTP/AVP 3 101 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 ------------------------------------------------------------------------ nta: sent 183 Session Progress for INVITE (102) nta: timer set to 60000 ms nua(02EDF6E0): call state changed: received -> early, sent answer soa_get_local_sdp(static::01B75A20, [027FFE48], [027FFE44], [00000000]) called soa_get_params(static::01B75A20, ...) called nua(02EDF6E0): event i_state 183 Session Progress tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 02F4BC48 from (udp/192.168.0.74:5060) has 316 bytes, veclen = 1 recv 316 bytes from udp/[192.168.0.52]:63214 at 15:10:08.325933: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.0.74;rport=5060;branch=z9hG4bKNp280jv1Zr9Fj To: From: "+39020000000" ;tag=r2UgeNKeHUjXa Call-ID: c4ecd490-5f4b-122c-2780-39a48cb53b8d CSeq: 109984168 INVITE Content-Length: 0 ------------------------------------------------------------------------ tport(01AE9650): msg 02F4BC48 (316 bytes) from udp/192.168.0.52:5060/sip next=00000000 nta: received 100 Trying for INVITE (109984168) nta: 100 Trying is going to a transaction nta_outgoing: RTT is 109.343 ms tport(01AE9650): 02FC1940 by 02F30CF8 with 02F4BC48 (preliminary) tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 02F4BC48 from (udp/192.168.0.74:5060) has 442 bytes, veclen = 1 recv 442 bytes from udp/[192.168.0.52]:63214 at 15:10:08.638343: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.0.74;rport=5060;branch=z9hG4bKNp280jv1Zr9Fj Contact: To: ;tag=8b08c857 From: "+39020000000";tag=r2UgeNKeHUjXa Call-ID: c4ecd490-5f4b-122c-2780-39a48cb53b8d CSeq: 109984168 INVITE User-Agent: X-Lite release 1100l stamp 47546 Content-Length: 0 ------------------------------------------------------------------------ tport(01AE9650): msg 02F4BC48 (442 bytes) from udp/192.168.0.52:5060/sip next=00000000 nta: received 180 Ringing for INVITE (109984168) nta: 180 Ringing is going to a transaction tport(01AE9650): 02FC1940 by 02F30CF8 with 02F4BC48 (preliminary) nua(02EDF468): event r_invite 180 Ringing nua(02EDF468): call state changed: calling -> proceeding nua(02EDF468): event i_state 180 Ringing 2009-01-17 16:10:08 [NOTICE] sofia.c:2627 sofia_handle_sip_i_state() Ring-Ready sofia/internal/sip:1008@192.168.0.52:63214;rinstance=a8691dfbc11775d5! nta: timer set next to 21113 ms tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 02F51A68 from (udp/192.168.0.74:5060) has 740 bytes, veclen = 1 recv 740 bytes from udp/[192.168.0.52]:63214 at 15:10:12.059233: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.74;rport=5060;branch=z9hG4bKNp280jv1Zr9Fj Contact: To: ;tag=8b08c857 From: "+39020000000";tag=r2UgeNKeHUjXa Call-ID: c4ecd490-5f4b-122c-2780-39a48cb53b8d CSeq: 109984168 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Content-Type: application/sdp User-Agent: X-Lite release 1100l stamp 47546 Content-Length: 187 v=0 o=- 6 2 IN IP4 192.168.0.52 s=CounterPath X-Lite 3.0 c=IN IP4 192.168.0.52 t=0 0 m=audio 28768 RTP/AVP 3 0 8 101 a=fmtp:101 0-15 a=rtpmap:101 telephone-event/8000 a=sendrecv ------------------------------------------------------------------------ tport(01AE9650): msg 02F51A68 (740 bytes) from udp/192.168.0.52:5060/sip next=00000000 nta: received 200 OK for INVITE (109984168) nta: 200 OK is going to a transaction tport(01AE9650): 02FC1940 by 02F30CF8 with 02F51A68 soa_set_remote_sdp(static::01D7FE78, 00000000, 02FD0601, 187) called soa_process_answer(static::01D7FE78) called soa_static_offer_answer_action(01D7FE78, soa_process_answer): called soa_sdp_mode_set(02FC27A0, 03007728, ""): called soa_static(01D7FE78, soa_process_answer): upgrade codecs with remote description soa_static(01D7FE78, soa_process_answer): storing local description soa_activate(static::01D7FE78, (nil)) called nua(02EDF468): INVITE: processed SDP answer in 200 OK nua(02EDF468): event r_invite 200 OK soa_activate(static::01D7FE78, (nil)) called nta: selecting scheme sip tport_tsend(01AE9650) tpn = */192.168.0.52:63214 tport_resolve addrinfo = 192.168.0.52:63214 tport(01AE9650): not found by name */192.168.0.52:63214 tport_vsend(01AE9650): 431 bytes of 431 to udp/192.168.0.52:63214 tport_vsend returned 431 send 431 bytes to udp/[192.168.0.52]:63214 at 15:10:12.059233: ------------------------------------------------------------------------ ACK sip:1008@192.168.0.52:63214;rinstance=a8691dfbc11775d5 SIP/2.0 Via: SIP/2.0/UDP 192.168.0.74;rport;branch=z9hG4bKpZU12DD5v1Z2D Max-Forwards: 70 From: "+39020000000" ;tag=r2UgeNKeHUjXa To: ;tag=8b08c857 Call-ID: c4ecd490-5f4b-122c-2780-39a48cb53b8d CSeq: 109984168 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ nta: sent ACK (109984168) to */192.168.0.52:63214 nua(02EDF468): call state changed: proceeding -> ready, received answer soa_get_remote_sdp(static::01D7FE78, [028FFC2C], [028FFC28], [00000000]) called soa_get_params(static::01D7FE78, ...) called nua(02EDF468): event i_state 200 OK nua(02EDF468): event i_active 200 Call active nua(02EDF6E0): recv signal r_respond 200 OK soa_set_params(static::01B75A20, ...) called soa_set_user_sdp(static::01B75A20, 00000000, 0127FA5D, -1) called soa_get_local_sdp(static::01B75A20, [00000000], [027FFE0C], [027FFE08]) called tport_tsend(00DD8D20) tpn = UDP/212.97.59.76:5060 2009-01-17 16:10:12 [NOTICE] sofia.c:3065 sofia_handle_sip_i_state() Channel [sofia/internal/sip:1008@192.168.0.52:63214;rinstance=a8691dfbc11775d5] has been answered tport_resolve addrinfo = 212.97.59.76:5060 tport(00DD8D20): not found by name UDP/212.97.59.76:5060 tport_vsend(00DD8D20): 1144 bytes of 1144 to udp/212.97.59.76:5060 tport_vsend returned 1144 send 1144 bytes to udp/[212.97.59.76]:5060 at 15:10:12.074853: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 212.97.59.76;branch=z9hG4bKe61f.f220cd02.0 Via: SIP/2.0/UDP 212.97.59.87:5060;branch=z9hG4bK57267a99;rport=5060 Record-Route: Record-Route: From: "+39020000000" ;tag=as302aa103 To: ;tag=N7F68303S0F5Q Call-ID: 0991575e53c716557b287c0f4e34a842@sip.messagenet.it CSeq: 102 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO Supported: timer, precondition, path, replaces Allow-Events: talk, refer Min-SE: 120 Content-Type: application/sdp Content-Disposition: session Content-Length: 266 v=0 o=FreeSWITCH 1110445663877587962 6324161778166975444 IN IP4 212.001.001.001 s=FreeSWITCH c=IN IP4 212.001.001.001 t=0 0 m=audio 29688 RTP/AVP 3 101 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 ------------------------------------------------------------------------ nta: sent 200 OK for INVITE (102) nta: timer shortened to 500 ms nua(02EDF6E0): call state changed: early -> completed, sent answer soa_get_local_sdp(static::01B75A20, [027FFE48], [027FFE44], [00000000]) called soa_get_params(static::01B75A20, ...) called nua(02EDF6E0): event i_state 200 OK nua(02EDF6E0): sent signal r_respond 2009-01-17 16:10:12 [NOTICE] switch_ivr_originate.c:1597 switch_ivr_originate() Channel [sofia/external/+39020000000@sip.messagenet.it] has been answered tport_wakeup_pri(00DD8D20): events IN tport_recv_event(00DD8D20) tport(00DD8D20) msg 02F87F58 from (udp/10.0.3.6:5080) has 628 bytes, veclen = 1 recv 628 bytes from udp/[212.97.59.76]:5060 at 15:10:12.106094: ------------------------------------------------------------------------ ACK sip:mod_sofia@212.001.001.001:5080;transport=udp SIP/2.0 Record-Route: Record-Route: Via: SIP/2.0/UDP 212.97.59.76;branch=z9hG4bKe61f.f220cd02.2 Via: SIP/2.0/UDP 212.97.59.87:5060;branch=z9hG4bK0498006e;rport=5060 From: "+39020000000" ;tag=as302aa103 To: ;tag=N7F68303S0F5Q Contact: Call-ID: 0991575e53c716557b287c0f4e34a842@sip.messagenet.it CSeq: 102 ACK User-Agent: alpha Max-Forwards: 69 Content-Length: 0 ------------------------------------------------------------------------ tport(00DD8D20): msg 02F87F58 (628 bytes) from udp/212.97.59.76:5080/sip next=00000000 nta: received ACK sip:mod_sofia@212.001.001.001:5080;transport=udp SIP/2.0 (CSeq 102) nta: ACK (102) is going to INVITE (102) soa_clear_remote_sdp(static::01B75A20) called nua(02EDF6E0): event i_ack 200 OK nua(02EDF6E0): call state changed: completed -> ready nua(02EDF6E0): event i_state 200 OK nua(02EDF6E0): event i_active 200 Call active tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 02F87F58 from (udp/192.168.0.74:5060) has 574 bytes, veclen = 1 recv 574 bytes from udp/[192.168.0.38]:51091 at 15:10:12.527848: ------------------------------------------------------------------------ SUBSCRIBE sip:1002@192.168.0.74 SIP/2.0 Via: SIP/2.0/UDP 192.168.0.38:51091;branch=z9hG4bK-d8754z-08453f6c6e106020-1---d8754z-;rport Max-Forwards: 70 Contact: To: "Alessandro Ceccarelli" From: "Alessandro Ceccarelli";tag=333d701e Call-ID: NTU2NDA2N2VjMzZlZTY4ODJlYmUwN2Y1MDc3NmFiOGY. CSeq: 1 SUBSCRIBE Expires: 300 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO User-Agent: X-Lite release 1100l stamp 47546 Event: message-summary Content-Length: 0 ------------------------------------------------------------------------ tport(01AE9650): msg 02F87F58 (574 bytes) from udp/192.168.0.38:5060/sip next=00000000 nta: received SUBSCRIBE sip:1002@192.168.0.74 SIP/2.0 (CSeq 1) nta: canonizing sip:1002@192.168.0.74 with contact nta: SUBSCRIBE (1) going to a default leg soa_clone(static::01AE57C0, 01AE28C0, 02F49AA0) called soa_set_params(static::02F88ED0, ...) called nta_leg_tcreate(02F039B0) nua(02F49AA0): adding notify usage with event message-summary nua(02F49AA0): event i_subscribe 100 Trying nua(): refresh notify after 600 seconds (in [600..600]) nua(): refresh notify after 600 seconds nua(02F49AA0): sent signal r_respond nua(02F49AA0): recv signal r_respond 202 Accepted soa_set_params(static::02F88ED0, ...) called tport_tsend(01AE9650) tpn = UDP/192.168.0.38:51091 tport_resolve addrinfo = 192.168.0.38:51091 tport(01AE9650): not found by name UDP/192.168.0.38:51091 tport_vsend(01AE9650): 813 bytes of 813 to udp/192.168.0.38:51091 tport_vsend returned 813 send 813 bytes to udp/[192.168.0.38]:51091 at 15:10:12.527848: ------------------------------------------------------------------------ SIP/2.0 202 Accepted Via: SIP/2.0/UDP 192.168.0.38:51091;branch=z9hG4bK-d8754z-08453f6c6e106020-1---d8754z-;rport=51091 From: "Alessandro Ceccarelli";tag=333d701e To: "Alessandro Ceccarelli" ;tag=SBN9Fg4He48Fp Call-ID: NTU2NDA2N2VjMzZlZTY4ODJlYmUwN2Y1MDc3NmFiOGY. CSeq: 1 SUBSCRIBE Contact: "Alessandro Ceccarelli" Expires: 300 User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer Subscription-State: active;expires=300 Content-Length: 0 ------------------------------------------------------------------------ nta: sent 202 Accepted for SUBSCRIBE (1) nta: timer set next to 4453 ms nta: timer I fired, terminate 200 response incoming_reclaim_all(00000000, 00000000, 027FFEBC) nta_incoming_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free nta: timer not set tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 01D71768 from (udp/192.168.0.74:5060) has 801 bytes, veclen = 1 recv 801 bytes from udp/[192.168.0.52]:63214 at 15:10:19.104078: ------------------------------------------------------------------------ INVITE sip:mod_sofia@192.168.0.74:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.0.52:63214;branch=z9hG4bK-d8754z-4e76a82c441aae66-1---d8754z-;rport Max-Forwards: 70 Contact: To: "+39020000000";tag=r2UgeNKeHUjXa From: ;tag=8b08c857 Call-ID: c4ecd490-5f4b-122c-2780-39a48cb53b8d CSeq: 2 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Content-Type: application/sdp User-Agent: X-Lite release 1100l stamp 47546 Content-Length: 182 v=0 o=- 6 3 IN IP4 192.168.0.52 s=CounterPath X-Lite 3.0 c=IN IP4 0.0.0.0 t=0 0 m=audio 28768 RTP/AVP 3 0 8 101 a=fmtp:101 0-15 a=rtpmap:101 telephone-event/8000 a=sendonly ------------------------------------------------------------------------ tport(01AE9650): msg 01D71768 (801 bytes) from udp/192.168.0.52:5060/sip next=00000000 nta: received INVITE sip:mod_sofia@192.168.0.74:5060 SIP/2.0 (CSeq 2) nta: canonizing sip:mod_sofia@192.168.0.74:5060 with contact nta: INVITE (2) going to existing leg nta: timer shortened to 200 ms soa_init_offer_answer(static::01D7FE78) called soa_set_remote_sdp(static::01D7FE78, 00000000, 01279F8B, 182) called tport_tsend(01AE9650) tpn = UDP/192.168.0.52:63214 tport_resolve addrinfo = 192.168.0.52:63214 tport(01AE9650): not found by name UDP/192.168.0.52:63214 tport_vsend(01AE9650): 402 bytes of 402 to udp/192.168.0.52:63214 tport_vsend returned 402 send 402 bytes to udp/[192.168.0.52]:63214 at 15:10:19.119699: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.0.52:63214;branch=z9hG4bK-d8754z-4e76a82c441aae66-1---d8754z-;rport=63214 From: ;tag=8b08c857 To: "+39020000000";tag=r2UgeNKeHUjXa Call-ID: c4ecd490-5f4b-122c-2780-39a48cb53b8d CSeq: 2 INVITE User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Content-Length: 0 ------------------------------------------------------------------------ nta: sent 100 Trying for INVITE (2) nua(02EDF468): event i_invite 100 Trying nua(02EDF468): ready call updated: received received offer soa_get_remote_sdp(static::01D7FE78, [028FFC14], [028FFC10], [00000000]) called nua(02EDF468): event i_state 100 Trying nta: timer set next to 10538 ms nua(02EDF468): sent signal r_respond nua(02EDF468): recv signal r_respond 200 OK soa_set_params(static::01D7FE78, ...) called soa_set_user_sdp(static::01D7FE78, 00000000, 01B8170C, -1) called soa_generate_answer(static::01D7FE78) called soa_static_offer_answer_action(01D7FE78, soa_generate_answer): called soa_static(01D7FE78, soa_generate_answer): upgrade with remote description soa_static(01D7FE78, soa_generate_answer): marking rejected media soa_sdp_mode_set(028FDCF8, 01D705C8, ""): called soa_sdp_mode_set(028FDCF8, 01D705C8, ""): called soa_static(01D7FE78, soa_generate_answer): storing local description soa_activate(static::01D7FE78, (nil)) called soa_get_local_sdp(static::01D7FE78, [00000000], [028FFE0C], [028FFE08]) called tport_tsend(01AE9650) tpn = UDP/192.168.0.52:63214 tport_resolve addrinfo = 192.168.0.52:63214 tport(01AE9650): not found by name UDP/192.168.0.52:63214 tport_vsend(01AE9650): 984 bytes of 984 to udp/192.168.0.52:63214 tport_vsend returned 984 send 984 bytes to udp/[192.168.0.52]:63214 at 15:10:19.369627: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.52:63214;branch=z9hG4bK-d8754z-4e76a82c441aae66-1---d8754z-;rport=63214 From: ;tag=8b08c857 To: "+39020000000";tag=r2UgeNKeHUjXa Call-ID: c4ecd490-5f4b-122c-2780-39a48cb53b8d CSeq: 2 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces Min-SE: 120 Content-Type: application/sdp Content-Disposition: session Content-Length: 276 v=0 o=FreeSWITCH 6699757194367626025 7696645903169343293 IN IP4 192.168.0.74 s=FreeSWITCH c=IN IP4 192.168.0.74 t=0 0 m=audio 29688 RTP/AVP 3 101 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=recvonly a=silenceSupp:off - - - - a=ptime:20 ------------------------------------------------------------------------ nta: sent 200 OK for INVITE (2) nta: timer shortened to 500 ms nua(02EDF468): ready call updated: completed sent answer soa_get_local_sdp(static::01D7FE78, [028FFE48], [028FFE44], [00000000]) called soa_get_params(static::01D7FE78, ...) called nua(02EDF468): event i_state 200 OK tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 02F4BC48 from (udp/192.168.0.74:5060) has 497 bytes, veclen = 1 recv 497 bytes from udp/[192.168.0.52]:63214 at 15:10:19.478970: ------------------------------------------------------------------------ ACK sip:mod_sofia@192.168.0.74:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.0.52:63214;branch=z9hG4bK-d8754z-0a230c1b27666c30-1---d8754z-;rport Max-Forwards: 70 Contact: To: "+39020000000";tag=r2UgeNKeHUjXa From: ;tag=8b08c857 Call-ID: c4ecd490-5f4b-122c-2780-39a48cb53b8d CSeq: 2 ACK User-Agent: X-Lite release 1100l stamp 47546 Content-Length: 0 ------------------------------------------------------------------------ tport(01AE9650): msg 02F4BC48 (497 bytes) from udp/192.168.0.52:5060/sip next=00000000 nta: received ACK sip:mod_sofia@192.168.0.74:5060 SIP/2.0 (CSeq 2) nta: ACK (2) is going to INVITE (2) soa_clear_remote_sdp(static::01D7FE78) called nua(02EDF468): event i_ack 200 OK nua(02EDF468): ready call updated: ready nua(02EDF468): event i_state 200 OK nua(02EDF468): event i_active 200 Call active nta: timer set next to 4594 ms nta: timer I fired, terminate 200 response incoming_reclaim_all(00000000, 00000000, 028FFEBC) nta_incoming_timer: 0/0 resent, 0/0 tout, 1/5 term, 1/5 free nta: timer set next to 5352 ms tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 01D71768 from (udp/192.168.0.74:5060) has 4 bytes, veclen = 1 tport(01AE9650): bad msg 01D71768 (4 bytes) from udp/192.168.0.52:5060/sip next=00000000 nta: timer J fired, terminate 401 response incoming_reclaim_all(00000000, 00000000, 028FFEBC) nta_incoming_timer: 0/0 resent, 0/0 tout, 1/4 term, 1/4 free nta: timer set next to 197 ms nta: timer J fired, terminate 200 response incoming_reclaim_all(00000000, 00000000, 028FFEBC) nta_incoming_timer: 0/0 resent, 0/0 tout, 1/3 term, 1/3 free nta: timer set next to 87 ms nta: timer J fired, terminate 202 response incoming_reclaim_all(00000000, 00000000, 028FFEBC) nta_incoming_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free nta: timer set next to 13911 ms tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 01D71768 from (udp/192.168.0.74:5060) has 4 bytes, veclen = 1 tport(01AE9650): bad msg 01D71768 (4 bytes) from udp/192.168.0.38:5060/sip next=00000000 tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 01D71768 from (udp/192.168.0.74:5060) has 802 bytes, veclen = 1 recv 802 bytes from udp/[192.168.0.52]:63214 at 15:10:39.504451: ------------------------------------------------------------------------ INVITE sip:mod_sofia@192.168.0.74:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.0.52:63214;branch=z9hG4bK-d8754z-4b5f9a669369bb1f-1---d8754z-;rport Max-Forwards: 70 Contact: To: "+39020000000";tag=r2UgeNKeHUjXa From: ;tag=8b08c857 Call-ID: c4ecd490-5f4b-122c-2780-39a48cb53b8d CSeq: 3 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Content-Type: application/sdp User-Agent: X-Lite release 1100l stamp 47546 Content-Length: 183 v=0 o=- 6 4 IN IP4 192.168.0.52 s=CounterPath X-Lite 3.0 c=IN IP4 192.168.0.52 t=0 0 m=audio 28768 RTP/AVP 3 101 a=fmtp:101 0-15 a=rtpmap:101 telephone-event/8000 a=sendrecv ------------------------------------------------------------------------ tport(01AE9650): msg 01D71768 (802 bytes) from udp/192.168.0.52:5060/sip next=00000000 nta: received INVITE sip:mod_sofia@192.168.0.74:5060 SIP/2.0 (CSeq 3) nta: canonizing sip:mod_sofia@192.168.0.74:5060 with contact nta: INVITE (3) going to existing leg nta: timer shortened to 200 ms soa_init_offer_answer(static::01D7FE78) called soa_set_remote_sdp(static::01D7FE78, 00000000, 01279F8B, 183) called tport_tsend(01AE9650) tpn = UDP/192.168.0.52:63214 tport_resolve addrinfo = 192.168.0.52:63214 tport(01AE9650): not found by name UDP/192.168.0.52:63214 tport_vsend(01AE9650): 402 bytes of 402 to udp/192.168.0.52:63214 tport_vsend returned 402 send 402 bytes to udp/[192.168.0.52]:63214 at 15:10:39.504451: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.0.52:63214;branch=z9hG4bK-d8754z-4b5f9a669369bb1f-1---d8754z-;rport=63214 From: ;tag=8b08c857 To: "+39020000000";tag=r2UgeNKeHUjXa Call-ID: c4ecd490-5f4b-122c-2780-39a48cb53b8d CSeq: 3 INVITE User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Content-Length: 0 ------------------------------------------------------------------------ nta: sent 100 Trying for INVITE (3) nua(02EDF468): event i_invite 100 Trying nua(02EDF468): ready call updated: received received offer soa_get_remote_sdp(static::01D7FE78, [028FFC14], [028FFC10], [00000000]) called nua(02EDF468): event i_state 100 Trying nta: timer set next to 4351 ms nua(02EDF468): sent signal r_respond nua(02EDF468): recv signal r_respond 200 OK soa_set_params(static::01D7FE78, ...) called soa_set_user_sdp(static::01D7FE78, 00000000, 01B8170C, -1) called soa_generate_answer(static::01D7FE78) called soa_static_offer_answer_action(01D7FE78, soa_generate_answer): called soa_static(01D7FE78, soa_generate_answer): upgrade with remote description soa_sdp_mode_set(028FDCF8, 00DE0118, ""): called soa_static(01D7FE78, soa_generate_answer): storing local description soa_activate(static::01D7FE78, (nil)) called soa_get_local_sdp(static::01D7FE78, [00000000], [028FFE0C], [028FFE08]) called tport_tsend(01AE9650) tpn = UDP/192.168.0.52:63214 tport_resolve addrinfo = 192.168.0.52:63214 tport(01AE9650): not found by name UDP/192.168.0.52:63214 tport_vsend(01AE9650): 972 bytes of 972 to udp/192.168.0.52:63214 tport_vsend returned 972 send 972 bytes to udp/[192.168.0.52]:63214 at 15:10:39.785620: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.52:63214;branch=z9hG4bK-d8754z-4b5f9a669369bb1f-1---d8754z-;rport=63214 From: ;tag=8b08c857 To: "+39020000000";tag=r2UgeNKeHUjXa Call-ID: c4ecd490-5f4b-122c-2780-39a48cb53b8d CSeq: 3 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces Min-SE: 120 Content-Type: application/sdp Content-Disposition: session Content-Length: 264 v=0 o=FreeSWITCH 6699757194367626025 7696645903169343294 IN IP4 192.168.0.74 s=FreeSWITCH c=IN IP4 192.168.0.74 t=0 0 m=audio 29688 RTP/AVP 3 101 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 ------------------------------------------------------------------------ nta: sent 200 OK for INVITE (3) nta: timer shortened to 500 ms nua(02EDF468): ready call updated: completed sent answer soa_get_local_sdp(static::01D7FE78, [028FFE48], [028FFE44], [00000000]) called soa_get_params(static::01D7FE78, ...) called nua(02EDF468): event i_state 200 OK tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 02F04E20 from (udp/192.168.0.74:5060) has 497 bytes, veclen = 1 recv 497 bytes from udp/[192.168.0.52]:63214 at 15:10:39.894964: ------------------------------------------------------------------------ ACK sip:mod_sofia@192.168.0.74:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.0.52:63214;branch=z9hG4bK-d8754z-b131c97e6c16e653-1---d8754z-;rport Max-Forwards: 70 Contact: To: "+39020000000";tag=r2UgeNKeHUjXa From: ;tag=8b08c857 Call-ID: c4ecd490-5f4b-122c-2780-39a48cb53b8d CSeq: 3 ACK User-Agent: X-Lite release 1100l stamp 47546 Content-Length: 0 ------------------------------------------------------------------------ tport(01AE9650): msg 02F04E20 (497 bytes) from udp/192.168.0.52:5060/sip next=00000000 nta: received ACK sip:mod_sofia@192.168.0.74:5060 SIP/2.0 (CSeq 3) nta: ACK (3) is going to INVITE (3) soa_clear_remote_sdp(static::01D7FE78) called nua(02EDF468): event i_ack 200 OK nua(02EDF468): ready call updated: ready nua(02EDF468): event i_state 200 OK nua(02EDF468): event i_active 200 Call active nta: timer set next to 3758 ms tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 02F04E20 from (udp/192.168.0.74:5060) has 537 bytes, veclen = 1 recv 537 bytes from udp/[192.168.0.52]:63214 at 15:10:42.316141: ------------------------------------------------------------------------ BYE sip:mod_sofia@192.168.0.74:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.0.52:63214;branch=z9hG4bK-d8754z-5f47b655ea7fe925-1---d8754z-;rport Max-Forwards: 70 Contact: To: "+39020000000";tag=r2UgeNKeHUjXa From: ;tag=8b08c857 Call-ID: c4ecd490-5f4b-122c-2780-39a48cb53b8d CSeq: 4 BYE User-Agent: X-Lite release 1100l stamp 47546 Reason: SIP;description="User Hung Up" Content-Length: 0 ------------------------------------------------------------------------ tport(01AE9650): msg 02F04E20 (537 bytes) from udp/192.168.0.52:5060/sip next=00000000 nta: received BYE sip:mod_sofia@192.168.0.74:5060 SIP/2.0 (CSeq 4) nta: canonizing sip:mod_sofia@192.168.0.74:5060 with contact nta: BYE (4) going to existing leg tport_tsend(01AE9650) tpn = UDP/192.168.0.52:63214 tport_resolve addrinfo = 192.168.0.52:63214 tport(01AE9650): not found by name UDP/192.168.0.52:63214 tport_vsend(01AE9650): 560 bytes of 560 to udp/192.168.0.52:63214 tport_vsend returned 560 send 560 bytes to udp/[192.168.0.52]:63214 at 15:10:42.316141: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.52:63214;branch=z9hG4bK-d8754z-5f47b655ea7fe925-1---d8754z-;rport=63214 From: ;tag=8b08c857 To: "+39020000000";tag=r2UgeNKeHUjXa Call-ID: c4ecd490-5f4b-122c-2780-39a48cb53b8d CSeq: 4 BYE User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces Content-Length: 0 ------------------------------------------------------------------------ nta: sent 200 OK for BYE (4) nua(02EDF468): event i_bye 200 Session Terminated nua(02EDF468): removing session usage nua(02EDF468): call state changed: ready -> terminated nua(02EDF468): event i_state 200 Session Terminated nua(02EDF468): event i_terminated 200 Session Terminated soa_destroy(static::01D7FE78) called nta_leg_destroy(02F47280) 2009-01-17 16:10:42 [NOTICE] sofia.c:3124 sofia_handle_sip_i_state() Hangup sofia/internal/sip:1008@192.168.0.52:63214;rinstance=a8691dfbc11775d5 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] nua(02EDF468): sent signal r_destroy nua(02EDF468): event i_terminated dropped nua(02EDF468): recv signal r_destroy nta_leg_destroy(00000000) 2009-01-17 16:10:42 [NOTICE] switch_ivr_bridge.c:955 switch_ivr_multi_threaded_bridge() Hangup sofia/external/+39020000000@sip.messagenet.it [CS_EXECUTE] [NORMAL_CLEARING] 2009-01-17 16:10:42 [NOTICE] switch_core_session.c:960 switch_core_session_thread() Session 2 (sofia/internal/sip:1008@192.168.0.52:63214;rinstance=a8691dfbc11775d5) Ended 2009-01-17 16:10:42 [NOTICE] switch_core_session.c:962 switch_core_session_thread() Close Channel sofia/internal/sip:1008@192.168.0.52:63214;rinstance=a8691dfbc11775d5 [CS_HANGUP] nua(02EDF6E0): recv signal r_bye soa_set_params(static::01B75A20, ...) called soa_terminate(static::01B75A20) called soa_init_offer_answer(static::01B75A20) called nta: selecting scheme sip tport_tsend(00DD8D20) tpn = */212.97.59.76:5060 nua(02EDF6E0): sent signal r_bye tport_resolve addrinfo = 212.97.59.76:5060 tport(00DD8D20): not found by name */212.97.59.76:5060 tport_vsend(00DD8D20): 796 bytes of 796 to udp/212.97.59.76:5060 tport_vsend returned 796 send 796 bytes to udp/[212.97.59.76]:5060 at 15:10:42.331762: ------------------------------------------------------------------------ BYE sip:+39020000000@212.97.59.87 SIP/2.0 Via: SIP/2.0/UDP 212.001.001.001:5080;rport;branch=z9hG4bKpZU12DD5v1Z2D Route: Route: Max-Forwards: 70 From: ;tag=N7F68303S0F5Q To: "+39020000000" ;tag=as302aa103 Call-ID: 0991575e53c716557b287c0f4e34a842@sip.messagenet.it CSeq: 109984185 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-UNKNOWN Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO Supported: timer, precondition, path, replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 ------------------------------------------------------------------------ nta: sent BYE (109984185) to */212.97.59.76:5060 tport_pend(00DD8D20): pending 02F4B840 for udp/10.0.3.6:5080 (already 0) nta: timer set to 32000 ms nta: timer shortened to 500 ms 2009-01-17 16:10:42 [NOTICE] switch_core_session.c:960 switch_core_session_thread() Session 1 (sofia/external/+39020000000@sip.messagenet.it) Ended 2009-01-17 16:10:42 [NOTICE] switch_core_session.c:962 switch_core_session_thread() Close Channel sofia/external/+39020000000@sip.messagenet.it [CS_HANGUP] tport_wakeup_pri(00DD8D20): events IN tport_recv_event(00DD8D20) tport(00DD8D20) msg 02F8AAA8 from (udp/10.0.3.6:5080) has 612 bytes, veclen = 1 recv 612 bytes from udp/[212.97.59.76]:5060 at 15:10:42.347382: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 212.001.001.001:5080;rport=5080;branch=z9hG4bKpZU12DD5v1Z2D Record-Route: Record-Route: From: ;tag=N7F68303S0F5Q To: "+39020000000" ;tag=as302aa103 Call-ID: 0991575e53c716557b287c0f4e34a842@sip.messagenet.it CSeq: 109984185 BYE User-Agent: alpha Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 ------------------------------------------------------------------------ tport(00DD8D20): msg 02F8AAA8 (612 bytes) from udp/212.97.59.76:5080/sip next=00000000 nta: received 200 OK for BYE (109984185) nta: 200 OK is going to a transaction nta_outgoing: RTT is 31.241 ms tport(00DD8D20): 02F4B840 by 01B70810 with 02F8AAA8 nua(02EDF6E0): event r_bye 200 OK nua(02EDF6E0): call state changed: terminating -> terminated nua(02EDF6E0): event i_state 200 to BYE nua(02EDF6E0): event i_terminated 200 to BYE nua(02EDF6E0): removing session usage soa_destroy(static::01B75A20) called nta_leg_destroy(02F004E0) nua: terminated session 02EDF6E0 nua(02EDF6E0): recv signal r_destroy nta_leg_destroy(00000000) nua(02EDF6E0): sent signal r_destroy nta: timer set next to 4500 ms nta: timer D fired, terminate INVITE (109984168) nta: timer F fired, terminating ACK (109984168) outgoing_reclaim_all(00000000, 00000000, 028FFEC0) nta_outgoing_timer: 0/0 resent, 1/1 tout, 1/1 term, 2/2 free nta: timer set next to 477 ms nta: timer J fired, terminate 202 response incoming_reclaim_all(00000000, 00000000, 028FFEBC) nta_incoming_timer: 0/0 resent, 0/0 tout, 1/3 term, 1/3 free nta: timer set next to 345 ms nta: timer I fired, terminate 200 response incoming_reclaim_all(00000000, 00000000, 028FFEBC) nta_incoming_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free nta: timer set next to 29407 ms nta: timer K fired, terminate BYE (109984185) outgoing_reclaim_all(00000000, 00000000, 027FFEC0) nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free nta: timer not set 2009-01-17 16:10:57 [CONSOLE] switch_console.c:229 switch_console_process() Bye! 2009-01-17 16:10:57 [CONSOLE] switch_core.c:1459 switch_core_destroy() End existing sessions 2009-01-17 16:10:57 [CONSOLE] switch_core.c:1461 switch_core_destroy() Clean up modules. 2009-01-17 16:10:57 [CONSOLE] switch_core_memory.c:443 switch_core_memory_stop() Stopping memory pool queue. 2009-01-17 16:10:57 [CONSOLE] switch_core_memory.c:374 switch_core_memory_reclaim() Returning 3 recycled memory pool(s) 2009-01-17 16:10:57 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'lua' 2009-01-17 16:10:57 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'luarun' 2009-01-17 16:10:57 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'lua' 2009-01-17 16:10:57 [CONSOLE] switch_loadable_module.c:1231 do_shutdown() Stopping: mod_lua 2009-01-17 16:10:57 [NOTICE] switch_event.c:1189 switch_event_unbind() Event Binding deleted for mod_lua:MESSAGE_QUERY 2009-01-17 16:10:57 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'limit' 2009-01-17 16:10:57 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'limit_hash' 2009-01-17 16:10:57 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'db' 2009-01-17 16:10:57 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'hash' 2009-01-17 16:10:57 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'group' 2009-01-17 16:10:57 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'limit_hash_usage' 2009-01-17 16:10:57 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'limit_usage' 2009-01-17 16:10:57 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'db' 2009-01-17 16:10:57 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'hash' 2009-01-17 16:10:57 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'group' 2009-01-17 16:10:57 [CONSOLE] switch_loadable_module.c:1234 do_shutdown() mod_limit has no shutdown routine 2009-01-17 16:10:57 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'fifo' 2009-01-17 16:10:57 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'fifo' 2009-01-17 16:10:57 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'fifo_member' 2009-01-17 16:10:57 [CONSOLE] switch_loadable_module.c:1231 do_shutdown() Stopping: mod_fifo 2009-01-17 16:10:57 [NOTICE] switch_event.c:1189 switch_event_unbind() Event Binding deleted for mod_fifo:PRESENCE_PROBE 2009-01-17 16:10:57 [NOTICE] switch_event.c:374 switch_event_free_subclass_detailed() Subclass reservation deleted for .\mod_fifo.c:fifo::info tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 02F053A0 from (udp/192.168.0.74:5060) has 4 bytes, veclen = 1 tport(01AE9650): bad msg 02F053A0 (4 bytes) from udp/192.168.0.52:5060/sip next=00000000 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'expr' 2009-01-17 16:10:58 [CONSOLE] switch_loadable_module.c:1234 do_shutdown() mod_expr has no shutdown routine 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'aiff' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'au' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'avr' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'caf' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'htk' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'iff' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'mat' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'mat' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'paf' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'pvf' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'raw' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'sd2' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'sds' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'sf' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'voc' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'w64' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'wav' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'wav' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'wav' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'xi' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'r8' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'r16' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'r24' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'r32' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'gsm' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'ul' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:575 switch_loadable_module_unprocess() Deleting File Format 'al' 2009-01-17 16:10:58 [CONSOLE] switch_loadable_module.c:1231 do_shutdown() Stopping: mod_sndfile 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:700 switch_loadable_module_unprocess() Deleting Say interface 'en' 2009-01-17 16:10:58 [CONSOLE] switch_loadable_module.c:1234 do_shutdown() mod_say_en has no shutdown routine 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:434 switch_loadable_module_unprocess() Deleting Endpoint 'loopback' 2009-01-17 16:10:58 [CONSOLE] switch_loadable_module.c:1231 do_shutdown() Stopping: mod_loopback 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'conference' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'conference_set_auto_outcall' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'conference' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:677 switch_loadable_module_unprocess() Deleting Chat interface 'conf' 2009-01-17 16:10:58 [CONSOLE] switch_loadable_module.c:1231 do_shutdown() Stopping: mod_conference 2009-01-17 16:10:58 [NOTICE] switch_event.c:1189 switch_event_unbind() Event Binding deleted for mod_conference:PRESENCE_PROBE 2009-01-17 16:10:58 [NOTICE] switch_event.c:374 switch_event_free_subclass_detailed() Subclass reservation deleted for .\mod_conference.c:conference::maintenance 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PROXY-VID' (PROXY VIDEO PASS-THROUGH) 90000hz 0ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PROXY' (PROXY PASS-THROUGH) 8000hz 20ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 11025hz 40ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 22050hz 20ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 48000hz 20ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 8ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 6ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 4ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 2ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 8ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 6ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 4ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 2ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 8ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 6ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 4ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 2ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 48000hz 10ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 48000hz 8ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 48000hz 6ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 48000hz 4ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 48000hz 2ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 10ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 20ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 30ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 40ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 50ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 32000hz 60ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 10ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 20ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 30ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 40ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 50ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 16000hz 60ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 10ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 20ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 30ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 40ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 50ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 60ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 70ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 80ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 90ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 100ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 110ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'L16' (RAW Signed Linear (16 bit)) 8000hz 120ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMU' (G.711 ulaw) 8000hz 10ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMU' (G.711 ulaw) 8000hz 20ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMU' (G.711 ulaw) 8000hz 30ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMU' (G.711 ulaw) 8000hz 40ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMU' (G.711 ulaw) 8000hz 50ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMU' (G.711 ulaw) 8000hz 60ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMU' (G.711 ulaw) 8000hz 70ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMU' (G.711 ulaw) 8000hz 80ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMU' (G.711 ulaw) 8000hz 90ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMU' (G.711 ulaw) 8000hz 100ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMU' (G.711 ulaw) 8000hz 110ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMU' (G.711 ulaw) 8000hz 120ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMA' (G.711 alaw) 8000hz 10ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMA' (G.711 alaw) 8000hz 20ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMA' (G.711 alaw) 8000hz 30ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMA' (G.711 alaw) 8000hz 40ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMA' (G.711 alaw) 8000hz 50ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMA' (G.711 alaw) 8000hz 60ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMA' (G.711 alaw) 8000hz 70ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMA' (G.711 alaw) 8000hz 80ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMA' (G.711 alaw) 8000hz 90ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMA' (G.711 alaw) 8000hz 100ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMA' (G.711 alaw) 8000hz 110ms 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'PCMA' (G.711 alaw) 8000hz 120ms 2009-01-17 16:10:58 [CONSOLE] switch_loadable_module.c:1231 do_shutdown() Stopping: CORE_PCM_MODULE 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'socket' 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'event_sink' 2009-01-17 16:10:58 [CONSOLE] switch_loadable_module.c:1231 do_shutdown() Stopping: mod_event_socket 2009-01-17 16:10:58 [NOTICE] switch_event.c:1189 switch_event_unbind() Event Binding deleted for mod_event_socket:ALL 2009-01-17 16:10:58 [NOTICE] mod_event_socket.c:2079 mod_event_socket_runtime() Shutting Down 2009-01-17 16:10:58 [NOTICE] switch_loadable_module.c:95 switch_loadable_module_exec() Thread ended for mod_event_socket 2009-01-17 16:10:59 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'AMR' (AMR) 8000hz 20ms 2009-01-17 16:10:59 [CONSOLE] switch_loadable_module.c:1234 do_shutdown() mod_amr has no shutdown routine 2009-01-17 16:10:59 [NOTICE] switch_loadable_module.c:434 switch_loadable_module_unprocess() Deleting Endpoint 'error' 2009-01-17 16:10:59 [NOTICE] switch_loadable_module.c:434 switch_loadable_module_unprocess() Deleting Endpoint 'group' 2009-01-17 16:10:59 [NOTICE] switch_loadable_module.c:434 switch_loadable_module_unprocess() Deleting Endpoint 'user' 2009-01-17 16:10:59 [NOTICE] switch_loadable_module.c:479 switch_loadable_module_unprocess() Deleting Dialplan 'inline' 2009-01-17 16:10:59 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'privacy' 2009-01-17 16:10:59 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'flush_dtmf' 2009-01-17 16:10:59 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'hold' 2009-01-17 16:10:59 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'unhold' 2009-01-17 16:10:59 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'transfer' tport_wakeup_pri(01AE9650): events IN tport_recv_event(01AE9650) tport(01AE9650) msg 02F053A0 from (udp/192.168.0.74:5060) has 4 bytes, veclen = 1 tport(01AE9650): bad msg 02F053A0 (4 bytes) from udp/192.168.0.38:5060/sip next=00000000 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'check_acl' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'verbose_events' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'sleep' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'delay_echo' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'strftime' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'phrase' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'eval' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'pre_answer' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'answer' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'hangup' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'set_name' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'presence' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'log' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'info' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'event' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'export' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'set' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'set_global' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'set_profile_var' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'unset' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'ring_ready' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'remove_bugs' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'break' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'detect_speech' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'ivr' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'redirect' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'send_display' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'respond' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'deflect' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'queue_dtmf' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'send_dtmf' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'sched_hangup' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'sched_broadcast' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'sched_transfer' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'execute_extension' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'mkdir' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'soft_hold' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'bind_meta_app' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'unbind_meta_app' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'intercept' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'eavesdrop' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'three_way' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'set_user' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'stop_dtmf' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'start_dtmf' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'stop_dtmf_generate' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'start_dtmf_generate' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'stop_tone_detect' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'fax_detect' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'tone_detect' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'echo' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'park' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'park_state' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'gentones' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'playback' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'att_xfer' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'read' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'stop_record_session' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'record_session' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'record' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'stop_displace_session' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'displace_session' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'speak' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'clear_speech_cache' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'bridge' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'system' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'say' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:510 switch_loadable_module_unprocess() Deleting Application 'wait_for_silence' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'strepoch' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'chat' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'strftime' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'presence' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:677 switch_loadable_module_unprocess() Deleting Chat interface 'event' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:677 switch_loadable_module_unprocess() Deleting Chat interface 'api' 2009-01-17 16:11:03 [CONSOLE] switch_loadable_module.c:1234 do_shutdown() mod_dptools has no shutdown routine 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:455 switch_loadable_module_unprocess() Deleting Codec 'G723' (G.723.1 6.3k) 8000hz 30ms 2009-01-17 16:11:03 [CONSOLE] switch_loadable_module.c:1234 do_shutdown() mod_g723_1 has no shutdown routine 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'console' 2009-01-17 16:11:03 [CONSOLE] switch_loadable_module.c:1231 do_shutdown() Stopping: mod_logfile 2009-01-17 16:11:03 [NOTICE] switch_event.c:1189 switch_event_unbind() Event Binding deleted for mod_logfile:TRAP 2009-01-17 16:11:03 [INFO] mod_logfile.c:391 mod_logfile_shutdown() Closing C:\Program Files\FreeSwitch\log\freeswitch.log 2009-01-17 16:11:03 [DEBUG] switch_loadable_module.c:427 switch_loadable_module_unprocess() Write lock interface 'sofia' to wait for existing references. 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:434 switch_loadable_module_unprocess() Deleting Endpoint 'sofia' 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'sofia' 2009-01-17 16:11:03 [DEBUG] switch_loadable_module.c:538 switch_loadable_module_unprocess() Write lock interface 'sofia' to wait for existing references. 2009-01-17 16:11:03 [NOTICE] switch_loadable_module.c:536 switch_loadable_module_unprocess() Deleting API Function 'sofia_contact' 2009-01-17 16:11:03 [DEBUG] switch_loadable_module.c:538 switch_loadable_module_unprocess() Write lock interface 'sofia_contact' to wait for existing references. 2009-01-17 16:11:03 [DEBUG] switch_loadable_module.c:669 switch_loadable_module_unprocess() Write lock interface 'sip' to wait for existing referen