2007-11-07 14:38:48 [DEBUG] switch_core_session.c:625 switch_core_session_signal_state_change() Kill sofia/192.168.1.8/agx1@192.168.1.8:5061 [BREAK]
2007-11-07 14:38:48 [DEBUG] switch_core_state_machine.c:400 switch_core_session_run() (sofia/192.168.1.8/agx1@192.168.1.8:5061) State RING
2007-11-07 14:38:48 [DEBUG] mod_sofia.c:120 sofia_on_ring() SOFIA RING
2007-11-07 14:38:48 [DEBUG] switch_core_state_machine.c:64 switch_core_standard_on_ring() Standard RING sofia/192.168.1.8/agx1@192.168.1.8:5061
2007-11-07 14:38:48 [INFO] mod_dialplan_xml.c:304 dialplan_hunt() Processing agxsip->1000!
2007-11-07 14:38:48 [DEBUG] mod_dialplan_xml.c:106 parse_exten() test conditions destination_number(1000) =~ /^100$/
2007-11-07 14:38:48 [DEBUG] mod_dialplan_xml.c:108 parse_exten() Regex mismatch
2007-11-07 14:38:48 [DEBUG] mod_dialplan_xml.c:106 parse_exten() test conditions destination_number(1000) =~ /.*/
2007-11-07 14:38:48 [DEBUG] mod_dialplan_xml.c:360 dialplan_hunt() sofia/192.168.1.8/agx1@192.168.1.8:5061 State Change CS_RING -> CS_EXECUTE
2007-11-07 14:38:48 [DEBUG] switch_core_session.c:625 switch_core_session_signal_state_change() Kill sofia/192.168.1.8/agx1@192.168.1.8:5061 [BREAK]
2007-11-07 14:38:48 [DEBUG] switch_core_state_machine.c:406 switch_core_session_run() (sofia/192.168.1.8/agx1@192.168.1.8:5061) State EXECUTE
2007-11-07 14:38:48 [DEBUG] mod_sofia.c:137 sofia_on_execute() SOFIA EXECUTE
2007-11-07 14:38:48 [DEBUG] switch_core_state_machine.c:121 switch_core_standard_on_execute() Standard EXECUTE
2007-11-07 14:38:48 [NOTICE] switch_core_state_machine.c:134 switch_core_standard_on_execute() Execute answer()
2007-11-07 14:38:48 [DEBUG] sofia_glue.c:389 sofia_glue_ext_address_lookup() Stun Success [88.34.216.63]:[16388]
2007-11-07 14:38:48 [DEBUG] sofia_glue.c:984 sofia_glue_activate_rtp() AUDIO RTP [sofia/192.168.1.8/agx1@192.168.1.8:5061] 192.168.1.8:16388->192.168.1.13:8000 codec: 8 ms: 20
2007-11-07 14:38:48 [DEBUG] switch_rtp.c:545 switch_rtp_create() Starting timer [soft] 160 bytes per 20000ms
2007-11-07 14:38:48 [DEBUG] mod_sofia.c:323 sofia_answer_channel() Local SDP sofia/192.168.1.8/agx1@192.168.1.8:5061:
v=0
o=FreeSWITCH 1194426340 1194426341 IN IP4 88.34.216.63
s=FreeSWITCH
c=IN IP4 88.34.216.63
t=0 0
a=sendrecv
m=audio 16388 RTP/AVP 8 101 13
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20

2007-11-07 14:38:48 [DEBUG] sofia.c:1149 sofia_handle_sip_i_state() Channel sofia/192.168.1.8/agx1@192.168.1.8:5061 entering state [completed]
2007-11-07 14:38:48 [DEBUG] switch_core_session.c:339 switch_core_session_receive_message() Kill sofia/192.168.1.8/agx1@192.168.1.8:5061 [BREAK]
2007-11-07 14:38:48 [NOTICE] mod_dptools.c:300 answer_function() Channel [sofia/192.168.1.8/agx1@192.168.1.8:5061] has been answered
2007-11-07 14:38:48 [NOTICE] switch_core_state_machine.c:134 switch_core_standard_on_execute() Execute sleep(500)
2007-11-07 14:38:48 [DEBUG] sofia.c:1149 sofia_handle_sip_i_state() Channel sofia/192.168.1.8/agx1@192.168.1.8:5061 entering state [ready]
2007-11-07 14:38:49 [NOTICE] switch_core_state_machine.c:134 switch_core_standard_on_execute() Execute phrase(play,pbx-invalid)
2007-11-07 14:38:49 [DEBUG] mod_dptools.c:272 phrase_function() Execute play(pbx-invalid) lang (null)
2007-11-07 14:38:49 [DEBUG] switch_ivr_play_say.c:117 switch_ivr_phrase_macro() No language specified - Using [en]
2007-11-07 14:38:49 [DEBUG] switch_ivr_play_say.c:256 switch_ivr_phrase_macro() Handle execute:[sleep(200)] (en:en)
2007-11-07 14:38:49 [DEBUG] switch_ivr_play_say.c:256 switch_ivr_phrase_macro() Handle play-file:[pbx-invalid.wav] (en:en)
2007-11-07 14:38:49 [DEBUG] mod_sndfile.c:151 sndfile_file_open() Opening File [/opt/sounds/en/pbx-invalid.wav] 8000hz
2007-11-07 14:38:49 [DEBUG] switch_ivr_play_say.c:798 switch_ivr_play_file() Codec Activated L16@8000hz 1 channels 20ms
2007-11-07 14:38:53 [DEBUG] switch_ivr_play_say.c:1047 switch_ivr_play_file() done playing file
2007-11-07 14:38:53 [DEBUG] switch_ivr_play_say.c:256 switch_ivr_phrase_macro() Handle execute:[sleep(1000)] (en:en)
2007-11-07 14:38:53 [NOTICE] switch_core_state_machine.c:134 switch_core_standard_on_execute() Execute sleep(500)
2007-11-07 14:38:54 [NOTICE] switch_core_state_machine.c:184 switch_core_standard_on_execute() Hangup sofia/192.168.1.8/agx1@192.168.1.8:5061 [CS_EXECUTE] [NORMAL_CLEARING]
2007-11-07 14:38:54 [DEBUG] switch_channel.c:1070 switch_channel_perform_hangup() Kill sofia/192.168.1.8/agx1@192.168.1.8:5061 [KILL]
2007-11-07 14:38:54 [DEBUG] switch_core_session.c:625 switch_core_session_signal_state_change() Kill sofia/192.168.1.8/agx1@192.168.1.8:5061 [BREAK]
2007-11-07 14:38:54 [DEBUG] switch_core_state_machine.c:394 switch_core_session_run() (sofia/192.168.1.8/agx1@192.168.1.8:5061) State HANGUP
2007-11-07 14:38:54 [DEBUG] mod_sofia.c:217 sofia_on_hangup() Channel sofia/192.168.1.8/agx1@192.168.1.8:5061 hanging up, cause: NORMAL_CLEARING
2007-11-07 14:38:54 [DEBUG] mod_sofia.c:235 sofia_on_hangup() Sending BYE to sofia/192.168.1.8/agx1@192.168.1.8:5061
2007-11-07 14:38:54 [DEBUG] switch_core_state_machine.c:45 switch_core_standard_on_hangup() Standard HANGUP sofia/192.168.1.8/agx1@192.168.1.8:5061, cause: NORMAL_CLEARING
2007-11-07 14:38:54 [DEBUG] switch_core_session.c:683 switch_core_session_thread() Session 3 (sofia/192.168.1.8/agx1@192.168.1.8:5061) Locked, Waiting on external entities
2007-11-07 14:38:54 [INFO] switch_core_session.c:689 switch_core_session_thread() Session 3 (sofia/192.168.1.8/agx1@192.168.1.8:5061) Ended
2007-11-07 14:38:54 [NOTICE] switch_core_session.c:691 switch_core_session_thread() Close Channel sofia/192.168.1.8/agx1@192.168.1.8:5061 [CS_HANGUP]