freeswitch@bogon> originate sofia/test/13063737014@GATEWAY_IP 13063414344 2006-11-08 18:42:00 [NOTICE] switch_channel.c:383 switch_channel_set_name() New Chan sofia/test/13063737014@GATEWAY_IP [2f5610b4-c521-0410-b7a1-b95f88e4c907] 2006-11-08 18:42:00 [DEBUG] mod_sofia.c:2072 sofia_outgoing_channel() sofia/test/13063737014@GATEWAY_IP State Change CS_NEW -> CS_INIT 2006-11-08 18:42:00 [DEBUG] switch_core.c:2790 switch_core_session_run() (sofia/test/13063737014@GATEWAY_IP) State INIT 2006-11-08 18:42:00 [DEBUG] mod_sofia.c:1085 sofia_on_init() SOFIA INIT send 951 bytes to udp/[GATEWAY_IP]:5060 at 00:42:00.088710: ------------------------------------------------------------------------ INVITE sip:13063737014@GATEWAY_IP SIP/2.0 Via: SIP/2.0/UDP FREESWITCH_IP;rport;branch=z9hG4bK29cmUyX35tj9F Max-Forwards: 70 From: "FreeSWITCH" ;tag=2rrB275pKmmjD To: Call-ID: f3833af5-ea2d-1229-1dbf-0011d8333b24 CSeq: 75398125 INVITE Contact: User-Agent: FreeSWITCH(mod_sofia) Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO Supported: 100rel, precondition Content-Type: application/sdp Content-Disposition: session Content-Length: 248 Remote-Party-ID: "FreeSWITCH" ;party=calling;screen=yes;privacy=no v=0 o=FreeSWITCH 6344795930158429412 4431353960502009534 IN IP4 FREESWITCH_IP s=FreeSWITCH c=IN IP4 FREESWITCH_IP t=0 0 a=sendrecv m=audio 16384 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ 2006-11-08 18:42:00 [DEBUG] mod_sofia.c:1096 sofia_on_init() sofia/test/13063737014@GATEWAY_IP State Change CS_INIT -> CS_RING 2006-11-08 18:42:00 [DEBUG] switch_core.c:2826 switch_core_session_run() (sofia/test/13063737014@GATEWAY_IP) State RING 2006-11-08 18:42:00 [DEBUG] mod_sofia.c:1112 sofia_on_ring() SOFIA RING 2006-11-08 18:42:00 [DEBUG] switch_core.c:2485 switch_core_standard_on_ring() Standard RING sofia/test/13063737014@GATEWAY_IP 2006-11-08 18:42:00 [INFO] switch_core.c:2497 switch_core_standard_on_ring() No Dialplan, changing state to HOLD 2006-11-08 18:42:00 [DEBUG] switch_core.c:2498 switch_core_standard_on_ring() sofia/test/13063737014@GATEWAY_IP State Change CS_RING -> CS_HOLD 2006-11-08 18:42:00 [DEBUG] switch_core.c:2971 switch_core_session_run() (sofia/test/13063737014@GATEWAY_IP) State HOLD 2006-11-08 18:42:00 [DEBUG] switch_core.c:2609 switch_core_standard_on_hold() Standard HOLD 2006-11-08 18:42:00 [DEBUG] mod_sofia.c:4159 event_callback() event [nua_i_state] status [0][INVITE sent] session: sofia/test/13063737014@GATEWAY_IP 2006-11-08 18:42:00 [DEBUG] mod_sofia.c:2471 sip_i_state() Channel sofia/test/13063737014@GATEWAY_IP entering state [calling] recv 287 bytes from udp/[GATEWAY_IP]:5060 at 00:42:00.123752: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP FREESWITCH_IP;received=FREESWITCH_IP;branch=z9hG4bK29cmUyX35tj9F;rport=5060 From: "FreeSWITCH" ;tag=2rrB275pKmmjD To: Call-ID: f3833af5-ea2d-1229-1dbf-0011d8333b24 CSeq: 75398125 INVITE ------------------------------------------------------------------------ recv 425 bytes from udp/[GATEWAY_IP]:5060 at 00:42:00.823949: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP FREESWITCH_IP;received=FREESWITCH_IP;branch=z9hG4bK29cmUyX35tj9F;rport=5060 From: "FreeSWITCH" ;tag=2rrB275pKmmjD To: ;tag=SD5mrv799-eee40007-0-1658039143 Call-ID: f3833af5-ea2d-1229-1dbf-0011d8333b24 CSeq: 75398125 INVITE Server: DC-SIP/1.2 Contact: Content-Length: 0 ------------------------------------------------------------------------ 2006-11-08 18:42:00 [DEBUG] mod_sofia.c:4159 event_callback() event [nua_r_invite] status [180][Ringing] session: sofia/test/13063737014@GATEWAY_IP 2006-11-08 18:42:00 [DEBUG] mod_sofia.c:4159 event_callback() event [nua_i_state] status [180][Ringing] session: sofia/test/13063737014@GATEWAY_IP 2006-11-08 18:42:00 [DEBUG] mod_sofia.c:2471 sip_i_state() Channel sofia/test/13063737014@GATEWAY_IP entering state [proceeding] recv 671 bytes from udp/[GATEWAY_IP]:5060 at 00:42:03.571146: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP FREESWITCH_IP;received=FREESWITCH_IP;branch=z9hG4bK29cmUyX35tj9F;rport=5060 From: "FreeSWITCH" ;tag=2rrB275pKmmjD To: ;tag=SD5mrv799-eee40007-0-1658039143 Call-ID: f3833af5-ea2d-1229-1dbf-0011d8333b24 CSeq: 75398125 INVITE Server: DC-SIP/1.2 Contact: Content-Type: application/sdp Content-Length: 218 v=0 o=- 3372021723 3372021756 IN IP4 GATEWAY_IP s=- c=IN IP4 GATEWAY_IP t=0 0 m=audio 10826 RTP/AVP 0 101 a=sendrecv a=ptime:20 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 ------------------------------------------------------------------------ send 417 bytes to udp/[GATEWAY_IP]:5060 at 00:42:03.571916: ------------------------------------------------------------------------ ACK sip:13063737014@GATEWAY_IP:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP FREESWITCH_IP;rport;branch=z9hG4bK3j6cXSe7238UB Max-Forwards: 70 From: "FreeSWITCH" ;tag=2rrB275pKmmjD To: ;tag=SD5mrv799-eee40007-0-1658039143 Call-ID: f3833af5-ea2d-1229-1dbf-0011d8333b24 CSeq: 75398125 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ 2006-11-08 18:42:03 [DEBUG] mod_sofia.c:2471 sip_i_state() Channel sofia/test/13063737014@GATEWAY_IP entering state [ready] 2006-11-08 18:42:03 [DEBUG] mod_sofia.c:2476 sip_i_state() Remote SDP: v=0 o=- 3372021723 3372021756 IN IP4 GATEWAY_IP s=- c=IN IP4 GATEWAY_IP t=0 0 m=audio 10826 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 2006-11-08 18:42:03 [DEBUG] mod_sofia.c:2128 negotiate_sdp() Codec Compare [PCMU:0]/[PCMU:0] 2006-11-08 18:42:03 [INFO] mod_sofia.c:1335 tech_set_codec() Set Codec sofia/test/13063737014@GATEWAY_IP PCMU/8000 20 ms 2006-11-08 18:42:03 [INFO] mod_sofia.c:1383 activate_rtp() RTP [sofia/test/13063737014@GATEWAY_IP] FREESWITCH_IP:16384->GATEWAY_IP:10826 codec: 0 ms: 20 2006-11-08 18:42:03 [DEBUG] switch_rtp.c:495 switch_rtp_create() Starting timer [soft] 160 bytes per 20000ms 2006-11-08 18:42:03 [DEBUG] switch_ivr.c:2258 switch_ivr_originate() Originate Resulted in Success: [sofia/test/13063737014@GATEWAY_IP] 2006-11-08 18:42:03 [DEBUG] switch_ivr.c:2865 switch_ivr_session_transfer() sofia/test/13063737014@GATEWAY_IP State Change CS_HOLD -> CS_RING 2006-11-08 18:42:03 [NOTICE] switch_ivr.c:2871 switch_ivr_session_transfer() Transfer sofia/test/13063737014@GATEWAY_IP to XML[13063414344@default] API CALL [originate(sofia/test/13063737014@GATEWAY_IP)] output: Created Session: 2f5610b4-c521-0410-b7a1-b95f88e4c907 freeswitch@bogon> 2006-11-08 18:42:03 [DEBUG] switch_core.c:2826 switch_core_session_run() (sofia/test/13063737014@GATEWAY_IP) State RING 2006-11-08 18:42:03 [DEBUG] mod_sofia.c:1112 sofia_on_ring() SOFIA RING 2006-11-08 18:42:03 [DEBUG] switch_core.c:2485 switch_core_standard_on_ring() Standard RING sofia/test/13063737014@GATEWAY_IP 2006-11-08 18:42:03 [INFO] mod_dialplan_xml.c:278 dialplan_hunt() Processing FreeSWITCH->13063414344! 2006-11-08 18:42:03 [DEBUG] mod_dialplan_xml.c:199 parse_exten() test conditions destination_number(13063414344) =~ /^(18(0{2}|8{2}|7{2}|6{2})\d{7})$/ 2006-11-08 18:42:03 [DEBUG] mod_dialplan_xml.c:201 parse_exten() Regex mismatch 2006-11-08 18:42:03 [DEBUG] mod_dialplan_xml.c:199 parse_exten() test conditions destination_number(13063414344) =~ /^0(.+)$/ 2006-11-08 18:42:03 [DEBUG] mod_dialplan_xml.c:201 parse_exten() Regex mismatch 2006-11-08 18:42:03 [DEBUG] mod_dialplan_xml.c:199 parse_exten() test conditions destination_number(13063414344) =~ /^(1\d+)$/ 2006-11-08 18:42:03 [DEBUG] mod_dialplan_xml.c:326 dialplan_hunt() sofia/test/13063737014@GATEWAY_IP State Change CS_RING -> CS_EXECUTE 2006-11-08 18:42:03 [DEBUG] switch_core.c:2862 switch_core_session_run() (sofia/test/13063737014@GATEWAY_IP) State EXECUTE 2006-11-08 18:42:03 [DEBUG] mod_sofia.c:1130 sofia_on_execute() SOFIA EXECUTE 2006-11-08 18:42:03 [DEBUG] switch_core.c:2519 switch_core_standard_on_execute() Standard EXECUTE 2006-11-08 18:42:03 [NOTICE] switch_core.c:2528 switch_core_standard_on_execute() Execute bridge(sofia/test/13063414344@GATEWAY_IP) 2006-11-08 18:42:03 [NOTICE] switch_channel.c:383 switch_channel_set_name() New Chan sofia/test/13063414344@GATEWAY_IP [079345b4-c521-0410-b7a1-b95f88e4c907] 2006-11-08 18:42:03 [DEBUG] mod_sofia.c:2072 sofia_outgoing_channel() sofia/test/13063414344@GATEWAY_IP State Change CS_NEW -> CS_INIT 2006-11-08 18:42:03 [DEBUG] switch_core.c:2790 switch_core_session_run() (sofia/test/13063414344@GATEWAY_IP) State INIT 2006-11-08 18:42:03 [DEBUG] mod_sofia.c:1085 sofia_on_init() SOFIA INIT send 950 bytes to udp/[GATEWAY_IP]:5060 at 00:42:03.576382: ------------------------------------------------------------------------ INVITE sip:13063414344@GATEWAY_IP SIP/2.0 Via: SIP/2.0/UDP FREESWITCH_IP;rport;branch=z9hG4bK4UZ5ymZa0cZeQ Max-Forwards: 70 From: "FreeSWITCH" ;tag=31H432ptgXa5r To: Call-ID: f5976970-ea2d-1229-1dbf-0011d8333b24 CSeq: 75398126 INVITE Contact: User-Agent: FreeSWITCH(mod_sofia) Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO Supported: 100rel, precondition Content-Type: application/sdp Content-Disposition: session Content-Length: 247 Remote-Party-ID: "FreeSWITCH" ;party=calling;screen=yes;privacy=no v=0 o=FreeSWITCH 7824202462573636439 270339861978168856 IN IP4 FREESWITCH_IP s=FreeSWITCH c=IN IP4 FREESWITCH_IP t=0 0 a=sendrecv m=audio 16386 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ 2006-11-08 18:42:03 [DEBUG] mod_sofia.c:4159 event_callback() event [nua_i_state] status [0][INVITE sent] session: sofia/test/13063414344@GATEWAY_IP 2006-11-08 18:42:03 [DEBUG] mod_sofia.c:2471 sip_i_state() Channel sofia/test/13063414344@GATEWAY_IP entering state [calling] 2006-11-08 18:42:03 [DEBUG] mod_sofia.c:1096 sofia_on_init() sofia/test/13063414344@GATEWAY_IP State Change CS_INIT -> CS_RING 2006-11-08 18:42:03 [DEBUG] switch_core.c:2826 switch_core_session_run() (sofia/test/13063414344@GATEWAY_IP) State RING 2006-11-08 18:42:03 [DEBUG] mod_sofia.c:1112 sofia_on_ring() SOFIA RING 2006-11-08 18:42:03 [DEBUG] switch_ivr.c:1602 audio_bridge_on_ring() CUSTOM RING 2006-11-08 18:42:03 [DEBUG] switch_ivr.c:1605 audio_bridge_on_ring() sofia/test/13063414344@GATEWAY_IP State Change CS_RING -> CS_HOLD 2006-11-08 18:42:03 [DEBUG] switch_ivr.c:2144 switch_ivr_originate() Raw Codec Activation Success L16@8000hz 1 channel 20ms 2006-11-08 18:42:03 [DEBUG] switch_core.c:2971 switch_core_session_run() (sofia/test/13063414344@GATEWAY_IP) State HOLD 2006-11-08 18:42:03 [DEBUG] switch_ivr.c:1616 audio_bridge_on_hold() CUSTOM HOLD recv 287 bytes from udp/[GATEWAY_IP]:5060 at 00:42:03.610762: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP FREESWITCH_IP;received=FREESWITCH_IP;branch=z9hG4bK4UZ5ymZa0cZeQ;rport=5060 From: "FreeSWITCH" ;tag=31H432ptgXa5r To: Call-ID: f5976970-ea2d-1229-1dbf-0011d8333b24 CSeq: 75398126 INVITE ------------------------------------------------------------------------ recv 685 bytes from udp/[GATEWAY_IP]:5060 at 00:42:07.925179: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP FREESWITCH_IP;received=FREESWITCH_IP;branch=z9hG4bK4UZ5ymZa0cZeQ;rport=5060 From: "FreeSWITCH" ;tag=31H432ptgXa5r To: ;tag=SDukr9599-df220007-0-1643875197 Call-ID: f5976970-ea2d-1229-1dbf-0011d8333b24 CSeq: 75398126 INVITE Server: DC-SIP/1.2 Contact: Content-Type: application/sdp Content-Length: 218 v=0 o=- 3372021727 3372021770 IN IP4 GATEWAY_IP s=- c=IN IP4 GATEWAY_IP t=0 0 m=audio 10828 RTP/AVP 0 101 a=sendrecv a=ptime:20 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 ------------------------------------------------------------------------ 2006-11-08 18:42:07 [DEBUG] mod_sofia.c:4159 event_callback() event [nua_r_invite] status [183][Session Progress] session: sofia/test/13063414344@GATEWAY_IP 2006-11-08 18:42:07 [DEBUG] mod_sofia.c:4159 event_callback() event [nua_i_state] status [183][Session Progress] session: sofia/test/13063414344@GATEWAY_IP 2006-11-08 18:42:07 [DEBUG] mod_sofia.c:2471 sip_i_state() Channel sofia/test/13063414344@GATEWAY_IP entering state [proceeding] 2006-11-08 18:42:07 [DEBUG] mod_sofia.c:2476 sip_i_state() Remote SDP: v=0 o=- 3372021727 3372021770 IN IP4 GATEWAY_IP s=- c=IN IP4 GATEWAY_IP t=0 0 m=audio 10828 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 2006-11-08 18:43:31 [DEBUG] switch_ivr.c:2258 switch_ivr_originate() Originate Resulted in Success: [sofia/test/13063414344@GATEWAY_IP] 2006-11-08 18:43:31 [DEBUG] switch_ivr.c:2621 switch_ivr_signal_bridge() sofia/test/13063737014@GATEWAY_IP State Change CS_EXECUTE -> CS_HIBERNATE 2006-11-08 18:43:31 [DEBUG] switch_ivr.c:2622 switch_ivr_signal_bridge() sofia/test/13063414344@GATEWAY_IP State Change CS_HOLD -> CS_HIBERNATE 2006-11-08 18:43:31 [DEBUG] switch_core.c:3008 switch_core_session_run() (sofia/test/13063737014@GATEWAY_IP) State HIBERNATE 2006-11-08 18:43:31 [DEBUG] switch_core.c:2615 switch_core_standard_on_hibernate() Standard HIBERNATE 2006-11-08 18:43:31 [DEBUG] switch_core.c:3008 switch_core_session_run() (sofia/test/13063414344@GATEWAY_IP) State HIBERNATE 2006-11-08 18:43:31 [DEBUG] switch_core.c:2615 switch_core_standard_on_hibernate() Standard HIBERNATE recv 671 bytes from udp/[GATEWAY_IP]:5060 at 00:43:34.769897: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP FREESWITCH_IP;received=FREESWITCH_IP;branch=z9hG4bKtaHaSjFrF3KBr;rport=5060 From: "FreeSWITCH" ;tag=SpD5H1BX9475N To: ;tag=SDf472599-a7330003-0-1961447673 Call-ID: 274da1c7-ea2e-1229-0a9a-0011d8333b24 CSeq: 75398168 INVITE Server: DC-SIP/1.2 Contact: Content-Type: application/sdp Content-Length: 218 v=0 o=- 3372021811 3372021859 IN IP4 GATEWAY_IP s=- c=IN IP4 GATEWAY_IP t=0 0 m=audio 10832 RTP/AVP 0 101 a=sendrecv a=ptime:20 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 ------------------------------------------------------------------------ nua(0x80a5258): INVITE: ignoring duplicate SDP in 200 OK send 417 bytes to udp/[GATEWAY_IP]:5060 at 00:43:34.770802: ------------------------------------------------------------------------ ACK sip:13063414344@GATEWAY_IP:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP FREESWITCH_IP;rport;branch=z9hG4bKUKa3tD0UccayK Max-Forwards: 70 From: "FreeSWITCH" ;tag=SpD5H1BX9475N To: ;tag=SDf472599-a7330003-0-1961447673 Call-ID: 274da1c7-ea2e-1229-0a9a-0011d8333b24 CSeq: 75398168 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ 2006-11-08 18:43:34 [DEBUG] mod_sofia.c:2471 sip_i_state() Channel sofia/test/13063414344@GATEWAY_IP entering state [ready] recv 675 bytes from udp/[GATEWAY_IP]:5060 at 00:44:10.011321: ------------------------------------------------------------------------ INVITE sip:mod_sofia@FREESWITCH_IP:5060 SIP/2.0 Via: SIP/2.0/UDP GATEWAY_IP:5060;branch=z9hG4bK081mpn204o81odgjs4k1sb0000g00.1 Call-ID: 25a2e734-ea2e-1229-0a9a-0011d8333b24 From: ;tag=SD2m6ue99-69480002-0-1921532189 To: "FreeSWITCH" ;tag=rDmcg6tScvHKa CSeq: 1 INVITE Max-Forwards: 69 Contact: Content-Type: application/sdp Content-Length: 218 v=0 o=- 3372021849 3372022307 IN IP4 GATEWAY_IP s=- c=IN IP4 GATEWAY_IP t=0 0 m=audio 10830 RTP/AVP 0 101 a=inactive a=ptime:20 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 ------------------------------------------------------------------------ send 852 bytes to udp/[GATEWAY_IP]:5060 at 00:44:10.012382: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP GATEWAY_IP:5060;branch=z9hG4bK081mpn204o81odgjs4k1sb0000g00.1 From: ;tag=SD2m6ue99-69480002-0-1921532189 To: "FreeSWITCH" ;tag=rDmcg6tScvHKa Call-ID: 25a2e734-ea2e-1229-0a9a-0011d8333b24 CSeq: 1 INVITE Contact: User-Agent: FreeSWITCH(mod_sofia) Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO Supported: 100rel, precondition Content-Type: application/sdp Content-Disposition: session Content-Length: 260 v=0 o=FreeSWITCH 4369813140879285709 3322204087283390082 IN IP4 FREESWITCH_IP s=FreeSWITCH c=IN IP4 FREESWITCH_IP t=0 0 a=sendrecv m=audio 16384 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=recvonly a=ptime:20 ------------------------------------------------------------------------ 2006-11-08 18:44:10 [DEBUG] mod_sofia.c:2471 sip_i_state() Channel sofia/test/13063737014@GATEWAY_IP entering state [completed] 2006-11-08 18:44:10 [DEBUG] mod_sofia.c:2476 sip_i_state() Remote SDP: v=0 o=- 3372021849 3372022307 IN IP4 GATEWAY_IP s=- c=IN IP4 GATEWAY_IP t=0 0 m=audio 10830 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=inactive a=ptime:20 2006-11-08 18:44:10 [DEBUG] mod_sofia.c:2128 negotiate_sdp() Codec Compare [PCMU:0]/[PCMU:0] 2006-11-08 18:44:10 [DEBUG] mod_sofia.c:1297 tech_set_codec() Already using PCMU 2006-11-08 18:44:10 [INFO] mod_sofia.c:1383 activate_rtp() RTP [sofia/test/13063737014@GATEWAY_IP] FREESWITCH_IP:16384->GATEWAY_IP:10830 codec: 0 ms: 20 2006-11-08 18:44:10 [DEBUG] mod_sofia.c:1405 activate_rtp() RTP CHANGING DEST TO: [GATEWAY_IP:10830] 2006-11-08 18:44:10 [DEBUG] mod_sofia.c:2654 sip_i_state() Processing Reinvite recv 353 bytes from udp/[GATEWAY_IP]:5060 at 00:44:10.059802: ------------------------------------------------------------------------ ACK sip:FREESWITCH_IP SIP/2.0 Via: SIP/2.0/UDP GATEWAY_IP:5060;branch=z9hG4bK081mpn204o81odgjs4k1sc0000g00.1 Call-ID: 25a2e734-ea2e-1229-0a9a-0011d8333b24 From: ;tag=SD2m6ue99-69480002-0-1921532189 To: "FreeSWITCH" ;tag=rDmcg6tScvHKa CSeq: 1 ACK Content-Length: 0 Max-Forwards: 69 ------------------------------------------------------------------------ 2006-11-08 18:44:10 [DEBUG] mod_sofia.c:2471 sip_i_state() Channel sofia/test/13063737014@GATEWAY_IP entering state [ready] recv 368 bytes from udp/[GATEWAY_IP]:5060 at 00:44:10.090900: ------------------------------------------------------------------------ BYE sip:mod_sofia@FREESWITCH_IP:5060 SIP/2.0 Via: SIP/2.0/UDP GATEWAY_IP:5060;branch=z9hG4bK081mpr201g11qdgdt300sd0000g00.1 Call-ID: 274da1c7-ea2e-1229-0a9a-0011d8333b24 From: ;tag=SDf472599-a7330003-0-1961447673 To: "FreeSWITCH" ;tag=SpD5H1BX9475N CSeq: 1 BYE Content-Length: 0 Max-Forwards: 69 ------------------------------------------------------------------------ send 322 bytes to udp/[GATEWAY_IP]:5060 at 00:44:10.091214: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP GATEWAY_IP:5060;branch=z9hG4bK081mpr201g11qdgdt300sd0000g00.1 From: ;tag=SDf472599-a7330003-0-1961447673 To: "FreeSWITCH" ;tag=SpD5H1BX9475N Call-ID: 274da1c7-ea2e-1229-0a9a-0011d8333b24 CSeq: 1 BYE Content-Length: 0 ------------------------------------------------------------------------ 2006-11-08 18:44:10 [DEBUG] mod_sofia.c:2471 sip_i_state() Channel sofia/test/13063414344@GATEWAY_IP entering state [terminated] 2006-11-08 18:44:10 [DEBUG] mod_sofia.c:800 terminate_session() Term called from line: 2733 2006-11-08 18:44:10 [NOTICE] mod_sofia.c:810 terminate_session() Hangup sofia/test/13063414344@GATEWAY_IP [CS_HIBERNATE] [NORMAL_CLEARING] 2006-11-08 18:44:10 [INFO] switch_channel.c:992 switch_channel_perform_hangup() Kill sofia/test/13063414344@GATEWAY_IP [1] 2006-11-08 18:44:10 [DEBUG] switch_core.c:2753 switch_core_session_run() (sofia/test/13063414344@GATEWAY_IP) State HANGUP 2006-11-08 18:44:10 [DEBUG] mod_sofia.c:1210 sofia_on_hangup() Channel sofia/test/13063414344@GATEWAY_IP hanging up, cause: NORMAL_CLEARING 2006-11-08 18:44:10 [NOTICE] switch_ivr.c:2553 signal_bridge_on_hangup() Hangup sofia/test/13063737014@GATEWAY_IP [CS_HIBERNATE] [NORMAL_CLEARING] 2006-11-08 18:44:10 [INFO] switch_channel.c:992 switch_channel_perform_hangup() Kill sofia/test/13063737014@GATEWAY_IP [1] 2006-11-08 18:44:10 [DEBUG] switch_core.c:2475 switch_core_standard_on_hangup() Standard HANGUP sofia/test/13063414344@GATEWAY_IP, cause: NORMAL_CLEARING 2006-11-08 18:44:10 [DEBUG] switch_core.c:3197 switch_core_session_thread() Session 2 (sofia/test/13063414344@GATEWAY_IP) Locked, Waiting on external entities 2006-11-08 18:44:10 [DEBUG] switch_core.c:2753 switch_core_session_run() (sofia/test/13063737014@GATEWAY_IP) State HANGUP 2006-11-08 18:44:10 [DEBUG] mod_sofia.c:1210 sofia_on_hangup() Channel sofia/test/13063737014@GATEWAY_IP hanging up, cause: NORMAL_CLEARING 2006-11-08 18:44:10 [DEBUG] mod_sofia.c:1226 sofia_on_hangup() Sending BYE send 473 bytes to udp/[GATEWAY_IP]:5060 at 00:44:10.092849: ------------------------------------------------------------------------ BYE sip:GATEWAY_IP:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP FREESWITCH_IP;rport;branch=z9hG4bKvv3Uv8gZ9m0gF Max-Forwards: 70 From: "FreeSWITCH" ;tag=rDmcg6tScvHKa To: ;tag=SD2m6ue99-69480002-0-1921532189 Call-ID: 25a2e734-ea2e-1229-0a9a-0011d8333b24 CSeq: 75398168 BYE Contact: User-Agent: FreeSWITCH(mod_sofia) Supported: 100rel, precondition Content-Length: 0 ------------------------------------------------------------------------ 2006-11-08 18:44:10 [DEBUG] switch_core.c:2475 switch_core_standard_on_hangup() Standard HANGUP sofia/test/13063737014@GATEWAY_IP, cause: NORMAL_CLEARING 2006-11-08 18:44:10 [DEBUG] switch_core.c:3197 switch_core_session_thread() Session 1 (sofia/test/13063737014@GATEWAY_IP) Locked, Waiting on external entities 2006-11-08 18:44:10 [INFO] switch_core.c:3204 switch_core_session_thread() Session 1 (sofia/test/13063737014@GATEWAY_IP) Ended 2006-11-08 18:44:10 [NOTICE] switch_core.c:3077 switch_core_session_destroy() Close Channel sofia/test/13063737014@GATEWAY_IP 2006-11-08 18:44:10 [INFO] switch_core.c:3204 switch_core_session_thread() Session 2 (sofia/test/13063414344@GATEWAY_IP) Ended 2006-11-08 18:44:10 [NOTICE] switch_core.c:3077 switch_core_session_destroy() Close Channel sofia/test/13063414344@GATEWAY_IP recv 355 bytes from udp/[GATEWAY_IP]:5060 at 00:44:10.135887: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP FREESWITCH_IP;received=FREESWITCH_IP;branch=z9hG4bKvv3Uv8gZ9m0gF;rport=5060 From: "FreeSWITCH" ;tag=rDmcg6tScvHKa To: ;tag=SD2m6ue99-69480002-0-1921532189 Call-ID: 25a2e734-ea2e-1229-0a9a-0011d8333b24 CSeq: 75398168 BYE Server: DC-SIP/1.2 Content-Length: 0 ------------------------------------------------------------------------ 2006-11-08 18:44:10 [DEBUG] mod_sofia.c:2471 sip_i_state() Channel sofia/test/13063737014@GATEWAY_IP entering state [terminated] freeswitch@bogon> shutdown