rendered paste bodyfreeswitch@bogon> originate sofia/test/LEG_A@GATEWAY_IP LEG_B
2006-11-08 18:42:00 [NOTICE] switch_channel.c:383 switch_channel_set_name() New Chan sofia/test/LEG_A@GATEWAY_IP [2f5610b4-c521-0410-b7a1-b95f88e4c907]
2006-11-08 18:42:00 [DEBUG] mod_sofia.c:2072 sofia_outgoing_channel() sofia/test/LEG_A@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/LEG_A@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:LEG_A@GATEWAY_IP SIP/2.0
Via: SIP/2.0/UDP FREESWITCH_IP;rport;branch=z9hG4bK29cmUyX35tj9F
Max-Forwards: 70
From: "FreeSWITCH" <sip:0000000000@FREESWITCH_IP>;tag=2rrB275pKmmjD
To: <sip:LEG_A@GATEWAY_IP>
Call-ID: f3833af5-ea2d-1229-1dbf-0011d8333b24
CSeq: 75398125 INVITE
Contact: <sip:mod_sofia@FREESWITCH_IP:5060>
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" <sip:0000000000@FREESWITCH_IP>;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/LEG_A@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/LEG_A@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/LEG_A@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/LEG_A@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/LEG_A@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/LEG_A@GATEWAY_IP
2006-11-08 18:42:00 [DEBUG] mod_sofia.c:2471 sip_i_state() Channel sofia/test/LEG_A@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" <sip:0000000000@FREESWITCH_IP>;tag=2rrB275pKmmjD
To: <sip:LEG_A@GATEWAY_IP>
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" <sip:0000000000@FREESWITCH_IP>;tag=2rrB275pKmmjD
To: <sip:LEG_A@GATEWAY_IP>;tag=SD5mrv799-eee40007-0-1658039143
Call-ID: f3833af5-ea2d-1229-1dbf-0011d8333b24
CSeq: 75398125 INVITE
Server: DC-SIP/1.2
Contact: <sip:LEG_A@GATEWAY_IP:5060;transport=udp>
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/LEG_A@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/LEG_A@GATEWAY_IP
2006-11-08 18:42:00 [DEBUG] mod_sofia.c:2471 sip_i_state() Channel sofia/test/LEG_A@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" <sip:0000000000@FREESWITCH_IP>;tag=2rrB275pKmmjD
To: <sip:LEG_A@GATEWAY_IP>;tag=SD5mrv799-eee40007-0-1658039143
Call-ID: f3833af5-ea2d-1229-1dbf-0011d8333b24
CSeq: 75398125 INVITE
Server: DC-SIP/1.2
Contact: <sip:LEG_A@GATEWAY_IP:5060;transport=udp>
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:LEG_A@GATEWAY_IP:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP FREESWITCH_IP;rport;branch=z9hG4bK3j6cXSe7238UB
Max-Forwards: 70
From: "FreeSWITCH" <sip:0000000000@FREESWITCH_IP>;tag=2rrB275pKmmjD
To: <sip:LEG_A@GATEWAY_IP>;tag=SD5mrv799-eee40007-0-1658039143
Call-ID: f3833af5-ea2d-1229-1dbf-0011d8333b24
CSeq: 75398125 ACK
Contact: <sip:mod_sofia@FREESWITCH_IP:5060>
Content-Length: 0
------------------------------------------------------------------------
2006-11-08 18:42:03 [DEBUG] mod_sofia.c:2471 sip_i_state() Channel sofia/test/LEG_A@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/LEG_A@GATEWAY_IP PCMU/8000 20 ms
2006-11-08 18:42:03 [INFO] mod_sofia.c:1383 activate_rtp() RTP [sofia/test/LEG_A@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/LEG_A@GATEWAY_IP]
2006-11-08 18:42:03 [DEBUG] switch_ivr.c:2865 switch_ivr_session_transfer() sofia/test/LEG_A@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/LEG_A@GATEWAY_IP to XML[LEG_B@default]
API CALL [originate(sofia/test/LEG_A@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/LEG_A@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/LEG_A@GATEWAY_IP
2006-11-08 18:42:03 [INFO] mod_dialplan_xml.c:278 dialplan_hunt() Processing FreeSWITCH->LEG_B!
2006-11-08 18:42:03 [DEBUG] mod_dialplan_xml.c:199 parse_exten() test conditions destination_number(LEG_B) =~ /^(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(LEG_B) =~ /^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(LEG_B) =~ /^(1\d+)$/
2006-11-08 18:42:03 [DEBUG] mod_dialplan_xml.c:326 dialplan_hunt() sofia/test/LEG_A@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/LEG_A@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/LEG_B@GATEWAY_IP)
2006-11-08 18:42:03 [NOTICE] switch_channel.c:383 switch_channel_set_name() New Chan sofia/test/LEG_B@GATEWAY_IP [079345b4-c521-0410-b7a1-b95f88e4c907]
2006-11-08 18:42:03 [DEBUG] mod_sofia.c:2072 sofia_outgoing_channel() sofia/test/LEG_B@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/LEG_B@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:LEG_B@GATEWAY_IP SIP/2.0
Via: SIP/2.0/UDP FREESWITCH_IP;rport;branch=z9hG4bK4UZ5ymZa0cZeQ
Max-Forwards: 70
From: "FreeSWITCH" <sip:0000000000@FREESWITCH_IP>;tag=31H432ptgXa5r
To: <sip:LEG_B@GATEWAY_IP>
Call-ID: f5976970-ea2d-1229-1dbf-0011d8333b24
CSeq: 75398126 INVITE
Contact: <sip:mod_sofia@FREESWITCH_IP:5060>
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" <sip:0000000000@FREESWITCH_IP>;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/LEG_B@GATEWAY_IP
2006-11-08 18:42:03 [DEBUG] mod_sofia.c:2471 sip_i_state() Channel sofia/test/LEG_B@GATEWAY_IP entering state [calling]
2006-11-08 18:42:03 [DEBUG] mod_sofia.c:1096 sofia_on_init() sofia/test/LEG_B@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/LEG_B@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/LEG_B@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/LEG_B@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" <sip:0000000000@FREESWITCH_IP>;tag=31H432ptgXa5r
To: <sip:LEG_B@GATEWAY_IP>
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" <sip:0000000000@FREESWITCH_IP>;tag=31H432ptgXa5r
To: <sip:LEG_B@GATEWAY_IP>;tag=SDukr9599-df220007-0-1643875197
Call-ID: f5976970-ea2d-1229-1dbf-0011d8333b24
CSeq: 75398126 INVITE
Server: DC-SIP/1.2
Contact: <sip:LEG_B@GATEWAY_IP:5060;transport=udp>
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/LEG_B@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/LEG_B@GATEWAY_IP
2006-11-08 18:42:07 [DEBUG] mod_sofia.c:2471 sip_i_state() Channel sofia/test/LEG_B@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/LEG_B@GATEWAY_IP]
2006-11-08 18:43:31 [DEBUG] switch_ivr.c:2621 switch_ivr_signal_bridge() sofia/test/LEG_A@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/LEG_B@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/LEG_A@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/LEG_B@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" <sip:0000000000@FREESWITCH_IP>;tag=SpD5H1BX9475N
To: <sip:LEG_B@GATEWAY_IP>;tag=SDf472599-a7330003-0-1961447673
Call-ID: 274da1c7-ea2e-1229-0a9a-0011d8333b24
CSeq: 75398168 INVITE
Server: DC-SIP/1.2
Contact: <sip:LEG_B@GATEWAY_IP:5060;transport=udp>
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:LEG_B@GATEWAY_IP:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP FREESWITCH_IP;rport;branch=z9hG4bKUKa3tD0UccayK
Max-Forwards: 70
From: "FreeSWITCH" <sip:0000000000@FREESWITCH_IP>;tag=SpD5H1BX9475N
To: <sip:LEG_B@GATEWAY_IP>;tag=SDf472599-a7330003-0-1961447673
Call-ID: 274da1c7-ea2e-1229-0a9a-0011d8333b24
CSeq: 75398168 ACK
Contact: <sip:mod_sofia@FREESWITCH_IP:5060>
Content-Length: 0
------------------------------------------------------------------------
2006-11-08 18:43:34 [DEBUG] mod_sofia.c:2471 sip_i_state() Channel sofia/test/LEG_B@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: <sip:LEG_A@GATEWAY_IP>;tag=SD2m6ue99-69480002-0-1921532189
To: "FreeSWITCH" <sip:0000000000@FREESWITCH_IP>;tag=rDmcg6tScvHKa
CSeq: 1 INVITE
Max-Forwards: 69
Contact: <sip:GATEWAY_IP:5060;transport=udp>
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: <sip:LEG_A@GATEWAY_IP>;tag=SD2m6ue99-69480002-0-1921532189
To: "FreeSWITCH" <sip:0000000000@FREESWITCH_IP>;tag=rDmcg6tScvHKa
Call-ID: 25a2e734-ea2e-1229-0a9a-0011d8333b24
CSeq: 1 INVITE
Contact: <sip:FREESWITCH_IP>
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/LEG_A@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/LEG_A@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: <sip:LEG_A@GATEWAY_IP>;tag=SD2m6ue99-69480002-0-1921532189
To: "FreeSWITCH" <sip:0000000000@FREESWITCH_IP>;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/LEG_A@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: <sip:LEG_B@GATEWAY_IP>;tag=SDf472599-a7330003-0-1961447673
To: "FreeSWITCH" <sip:0000000000@FREESWITCH_IP>;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: <sip:LEG_B@GATEWAY_IP>;tag=SDf472599-a7330003-0-1961447673
To: "FreeSWITCH" <sip:0000000000@FREESWITCH_IP>;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/LEG_B@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/LEG_B@GATEWAY_IP [CS_HIBERNATE] [NORMAL_CLEARING]
2006-11-08 18:44:10 [INFO] switch_channel.c:992 switch_channel_perform_hangup() Kill sofia/test/LEG_B@GATEWAY_IP [1]
2006-11-08 18:44:10 [DEBUG] switch_core.c:2753 switch_core_session_run() (sofia/test/LEG_B@GATEWAY_IP) State HANGUP
2006-11-08 18:44:10 [DEBUG] mod_sofia.c:1210 sofia_on_hangup() Channel sofia/test/LEG_B@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/LEG_A@GATEWAY_IP [CS_HIBERNATE] [NORMAL_CLEARING]
2006-11-08 18:44:10 [INFO] switch_channel.c:992 switch_channel_perform_hangup() Kill sofia/test/LEG_A@GATEWAY_IP [1]
2006-11-08 18:44:10 [DEBUG] switch_core.c:2475 switch_core_standard_on_hangup() Standard HANGUP sofia/test/LEG_B@GATEWAY_IP, cause: NORMAL_CLEARING
2006-11-08 18:44:10 [DEBUG] switch_core.c:3197 switch_core_session_thread() Session 2 (sofia/test/LEG_B@GATEWAY_IP) Locked, Waiting on external entities
2006-11-08 18:44:10 [DEBUG] switch_core.c:2753 switch_core_session_run() (sofia/test/LEG_A@GATEWAY_IP) State HANGUP
2006-11-08 18:44:10 [DEBUG] mod_sofia.c:1210 sofia_on_hangup() Channel sofia/test/LEG_A@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" <sip:0000000000@FREESWITCH_IP>;tag=rDmcg6tScvHKa
To: <sip:LEG_A@GATEWAY_IP>;tag=SD2m6ue99-69480002-0-1921532189
Call-ID: 25a2e734-ea2e-1229-0a9a-0011d8333b24
CSeq: 75398168 BYE
Contact: <sip:mod_sofia@FREESWITCH_IP:5060>
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/LEG_A@GATEWAY_IP, cause: NORMAL_CLEARING
2006-11-08 18:44:10 [DEBUG] switch_core.c:3197 switch_core_session_thread() Session 1 (sofia/test/LEG_A@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/LEG_A@GATEWAY_IP) Ended
2006-11-08 18:44:10 [NOTICE] switch_core.c:3077 switch_core_session_destroy() Close Channel sofia/test/LEG_A@GATEWAY_IP
2006-11-08 18:44:10 [INFO] switch_core.c:3204 switch_core_session_thread() Session 2 (sofia/test/LEG_B@GATEWAY_IP) Ended
2006-11-08 18:44:10 [NOTICE] switch_core.c:3077 switch_core_session_destroy() Close Channel sofia/test/LEG_B@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" <sip:0000000000@FREESWITCH_IP>;tag=rDmcg6tScvHKa
To: <sip:LEG_A@GATEWAY_IP>;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/LEG_A@GATEWAY_IP entering state [terminated]
freeswitch@bogon> shutdown