2007-11-07 16:40:34 [INFO] mod_dialplan_xml.c:304 dialplan_hunt() Processing Christian Arroyo->1001!
2007-11-07 16:40:34 [DEBUG] mod_dialplan_xml.c:106 parse_exten() test conditions destination_number(1001) =~ /^(10[01][0-9])$/
2007-11-07 16:40:34 [DEBUG] mod_dialplan_xml.c:106 parse_exten() test conditions destination_number(1001) =~ /^1001$/
2007-11-07 16:40:34 [DEBUG] mod_dialplan_xml.c:360 dialplan_hunt() sofia/internal/1001@74.54.99.18:5060 State Change CS_RING -> CS_EXECUTE
2007-11-07 16:40:34 [DEBUG] switch_core_session.c:625 switch_core_session_signal_state_change() Kill sofia/internal/1001@74.54.99.18:5060 [BREAK]
2007-11-07 16:40:34 [DEBUG] switch_core_state_machine.c:406 switch_core_session_run() (sofia/internal/1001@74.54.99.18:5060) State EXECUTE
2007-11-07 16:40:34 [DEBUG] mod_sofia.c:137 sofia_on_execute() SOFIA EXECUTE
2007-11-07 16:40:34 [DEBUG] switch_core_state_machine.c:121 switch_core_standard_on_execute() Standard EXECUTE
2007-11-07 16:40:34 [NOTICE] switch_core_state_machine.c:134 switch_core_standard_on_execute() Execute set(dialed_ext=1001)
2007-11-07 16:40:34 [DEBUG] mod_dptools.c:358 set_function() SET [dialed_ext]=[1001]
2007-11-07 16:40:34 [NOTICE] switch_core_state_machine.c:134 switch_core_standard_on_execute() Execute set(voicemail_authorized=${sip_authorized})
2007-11-07 16:40:34 [DEBUG] switch_core_state_machine.c:158 switch_core_standard_on_execute() Expanded String set(voicemail_authorized=true)
2007-11-07 16:40:34 [DEBUG] mod_dptools.c:358 set_function() SET [voicemail_authorized]=[true]
2007-11-07 16:40:34 [NOTICE] switch_core_state_machine.c:134 switch_core_standard_on_execute() Execute voicemail(check default 74.54.99.18 ${dialed_ext})
2007-11-07 16:40:34 [DEBUG] switch_core_state_machine.c:158 switch_core_standard_on_execute() Expanded String voicemail(check default 74.54.99.18 1001)
2007-11-07 16:40:34 [DEBUG] switch_ivr_play_say.c:117 switch_ivr_phrase_macro() No language specified - Using [en]
2007-11-07 16:40:34 [INFO] mod_sofia.c:893 sofia_receive_message() Asked to send early media by sofia/internal/1001@74.54.99.18:5060
2007-11-07 16:40:35 [DEBUG] sofia_glue.c:389 sofia_glue_ext_address_lookup() Stun Success [74.54.99.18]:[16398]
2007-11-07 16:40:35 [DEBUG] sofia_glue.c:984 sofia_glue_activate_rtp() AUDIO RTP [sofia/internal/1001@74.54.99.18:5060] 74.54.99.18:16398->192.168.1.105:52866 codec: 0 ms: 20
2007-11-07 16:40:35 [DEBUG] switch_rtp.c:545 switch_rtp_create() Starting timer [soft] 160 bytes per 20000ms
2007-11-07 16:40:35 [INFO] mod_sofia.c:923 sofia_receive_message() Ring SDP:
v=0
o=FreeSWITCH 1194458837 1194458838 IN IP4 74.54.99.18
s=FreeSWITCH
c=IN IP4 74.54.99.18
t=0 0
a=sendrecv
m=audio 16398 RTP/AVP 0 101 13
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20

2007-11-07 16:40:35 [NOTICE] switch_channel.c:1096 switch_channel_perform_mark_pre_answered() Ring-Ready sofia/internal/1001@74.54.99.18:5060!
2007-11-07 16:40:35 [NOTICE] mod_sofia.c:926 sofia_receive_message() Pre-Answer sofia/internal/1001@74.54.99.18:5060!
2007-11-07 16:40:35 [DEBUG] switch_core_session.c:339 switch_core_session_receive_message() Kill sofia/internal/1001@74.54.99.18:5060 [BREAK]
2007-11-07 16:40:35 [DEBUG] switch_ivr_play_say.c:256 switch_ivr_phrase_macro() Handle speak-text:[welcome to your voicemail.] (en:en)
2007-11-07 16:40:35 [DEBUG] mod_sofia.c:321 sofia_answer_channel() Local SDP sofia/internal/1001@74.54.99.18:5060:
v=0
o=FreeSWITCH 1194458837 1194458839 IN IP4 74.54.99.18
s=FreeSWITCH
c=IN IP4 74.54.99.18
t=0 0
a=sendrecv
m=audio 16398 RTP/AVP 0 101 13
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20

2007-11-07 16:40:35 [DEBUG] switch_core_session.c:339 switch_core_session_receive_message() Kill sofia/internal/1001@74.54.99.18:5060 [BREAK]
2007-11-07 16:40:35 [NOTICE] switch_ivr_play_say.c:1544 switch_ivr_speak_text() Channel [sofia/internal/1001@74.54.99.18:5060] has been answered
2007-11-07 16:40:35 [DEBUG] switch_ivr_play_say.c:1545 switch_ivr_speak_text() OPEN TTS cepstral
2007-11-07 16:40:35 [DEBUG] switch_ivr_play_say.c:1554 switch_ivr_speak_text() Raw Codec Activated
2007-11-07 16:40:35 [DEBUG] sofia.c:117 sofia_event_callback() event [nua_i_state] status [183][Session Progress] session: sofia/internal/1001@74.54.99.18:5060
2007-11-07 16:40:35 [DEBUG] sofia.c:1149 sofia_handle_sip_i_state() Channel sofia/internal/1001@74.54.99.18:5060 entering state [early]
2007-11-07 16:40:35 [DEBUG] sofia.c:1149 sofia_handle_sip_i_state() Channel sofia/internal/1001@74.54.99.18:5060 entering state [completed]
2007-11-07 16:40:35 [DEBUG] switch_ivr_play_say.c:1261 switch_ivr_speak_text_handle() Speaking text: welcome to your voicemail.
2007-11-07 16:40:35 [DEBUG] sofia.c:1149 sofia_handle_sip_i_state() Channel sofia/internal/1001@74.54.99.18:5060 entering state [ready]
2007-11-07 16:40:35 [WARNING] switch_rtp.c:998 rtp_common_read() Auto Changing port from 192.168.1.105:52866 to 70.46.93.252:62151
2007-11-07 16:40:38 [DEBUG] switch_ivr_play_say.c:1432 switch_ivr_speak_text_handle() done speaking text
2007-11-07 16:40:39 [DEBUG] switch_ivr_play_say.c:117 switch_ivr_phrase_macro() No language specified - Using [en]
2007-11-07 16:40:39 [DEBUG] switch_ivr_play_say.c:117 switch_ivr_phrase_macro() No language specified - Using [en]
2007-11-07 16:40:39 [DEBUG] switch_ivr_play_say.c:256 switch_ivr_phrase_macro() Handle speak-text:[goodbye.] (en:en)
2007-11-07 16:40:39 [DEBUG] switch_ivr_play_say.c:1545 switch_ivr_speak_text() OPEN TTS cepstral
2007-11-07 16:40:39 [DEBUG] switch_ivr_play_say.c:1554 switch_ivr_speak_text() Raw Codec Activated
2007-11-07 16:40:39 [DEBUG] switch_ivr_play_say.c:1261 switch_ivr_speak_text_handle() Speaking text: goodbye.
nta_agent: received garbage from udp/74.236.61.168:5060/sip
2007-11-07 16:40:42 [DEBUG] switch_ivr_play_say.c:1432 switch_ivr_speak_text_handle() done speaking text
2007-11-07 16:40:42 [NOTICE] switch_core_state_machine.c:134 switch_core_standard_on_execute() Execute set(shout://scfire-dll-aa02.stream.aol.com:80/stream/1074)
2007-11-07 16:40:42 [DEBUG] mod_dptools.c:358 set_function() SET [shout://scfire-dll-aa02.stream.aol.com:80/stream/1074]=[UNDEF]
2007-11-07 16:40:42 [NOTICE] switch_core_state_machine.c:184 switch_core_standard_on_execute() Hangup sofia/internal/1001@74.54.99.18:5060 [CS_EXECUTE] [NORMAL_CLEARING]
2007-11-07 16:40:42 [DEBUG] switch_channel.c:1070 switch_channel_perform_hangup() Kill sofia/internal/1001@74.54.99.18:5060 [KILL]
2007-11-07 16:40:42 [DEBUG] switch_core_session.c:625 switch_core_session_signal_state_change() Kill sofia/internal/1001@74.54.99.18:5060 [BREAK]
2007-11-07 16:40:42 [DEBUG] switch_core_state_machine.c:394 switch_core_session_run() (sofia/internal/1001@74.54.99.18:5060) State HANGUP
2007-11-07 16:40:42 [DEBUG] mod_sofia.c:217 sofia_on_hangup() Channel sofia/internal/1001@74.54.99.18:5060 hanging up, cause: NORMAL_CLEARING
2007-11-07 16:40:42 [DEBUG] mod_sofia.c:235 sofia_on_hangup() Sending BYE to sofia/internal/1001@74.54.99.18:5060
2007-11-07 16:40:42 [DEBUG] switch_core_state_machine.c:45 switch_core_standard_on_hangup() Standard HANGUP sofia/internal/1001@74.54.99.18:5060, cause: NORMAL_CLEARING
2007-11-07 16:40:42 [DEBUG] switch_core_session.c:683 switch_core_session_thread() Session 8 (sofia/internal/1001@74.54.99.18:5060) Locked, Waiting on external entities
2007-11-07 16:40:42 [INFO] switch_core_session.c:689 switch_core_session_thread() Session 8 (sofia/internal/1001@74.54.99.18:5060) Ended
2007-11-07 16:40:42 [NOTICE] switch_core_session.c:691 switch_core_session_thread() Close Channel sofia/internal/1001@74.54.99.18:5060 [CS_HANGUP]