2009-03-30 11:14:43 [DEBUG] switch_ivr_play_say.c:272 switch_ivr_phrase_macro() Handle play-file:[7-mono-8kHz.wav] (en:en)
2009-03-30 11:14:43 [DEBUG] switch_ivr_play_say.c:989 switch_ivr_play_file() Codec Activated L16@8000hz 1 channels 20ms
2009-03-30 11:14:43 [DEBUG] switch_core_io.c:652 switch_core_session_write_frame() sofia/external/xxxx@xxx.xxx.xxx.xxx receive message [TRANSCODING_NECESSARY]
2009-03-30 11:14:49 [DEBUG] switch_ivr_play_say.c:1279 switch_ivr_play_file() done playing file
2009-03-30 11:14:49 [INFO] ivrmenu.js:36 console_log() 
2009-03-30 11:14:49 [INFO] ivrmenu.js:36 console_log() 
2009-03-30 11:14:49 [INFO] ivrmenu.js:36 console_log() 
2009-03-30 11:14:49 [INFO] ivrmenu.js:36 console_log() 
2009-03-30 11:14:49 [INFO] ivrmenu.js:36 console_log() 
2009-03-30 11:14:49 [INFO] ivrmenu.js:36 console_log() 
2009-03-30 11:14:49 [WARNING] mod_shout.c:1088 shout_file_set_string() Value Ignored
2009-03-30 11:14:49 [DEBUG] switch_ivr_play_say.c:505 switch_ivr_record_file() Raw Codec Activated
2009-03-30 11:14:49 [DEBUG] switch_core_io.c:234 switch_core_session_read_frame() sofia/external/xxxx@xxx.xxx.xxx.xxx receive message [TRANSCODING_NECESSARY]
2009-03-30 11:14:53 [INFO] mod_shout.c:280 log_msg() LAME 3.97 32bits (http://www.mp3dev.org/)
2009-03-30 11:14:53 [INFO] mod_shout.c:280 log_msg() polyphase lowpass filter disabled
2009-03-30 11:15:24 [DEBUG] switch_core_io.c:403 switch_core_session_read_frame() Engaging Read Buffer at 320 bytes vs 40
2009-03-30 11:15:24 [DEBUG] switch_rtp.c:1767 switch_rtp_dequeue_dtmf() RTP RECV DTMF #:2000
2009-03-30 11:15:43 [DEBUG] sofia.c:2728 sofia_handle_sip_i_state() Channel sofia/external/xxxx@xxx.xxx.xxx.xxx entering state [received]
2009-03-30 11:15:43 [DEBUG] sofia.c:2732 sofia_handle_sip_i_state() Remote SDP:
v=0
o=root 3242 3244 IN IP4 194.145.190.143
s=session
c=IN IP4 194.145.190.143
t=0 0
m=audio 10314 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
2009-03-30 11:15:43 [DEBUG] sofia_glue.c:2549 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMA:8:8000:0]/[PCMU:0:8000:20]
2009-03-30 11:15:43 [DEBUG] sofia_glue.c:2549 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMA:8:8000:0]/[PCMA:8:8000:20]
2009-03-30 11:15:43 [DEBUG] sofia_glue.c:1635 sofia_glue_tech_set_codec() Already using PCMA
2009-03-30 11:15:43 [DEBUG] sofia_glue.c:2509 sofia_glue_negotiate_sdp() Set 2833 dtmf payload to 101
2009-03-30 11:15:43 [DEBUG] sofia_glue.c:1901 sofia_glue_activate_rtp() Audio params changed for sofia/external/xxxx@xxx.xxx.xxx.xxx from 213.166.5.140:17856 to 194.145.190.143:10314
2009-03-30 11:15:43 [DEBUG] sofia_glue.c:1908 sofia_glue_activate_rtp() AUDIO RTP [sofia/external/xxxx@xxx.xxx.xxx.xxx] 192.168.4.2 port 27496 -> 194.145.190.143 port 10314 codec: 8 ms: 20
2009-03-30 11:15:43 [DEBUG] sofia_glue.c:1927 sofia_glue_activate_rtp() AUDIO RTP CHANGING DEST TO: [194.145.190.143:10314]
2009-03-30 11:15:43 [DEBUG] sofia.c:3084 sofia_handle_sip_i_state() Processing Reinvite
2009-03-30 11:15:43 [DEBUG] sofia.c:2728 sofia_handle_sip_i_state() Channel sofia/external/xxxx@xxx.xxx.xxx.xxx entering state [completed]
2009-03-30 11:15:43 [DEBUG] sofia.c:2728 sofia_handle_sip_i_state() Channel sofia/external/xxxx@xxx.xxx.xxx.xxx entering state [terminated]
2009-03-30 11:15:43 [NOTICE] sofia.c:3279 sofia_handle_sip_i_state() Hangup sofia/external/xxxx@xxx.xxx.xxx.xxx [CS_EXECUTE] [NORMAL_CLEARING]
2009-03-30 11:15:43 [DEBUG] switch_channel.c:1566 switch_channel_perform_hangup() Send signal sofia/external/xxxx@xxx.xxx.xxx.xxx [KILL]
2009-03-30 11:15:43 [DEBUG] switch_core_session.c:820 switch_core_session_signal_state_change() Send signal sofia/external/xxxx@xxx.xxx.xxx.xxx [BREAK]
2009-03-30 11:15:43 [DEBUG] switch_core_codec.c:122 switch_core_session_set_read_codec() Restore original codec.
2009-03-30 11:15:43 [INFO] ivrmenu.js:36 console_log() 
##IVR Message[07703345353]: Dropped out of record!
2009-03-30 11:15:43 [INFO] ivrmenu.js:36 console_log() 
##IVR Message[07703345353]: Session no longer active!
2009-03-30 11:15:43 [INFO] ivrmenu.js:36 console_log() 
2009-03-30 11:15:44 [DEBUG] mod_shout.c:620 write_stream_thread() Thread Done
2009-03-30 11:16:43 [ERR] mod_spidermonkey.c:2406 fetch_url_callback() Data do not fit in the allocated buffer
2009-03-30 11:16:43 [ERR] ivrmenu.js:96 mod_spidermonkey()  TypeError: st has no properties
2009-03-30 11:16:43 [DEBUG] switch_core_state_machine.c:464 switch_core_session_run() (sofia/external/xxxx@xxx.xxx.xxx.xxx) State EXECUTE going to sleep
2009-03-30 11:16:43 [DEBUG] switch_core_state_machine.c:383 switch_core_session_run() (sofia/external/xxxx@xxx.xxx.xxx.xxx) Running State Change CS_HANGUP
2009-03-30 11:16:43 [DEBUG] switch_core_state_machine.c:414 switch_core_session_run() (sofia/external/xxxx@xxx.xxx.xxx.xxx) State HANGUP
2009-03-30 11:16:43 [DEBUG] mod_sofia.c:253 sofia_on_hangup() sofia/external/xxxx@xxx.xxx.xxx.xxx Overriding SIP cause 480 with 200 from the other leg
2009-03-30 11:16:43 [DEBUG] mod_sofia.c:287 sofia_on_hangup() Channel sofia/external/xxxx@xxx.xxx.xxx.xxx hanging up, cause: NORMAL_CLEARING
2009-03-30 11:16:43 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() sofia/external/xxxx@xxx.xxx.xxx.xxx Standard HANGUP, cause: NORMAL_CLEARING
2009-03-30 11:16:43 [DEBUG] switch_core_state_machine.c:414 switch_core_session_run() (sofia/external/xxxx@xxx.xxx.xxx.xxx) State HANGUP going to sleep
2009-03-30 11:16:43 [DEBUG] switch_core_session.c:952 switch_core_session_thread() Session 22 (sofia/external/xxxx@xxx.xxx.xxx.xxx) Locked, Waiting on external entities
2009-03-30 11:16:43 [NOTICE] switch_core_session.c:970 switch_core_session_thread() Session 22 (sofia/external/xxxx@xxx.xxx.xxx.xxx) Ended
2009-03-30 11:16:43 [NOTICE] switch_core_session.c:972 switch_core_session_thread() Close Channel sofia/external/xxxx@xxx.xxx.xxx.xxx [CS_HANGUP]