2006-09-01 14:07:28 [CONSOLE] switch_core.c:3229 switch_core_init_and_modload() Bringing up environment. 2006-09-01 14:07:28 [CONSOLE] switch_core.c:3230 switch_core_init_and_modload() Loading Modules. 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_console] 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_dptools] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:160 switch_loadable_module_process() Adding Application 'sleep' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:160 switch_loadable_module_process() Adding Application 'strftime' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:160 switch_loadable_module_process() Adding Application 'answer' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:160 switch_loadable_module_process() Adding Application 'set' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:175 switch_loadable_module_process() Adding API Function 'strftime' 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_bridgecall] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:160 switch_loadable_module_process() Adding Application 'bridge' 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_playback] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:160 switch_loadable_module_process() Adding Application 'playback' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:160 switch_loadable_module_process() Adding Application 'record' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:160 switch_loadable_module_process() Adding Application 'speak' 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_commands] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:175 switch_loadable_module_process() Adding API Function 'originate' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:175 switch_loadable_module_process() Adding API Function 'killchan' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:175 switch_loadable_module_process() Adding API Function 'reloadxml' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:175 switch_loadable_module_process() Adding API Function 'load' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:175 switch_loadable_module_process() Adding API Function 'transfer' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:175 switch_loadable_module_process() Adding API Function 'pause' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:175 switch_loadable_module_process() Adding API Function 'show' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:175 switch_loadable_module_process() Adding API Function 'status' 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_dialplan_xml] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:132 switch_loadable_module_process() Adding Dialplan 'XML' 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_g711] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'PCMU' (g711 ulaw) 8000khz 20ms 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'PCMA' (g711 alaw) 8000khz 20ms 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_gsm] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'gsm' (gsm) 8000khz 20ms 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_l16] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 8000khz 120ms 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 8000khz 60ms 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 8000khz 30ms 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 8000khz 20ms 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 16000khz 20ms 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 22050khz 20ms 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 32000khz 20ms 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_ilbc] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'iLBC' (ilbc) 8000khz 20ms 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'iLBC' (ilbc) 8000khz 30ms 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'iLBC102' (ilbc) 8000khz 20ms 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'iLBC' (ilbc) 8000khz 30ms 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'iLBC20ms' (ilbc) 8000khz 20ms 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_sndfile] 2006-09-01 14:07:28 [INFO] mod_sndfile.c:282 setup_formats() LibSndFile Version : libsndfile-1.0.16 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") FLAC (FLAC Lossless Audio Codec) (extension "flac") 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") ================================================================================ 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'aiff' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'au' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'avr' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'caf' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'flac' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'htk' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'iff' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'mat' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'paf' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'pvf' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'raw' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'sd2' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'sds' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'sf' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'voc' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'w64' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'wav' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'xi' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'r8' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'r16' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'r24' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'r32' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:191 switch_loadable_module_process() Adding File Format 'gsm' 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_softtimer] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:146 switch_loadable_module_process() Adding Timer 'soft' 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_spidermonkey] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:160 switch_loadable_module_process() Adding Application 'javascript' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:175 switch_loadable_module_process() Adding API Function 'jsrun' 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_cepstral] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:206 switch_loadable_module_process() Adding Speech interface 'cepstral' 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_rss] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:160 switch_loadable_module_process() Adding Application 'rss' 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_event_socket] 2006-09-01 14:07:28 [DEBUG] mod_event_socket.c:741 switch_module_runtime() Socket up listening on 127.0.0.1:8021 2006-09-01 14:07:28 [DEBUG] mod_dingaling.c:1259 init_profile() Started Thread for demo@freeswitch.org/talk@XML 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_dingaling] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:99 switch_loadable_module_process() Adding Endpoint 'dingaling' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:175 switch_loadable_module_process() Adding API Function 'dl_login' 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:175 switch_loadable_module_process() Adding API Function 'dl_logout' 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_iax] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:99 switch_loadable_module_process() Adding Endpoint 'iax' 2006-09-01 14:07:28 [INFO] mod_iax.c:921 switch_module_runtime() IAX Ready Port 4569 2006-09-01 14:07:28 [DEBUG] libdingaling.c:696 on_stream() XMPP server connected 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_speex] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'speex' (speex) 8000khz 20ms 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'speex' (speex) 16000khz 20ms 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'speex' (speex) 32000khz 20ms 2006-09-01 14:07:28 [NOTICE] mod_sofia.c:1744 config_sofia() Started Profile test [sip:208.64.200.40:5060] 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_sofia] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:99 switch_loadable_module_process() Adding Endpoint 'sofia' 2006-09-01 14:07:28 [DEBUG] mod_sofia.c:1432 event_callback() event [nua_r_set_params] status [200] [OK] 2006-09-01 14:07:28 [DEBUG] mod_sofia.c:1543 event_callback() nua_r_set_params: unknown event 23: 200 OK 2006-09-01 14:07:28 [DEBUG] libdingaling.c:708 on_stream() XMPP authenticated 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:305 switch_loadable_module_load_file() Successfully Loaded [mod_g726] 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'G726-40' (G.726 40k) 8000khz 20ms 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'G726-32' (G.726 32k) 8000khz 20ms 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'G726-24' (G.726 24k) 8000khz 20ms 2006-09-01 14:07:28 [NOTICE] switch_loadable_module.c:110 switch_loadable_module_process() Adding Codec 'G726-16' (G.726 16k) 8000khz 20ms 2006-09-01 14:07:28 [CONSOLE] switch_loadable_module.c:514 switch_loadable_module_init() open of post_load_modules.conf failed 2006-09-01 14:07:28 [CONSOLE] switch_core.c:3246 switch_core_init_and_modload() FreeSWITCH Version 1.0.@SVN_VERSION@ Started. Crash Protection [DISABLED] Max Sessions[10000] freeswitch@tonybox1> recv 833 bytes from udp/[69.76.83.52]:5060 at 18:07:29.408381: INVITE sip:1000@208.64.200.40 SIP/2.0 Via: SIP/2.0/UDP 69.76.83.52:5060;branch=z9hG4bK6d96d0d8;rport From: "FUCKHEAD" ;tag=as2870ffc3 To: Contact: Call-ID: 77f38a976e3917690b566233401da1da@69.76.83.52 CSeq: 102 INVITE User-Agent: Asterisk PBX Max-Forwards: 70 Date: Fri, 01 Sep 2006 18:07:28 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, PRACK Content-Type: application/sdp Content-Length: 316 v=0 o=root 28142 28142 IN IP4 69.76.83.52 s=session c=IN IP4 69.76.83.52 t=0 0 m=audio 14816 RTP/AVP 0 8 98 10 97 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:98 speex/8000 a=rtpmap:10 L16/8000 a=rtpmap:97 iLBC/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - send 275 bytes to udp/[69.76.83.52]:5060 at 18:07:29.409202: SIP/2.0 100 Trying Via: SIP/2.0/UDP 69.76.83.52:5060;branch=z9hG4bK6d96d0d8;rport=5060 From: "FUCKHEAD" ;tag=as2870ffc3 To: Call-ID: 77f38a976e3917690b566233401da1da@69.76.83.52 CSeq: 102 INVITE Content-Length: 0 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:1432 event_callback() event [nua_i_invite] status [100] [Trying] 2006-09-01 14:07:29 [NOTICE] switch_channel.c:328 switch_channel_set_name() New Chan sofia/test/4141231234@69.76.83.52 [b045fb7e-704d-40bd-b315-2de6c0171c72] 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:1432 event_callback() event [nua_i_state] status [100] [Trying] 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:1221 sip_i_state() Channel sofia/test/4141231234@69.76.83.52 entering state [received] 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:1227 sip_i_state() Remote SDP: v=0 o=root 28142 28142 IN IP4 69.76.83.52 s=session c=IN IP4 69.76.83.52 t=0 0 m=audio 14816 RTP/AVP 0 8 98 10 97 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:98 speex/8000 a=rtpmap:10 L16/8000 a=rtpmap:97 iLBC/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - 2006-09-01 14:07:29 [INFO] mod_sofia.c:632 tech_set_codec() Set Codec sofia/test/4141231234@69.76.83.52 PCMU/0 20 ms 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:1292 sip_i_state() sofia/test/4141231234@69.76.83.52 State Change CS_NEW -> CS_INIT send 721 bytes to udp/[69.76.83.52]:5060 at 18:07:29.414516: SIP/2.0 200 OK Via: SIP/2.0/UDP 69.76.83.52:5060;branch=z9hG4bK6d96d0d8;rport=5060 From: "FUCKHEAD" ;tag=as2870ffc3 To: ;tag=a099HamByFgDF Call-ID: 77f38a976e3917690b566233401da1da@69.76.83.52 CSeq: 102 INVITE Contact: User-Agent: sofia-sip/1.12.1 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE Supported: 100rel, precondition Content-Type: application/sdp Content-Disposition: session Content-Length: 184 v=0 o=FreeSWITCH 1404049834728515938 5928825133299168148 IN IP4 208.64.200.40 s=FreeSWITCH c=IN IP4 208.64.200.40 t=0 0 a=sendrecv m=audio 16384 RTP/AVP 0 a=rtpmap:0 PCMU/8000 2006-09-01 14:07:29 [DEBUG] switch_core.c:2287 switch_core_session_run() (sofia/test/4141231234@69.76.83.52) State INIT 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:487 sofia_on_init() SOFIA INIT 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:494 sofia_on_init() sofia/test/4141231234@69.76.83.52 State Change CS_INIT -> CS_RING 2006-09-01 14:07:29 [DEBUG] switch_core.c:2323 switch_core_session_run() (sofia/test/4141231234@69.76.83.52) State RING 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:510 sofia_on_ring() SOFIA RING 2006-09-01 14:07:29 [DEBUG] switch_core.c:2015 switch_core_standard_on_ring() Standard RING sofia/test/4141231234@69.76.83.52 2006-09-01 14:07:29 [INFO] mod_dialplan_xml.c:274 dialplan_hunt() Processing FUCKHEAD->1000! 2006-09-01 14:07:29 [DEBUG] mod_dialplan_xml.c:195 parse_exten() test conditions destination_number(1000) =~ /^1000$/ 2006-09-01 14:07:29 [DEBUG] mod_dialplan_xml.c:314 dialplan_hunt() sofia/test/4141231234@69.76.83.52 State Change CS_RING -> CS_EXECUTE 2006-09-01 14:07:29 [DEBUG] switch_core.c:2359 switch_core_session_run() (sofia/test/4141231234@69.76.83.52) State EXECUTE 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:528 sofia_on_execute() SOFIA EXECUTE 2006-09-01 14:07:29 [DEBUG] switch_core.c:2049 switch_core_standard_on_execute() Standard EXECUTE 2006-09-01 14:07:29 [NOTICE] switch_core.c:2057 switch_core_standard_on_execute() Execute answer() 2006-09-01 14:07:29 [INFO] mod_sofia.c:676 activate_rtp() RTP [sofia/test/4141231234@69.76.83.52] 208.64.200.40:16384->69.76.83.52:14816 codec: 0 ms: 20 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:744 sofia_answer_channel() Local SDP: v=0 o=FreeSWITCH 163841157134049411083 163841157134049411083 IN IP4 208.64.200.40 s=FreeSWITCH c=IN IP4 208.64.200.40 t=0 0 a=sendrecv m=audio 16384 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 2006-09-01 14:07:29 [NOTICE] mod_dptools.c:53 answer_function() Answer sofia/test/4141231234@69.76.83.52! 2006-09-01 14:07:29 [NOTICE] switch_core.c:2057 switch_core_standard_on_execute() Execute playback(/ram/sr8k.wav) 2006-09-01 14:07:29 [INFO] mod_sndfile.c:134 sndfile_file_open() Opening File [/ram/sr8k.wav] 8000hz 2006-09-01 14:07:29 [DEBUG] switch_ivr.c:427 switch_ivr_play_file() OPEN FILE /ram/sr8k.wav 8000hz 1 channels TITLE= COPYRIGHT= SOFTWARE= ARTIST= COMMENT= DATE= 2006-09-01 14:07:29 [DEBUG] switch_ivr.c:455 switch_ivr_play_file() Raw Codec Activated recv 379 bytes from udp/[69.76.83.52]:5060 at 18:07:29.502689: ACK sip:208.64.200.40 SIP/2.0 Via: SIP/2.0/UDP 69.76.83.52:5060;branch=z9hG4bK001cca4d;rport From: "FUCKHEAD" ;tag=as2870ffc3 To: ;tag=a099HamByFgDF Contact: Call-ID: 77f38a976e3917690b566233401da1da@69.76.83.52 CSeq: 102 ACK User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:1432 event_callback() event [nua_i_state] status [200] [OK] 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:1221 sip_i_state() Channel sofia/test/4141231234@69.76.83.52 entering state [completed] 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:1432 event_callback() event [nua_i_ack] status [200] [OK] 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:1432 event_callback() event [nua_i_state] status [200] [OK] 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:1221 sip_i_state() Channel sofia/test/4141231234@69.76.83.52 entering state [ready] 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:1432 event_callback() event [nua_i_active] status [200] [Call active] 2006-09-01 14:07:29 [DEBUG] mod_sofia.c:1432 event_callback() event [nua_i_error] status [500] [Responding to a Non-Existing Request] 2006-09-01 14:07:36 [DEBUG] switch_core.c:1564 switch_core_session_write_frame() Engaging Write Buffer at 3200 bytes to accomodate 128->320 2006-09-01 14:07:36 [DEBUG] switch_ivr.c:633 switch_ivr_play_file() done playing file 2006-09-01 14:07:36 [NOTICE] switch_core.c:2087 switch_core_standard_on_execute() Hangup sofia/test/4141231234@69.76.83.52 [CS_EXECUTE] [NORMAL_CLEARING] 2006-09-01 14:07:36 [INFO] switch_channel.c:866 switch_channel_perform_hangup() Kill sofia/test/4141231234@69.76.83.52 [1] 2006-09-01 14:07:36 [DEBUG] switch_core.c:2248 switch_core_session_run() (sofia/test/4141231234@69.76.83.52) State HANGUP send 393 bytes to udp/[69.76.83.52]:5060 at 18:07:36.880202: BYE sip:4141231234@69.76.83.52 SIP/2.0 Via: SIP/2.0/UDP 208.64.200.40;rport;branch=z9hG4bKa9vNrZDUBKtZF Max-Forwards: 70 From: ;tag=a099HamByFgDF To: "FUCKHEAD" ;tag=as2870ffc3 Call-ID: 77f38a976e3917690b566233401da1da@69.76.83.52 CSeq: 72448689 BYE User-Agent: sofia-sip/1.12.1 Supported: 100rel, precondition Content-Length: 0 recv 510 bytes from udp/[69.76.83.52]:5060 at 18:07:36.961071: SIP/2.0 200 OK Via: SIP/2.0/UDP 208.64.200.40;rport;branch=z9hG4bKa9vNrZDUBKtZF;received=208.64.200.40 From: ;tag=a099HamByFgDF To: "FUCKHEAD" ;tag=as2870ffc3 Call-ID: 77f38a976e3917690b566233401da1da@69.76.83.52 CSeq: 72448689 BYE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, PRACK Max-Forwards: 70 Contact: Content-Length: 0 X-Asterisk-HangupCause: Normal Clearing 2006-09-01 14:07:36 [DEBUG] mod_sofia.c:562 sofia_on_hangup() SOFIA HANGUP 2006-09-01 14:07:36 [DEBUG] switch_core.c:2005 switch_core_standard_on_hangup() Standard HANGUP sofia/test/4141231234@69.76.83.52 2006-09-01 14:07:36 [DEBUG] switch_core.c:2283 switch_core_session_run() sofia/test/4141231234@69.76.83.52 State Change CS_HANGUP -> CS_DONE 2006-09-01 14:07:36 [DEBUG] switch_core.c:2643 switch_core_session_thread() Session 1 (sofia/test/4141231234@69.76.83.52) Locked, Waiting on external entities 2006-09-01 14:07:36 [INFO] switch_core.c:2650 switch_core_session_thread() Session 1 (sofia/test/4141231234@69.76.83.52) Ended 2006-09-01 14:07:36 [NOTICE] switch_core.c:2528 switch_core_session_destroy() Close Channel sofia/test/4141231234@69.76.83.52 freeswitch@tonybox1> 2006-09-01 14:07:38 [CONSOLE] switch_console.c:84 switch_console_process() Bye! 2006-09-01 14:07:38 [CONSOLE] switch_core.c:3281 switch_core_destroy() End existing sessions 2006-09-01 14:07:38 [CONSOLE] switch_core.c:3283 switch_core_destroy() Clean up modules. 2006-09-01 14:07:38 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_iax (yes) 2006-09-01 14:07:38 [DEBUG] switch_loadable_module.c:75 switch_loadable_module_exec() Thread ended for mod_iax 2006-09-01 14:07:39 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_sndfile (no) 2006-09-01 14:07:39 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_dptools (no) 2006-09-01 14:07:39 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_speex (no) 2006-09-01 14:07:39 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_g726 (no) 2006-09-01 14:07:39 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_gsm (no) 2006-09-01 14:07:39 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_playback (no) 2006-09-01 14:07:39 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_bridgecall (no) 2006-09-01 14:07:39 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_event_socket (yes) 2006-09-01 14:07:39 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_dingaling (yes) 2006-09-01 14:07:39 [ERR] mod_event_socket.c:765 switch_module_runtime() Socket Error 2006-09-01 14:07:39 [DEBUG] switch_loadable_module.c:75 switch_loadable_module_exec() Thread ended for mod_event_socket 2006-09-01 14:07:39 [DEBUG] mod_dingaling.c:264 handle_thread_run() Handle jingle [demo@freeswitch.org/talk] Destroyed 2006-09-01 14:07:40 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_commands (no) 2006-09-01 14:07:40 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_sofia (yes) 2006-09-01 14:07:40 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_l16 (no) 2006-09-01 14:07:40 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_console (no) 2006-09-01 14:07:40 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_dialplan_xml (no) 2006-09-01 14:07:40 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_rss (no) 2006-09-01 14:07:40 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_spidermonkey (yes) 2006-09-01 14:07:40 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_ilbc (no) 2006-09-01 14:07:40 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_softtimer (no) 2006-09-01 14:07:40 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_cepstral (no) 2006-09-01 14:07:40 [CONSOLE] switch_loadable_module.c:565 switch_loadable_module_shutdown() Checking mod_g711 (no) 2006-09-01 14:07:40 [CONSOLE] switch_core.c:3286 switch_core_destroy() Closing Event Engine. 2006-09-01 14:07:40 [CONSOLE] switch_core.c:3290 switch_core_destroy() Waiting for unfinished SQL transactions 2006-09-01 14:07:40 [NOTICE] switch_core.c:2847 switch_core_sql_thread() SQL thread ending 2006-09-01 14:07:40 [CONSOLE] switch_core.c:3298 switch_core_destroy() Finalizing Shutdown. 2006-09-01 14:07:40 [CONSOLE] switch_log.c:161 log_thread() Logger Ended.