2007-11-07 20:09:41 [NOTICE] switch_ivr.c:933 switch_ivr_session_transfer() Transfer sofia/74.54.99.18/19549227122@flgw:5060 to XML[1009@default]
2007-11-07 20:09:41 [DEBUG] switch_ivr_menu.c:449 switch_ivr_menu_execute() exit-sound 'say:ok, man, c u later'
2007-11-07 20:09:41 [DEBUG] switch_ivr_menu.c:245 play_or_say() play_or_say sound=[say:ok, man, c u later]
2007-11-07 20:09:41 [DEBUG] switch_ivr_play_say.c:1553 switch_ivr_speak_text() OPEN TTS cepstral
2007-11-07 20:09:41 [DEBUG] switch_ivr_play_say.c:1562 switch_ivr_speak_text() Raw Codec Activated
2007-11-07 20:09:41 [DEBUG] switch_ivr_play_say.c:1269 switch_ivr_speak_text_handle() Speaking text: ok, man, c u later
2007-11-07 20:09:42 [DEBUG] switch_ivr_play_say.c:1440 switch_ivr_speak_text_handle() done speaking text
2007-11-07 20:09:42 [DEBUG] switch_ivr_menu.c:282 play_or_say() play_or_say returning [1]
2007-11-07 20:09:42 [DEBUG] switch_core_state_machine.c:400 switch_core_session_run() (sofia/74.54.99.18/19549227122@flgw:5060) State RING
2007-11-07 20:09:42 [DEBUG] mod_sofia.c:120 sofia_on_ring() SOFIA RING
2007-11-07 20:09:42 [DEBUG] switch_core_state_machine.c:64 switch_core_standard_on_ring() Standard RING sofia/74.54.99.18/19549227122@flgw:5060
2007-11-07 20:09:42 [INFO] mod_dialplan_xml.c:304 dialplan_hunt() Processing 19549227122->1009!
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:106 parse_exten() test conditions destination_number(1009) =~ /^(10[01][0-9])$/
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:106 parse_exten() test conditions destination_number(1009) =~ /^19549227122$/
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:108 parse_exten() Regex mismatch
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:106 parse_exten() test conditions destination_number(1009) =~ /^(3\d{3})$/
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:108 parse_exten() Regex mismatch
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:106 parse_exten() test conditions destination_number(1009) =~ /^888$/
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:108 parse_exten() Regex mismatch
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:106 parse_exten() test conditions destination_number(1009) =~ /^8192$/
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:108 parse_exten() Regex mismatch
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:106 parse_exten() test conditions destination_number(1009) =~ /^8193$/
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:108 parse_exten() Regex mismatch
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:106 parse_exten() test conditions destination_number(1009) =~ /^\*393([\*\d]+)$/
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:108 parse_exten() Regex mismatch
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:106 parse_exten() test conditions destination_number(1009) =~ /5000/
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:108 parse_exten() Regex mismatch
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:106 parse_exten() test conditions destination_number(1009) =~ /^5900$/
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:108 parse_exten() Regex mismatch
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:106 parse_exten() test conditions destination_number(1009) =~ /^5901$/
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:108 parse_exten() Regex mismatch
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:106 parse_exten() test conditions destination_number(1009) =~ /^\*45$/
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:108 parse_exten() Regex mismatch
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:106 parse_exten() test conditions ${numbering_plan}() =~ /^[[:alnum:]]+$/
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:108 parse_exten() Regex mismatch
2007-11-07 20:09:42 [DEBUG] mod_dialplan_xml.c:360 dialplan_hunt() sofia/74.54.99.18/19549227122@flgw:5060 State Change CS_RING -> CS_EXECUTE
2007-11-07 20:09:42 [DEBUG] switch_core_session.c:625 switch_core_session_signal_state_change() Kill sofia/74.54.99.18/19549227122@flgw:5060 [BREAK]
2007-11-07 20:09:42 [DEBUG] switch_core_state_machine.c:406 switch_core_session_run() (sofia/74.54.99.18/19549227122@flgw:5060) State EXECUTE
2007-11-07 20:09:42 [DEBUG] mod_sofia.c:137 sofia_on_execute() SOFIA EXECUTE
2007-11-07 20:09:42 [DEBUG] switch_core_state_machine.c:121 switch_core_standard_on_execute() Standard EXECUTE
2007-11-07 20:09:42 [NOTICE] switch_core_state_machine.c:134 switch_core_standard_on_execute() Execute set(dialed_ext=1009)
2007-11-07 20:09:42 [DEBUG] mod_dptools.c:358 set_function() SET [dialed_ext]=[1009]
2007-11-07 20:09:42 [NOTICE] switch_core_state_machine.c:134 switch_core_standard_on_execute() Execute set(call_timeout=30000)
2007-11-07 20:09:42 [DEBUG] mod_dptools.c:358 set_function() SET [call_timeout]=[30000]
2007-11-07 20:09:42 [NOTICE] switch_core_state_machine.c:134 switch_core_standard_on_execute() Execute set(hangup_after_bridge=true)
2007-11-07 20:09:42 [DEBUG] mod_dptools.c:358 set_function() SET [hangup_after_bridge]=[true]
2007-11-07 20:09:42 [NOTICE] switch_core_state_machine.c:134 switch_core_standard_on_execute() Execute bridge(sofia/internal/${dialed_ext}%74.54.99.18)
2007-11-07 20:09:42 [DEBUG] switch_core_state_machine.c:158 switch_core_standard_on_execute() Expanded String bridge(sofia/internal/1009%74.54.99.18)
2007-11-07 20:09:42 [NOTICE] switch_channel.c:391 switch_channel_set_name() New Chan sofia/internal/1009 [105994b5-8b1e-4ce4-874b-3ddad02ce012]
2007-11-07 20:09:42 [DEBUG] mod_sofia.c:1609 sofia_outgoing_channel() sofia/internal/1009 State Change CS_NEW -> CS_INIT
2007-11-07 20:09:42 [DEBUG] switch_core_session.c:625 switch_core_session_signal_state_change() Kill sofia/internal/1009 [BREAK]
2007-11-07 20:09:42 [DEBUG] switch_core_state_machine.c:397 switch_core_session_run() (sofia/internal/1009) State INIT
2007-11-07 20:09:42 [DEBUG] mod_sofia.c:92 sofia_on_init() SOFIA INIT
2007-11-07 20:09:42 [DEBUG] sofia_glue.c:389 sofia_glue_ext_address_lookup() Stun Success [74.54.99.18]:[16386]
2007-11-07 20:09:42 [DEBUG] mod_sofia.c:105 sofia_on_init() sofia/internal/1009 State Change CS_INIT -> CS_RING
2007-11-07 20:09:42 [DEBUG] switch_core_session.c:625 switch_core_session_signal_state_change() Kill sofia/internal/1009 [BREAK]
2007-11-07 20:09:42 [DEBUG] switch_core_state_machine.c:400 switch_core_session_run() (sofia/internal/1009) State RING
2007-11-07 20:09:42 [DEBUG] mod_sofia.c:120 sofia_on_ring() SOFIA RING
2007-11-07 20:09:42 [DEBUG] switch_ivr_originate.c:48 originate_on_ring() sofia/internal/1009 State Change CS_RING -> CS_HOLD
2007-11-07 20:09:42 [DEBUG] switch_core_session.c:625 switch_core_session_signal_state_change() Kill sofia/internal/1009 [BREAK]
2007-11-07 20:09:42 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/internal/1009) State HOLD
2007-11-07 20:09:42 [DEBUG] switch_core_state_machine.c:204 switch_core_standard_on_hold() Standard HOLD
2007-11-07 20:09:42 [DEBUG] sofia.c:117 sofia_event_callback() event [nua_i_state] status [0][INVITE sent] session: sofia/internal/1009
2007-11-07 20:09:42 [DEBUG] sofia.c:1147 sofia_handle_sip_i_state() Channel sofia/internal/1009 entering state [calling]
2007-11-07 20:09:42 [DEBUG] switch_ivr_originate.c:625 switch_ivr_originate() Raw Codec Activation Success L16@8000hz 1 channel 20ms
2007-11-07 20:09:42 [DEBUG] switch_ivr_originate.c:653 switch_ivr_originate() Play Ringback File [/root/mp3s/playback.mp3]
2007-11-07 20:09:42 [DEBUG] switch_core_io.c:289 switch_core_session_read_frame() Engaging Read Buffer at 320 bytes
2007-11-07 20:09:42 [DEBUG] sofia.c:117 sofia_event_callback() event [nua_r_invite] status [180][Ringing] session: sofia/internal/1009
2007-11-07 20:09:42 [DEBUG] sofia.c:117 sofia_event_callback() event [nua_i_state] status [180][Ringing] session: sofia/internal/1009
2007-11-07 20:09:42 [DEBUG] sofia.c:1147 sofia_handle_sip_i_state() Channel sofia/internal/1009 entering state [proceeding]
2007-11-07 20:09:42 [NOTICE] sofia.c:1181 sofia_handle_sip_i_state() Ring-Ready sofia/internal/1009!