------------------------------------------------------------------------ recv 1149 bytes from udp/[213.192.59.75]:5060 at 00:56:29.545782: ------------------------------------------------------------------------ INVITE sip:8084473453@vm.akamaitel.com SIP/2.0 Record-Route: Record-Route: Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK6a9e.7e574dc5.0;i=d86 Via: SIP/2.0/TCP 192.168.1.47:37440;rport=51604;received=66.8.218.137;branch=z9hG4bK-d8754z-a30c554e5236171f-1---d8754z- Max-Forwards: 16 Contact: To: "8084473453@vm.akamaitel.com" From: "matthewdarnell";tag=8a095b76 Call-ID: MDFlYTEyOGQyNmE0NWI4MTFlZDhkNGUwM2RjMzMyNjY. CSeq: 2 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Content-Type: application/sdp User-Agent: X-Lite release 1100l stamp 47546 Content-Length: 202 P-hint: outbound P-RTP-Proxy: YES v=0 o=- 7 2 IN IP4 66.8.218.137 s=CounterPath X-Lite 3.0 c=IN IP4 213.192.59.91 t=0 0 m=audio 52402 RTP/AVP 0 101 a=fmtp:101 0-15 a=rtpmap:101 telephone-event/8000 a=sendrecv a=nortpproxy:yes ------------------------------------------------------------------------ send 688 bytes to udp/[213.192.59.75]:5060 at 00:56:29.546994: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK6a9e.7e574dc5.0;i=d86 Via: SIP/2.0/TCP 192.168.1.47:37440;rport=51604;received=66.8.218.137;branch=z9hG4bK-d8754z-a30c554e5236171f-1---d8754z- Record-Route: Record-Route: From: "matthewdarnell";tag=8a095b76 To: "8084473453@vm.akamaitel.com" Call-ID: MDFlYTEyOGQyNmE0NWI4MTFlZDhkNGUwM2RjMzMyNjY. CSeq: 2 INVITE User-Agent: FreeSWITCH-mod_sofia/1.0.1-exported Content-Length: 0 ------------------------------------------------------------------------ 2008-09-20 14:56:29 [NOTICE] switch_channel.c:534 switch_channel_set_name() New Channel sofia/external/matthewdarnell@iptel.org [63a6e8bf-46dd-4e66-9ebe-7b38df5e64cb] 2008-09-20 14:56:29 [DEBUG] sofia.c:2129 sofia_handle_sip_i_state() Channel sofia/external/matthewdarnell@iptel.org entering state [received] 2008-09-20 14:56:29 [DEBUG] sofia.c:2133 sofia_handle_sip_i_state() Remote SDP: v=0 o=- 7 2 IN IP4 66.8.218.137 s=CounterPath X-Lite 3.0 c=IN IP4 213.192.59.91 t=0 0 m=audio 52402 RTP/AVP 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=nortpproxy:yes 2008-09-20 14:56:29 [DEBUG] sofia_glue.c:2280 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMU:0:8000]/[G722:9:8000] 2008-09-20 14:56:29 [DEBUG] sofia_glue.c:2243 sofia_glue_negotiate_sdp() Set 2833 dtmf payload to 101 2008-09-20 14:56:29 [DEBUG] sofia_glue.c:2280 sofia_glue_negotiate_sdp() Audio Codec Compare [telephone-event:101:8000]/[G722:9:8000] 2008-09-20 14:56:29 [DEBUG] sofia_glue.c:2280 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMU:0:8000]/[PCMU:0:8000] 2008-09-20 14:56:29 [DEBUG] sofia_glue.c:1530 sofia_glue_tech_set_codec() Set Codec sofia/external/matthewdarnell@iptel.org PCMU/8000 20 ms 160 samples 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/external/matthewdarnell@iptel.org Running State Change CS_NEW 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:369 switch_core_session_run() (sofia/external/matthewdarnell@iptel.org) State NEW 2008-09-20 14:56:29 [DEBUG] sofia.c:2270 sofia_handle_sip_i_state() sofia/external/matthewdarnell@iptel.org State Change CS_NEW -> CS_INIT 2008-09-20 14:56:29 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/external/matthewdarnell@iptel.org [BREAK] 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/external/matthewdarnell@iptel.org Running State Change CS_INIT 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/external/matthewdarnell@iptel.org) State INIT 2008-09-20 14:56:29 [DEBUG] mod_sofia.c:80 sofia_on_init() sofia/external/matthewdarnell@iptel.org SOFIA INIT 2008-09-20 14:56:29 [DEBUG] mod_sofia.c:107 sofia_on_init() sofia/external/matthewdarnell@iptel.org State Change CS_INIT -> CS_ROUTING 2008-09-20 14:56:29 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/external/matthewdarnell@iptel.org [BREAK] 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/external/matthewdarnell@iptel.org) State INIT going to sleep 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/external/matthewdarnell@iptel.org Running State Change CS_ROUTING 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (sofia/external/matthewdarnell@iptel.org) State ROUTING 2008-09-20 14:56:29 [DEBUG] mod_sofia.c:119 sofia_on_routing() sofia/external/matthewdarnell@iptel.org SOFIA ROUTING 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:64 switch_core_standard_on_routing() Standard ROUTING sofia/external/matthewdarnell@iptel.org 2008-09-20 14:56:29 [INFO] mod_dialplan_xml.c:222 dialplan_hunt() Processing matthewdarnell->8084473453@public 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions desination-number() =~ /3451/ 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions ${sip_referred_by_user}() =~ /8084473451/ 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions ${sip_referred_by_user}() =~ /8084473452/ 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions ${sip_referred_by_user}() =~ /8084473453/ 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(8084473453) =~ /8085353111/ 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(8084473453) =~ /8085353112/ 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(8084473453) =~ /8087695297/ 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions ${sip_to_user}(8084473453) =~ /8084473451/ 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch 2008-09-20 14:56:29 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions ${sip_to_user}(8084473453) =~ /8084473453/ 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:97 switch_core_standard_on_routing() sofia/external/matthewdarnell@iptel.org State Change CS_ROUTING -> CS_EXECUTE 2008-09-20 14:56:29 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/external/matthewdarnell@iptel.org [BREAK] 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (sofia/external/matthewdarnell@iptel.org) State ROUTING going to sleep 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/external/matthewdarnell@iptel.org Running State Change CS_EXECUTE 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:430 switch_core_session_run() (sofia/external/matthewdarnell@iptel.org) State EXECUTE 2008-09-20 14:56:29 [DEBUG] mod_sofia.c:156 sofia_on_execute() sofia/external/matthewdarnell@iptel.org SOFIA EXECUTE 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:129 switch_core_standard_on_execute() Standard EXECUTE 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/external/matthewdarnell@iptel.org Execute info() 2008-09-20 14:56:29 [INFO] mod_dptools.c:876 info_function() CHANNEL_DATA: Channel-State: [CS_EXECUTE] Channel-State-Number: [4] Channel-Name: [sofia/external/matthewdarnell@iptel.org] Unique-ID: [63a6e8bf-46dd-4e66-9ebe-7b38df5e64cb] Call-Direction: [inbound] Answer-State: [ringing] Channel-Read-Codec-Name: [PCMU] Channel-Read-Codec-Rate: [8000] Channel-Write-Codec-Name: [PCMU] Channel-Write-Codec-Rate: [8000] Caller-Username: [matthewdarnell] Caller-Dialplan: [XML] Caller-Caller-ID-Name: [matthewdarnell] Caller-Caller-ID-Number: [matthewdarnell] Caller-Network-Addr: [213.192.59.75] Caller-Destination-Number: [8084473453] Caller-Unique-ID: [63a6e8bf-46dd-4e66-9ebe-7b38df5e64cb] Caller-Source: [mod_sofia] Caller-Context: [public] Caller-Channel-Name: [sofia/external/matthewdarnell@iptel.org] Caller-Profile-Index: [1] Caller-Channel-Created-Time: [1221958589547402] Caller-Channel-Answered-Time: [0] Caller-Channel-Hangup-Time: [0] Caller-Channel-Transfer-Time: [0] Caller-Screen-Bit: [yes] Caller-Privacy-Hide-Name: [no] Caller-Privacy-Hide-Number: [no] variable_sip_received_ip: [213.192.59.75] variable_sip_received_port: [5060] variable_sip_from_user: [matthewdarnell] variable_sip_from_uri: [matthewdarnell@iptel.org] variable_sip_from_host: [iptel.org] variable_sip_from_user_stripped: [matthewdarnell] variable_sip_from_tag: [8a095b76] variable_sofia_profile_name: [external] variable_sofia_profile_domain_name: [external] variable_sip_req_user: [8084473453] variable_sip_req_uri: [8084473453@vm.akamaitel.com] variable_sip_req_host: [vm.akamaitel.com] variable_sip_to_user: [8084473453] variable_sip_to_uri: [8084473453@vm.akamaitel.com] variable_sip_to_host: [vm.akamaitel.com] variable_sip_contact_params: [transport=TCP] variable_sip_contact_user: [matthewdarnell] variable_sip_contact_port: [51604] variable_sip_contact_uri: [matthewdarnell@66.8.218.137:51604] variable_sip_contact_host: [66.8.218.137] variable_channel_name: [sofia/external/matthewdarnell@iptel.org] variable_sip_call_id: [MDFlYTEyOGQyNmE0NWI4MTFlZDhkNGUwM2RjMzMyNjY.] variable_sip_user_agent: [X-Lite release 1100l stamp 47546] variable_sip_via_host: [213.192.59.75] variable_max_forwards: [16] variable_sip_h_P-hint: [outbound] variable_sip_h_P-RTP-Proxy: [YES] variable_switch_r_sdp: [v=0 o=- 7 2 IN IP4 66.8.218.137 s=CounterPath X-Lite 3.0 c=IN IP4 213.192.59.91 t=0 0 m=audio 52402 RTP/AVP 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=nortpproxy:yes ] variable_remote_media_ip: [213.192.59.91] variable_remote_media_port: [52402] variable_read_codec: [PCMU] variable_read_rate: [8000] variable_write_codec: [PCMU] variable_write_rate: [8000] variable_endpoint_disposition: [RECEIVED] variable_current_application: [info] 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/external/matthewdarnell@iptel.org Execute export(sip_h_Diversion=3453) 2008-09-20 14:56:29 [DEBUG] mod_dptools.c:816 export_function() EXPORT [sip_h_Diversion]=[3453] 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/external/matthewdarnell@iptel.org Execute bridge({absolute_codec_string=PCMA}sofia/gateway/exch.akamaitel.com/3453) 2008-09-20 14:56:29 [DEBUG] switch_ivr_originate.c:652 switch_ivr_originate() variable string 0 = [absolute_codec_string=PCMA] 2008-09-20 14:56:29 [NOTICE] switch_channel.c:534 switch_channel_set_name() New Channel sofia/internal/3453 [ccc144e0-3c8a-4097-8a39-7d8f2e07018c] 2008-09-20 14:56:29 [DEBUG] mod_sofia.c:2001 sofia_outgoing_channel() sofia/internal/3453 State Change CS_NEW -> CS_INIT 2008-09-20 14:56:29 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/internal/3453 [BREAK] 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/3453 Running State Change CS_INIT 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/internal/3453) State INIT 2008-09-20 14:56:29 [DEBUG] mod_sofia.c:80 sofia_on_init() sofia/internal/3453 SOFIA INIT 2008-09-20 14:56:29 [DEBUG] mod_sofia.c:107 sofia_on_init() sofia/internal/3453 State Change CS_INIT -> CS_ROUTING 2008-09-20 14:56:29 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/internal/3453 [BREAK] 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/internal/3453) State INIT going to sleep 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/3453 Running State Change CS_ROUTING 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (sofia/internal/3453) State ROUTING 2008-09-20 14:56:29 [DEBUG] mod_sofia.c:119 sofia_on_routing() sofia/internal/3453 SOFIA ROUTING 2008-09-20 14:56:29 [DEBUG] switch_ivr_originate.c:57 originate_on_routing() sofia/internal/3453 State Change CS_ROUTING -> CS_CONSUME_MEDIA 2008-09-20 14:56:29 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/internal/3453 [BREAK] 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (sofia/internal/3453) State ROUTING going to sleep 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/3453 Running State Change CS_CONSUME_MEDIA 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:442 switch_core_session_run() (sofia/internal/3453) State CONSUME_MEDIA 2008-09-20 14:56:29 [DEBUG] sofia.c:197 sofia_event_callback() event [nua_i_state] status [0][INVITE sent] session: sofia/internal/3453 2008-09-20 14:56:29 [DEBUG] sofia.c:2129 sofia_handle_sip_i_state() Channel sofia/internal/3453 entering state [calling] 2008-09-20 14:56:29 [DEBUG] sofia.c:2129 sofia_handle_sip_i_state() Channel sofia/internal/3453 entering state [calling] 2008-09-20 14:56:29 [DEBUG] sofia.c:197 sofia_event_callback() event [nua_r_invite] status [180][Ringing] session: sofia/internal/3453 2008-09-20 14:56:29 [DEBUG] sofia.c:197 sofia_event_callback() event [nua_i_state] status [180][Ringing] session: sofia/internal/3453 2008-09-20 14:56:29 [DEBUG] sofia.c:2129 sofia_handle_sip_i_state() Channel sofia/internal/3453 entering state [proceeding] 2008-09-20 14:56:29 [NOTICE] sofia.c:2167 sofia_handle_sip_i_state() Ring-Ready sofia/internal/3453! 2008-09-20 14:56:29 [NOTICE] mod_sofia.c:1058 sofia_receive_message() Ring-Ready sofia/external/matthewdarnell@iptel.org! 2008-09-20 14:56:29 [DEBUG] switch_core_session.c:430 switch_core_session_receive_message() Kill sofia/external/matthewdarnell@iptel.org [BREAK] 2008-09-20 14:56:29 [NOTICE] switch_ivr_originate.c:1148 switch_ivr_originate() Ring Ready sofia/external/matthewdarnell@iptel.org! 2008-09-20 14:56:29 [DEBUG] switch_core_session.c:430 switch_core_session_receive_message() Kill sofia/external/matthewdarnell@iptel.org [BREAK] send 976 bytes to udp/[213.192.59.75]:5060 at 00:56:29.603515: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK6a9e.7e574dc5.0;i=d86 Via: SIP/2.0/TCP 192.168.1.47:37440;rport=51604;received=66.8.218.137;branch=z9hG4bK-d8754z-a30c554e5236171f-1---d8754z- Record-Route: Record-Route: From: "matthewdarnell";tag=8a095b76 To: "8084473453@vm.akamaitel.com" ;tag=a9gB8pF2KSSca Call-ID: MDFlYTEyOGQyNmE0NWI4MTFlZDhkNGUwM2RjMzMyNjY. CSeq: 2 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.1-exported Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO Supported: 100rel, timer, precondition, path, replaces Allow-Events: talk Content-Length: 0 ------------------------------------------------------------------------ 2008-09-20 14:56:29 [DEBUG] sofia.c:197 sofia_event_callback() event [nua_i_state] status [180][Ringing] session: sofia/external/matthewdarnell@iptel.org 2008-09-20 14:56:29 [DEBUG] sofia.c:2129 sofia_handle_sip_i_state() Channel sofia/external/matthewdarnell@iptel.org entering state [early] 2008-09-20 14:56:29 [DEBUG] sofia.c:2129 sofia_handle_sip_i_state() Channel sofia/internal/3453 entering state [ready] 2008-09-20 14:56:29 [DEBUG] sofia.c:2133 sofia_handle_sip_i_state() Remote SDP: v=0 o=- 0 0 IN IP4 10.10.20.31 s=Microsoft Exchange Speech Engine c=IN IP4 10.10.20.31 t=0 0 m=audio 64000 RTP/AVP 8 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2008-09-20 14:56:29 [DEBUG] sofia_glue.c:2280 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMA:8:8000]/[PCMA:8:8000] 2008-09-20 14:56:29 [DEBUG] sofia_glue.c:1530 sofia_glue_tech_set_codec() Set Codec sofia/internal/3453 PCMA/8000 20 ms 160 samples 2008-09-20 14:56:29 [DEBUG] sofia_glue.c:2243 sofia_glue_negotiate_sdp() Set 2833 dtmf payload to 101 2008-09-20 14:56:29 [DEBUG] sofia_glue.c:1739 sofia_glue_activate_rtp() AUDIO RTP [sofia/internal/3453] 10.10.20.50 port 21900 -> 10.10.20.31 port 64000 codec: 8 ms: 20 2008-09-20 14:56:29 [DEBUG] switch_rtp.c:813 switch_rtp_create() Starting timer [soft] 160 bytes per 20000ms 2008-09-20 14:56:29 [DEBUG] switch_channel.c:1540 switch_channel_perform_mark_answered() Kill sofia/external/matthewdarnell@iptel.org [BREAK] 2008-09-20 14:56:29 [NOTICE] sofia.c:2491 sofia_handle_sip_i_state() Channel [sofia/internal/3453] has been answered 2008-09-20 14:56:29 [DEBUG] sofia_glue.c:1739 sofia_glue_activate_rtp() AUDIO RTP [sofia/external/matthewdarnell@iptel.org] 64.75.215.160 port 21900 -> 213.192.59.91 port 52402 codec: 0 ms: 20 2008-09-20 14:56:29 [DEBUG] switch_rtp.c:813 switch_rtp_create() Starting timer [soft] 160 bytes per 20000ms 2008-09-20 14:56:29 [DEBUG] mod_sofia.c:430 sofia_answer_channel() Local SDP sofia/external/matthewdarnell@iptel.org: v=0 o=FreeSWITCH 1221936689 1221936690 IN IP4 64.75.215.160 s=FreeSWITCH c=IN IP4 64.75.215.160 t=0 0 a=sendrecv m=audio 21900 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 2008-09-20 14:56:29 [DEBUG] switch_core_session.c:430 switch_core_session_receive_message() Kill sofia/external/matthewdarnell@iptel.org [BREAK] 2008-09-20 14:56:29 [NOTICE] switch_ivr_originate.c:1298 switch_ivr_originate() Channel [sofia/external/matthewdarnell@iptel.org] has been answered 2008-09-20 14:56:29 [DEBUG] switch_ivr_originate.c:1336 switch_ivr_originate() Originate Resulted in Success: [sofia/internal/3453] send 1297 bytes to udp/[213.192.59.75]:5060 at 00:56:29.691843: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK6a9e.7e574dc5.0;i=d86 Via: SIP/2.0/TCP 192.168.1.47:37440;rport=51604;received=66.8.218.137;branch=z9hG4bK-d8754z-a30c554e5236171f-1---d8754z- Record-Route: Record-Route: From: "matthewdarnell";tag=8a095b76 To: "8084473453@vm.akamaitel.com" ;tag=a9gB8pF2KSSca Call-ID: MDFlYTEyOGQyNmE0NWI4MTFlZDhkNGUwM2RjMzMyNjY. CSeq: 2 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.1-exported Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO Supported: 100rel, timer, precondition, path, replaces Allow-Events: talk Min-SE: 120 Content-Type: application/sdp Content-Disposition: session Content-Length: 275 v=0 o=FreeSWITCH 2051309078168030273 270946083756621899 IN IP4 64.75.215.160 s=FreeSWITCH c=IN IP4 64.75.215.160 t=0 0 a=sendrecv m=audio 21900 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 ------------------------------------------------------------------------ 2008-09-20 14:56:29 [DEBUG] switch_core_session.c:430 switch_core_session_receive_message() Kill sofia/internal/3453 [BREAK] 2008-09-20 14:56:29 [DEBUG] switch_core_session.c:430 switch_core_session_receive_message() Kill sofia/external/matthewdarnell@iptel.org [BREAK] 2008-09-20 14:56:29 [DEBUG] switch_ivr_bridge.c:778 switch_ivr_multi_threaded_bridge() sofia/internal/3453 State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2008-09-20 14:56:29 [DEBUG] sofia.c:2129 sofia_handle_sip_i_state() Channel sofia/external/matthewdarnell@iptel.org entering state [completed] 2008-09-20 14:56:29 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/internal/3453 [BREAK] 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:442 switch_core_session_run() (sofia/internal/3453) State CONSUME_MEDIA going to sleep 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/3453 Running State Change CS_EXCHANGE_MEDIA 2008-09-20 14:56:29 [DEBUG] switch_core_state_machine.c:433 switch_core_session_run() (sofia/internal/3453) State EXCHANGE_MEDIA 2008-09-20 14:56:29 [DEBUG] mod_sofia.c:356 sofia_on_exchange_media() SOFIA LOOPBACK 2008-09-20 14:56:29 [WARNING] mod_sofia.c:787 sofia_receive_message() Cannot pass 2833 on a transcoded call. 2008-09-20 14:56:29 [DEBUG] switch_core_session.c:430 switch_core_session_receive_message() Kill sofia/external/matthewdarnell@iptel.org [BREAK] send 1297 bytes to udp/[213.192.59.75]:5060 at 00:56:30.191803: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK6a9e.7e574dc5.0;i=d86 Via: SIP/2.0/TCP 192.168.1.47:37440;rport=51604;received=66.8.218.137;branch=z9hG4bK-d8754z-a30c554e5236171f-1---d8754z- Record-Route: Record-Route: From: "matthewdarnell";tag=8a095b76 To: "8084473453@vm.akamaitel.com" ;tag=a9gB8pF2KSSca Call-ID: MDFlYTEyOGQyNmE0NWI4MTFlZDhkNGUwM2RjMzMyNjY. CSeq: 2 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.1-exported Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO Supported: 100rel, timer, precondition, path, replaces Allow-Events: talk Min-SE: 120 Content-Type: application/sdp Content-Disposition: session Content-Length: 275 v=0 o=FreeSWITCH 2051309078168030273 270946083756621899 IN IP4 64.75.215.160 s=FreeSWITCH c=IN IP4 64.75.215.160 t=0 0 a=sendrecv m=audio 21900 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 ------------------------------------------------------------------------ 2008-09-20 14:56:30 [DEBUG] switch_core_session.c:430 switch_core_session_receive_message() Kill sofia/internal/3453 [BREAK] recv 1068 bytes from udp/[213.192.59.75]:5060 at 00:56:30.482170: ------------------------------------------------------------------------ ACK sip:mod_sofia@64.75.215.160:5060;transport=udp SIP/2.0 Record-Route: Record-Route: Via: SIP/2.0/UDP 213.192.59.75;branch=0;i=d86 Via: SIP/2.0/TCP 192.168.1.47:37440;rport=51604;received=66.8.218.137;branch=z9hG4bK-d8754z-913ca92e8a3d2e18-1---d8754z- Max-Forwards: 16 Contact: To: "8084473453@vm.akamaitel.com";tag=a9gB8pF2KSSca From: "matthewdarnell";tag=8a095b76 Call-ID: MDFlYTEyOGQyNmE0NWI4MTFlZDhkNGUwM2RjMzMyNjY. CSeq: 2 ACK Proxy-Authorization: Digest username="matthewdarnell",realm="iptel.org",nonce="SNWbxEjVm7o6R3qdBfGurqbPhiiBGN51",uri="sip:8084473453@vm.akamaitel.com",response="30b91ac06db13426f1b6be0c3c870768",algorithm=MD5 User-Agent: X-Lite release 1100l stamp 47546 Content-Length: 0 P-hint: rr-enforced ------------------------------------------------------------------------ 2008-09-20 14:56:30 [DEBUG] sofia.c:2129 sofia_handle_sip_i_state() Channel sofia/external/matthewdarnell@iptel.org entering state [ready] recv 1068 bytes from udp/[213.192.59.75]:5060 at 00:56:30.914214: ------------------------------------------------------------------------ ACK sip:mod_sofia@64.75.215.160:5060;transport=udp SIP/2.0 Record-Route: Record-Route: Via: SIP/2.0/UDP 213.192.59.75;branch=0;i=d86 Via: SIP/2.0/TCP 192.168.1.47:37440;rport=51604;received=66.8.218.137;branch=z9hG4bK-d8754z-913ca92e8a3d2e18-1---d8754z- Max-Forwards: 16 Contact: To: "8084473453@vm.akamaitel.com";tag=a9gB8pF2KSSca From: "matthewdarnell";tag=8a095b76 Call-ID: MDFlYTEyOGQyNmE0NWI4MTFlZDhkNGUwM2RjMzMyNjY. CSeq: 2 ACK Proxy-Authorization: Digest username="matthewdarnell",realm="iptel.org",nonce="SNWbxEjVm7o6R3qdBfGurqbPhiiBGN51",uri="sip:8084473453@vm.akamaitel.com",response="30b91ac06db13426f1b6be0c3c870768",algorithm=MD5 User-Agent: X-Lite release 1100l stamp 47546 Content-Length: 0 P-hint: rr-enforced ------------------------------------------------------------------------ 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1201 do_2833() Send start packet for [3] ts=2646300 dur=160/160/2000 seq=35151 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [3] ts=2646300 dur=320/320/2000 seq=35152 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [3] ts=2646300 dur=480/480/2000 seq=35153 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [3] ts=2646300 dur=640/640/2000 seq=35154 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [3] ts=2646300 dur=800/800/2000 seq=35155 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [3] ts=2646300 dur=960/960/2000 seq=35156 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [3] ts=2646300 dur=1120/1120/2000 seq=35157 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [3] ts=2646300 dur=1280/1280/2000 seq=35158 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [3] ts=2646300 dur=1440/1440/2000 seq=35159 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [3] ts=2646300 dur=1600/1600/2000 seq=35160 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [3] ts=2646300 dur=1760/1760/2000 seq=35161 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [3] ts=2646300 dur=1920/1920/2000 seq=35162 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send end packet for [3] ts=2646300 dur=2080/2080/2000 seq=35163 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send end packet for [3] ts=2646300 dur=2080/2080/2000 seq=35164 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send end packet for [3] ts=2646300 dur=2080/2080/2000 seq=35165 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1201 do_2833() Send start packet for [4] ts=2649020 dur=160/160/2000 seq=35171 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [4] ts=2649020 dur=320/320/2000 seq=35172 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [4] ts=2649020 dur=480/480/2000 seq=35173 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [4] ts=2649020 dur=640/640/2000 seq=35174 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [4] ts=2649020 dur=800/800/2000 seq=35175 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [4] ts=2649020 dur=960/960/2000 seq=35176 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [4] ts=2649020 dur=1120/1120/2000 seq=35177 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [4] ts=2649020 dur=1280/1280/2000 seq=35178 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [4] ts=2649020 dur=1440/1440/2000 seq=35179 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [4] ts=2649020 dur=1600/1600/2000 seq=35180 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [4] ts=2649020 dur=1760/1760/2000 seq=35181 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [4] ts=2649020 dur=1920/1920/2000 seq=35182 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send end packet for [4] ts=2649020 dur=2080/2080/2000 seq=35183 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send end packet for [4] ts=2649020 dur=2080/2080/2000 seq=35184 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send end packet for [4] ts=2649020 dur=2080/2080/2000 seq=35185 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1201 do_2833() Send start packet for [5] ts=2651740 dur=160/160/2000 seq=35190 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [5] ts=2651740 dur=320/320/2000 seq=35191 2008-09-20 14:56:31 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [5] ts=2651740 dur=480/480/2000 seq=35192 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [5] ts=2651740 dur=640/640/2000 seq=35193 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [5] ts=2651740 dur=800/800/2000 seq=35194 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [5] ts=2651740 dur=960/960/2000 seq=35195 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [5] ts=2651740 dur=1120/1120/2000 seq=35196 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [5] ts=2651740 dur=1280/1280/2000 seq=35197 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [5] ts=2651740 dur=1440/1440/2000 seq=35198 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [5] ts=2651740 dur=1600/1600/2000 seq=35199 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [5] ts=2651740 dur=1760/1760/2000 seq=35200 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [5] ts=2651740 dur=1920/1920/2000 seq=35201 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send end packet for [5] ts=2651740 dur=2080/2080/2000 seq=35202 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send end packet for [5] ts=2651740 dur=2080/2080/2000 seq=35203 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send end packet for [5] ts=2651740 dur=2080/2080/2000 seq=35204 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1201 do_2833() Send start packet for [1] ts=2654460 dur=160/160/2000 seq=35210 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [1] ts=2654460 dur=320/320/2000 seq=35211 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [1] ts=2654460 dur=480/480/2000 seq=35212 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [1] ts=2654460 dur=640/640/2000 seq=35213 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [1] ts=2654460 dur=800/800/2000 seq=35214 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [1] ts=2654460 dur=960/960/2000 seq=35215 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [1] ts=2654460 dur=1120/1120/2000 seq=35216 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [1] ts=2654460 dur=1280/1280/2000 seq=35217 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [1] ts=2654460 dur=1440/1440/2000 seq=35218 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [1] ts=2654460 dur=1600/1600/2000 seq=35219 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [1] ts=2654460 dur=1760/1760/2000 seq=35220 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send middle packet for [1] ts=2654460 dur=1920/1920/2000 seq=35221 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send end packet for [1] ts=2654460 dur=2080/2080/2000 seq=35222 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send end packet for [1] ts=2654460 dur=2080/2080/2000 seq=35223 2008-09-20 14:56:32 [DEBUG] switch_rtp.c:1143 do_2833() Send end packet for [1] ts=2654460 dur=2080/2080/2000 seq=35224 recv 1167 bytes from udp/[213.192.59.75]:5060 at 00:56:33.629938: ------------------------------------------------------------------------ BYE sip:mod_sofia@64.75.215.160:5060;transport=udp SIP/2.0 Record-Route: Record-Route: Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK7a9e.2376fa26.0;i=d86 Via: SIP/2.0/TCP 192.168.1.47:37440;rport=51604;received=66.8.218.137;branch=z9hG4bK-d8754z-065f7a431710dd13-1---d8754z- Max-Forwards: 16 Contact: To: "8084473453@vm.akamaitel.com";tag=a9gB8pF2KSSca From: "matthewdarnell";tag=8a095b76 Call-ID: MDFlYTEyOGQyNmE0NWI4MTFlZDhkNGUwM2RjMzMyNjY. CSeq: 3 BYE Proxy-Authorization: Digest username="matthewdarnell",realm="iptel.org",nonce="SNWbxEjVm7o6R3qdBfGurqbPhiiBGN51",uri="sip:mod_sofia@64.75.215.160:5060;transport=udp",response="52564ae082d882bef9f77c6521f3a64c",algorithm=MD5 User-Agent: X-Lite release 1100l stamp 47546 Reason: SIP;description="User Hung Up" Content-Length: 0 P-hint: rr-enforced P-RTP-Proxy: UNFORCED ------------------------------------------------------------------------ send 661 bytes to udp/[213.192.59.75]:5060 at 00:56:33.630577: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK7a9e.2376fa26.0;i=d86 Via: SIP/2.0/TCP 192.168.1.47:37440;rport=51604;received=66.8.218.137;branch=z9hG4bK-d8754z-065f7a431710dd13-1---d8754z- From: "matthewdarnell";tag=8a095b76 To: "8084473453@vm.akamaitel.com";tag=a9gB8pF2KSSca Call-ID: MDFlYTEyOGQyNmE0NWI4MTFlZDhkNGUwM2RjMzMyNjY. CSeq: 3 BYE User-Agent: FreeSWITCH-mod_sofia/1.0.1-exported Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO Supported: 100rel, timer, precondition, path, replaces Content-Length: 0 ------------------------------------------------------------------------ 2008-09-20 14:56:33 [DEBUG] sofia.c:2129 sofia_handle_sip_i_state() Channel sofia/external/matthewdarnell@iptel.org entering state [terminated] 2008-09-20 14:56:33 [NOTICE] sofia.c:2545 sofia_handle_sip_i_state() Hangup sofia/external/matthewdarnell@iptel.org [CS_EXECUTE] [NORMAL_CLEARING] 2008-09-20 14:56:33 [DEBUG] switch_channel.c:1361 switch_channel_perform_hangup() Kill sofia/external/matthewdarnell@iptel.org [KILL] 2008-09-20 14:56:33 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/external/matthewdarnell@iptel.org [BREAK] 2008-09-20 14:56:33 [DEBUG] switch_ivr_bridge.c:281 audio_bridge_thread() read: sofia/external/matthewdarnell@iptel.org Bad Frame.... Bubye! 2008-09-20 14:56:33 [DEBUG] switch_ivr_bridge.c:350 audio_bridge_thread() Kill sofia/internal/3453 [BREAK] 2008-09-20 14:56:33 [DEBUG] switch_ivr_bridge.c:353 audio_bridge_thread() BRIDGE THREAD DONE [sofia/external/matthewdarnell@iptel.org] 2008-09-20 14:56:33 [DEBUG] switch_ivr_bridge.c:784 switch_ivr_multi_threaded_bridge() sofia/internal/3453 State Change CS_EXCHANGE_MEDIA -> CS_RESET 2008-09-20 14:56:33 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/internal/3453 [BREAK] 2008-09-20 14:56:33 [DEBUG] switch_core_session.c:430 switch_core_session_receive_message() Kill sofia/internal/3453 [BREAK] 2008-09-20 14:56:33 [DEBUG] switch_ivr_bridge.c:350 audio_bridge_thread() Kill sofia/external/matthewdarnell@iptel.org [BREAK] 2008-09-20 14:56:33 [DEBUG] switch_ivr_bridge.c:353 audio_bridge_thread() BRIDGE THREAD DONE [sofia/internal/3453] 2008-09-20 14:56:33 [NOTICE] switch_ivr_bridge.c:382 audio_bridge_on_exchange_media() Hangup sofia/internal/3453 [CS_RESET] [NORMAL_CLEARING] 2008-09-20 14:56:33 [DEBUG] switch_channel.c:1361 switch_channel_perform_hangup() Kill sofia/internal/3453 [KILL] 2008-09-20 14:56:33 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/internal/3453 [BREAK] 2008-09-20 14:56:33 [DEBUG] switch_core_state_machine.c:433 switch_core_session_run() (sofia/internal/3453) State EXCHANGE_MEDIA going to sleep 2008-09-20 14:56:33 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/3453 Running State Change CS_HANGUP 2008-09-20 14:56:33 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (sofia/internal/3453) State HANGUP 2008-09-20 14:56:33 [DEBUG] mod_sofia.c:230 sofia_on_hangup() sofia/internal/3453 Overriding SIP cause 480 with 200 from the other leg 2008-09-20 14:56:33 [DEBUG] mod_sofia.c:264 sofia_on_hangup() Channel sofia/internal/3453 hanging up, cause: NORMAL_CLEARING 2008-09-20 14:56:33 [DEBUG] switch_core_state_machine.c:430 switch_core_session_run() (sofia/external/matthewdarnell@iptel.org) State EXECUTE going to sleep 2008-09-20 14:56:33 [DEBUG] mod_sofia.c:318 sofia_on_hangup() Sending BYE to sofia/internal/3453 2008-09-20 14:56:33 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() Standard HANGUP sofia/internal/3453, cause: NORMAL_CLEARING 2008-09-20 14:56:33 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/external/matthewdarnell@iptel.org Running State Change CS_HANGUP 2008-09-20 14:56:33 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (sofia/external/matthewdarnell@iptel.org) State HANGUP 2008-09-20 14:56:33 [DEBUG] mod_sofia.c:264 sofia_on_hangup() Channel sofia/external/matthewdarnell@iptel.org hanging up, cause: NORMAL_CLEARING 2008-09-20 14:56:33 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() Standard HANGUP sofia/external/matthewdarnell@iptel.org, cause: NORMAL_CLEARING 2008-09-20 14:56:33 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (sofia/external/matthewdarnell@iptel.org) State HANGUP going to sleep 2008-09-20 14:56:33 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (sofia/internal/3453) State HANGUP going to sleep 2008-09-20 14:56:33 [DEBUG] switch_core_session.c:789 switch_core_session_thread() Session 7 (sofia/external/matthewdarnell@iptel.org) Locked, Waiting on external entities 2008-09-20 14:56:33 [NOTICE] switch_core_session.c:807 switch_core_session_thread() Session 7 (sofia/external/matthewdarnell@iptel.org) Ended 2008-09-20 14:56:33 [NOTICE] switch_core_session.c:809 switch_core_session_thread() Close Channel sofia/external/matthewdarnell@iptel.org [CS_HANGUP] 2008-09-20 14:56:33 [DEBUG] switch_core_session.c:789 switch_core_session_thread() Session 8 (sofia/internal/3453) Locked, Waiting on external entities 2008-09-20 14:56:33 [NOTICE] switch_core_session.c:807 switch_core_session_thread() Session 8 (sofia/internal/3453) Ended 2008-09-20 14:56:33 [NOTICE] switch_core_session.c:809 switch_core_session_thread() Close Channel sofia/internal/3453 [CS_HANGUP]