freeswitch@spider> 2007-05-07 17:35:00 [NOTICE] switch_channel.c:372 switch_channel_set_name() New Chan sofia/mydomain.com/4765@192.168.1.206:5060 [3109b3ce-fceb-11db-889f-3987eb2a4505]
2007-05-07 17:35:00 [DEBUG] sofia.c:987 sofia_handle_sip_i_state() Channel sofia/mydomain.com/4765@192.168.1.206:5060 entering state [received]
2007-05-07 17:35:00 [DEBUG] sofia.c:991 sofia_handle_sip_i_state() Remote SDP:
v=0
o=- 311904 311904 IN IP4 192.168.1.200
s=-
c=IN IP4 192.168.1.200
t=0 0
m=audio 16466 RTP/AVP 0 2 4 8 18 96 97 98 100 101
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:4 G723/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729a/8000
a=rtpmap:96 G726-40/8000
a=rtpmap:97 G726-24/8000
a=rtpmap:98 G726-16/8000
a=rtpmap:100 NSE/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:30

2007-05-07 17:35:00 [DEBUG] sofia_glue.c:1179 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMU:0]/[PCMU:0]
2007-05-07 17:35:00 [DEBUG] sofia_glue.c:1217 sofia_glue_negotiate_sdp() Substituting codec PCMU@30ms
2007-05-07 17:35:00 [INFO] sofia_glue.c:835 sofia_glue_tech_set_codec() Set Codec sofia/mydomain.com/4765@192.168.1.206:5060 PCMU/8000 30 ms
2007-05-07 17:35:00 [DEBUG] sofia_glue.c:1151 sofia_glue_negotiate_sdp() Set 2833 dtmf payload to 101
2007-05-07 17:35:00 [DEBUG] sofia.c:1092 sofia_handle_sip_i_state() sofia/mydomain.com/4765@192.168.1.206:5060 State Change CS_NEW -> CS_INIT
2007-05-07 17:35:00 [DEBUG] switch_core_state_machine.c:378 switch_core_session_run() (sofia/mydomain.com/4765@192.168.1.206:5060) State INIT
2007-05-07 17:35:00 [DEBUG] mod_sofia.c:88 sofia_on_init() SOFIA INIT
2007-05-07 17:35:00 [DEBUG] mod_sofia.c:102 sofia_on_init() sofia/mydomain.com/4765@192.168.1.206:5060 State Change CS_INIT -> CS_RING
2007-05-07 17:35:00 [DEBUG] switch_core_state_machine.c:413 switch_core_session_run() (sofia/mydomain.com/4765@192.168.1.206:5060) State RING
2007-05-07 17:35:00 [DEBUG] mod_sofia.c:117 sofia_on_ring() SOFIA RING
2007-05-07 17:35:00 [DEBUG] switch_core_state_machine.c:56 switch_core_standard_on_ring() Standard RING sofia/mydomain.com/4765@192.168.1.206:5060
2007-05-07 17:35:00 [DEBUG] mod_enum.c:537 enum_dialplan_hunt() ENUM Lookup on 2003
2007-05-07 17:35:00 [DEBUG] mod_enum.c:202 dnserror() unable to lookup NAPTR record for 3.0.0.2.e164.org: domain name does not exist
2007-05-07 17:35:00 [INFO] mod_dialplan_xml.c:315 dialplan_hunt() Processing 4765->2003!
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:115 parse_exten() test conditions destination_number(2003) =~ /^556$/
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:117 parse_exten() Regex mismatch
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:115 parse_exten() test conditions destination_number(2003) =~ /^(18(0{2}|8{2}|7{2}|6{2})\d{7})$/
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:117 parse_exten() Regex mismatch
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:115 parse_exten() test conditions source(mod_sofia) =~ /mod_portaudio/
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:117 parse_exten() Regex mismatch
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:115 parse_exten() test conditions destination_number(2003) =~ /^1234$/
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:117 parse_exten() Regex mismatch
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:115 parse_exten() test conditions destination_number(2003) =~ /^1000$/
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:117 parse_exten() Regex mismatch
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:115 parse_exten() test conditions destination_number(2003) =~ /^2001$/
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:117 parse_exten() Regex mismatch
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:115 parse_exten() test conditions destination_number(2003) =~ /^2004$/
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:117 parse_exten() Regex mismatch
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:115 parse_exten() test conditions destination_number(2003) =~ /^2002$/
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:117 parse_exten() Regex mismatch
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:115 parse_exten() test conditions destination_number(2003) =~ /^2003$/
2007-05-07 17:35:00 [DEBUG] mod_dialplan_xml.c:371 dialplan_hunt() sofia/mydomain.com/4765@192.168.1.206:5060 State Change CS_RING -> CS_EXECUTE
2007-05-07 17:35:00 [DEBUG] switch_core_state_machine.c:448 switch_core_session_run() (sofia/mydomain.com/4765@192.168.1.206:5060) State EXECUTE
2007-05-07 17:35:00 [DEBUG] mod_sofia.c:134 sofia_on_execute() SOFIA EXECUTE
2007-05-07 17:35:00 [DEBUG] switch_core_state_machine.c:114 switch_core_standard_on_execute() Standard EXECUTE
2007-05-07 17:35:00 [NOTICE] switch_core_state_machine.c:124 switch_core_standard_on_execute() Execute python(/usr/src/freeswitch/scripts/py_reload_bug.py)
2007-05-07 17:35:00 [NOTICE] mod_python.c:83 eval_some_python() running /usr/src/freeswitch/scripts/py_reload_bug.py
2007-05-07 17:35:00 [INFO] sofia_glue.c:900 sofia_glue_activate_rtp() AUDIO RTP [sofia/mydomain.com/4765@192.168.1.206:5060] 192.168.1.206:16384->192.168.1.200:16466 codec: 0 ms: 30
2007-05-07 17:35:00 [DEBUG] switch_rtp.c:534 switch_rtp_create() Starting timer [soft] 240 bytes per 30000ms
2007-05-07 17:35:00 [DEBUG] mod_sofia.c:315 sofia_answer_channel() Local SDP sofia/mydomain.com/4765@192.168.1.206:5060:
v=0
o=FreeSWITCH 1178560916 1178560917 IN IP4 192.168.1.206
s=FreeSWITCH
c=IN IP4 192.168.1.206
t=0 0
a=sendrecv
m=audio 16384 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:30

2007-05-07 17:35:00 [DEBUG] sofia.c:987 sofia_handle_sip_i_state() Channel sofia/mydomain.com/4765@192.168.1.206:5060 entering state [completed]
2007-05-07 17:35:00 [INFO] switch_core_session.c:345 switch_core_session_receive_message() Kill sofia/mydomain.com/4765@192.168.1.206:5060 [BREAK]
2007-05-07 17:35:00 [NOTICE] freeswitch_python.cpp:30 answer() Channel [sofia/mydomain.com/4765@192.168.1.206:5060] has been answered
2007-05-07 17:35:00 [NOTICE] freeswitch_python.cpp:45 hangup() Hangup sofia/mydomain.com/4765@192.168.1.206:5060 [CS_EXECUTE] [UNALLOCATED]
2007-05-07 17:35:00 [INFO] switch_channel.c:1055 switch_channel_perform_hangup() Kill sofia/mydomain.com/4765@192.168.1.206:5060 [KILL]
2007-05-07 17:35:00 [DEBUG] switch_core_state_machine.c:342 switch_core_session_run() (sofia/mydomain.com/4765@192.168.1.206:5060) State HANGUP
2007-05-07 17:35:00 [DEBUG] mod_sofia.c:214 sofia_on_hangup() Channel sofia/mydomain.com/4765@192.168.1.206:5060 hanging up, cause: UNALLOCATED
2007-05-07 17:35:00 [DEBUG] mod_sofia.c:230 sofia_on_hangup() Sending BYE to sofia/mydomain.com/4765@192.168.1.206:5060
2007-05-07 17:35:00 [DEBUG] switch_core_state_machine.c:45 switch_core_standard_on_hangup() Standard HANGUP sofia/mydomain.com/4765@192.168.1.206:5060, cause: UNALLOCATED
2007-05-07 17:35:00 [DEBUG] switch_core_session.c:696 switch_core_session_thread() Session 1 (sofia/mydomain.com/4765@192.168.1.206:5060) Locked, Waiting on external entities
2007-05-07 17:35:00 [INFO] switch_core_session.c:702 switch_core_session_thread() Session 1 (sofia/mydomain.com/4765@192.168.1.206:5060) Ended
2007-05-07 17:35:00 [NOTICE] switch_core_session.c:654 switch_core_session_destroy() Close Channel sofia/mydomain.com/4765@192.168.1.206:5060