<!--[if gte mso 9]><xml>
<w:WordDocument>
<w:View>Normal</w:View>
<w:Zoom>0</w:Zoom>
<w:PunctuationKerning/>
<w:ValidateAgainstSchemas/>
<w:SaveIfXMLInvalid>false</w:SaveIfXMLInvalid>
<w:IgnoreMixedContent>false</w:IgnoreMixedContent>
<w:AlwaysShowPlaceholderText>false</w:AlwaysShowPlaceholderText>
<w:Compatibility>
<w:BreakWrappedTables/>
<w:SnapToGridInCell/>
<w:WrapTextWithPunct/>
<w:UseAsianBreakRules/>
<w:DontGrowAutofit/>
<w:UseFELayout/>
</w:Compatibility>
<w:DoNotOptimizeForBrowser/>
</w:WordDocument>
</xml><![endif]--><!--[if gte mso 9]><xml>
<w:LatentStyles DefLockedState="false" LatentStyleCount="156">
</w:LatentStyles>
</xml><![endif]--><!--[if gte mso 10]>
<style>
/* Style Definitions */
table.MsoNormalTable
{mso-style-name:"Table Normal";
mso-tstyle-rowband-size:0;
mso-tstyle-colband-size:0;
mso-style-noshow:yes;
mso-style-parent:"";
mso-padding-alt:0in 5.4pt 0in 5.4pt;
mso-para-margin:0in;
mso-para-margin-bottom:.0001pt;
mso-pagination:widow-orphan;
font-size:10.0pt;
font-family:"Times New Roman";
mso-ansi-language:#0400;
mso-fareast-language:#0400;
mso-bidi-language:#0400;}
</style>
<![endif]-->
<p class="MsoPlainText">The FS1 should response back with ACK. It did not. <br></p><p class="MsoPlainText">The strange thing is that It works sometime.</p><p class="MsoPlainText">Here is the SIP trace from ngrep for both good and bad calls on FS1 server:</p><p class="MsoPlainText">SJPhone IP: 226.59.139.61</p><p class="MsoPlainText">FS1 IP:226.59.129.223</p><p class="MsoPlainText">FS2 IP:226.59.129.221</p><p class="MsoPlainText">You can see that FS2 sent several 200 OK and FS1 never reply back.<br></p><p class="MsoPlainText">Good sip call:</p><p class="MsoPlainText">U 226.59.139.61:5060 -> 226.59.129.223:5080<br> INVITE sip:5025155596@fs2000.lightyear.net SIP/2.0..Via: SIP/2.0/UDP 226.59.139.61;branch=z9hG4bKd8318b3d000007494da459a<br> 900006fac00000273;rport..From: "unknown" <sip:1009@fs2000.lightyear.net>;tag=a344c583a24..To: <sip:5025155596@fs2000.lig<br> htyear.net>..Contact: <sip:1009@226.59.139.61>..Call-ID: 56BF61B3189F4EDEB8C9067D5823C8480xd8318b3d..CSeq: 1 INVITE..Max<br> -Forwards: 70..User-Agent: SJphone/1.65.377a (SJ Labs)..Content-Length: 368..Content-Type: application/sdp..Supported: r<br> eplaces,norefersub,timer....v=0..o=- 3511605289 3511605289 IN IP4 226.59.139.61..s=SJphone..c=IN IP4 226.59.139.61..t=0<br> 0..m=audio 49352 RTP/AVP 3 97 98 8 0 101..c=IN IP4 226.59.139.61..a=rtpmap:3 GSM/8000..a=rtpmap:97 iLBC/8000..a=rtpmap:9<br> 8 iLBC/8000..a=fmtp:98 mode=20..a=rtpmap:8 PCMA/8000..a=rtpmap:0 PCMU/8000..a=rtpmap:101 telephone-event/8000..a=fmtp:10<br> 1 0-16..a=setup:active..a=sendrecv..<br>#<br>U 226.59.129.223:5080 -> 226.59.139.61:5060<br> SIP/2.0 100 Trying..Via: SIP/2.0/UDP 226.59.139.61;branch=z9hG4bKd8318b3d000007494da459a900006fac00000273;rport=5060..Fr<br> om: "unknown" <sip:1009@fs2000.lightyear.net>;tag=a344c583a24..To: <sip:5025155596@fs2000.lightyear.net>..Call-ID: 56BF6<br> 1B3189F4EDEB8C9067D5823C8480xd8318b3d..CSeq: 1 INVITE..User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-5310735 2011-04-07<br> 15-47-30 -0500..Content-Length: 0....<br>#<br>U 226.59.129.223:5060 -> 226.59.129.221:5060<br> INVITE sip:5596@226.59.129.221:5060 SIP/2.0..Via: SIP/2.0/UDP 226.59.129.223;rport;branch=z9hG4bK4g569HgUr3QXN..Max-Forw<br> ards: 68..From: "unknown" <sip:1009@226.59.129.223>;tag=KQKpXe0m39X6e..To: <sip:5596@226.59.129.221:5060>..Call-ID: 4639<br> 3d91-dfaf-122e-d1b0-e9d83e422dc6..CSeq: 10972180 INVITE..Contact: <sip:mod_sofia@226.59.129.223:5060>..User-Agent: FreeS<br> WITCH-mod_sofia/1.0.head-git-5310735 2011-04-07 15-47-30 -0500..Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDAT<br> E, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE..Supported: timer, precondition, path, replaces..Allow-Events: talk<br> , hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refe<br> r..Content-Type: application/sdp..Content-Disposition: session..Content-Length: 317..X-FS-Support: update_display..Remot<br> e-Party-ID: "unknown" <sip:1009@226.59.129.223>;party=calling;screen=yes;privacy=off....v=0..o=FreeSWITCH 1302587641 130<br> 2587642 IN IP4 226.59.129.223..s=FreeSWITCH..c=IN IP4 226.59.129.223..t=0 0..m=audio 28848 RTP/AVP 3 98 99 9 0 8 101 13.<br> .a=rtpmap:98 G7221/32000..a=fmtp:98 bitrate=48000..a=rtpmap:99 G7221/16000..a=fmtp:99 bitrate=32000..a=rtpmap:101 teleph<br> one-event/8000..a=fmtp:101 0-16..a=ptime:20..<br>#<br>U 226.59.129.221:5060 -> 226.59.129.223:5060<br> SIP/2.0 100 Trying..Via: SIP/2.0/UDP 226.59.129.223;rport=5060;branch=z9hG4bK4g569HgUr3QXN..From: "unknown" <sip:1009@21<br> 6.49.129.223>;tag=KQKpXe0m39X6e..To: <sip:5596@226.59.129.221:5060>..Call-ID: 46393d91-dfaf-122e-d1b0-e9d83e422dc6..CSeq<br> : 10972180 INVITE..User-Agent: FreeSWITCH-mod_sofia/1.0.7-hacked-20110119T213949Z..Content-Length: 0....<br>#<br>U 226.59.129.221:5060 -> 226.59.129.223:5060<br> SIP/2.0 200 OK..Via: SIP/2.0/UDP 226.59.129.223;rport=5060;branch=z9hG4bK4g569HgUr3QXN..From: "unknown" <sip:1009@226.59<br> .129.223>;tag=KQKpXe0m39X6e..To: <sip:5596@226.59.129.221:5060>;tag=7r593SDymv51S..Call-ID: 46393d91-dfaf-122e-d1b0-e9d8<br> 3e422dc6..CSeq: 10972180 INVITE..Contact: <sip:5596@226.59.129.221:5060;transport=udp>..User-Agent: FreeSWITCH-mod_sofia<br> /1.0.7-hacked-20110119T213949Z..Accept: application/sdp..Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO<br> , REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE..Supported: timer, precondition, path, replaces..Allow-Events: talk, hold,<br> presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer..Cont<br> ent-Type: application/sdp..Content-Disposition: session..Content-Length: 247..X-FS-Display-Name: 5596..X-FS-Display-Numb<br> er: sip:5596@226.59.129.221..X-FS-Support: update_display..Remote-Party-ID: "5596" <sip:5596@226.59.129.221>;party=calli<br> ng;privacy=off;screen=no....v=0..o=FreeSWITCH 1302597804 1302597805 IN IP4 226.59.129.221..s=FreeSWITCH..c=IN IP4 226.59<br> .129.221..t=0 0..m=audio 18640 RTP/AVP 3 101 13..a=rtpmap:3 GSM/8000..a=rtpmap:101 telephone-event/8000..a=fmtp:101 0-16<br> ..a=rtpmap:13 CN/8000..a=ptime:20..<br>#<br>U 226.59.129.223:5060 -> 226.59.129.221:5060<br> ACK sip:5596@226.59.129.221:5060;transport=udp SIP/2.0..Via: SIP/2.0/UDP 226.59.129.223;rport;branch=z9hG4bK5SyZBD1yNceg<br> H..Max-Forwards: 70..From: "unknown" <sip:1009@226.59.129.223>;tag=KQKpXe0m39X6e..To: <sip:5596@226.59.129.221:5060>;tag<br> =7r593SDymv51S..Call-ID: 46393d91-dfaf-122e-d1b0-e9d83e422dc6..CSeq: 10972180 ACK..Contact: <sip:mod_sofia@226.59.129.22<br> 3:5060>..Content-Length: 0....<br>#<br>U 226.59.129.223:5080 -> 226.59.139.61:5060<br> SIP/2.0 200 OK..Via: SIP/2.0/UDP 226.59.139.61;branch=z9hG4bKd8318b3d000007494da459a900006fac00000273;rport=5060..From:<br> "unknown" <sip:1009@fs2000.lightyear.net>;tag=a344c583a24..To: <sip:5025155596@fs2000.lightyear.net>;tag=KB4NB13DD35eg..<br> Call-ID: 56BF61B3189F4EDEB8C9067D5823C8480xd8318b3d..CSeq: 1 INVITE..Contact: <sip:5025155596@226.59.129.223:5080;transp<br> ort=udp>..User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-5310735 2011-04-07 15-47-30 -0500..Accept: application/sdp..Allo<br> w: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY..Supported: timer, precondition, pa<br> th, replaces..Allow-Events: talk, hold, refer..Content-Type: application/sdp..Content-Disposition: session..Content-Leng<br> th: 250..X-FS-Display-Name: 5596..X-FS-Display-Number: sip:5596@226.59.129.221..X-FS-Support: update_display..Remote-Par<br> ty-ID: "5596" <sip:5596@226.59.129.223>;party=calling;privacy=off;screen=no....v=0..o=FreeSWITCH 1302591209 1302591210 I<br> N IP4 226.59.129.223..s=FreeSWITCH..c=IN IP4 226.59.129.223..t=0 0..m=audio 25280 RTP/AVP 3 101..a=rtpmap:3 GSM/8000..a=<br> rtpmap:101 telephone-event/8000..a=fmtp:101 0-16..a=silenceSupp:off - - - -..a=ptime:20..<br>#<br>U 226.59.139.61:5060 -> 226.59.129.223:5080<br> ACK sip:5025155596@226.59.129.223:5080;transport=udp SIP/2.0..Via: SIP/2.0/UDP 226.59.139.61;branch=z9hG4bKd8318b3d00000<br> 7494da459a900003d7800000277;rport..From: "unknown" <sip:1009@fs2000.lightyear.net>;tag=a344c583a24..To: <sip:5025155596@<br> fs2000.lightyear.net>;tag=KB4NB13DD35eg..Contact: <sip:1009@226.59.139.61>..Call-ID: 56BF61B3189F4EDEB8C9067D5823C8480xd<br> 8318b3d..CSeq: 1 ACK..Max-Forwards: 70..User-Agent: SJphone/1.65.377a (SJ Labs)..Content-Length: 0....<br>#<br>U 226.59.129.223:5060 -> 226.59.129.221:5060<br> INFO sip:5596@226.59.129.221:5060;transport=udp SIP/2.0..Via: SIP/2.0/UDP 226.59.129.223;rport;branch=z9hG4bK62QrD8H2jN4<br> 2c..Max-Forwards: 70..From: "unknown" <sip:1009@226.59.129.223>;tag=KQKpXe0m39X6e..To: <sip:5596@226.59.129.221:5060>;ta<br> g=7r593SDymv51S..Call-ID: 46393d91-dfaf-122e-d1b0-e9d83e422dc6..CSeq: 10972181 INFO..Contact: <sip:mod_sofia@226.59.129.<br> 223:5060>..User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-5310735 2011-04-07 15-47-30 -0500..Allow: INVITE, ACK, BYE, CAN<br> CEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE..Supported: timer, precondition, path,<br> replaces..Content-Type: message/update_display..Content-Length: 0..X-FS-Display-Name: unknown..X-FS-Display-Number: 1009<br> ....<br>#<br>U 226.59.129.221:5060 -> 226.59.129.223:5060<br> SIP/2.0 200 OK..Via: SIP/2.0/UDP 226.59.129.223;rport=5060;branch=z9hG4bK62QrD8H2jN42c..From: "unknown" <sip:1009@226.59<br> .129.223>;tag=KQKpXe0m39X6e..To: <sip:5596@226.59.129.221:5060>;tag=7r593SDymv51S..Call-ID: 46393d91-dfaf-122e-d1b0-e9d8<br> 3e422dc6..CSeq: 10972181 INFO..User-Agent: FreeSWITCH-mod_sofia/1.0.7-hacked-20110119T213949Z..Allow: INVITE, ACK, BYE,<br> CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE..Supported: timer, precondition, pat<br> h, replaces..Content-Length: 0....<br>#<br>U 226.59.139.61:5060 -> 226.59.129.223:5080<br> BYE sip:5025155596@226.59.129.223:5080;transport=udp SIP/2.0..Via: SIP/2.0/UDP 226.59.139.61;branch=z9hG4bKd8318b3d00000<br> 74b4da459e200004b8500000278;rport..From: "unknown" <sip:1009@fs2000.lightyear.net>;tag=a344c583a24..To: <sip:5025155596@<br> fs2000.lightyear.net>;tag=KB4NB13DD35eg..Contact: <sip:1009@226.59.139.61>..Call-ID: 56BF61B3189F4EDEB8C9067D5823C8480xd<br> 8318b3d..CSeq: 2 BYE..Max-Forwards: 70..User-Agent: SJphone/1.65.377a (SJ Labs)..Content-Length: 0..Supported: replaces,<br> norefersub,timer....<br>#<br>U 226.59.129.223:5080 -> 226.59.139.61:5060<br> SIP/2.0 200 OK..Via: SIP/2.0/UDP 226.59.139.61;branch=z9hG4bKd8318b3d0000074b4da459e200004b8500000278;rport=5060..From:<br> "unknown" <sip:1009@fs2000.lightyear.net>;tag=a344c583a24..To: <sip:5025155596@fs2000.lightyear.net>;tag=KB4NB13DD35eg..<br> Call-ID: 56BF61B3189F4EDEB8C9067D5823C8480xd8318b3d..CSeq: 2 BYE..User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-5310735<br> 2011-04-07 15-47-30 -0500..Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY..Sup<br> ported: timer, precondition, path, replaces..Content-Length: 0....<br>#<br>U 226.59.129.223:5060 -> 226.59.129.221:5060<br> BYE sip:5596@226.59.129.221:5060;transport=udp SIP/2.0..Via: SIP/2.0/UDP 226.59.129.223;rport;branch=z9hG4bK7BHHF325FytN<br> r..Max-Forwards: 70..From: "unknown" <sip:1009@226.59.129.223>;tag=KQKpXe0m39X6e..To: <sip:5596@226.59.129.221:5060>;tag<br> =7r593SDymv51S..Call-ID: 46393d91-dfaf-122e-d1b0-e9d83e422dc6..CSeq: 10972182 BYE..Contact: <sip:mod_sofia@226.59.129.22<br> 3:5060>..User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-5310735 2011-04-07 15-47-30 -0500..Allow: INVITE, ACK, BYE, CANCE<br> L, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE..Supported: timer, precondition, path, re<br> places..Reason: Q.850;cause=16;text="NORMAL_CLEARING"..Content-Length: 0....<br>#<br>U 226.59.129.221:5060 -> 226.59.129.223:5060<br> SIP/2.0 200 OK..Via: SIP/2.0/UDP 226.59.129.223;rport=5060;branch=z9hG4bK7BHHF325FytNr..From: "unknown" <sip:1009@226.59<br> .129.223>;tag=KQKpXe0m39X6e..To: <sip:5596@226.59.129.221:5060>;tag=7r593SDymv51S..Call-ID: 46393d91-dfaf-122e-d1b0-e9d8<br> 3e422dc6..CSeq: 10972182 BYE..User-Agent: FreeSWITCH-mod_sofia/1.0.7-hacked-20110119T213949Z..Allow: INVITE, ACK, BYE, C<br> ANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE..Supported: timer, precondition, path<br> , replaces..Content-Length: 0....<br><br>Bad SIP call:</p><p class="MsoPlainText">U 226.59.139.61:5060 -> 226.59.129.223:5080<br> INVITE sip:5025155596@fs2000.lightyear.net SIP/2.0..Via: SIP/2.0/UDP 226.59.139.61;branch=z9hG4bKd8318b3d000007324da4586<br> e0000031200000260;rport..From: "unknown" <sip:1009@fs2000.lightyear.net>;tag=60654c536a55..To: <sip:5025155596@fs2000.li<br> ghtyear.net>..Contact: <sip:1009@226.59.139.61>..Call-ID: 44C45839A15C4201A81F99F69C8FDB4D0xd8318b3d..CSeq: 1 INVITE..Ma<br> x-Forwards: 70..User-Agent: SJphone/1.65.377a (SJ Labs)..Content-Length: 368..Content-Type: application/sdp..Supported:<br> replaces,norefersub,timer....v=0..o=- 3511604973 3511604973 IN IP4 226.59.139.61..s=SJphone..c=IN IP4 226.59.139.61..t=0<br> 0..m=audio 49346 RTP/AVP 3 97 98 8 0 101..c=IN IP4 226.59.139.61..a=rtpmap:3 GSM/8000..a=rtpmap:97 iLBC/8000..a=rtpmap:<br> 98 iLBC/8000..a=fmtp:98 mode=20..a=rtpmap:8 PCMA/8000..a=rtpmap:0 PCMU/8000..a=rtpmap:101 telephone-event/8000..a=fmtp:1<br> 01 0-16..a=setup:active..a=sendrecv..<br>#<br>U 226.59.129.223:5080 -> 226.59.139.61:5060<br> SIP/2.0 100 Trying..Via: SIP/2.0/UDP 226.59.139.61;branch=z9hG4bKd8318b3d000007324da4586e0000031200000260;rport=5060..Fr<br> om: "unknown" <sip:1009@fs2000.lightyear.net>;tag=60654c536a55..To: <sip:5025155596@fs2000.lightyear.net>..Call-ID: 44C4<br> 5839A15C4201A81F99F69C8FDB4D0xd8318b3d..CSeq: 1 INVITE..User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-5310735 2011-04-07<br> 15-47-30 -0500..Content-Length: 0....<br>#<br>U 226.59.129.223:5060 -> 226.59.129.221:5060<br> INVITE sip:5596@226.59.129.221:5060 SIP/2.0..Via: SIP/2.0/UDP 226.59.129.223;rport;branch=z9hG4bKtp8er37QNaS2H..Max-Forw<br> ards: 68..From: "unknown" <sip:1009@226.59.129.223>;tag=gv7BrXDacFUec..To: <sip:5596@226.59.129.221:5060>..Call-ID: 8a42<br> e048-dfae-122e-d1b0-e9d83e422dc6..CSeq: 10972023 INVITE..Contact: <sip:mod_sofia@226.59.129.223:5060>..User-Agent: FreeS<br> WITCH-mod_sofia/1.0.head-git-5310735 2011-04-07 15-47-30 -0500..Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDAT<br> E, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE..Supported: timer, precondition, path, replaces..Allow-Events: talk<br> , hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refe<br> r..Content-Type: application/sdp..Content-Disposition: session..Content-Length: 317..X-FS-Support: update_display..Remot<br> e-Party-ID: "unknown" <sip:1009@226.59.129.223>;party=calling;screen=yes;privacy=off....v=0..o=FreeSWITCH 1302588008 130<br> 2588009 IN IP4 226.59.129.223..s=FreeSWITCH..c=IN IP4 226.59.129.223..t=0 0..m=audio 28166 RTP/AVP 3 98 99 9 0 8 101 13.<br> .a=rtpmap:98 G7221/32000..a=fmtp:98 bitrate=48000..a=rtpmap:99 G7221/16000..a=fmtp:99 bitrate=32000..a=rtpmap:101 teleph<br> one-event/8000..a=fmtp:101 0-16..a=ptime:20..<br>#<br>U 226.59.129.221:5060 -> 226.59.129.223:5060<br> SIP/2.0 100 Trying..Via: SIP/2.0/UDP 226.59.129.223;rport=5060;branch=z9hG4bKtp8er37QNaS2H..From: "unknown" <sip:1009@21<br> 6.49.129.223>;tag=gv7BrXDacFUec..To: <sip:5596@226.59.129.221:5060>..Call-ID: 8a42e048-dfae-122e-d1b0-e9d83e422dc6..CSeq<br> : 10972023 INVITE..User-Agent: FreeSWITCH-mod_sofia/1.0.7-hacked-20110119T213949Z..Content-Length: 0....<br>#<br>U 226.59.129.221:5060 -> 226.59.129.223:5060<br> SIP/2.0 200 OK..Via: SIP/2.0/UDP 226.59.129.223;rport=5060;branch=z9hG4bKtp8er37QNaS2H..From: "unknown" <sip:1009@226.59<br> .129.223>;tag=gv7BrXDacFUec..To: <sip:5596@226.59.129.221:5060>;tag=4XSZy8tKX129p..Call-ID: 8a42e048-dfae-122e-d1b0-e9d8<br> 3e422dc6..CSeq: 10972023 INVITE..Contact: <sip:5596@226.59.129.221:5060;transport=udp>..User-Agent: FreeSWITCH-mod_sofia<br> /1.0.7-hacked-20110119T213949Z..Accept: application/sdp..Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO<br> , REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE..Supported: timer, precondition, path, replaces..Allow-Events: talk, hold,<br> presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer..Cont<br> ent-Type: application/sdp..Content-Disposition: session..Content-Length: 247..X-FS-Display-Name: 5596..X-FS-Display-Numb<br> er: sip:5596@226.59.129.221..X-FS-Support: update_display..Remote-Party-ID: "5596" <sip:5596@226.59.129.221>;party=calli<br> ng;privacy=off;screen=no....v=0..o=FreeSWITCH 1302589491 1302589492 IN IP4 226.59.129.221..s=FreeSWITCH..c=IN IP4 226.59<br> .129.221..t=0 0..m=audio 26638 RTP/AVP 3 101 13..a=rtpmap:3 GSM/8000..a=rtpmap:101 telephone-event/8000..a=fmtp:101 0-16<br> ..a=rtpmap:13 CN/8000..a=ptime:20..<br>#<br>U 226.59.129.221:5060 -> 226.59.129.223:5060<br> SIP/2.0 200 OK..Via: SIP/2.0/UDP 226.59.129.223;rport=5060;branch=z9hG4bKtp8er37QNaS2H..From: "unknown" <sip:1009@226.59<br> .129.223>;tag=gv7BrXDacFUec..To: <sip:5596@226.59.129.221:5060>;tag=4XSZy8tKX129p..Call-ID: 8a42e048-dfae-122e-d1b0-e9d8<br> 3e422dc6..CSeq: 10972023 INVITE..Contact: <sip:5596@226.59.129.221:5060;transport=udp>..User-Agent: FreeSWITCH-mod_sofia<br> /1.0.7-hacked-20110119T213949Z..Accept: application/sdp..Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO<br> , REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE..Supported: timer, precondition, path, replaces..Allow-Events: talk, hold,<br> presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer..Cont<br> ent-Type: application/sdp..Content-Disposition: session..Content-Length: 247..X-FS-Display-Name: 5596..X-FS-Display-Numb<br> er: sip:5596@226.59.129.221..X-FS-Support: update_display..Remote-Party-ID: "5596" <sip:5596@226.59.129.221>;party=calli<br> ng;privacy=off;screen=no....v=0..o=FreeSWITCH 1302589491 1302589492 IN IP4 226.59.129.221..s=FreeSWITCH..c=IN IP4 226.59<br> .129.221..t=0 0..m=audio 26638 RTP/AVP 3 101 13..a=rtpmap:3 GSM/8000..a=rtpmap:101 telephone-event/8000..a=fmtp:101 0-16<br> ..a=rtpmap:13 CN/8000..a=ptime:20..<br>#<br>U 226.59.129.221:5060 -> 226.59.129.223:5060<br> SIP/2.0 200 OK..Via: SIP/2.0/UDP 226.59.129.223;rport=5060;branch=z9hG4bKtp8er37QNaS2H..From: "unknown" <sip:1009@226.59<br> .129.223>;tag=gv7BrXDacFUec..To: <sip:5596@226.59.129.221:5060>;tag=4XSZy8tKX129p..Call-ID: 8a42e048-dfae-122e-d1b0-e9d8<br> 3e422dc6..CSeq: 10972023 INVITE..Contact: <sip:5596@226.59.129.221:5060;transport=udp>..User-Agent: FreeSWITCH-mod_sofia<br> /1.0.7-hacked-20110119T213949Z..Accept: application/sdp..Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO<br> , REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE..Supported: timer, precondition, path, replaces..Allow-Events: talk, hold,<br> presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer..Cont<br> ent-Type: application/sdp..Content-Disposition: session..Content-Length: 247..X-FS-Display-Name: 5596..X-FS-Display-Numb<br> er: sip:5596@226.59.129.221..X-FS-Support: update_display..Remote-Party-ID: "5596" <sip:5596@226.59.129.221>;party=calli<br> ng;privacy=off;screen=no....v=0..o=FreeSWITCH 1302589491 1302589492 IN IP4 226.59.129.221..s=FreeSWITCH..c=IN IP4 226.59<br> .129.221..t=0 0..m=audio 26638 RTP/AVP 3 101 13..a=rtpmap:3 GSM/8000..a=rtpmap:101 telephone-event/8000..a=fmtp:101 0-16<br> ..a=rtpmap:13 CN/8000..a=ptime:20..<br>#<br>U 226.59.129.221:5060 -> 226.59.129.223:5060<br> SIP/2.0 200 OK..Via: SIP/2.0/UDP 226.59.129.223;rport=5060;branch=z9hG4bKtp8er37QNaS2H..From: "unknown" <sip:1009@226.59<br> .129.223>;tag=gv7BrXDacFUec..To: <sip:5596@226.59.129.221:5060>;tag=4XSZy8tKX129p..Call-ID: 8a42e048-dfae-122e-d1b0-e9d8<br> 3e422dc6..CSeq: 10972023 INVITE..Contact: <sip:5596@226.59.129.221:5060;transport=udp>..User-Agent: FreeSWITCH-mod_sofia<br> /1.0.7-hacked-20110119T213949Z..Accept: application/sdp..Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO<br> , REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE..Supported: timer, precondition, path, replaces..Allow-Events: talk, hold,<br> presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer..Cont<br> ent-Type: application/sdp..Content-Disposition: session..Content-Length: 247..X-FS-Display-Name: 5596..X-FS-Display-Numb<br> er: sip:5596@226.59.129.221..X-FS-Support: update_display..Remote-Party-ID: "5596" <sip:5596@226.59.129.221>;party=calli<br> ng;privacy=off;screen=no....v=0..o=FreeSWITCH 1302589491 1302589492 IN IP4 226.59.129.221..s=FreeSWITCH..c=IN IP4 226.59<br> .129.221..t=0 0..m=audio 26638 RTP/AVP 3 101 13..a=rtpmap:3 GSM/8000..a=rtpmap:101 telephone-event/8000..a=fmtp:101 0-16<br> ..a=rtpmap:13 CN/8000..a=ptime:20..<br>U 226.59.129.221:5060 -> 226.59.129.223:5060<br> SIP/2.0 200 OK..Via: SIP/2.0/UDP 226.59.129.223;rport=5060;branch=z9hG4bKtp8er37QNaS2H..From: "unknown" <sip:1009@226.59<br> .129.223>;tag=gv7BrXDacFUec..To: <sip:5596@226.59.129.221:5060>;tag=4XSZy8tKX129p..Call-ID: 8a42e048-dfae-122e-d1b0-e9d8<br> 3e422dc6..CSeq: 10972023 INVITE..Contact: <sip:5596@226.59.129.221:5060;transport=udp>..User-Agent: FreeSWITCH-mod_sofia<br> /1.0.7-hacked-20110119T213949Z..Accept: application/sdp..Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO<br> , REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE..Supported: timer, precondition, path, replaces..Allow-Events: talk, hold,<br> presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer..Cont<br> ent-Type: application/sdp..Content-Disposition: session..Content-Length: 247..X-FS-Display-Name: 5596..X-FS-Display-Numb<br> er: sip:5596@226.59.129.221..X-FS-Support: update_display..Remote-Party-ID: "5596" <sip:5596@226.59.129.221>;party=calli<br> ng;privacy=off;screen=no....v=0..o=FreeSWITCH 1302589491 1302589492 IN IP4 226.59.129.221..s=FreeSWITCH..c=IN IP4 226.59<br> .129.221..t=0 0..m=audio 26638 RTP/AVP 3 101 13..a=rtpmap:3 GSM/8000..a=rtpmap:101 telephone-event/8000..a=fmtp:101 0-16<br> ..a=rtpmap:13 CN/8000..a=ptime:20..<br>#<br>U 226.59.129.221:5060 -> 226.59.129.223:5060<br> SIP/2.0 200 OK..Via: SIP/2.0/UDP 226.59.129.223;rport=5060;branch=z9hG4bKtp8er37QNaS2H..From: "unknown" <sip:1009@226.59<br> .129.223>;tag=gv7BrXDacFUec..To: <sip:5596@226.59.129.221:5060>;tag=4XSZy8tKX129p..Call-ID: 8a42e048-dfae-122e-d1b0-e9d8<br> 3e422dc6..CSeq: 10972023 INVITE..Contact: <sip:5596@226.59.129.221:5060;transport=udp>..User-Agent: FreeSWITCH-mod_sofia<br> /1.0.7-hacked-20110119T213949Z..Accept: application/sdp..Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO<br> , REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE..Supported: timer, precondition, path, replaces..Allow-Events: talk, hold,<br> presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer..Cont<br> ent-Type: application/sdp..Content-Disposition: session..Content-Length: 247..X-FS-Display-Name: 5596..X-FS-Display-Numb<br> er: sip:5596@226.59.129.221..X-FS-Support: update_display..Remote-Party-ID: "5596" <sip:5596@226.59.129.221>;party=calli<br> ng;privacy=off;screen=no....v=0..o=FreeSWITCH 1302589491 1302589492 IN IP4 226.59.129.221..s=FreeSWITCH..c=IN IP4 226.59<br> .129.221..t=0 0..m=audio 26638 RTP/AVP 3 101 13..a=rtpmap:3 GSM/8000..a=rtpmap:101 telephone-event/8000..a=fmtp:101 0-16<br> ..a=rtpmap:13 CN/8000..a=ptime:20..<br>#<br>U 226.59.129.221:5060 -> 226.59.129.223:5060<br> SIP/2.0 200 OK..Via: SIP/2.0/UDP 226.59.129.223;rport=5060;branch=z9hG4bKtp8er37QNaS2H..From: "unknown" <sip:1009@226.59<br> .129.223>;tag=gv7BrXDacFUec..To: <sip:5596@226.59.129.221:5060>;tag=4XSZy8tKX129p..Call-ID: 8a42e048-dfae-122e-d1b0-e9d8<br> 3e422dc6..CSeq: 10972023 INVITE..Contact: <sip:5596@226.59.129.221:5060;transport=udp>..User-Agent: FreeSWITCH-mod_sofia<br> /1.0.7-hacked-20110119T213949Z..Accept: application/sdp..Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO<br> , REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE..Supported: timer, precondition, path, replaces..Allow-Events: talk, hold,<br> presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer..Cont<br> ent-Type: application/sdp..Content-Disposition: session..Content-Length: 247..X-FS-Display-Name: 5596..X-FS-Display-Numb<br> er: sip:5596@226.59.129.221..X-FS-Support: update_display..Remote-Party-ID: "5596" <sip:5596@226.59.129.221>;party=calli<br> ng;privacy=off;screen=no....v=0..o=FreeSWITCH 1302589491 1302589492 IN IP4 226.59.129.221..s=FreeSWITCH..c=IN IP4 226.59<br> .129.221..t=0 0..m=audio 26638 RTP/AVP 3 101 13..a=rtpmap:3 GSM/8000..a=rtpmap:101 telephone-event/8000..a=fmtp:101 0-16<br> ..a=rtpmap:13 CN/8000..a=ptime:20..<br><br></p><p class="MsoPlainText">-----Original Message-----<br>
From: freeswitch-users-bounces@lists.freeswitch.org
[mailto:freeswitch-users-bounces@lists.freeswitch.org] On Behalf Of Anthony
Minessale<br>
Sent: Tuesday, April 12, 2011 8:21 PM<br>
To: FreeSWITCH Users Help<br>
Subject: Re: [Freeswitch-users] FS does not repose to SIP OK message.</p>
<p class="MsoPlainText">The ack is not being received.<span style=""> </span>Try your trace from the other side.</p>
<p class="MsoPlainText">Try finding the misconfiguration and the NAT or SIP alg
on your network.</p>
<p class="MsoPlainText"> </p>
<p class="MsoPlainText"> </p>
<p class="MsoPlainText"> </p>
<p class="MsoPlainText"> </p>
<p class="MsoPlainText">On Tue, Apr 12, 2011 at 3:00 PM, Gary Chen
<gchen00@insightbb.com> wrote:</p>
<p class="MsoPlainText">> Just update my test FS to newest snapshot:
FreeSWITCH Version 1.0.head</p>
<p class="MsoPlainText">> (git-5310735 2011-04-07 15-47-30 -0500)</p>
<p class="MsoPlainText">> I am using SJphone softphone to call into my test
FS1. This FS1 then forward</p>
<p class="MsoPlainText">> the call to another FS2.</p>
<p class="MsoPlainText">> FS2 will answer the call and start Music On
Hold. Basically I am using</p>
<p class="MsoPlainText">> SJphone to initiate a SIP call and let FS2 to
answer the call with Music On</p>
<p class="MsoPlainText">> Hold.</p>
<p class="MsoPlainText">> It is working with older version of FS. Now after
update to this newest</p>
<p class="MsoPlainText">> version. The call sometime will not go through. The
SJPhone just keep</p>
<p class="MsoPlainText">> ringing until timeout. This happens maybe on half of
the calls. I also tried</p>
<p class="MsoPlainText">> to use Asterisk to replace FS2 for Music On Hold and
it did the same thing.</p>
<p class="MsoPlainText">> The following is the part of console sofia log info:</p>
<p class="MsoPlainText">> 2011-04-12 15:29:06.285484 [DEBUG] mod_sofia.c:84</p>
<p class="MsoPlainText">> sofia/internal/5596@226.59.129.221:5060 SOFIA INIT</p>
<p class="MsoPlainText">> nua: nh_create_handle: entering</p>
<p class="MsoPlainText">> nua: nua_handle_bind: entering</p>
<p class="MsoPlainText">> nua: nua_invite: entering</p>
<p class="MsoPlainText">> nua(0x18cc2c80): sent signal r_invite</p>
<p class="MsoPlainText">> 2011-04-12 15:29:06.285484 [DEBUG] mod_sofia.c:124</p>
<p class="MsoPlainText">> (sofia/internal/5596@226.59.129.221:5060) State
Change CS_INIT -> CS_ROUTING</p>
<p class="MsoPlainText">> 2011-04-12 15:29:06.285484 [DEBUG]
switch_core_session.c:1116 Send signal</p>
<p class="MsoPlainText">> sofia/internal/5596@226.59.129.221:5060 [BREAK]</p>
<p class="MsoPlainText">> 2011-04-12 15:29:06.285484 [DEBUG]
switch_core_state_machine.c:361</p>
<p class="MsoPlainText">> (sofia/internal/5596@226.59.129.221:5060) State INIT
going to sleep</p>
<p class="MsoPlainText">> 2011-04-12 15:29:06.285484 [DEBUG]
switch_core_state_machine.c:325</p>
<p class="MsoPlainText">> (sofia/internal/5596@226.59.129.221:5060) Running
State Change CS_ROUTING</p>
<p class="MsoPlainText">> nua(0x18cc2c80): recv signal r_invite</p>
<p class="MsoPlainText">> 2011-04-12 15:29:06.285484 [DEBUG]
switch_channel.c:1668</p>
<p class="MsoPlainText">> (sofia/internal/5596@226.59.129.221:5060) Callstate
Change DOWN -> RINGING</p>
<p class="MsoPlainText">> nua: nua_stack_set_params: entering</p>
<p class="MsoPlainText">> soa_clone(static::0x18c66f60, 0x18c474e0,
0x18cc2c80) called</p>
<p class="MsoPlainText">> soa_set_params(static::0x2aaabc072a60, ...) called</p>
<p class="MsoPlainText">> soa_set_params(static::0x2aaabc072a60, ...) called</p>
<p class="MsoPlainText">> soa_set_user_sdp(static::0x2aaabc072a60, (nil),
0x18ccb7b7, -1) called</p>
<p class="MsoPlainText">> soa_set_capability_sdp(static::0x2aaabc072a60,
(nil), 0x18ccb7b7, -1) called</p>
<p class="MsoPlainText">> 2011-04-12 15:29:06.285484 [DEBUG]
switch_core_state_machine.c:364</p>
<p class="MsoPlainText">> (sofia/internal/5596@226.59.129.221:5060) State
ROUTING</p>
<p class="MsoPlainText">> 2011-04-12 15:29:06.285484 [DEBUG] mod_sofia.c:147</p>
<p class="MsoPlainText">> sofia/internal/5596@226.59.129.221:5060 SOFIA
ROUTING</p>
<p class="MsoPlainText">> 2011-04-12 15:29:06.285484 [DEBUG]
switch_ivr_originate.c:66</p>
<p class="MsoPlainText">> (sofia/internal/5596@226.59.129.221:5060) State
Change CS_ROUTING -></p>
<p class="MsoPlainText">> CS_CONSUME_MEDIA</p>
<p class="MsoPlainText">> nua(0x18cc2c80): adding session usage</p>
<p class="MsoPlainText">> 2011-04-12 15:29:06.285484 [DEBUG]
switch_core_session.c:1116 Send signal</p>
<p class="MsoPlainText">> sofia/internal/5596@226.59.129.221:5060 [BREAK]</p>
<p class="MsoPlainText">> 2011-04-12 15:29:06.285484 [DEBUG]
switch_core_state_machine.c:364</p>
<p class="MsoPlainText">> (sofia/internal/5596@226.59.129.221:5060) State
ROUTING going to sleep</p>
<p class="MsoPlainText">> 2011-04-12 15:29:06.285484 [DEBUG] switch_core_state_machine.c:325</p>
<p class="MsoPlainText">> (sofia/internal/5596@226.59.129.221:5060) Running
State Change</p>
<p class="MsoPlainText">> CS_CONSUME_MEDIA</p>
<p class="MsoPlainText">> nta_leg_tcreate(0x2aaaac05e220)</p>
<p class="MsoPlainText">> soa_init_offer_answer(static::0x2aaabc072a60) called</p>
<p class="MsoPlainText">> soa_generate_offer(static::0x2aaabc072a60, 0) called</p>
<p class="MsoPlainText">> soa_static_offer_answer_action(0x2aaabc072a60,
soa_generate_offer): called</p>
<p class="MsoPlainText">> soa_static(0x2aaabc072a60, soa_generate_offer):
generating local description</p>
<p class="MsoPlainText">> soa_static(0x2aaabc072a60, soa_generate_offer):
upgrade with local</p>
<p class="MsoPlainText">> description</p>
<p class="MsoPlainText">> 2011-04-12 15:29:06.285484 [DEBUG]
switch_core_state_machine.c:383</p>
<p class="MsoPlainText">> (sofia/internal/5596@226.59.129.221:5060) State
CONSUME_MEDIA</p>
<p class="MsoPlainText">> soa_sdp_mode_set(0x406f6c60, (nil), ""):
called</p>
<p class="MsoPlainText">> 2011-04-12 15:29:06.285484 [DEBUG]
switch_core_state_machine.c:383</p>
<p class="MsoPlainText">> (sofia/internal/5596@226.59.129.221:5060) State
CONSUME_MEDIA going to sleep</p>
<p class="MsoPlainText">> soa_static(0x2aaabc072a60, soa_generate_offer):
storing local description</p>
<p class="MsoPlainText">> soa_get_local_sdp(static::0x2aaabc072a60, [(nil)],
[0x406f6dc8],</p>
<p class="MsoPlainText">> [0x406f6dd4]) called</p>
<p class="MsoPlainText">> nta: selecting scheme sip</p>
<p class="MsoPlainText">> tport_tsend(0x18c69750) tpn = */226.59.129.221:5060</p>
<p class="MsoPlainText">> tport_resolve addrinfo = 226.59.129.221:5060</p>
<p class="MsoPlainText">> tport_by_addrinfo(0x18c69750): not found by name
*/226.59.129.221:5060</p>
<p class="MsoPlainText">> tport_vsend(0x18c69750): 1245 bytes of 1245 to
udp/226.59.129.221:5060</p>
<p class="MsoPlainText">> tport_vsend returned 1245</p>
<p class="MsoPlainText">> nta: sent INVITE (10982209) to */226.59.129.221:5060</p>
<p class="MsoPlainText">> tport_pend(0x18c69750): pending 0x2aaaac059c30 for
udp/226.59.129.223:5060</p>
<p class="MsoPlainText">> (already 0)</p>
<p class="MsoPlainText">> nta: timer set to 32000 ms</p>
<p class="MsoPlainText">> nta: timer shortened to 1000 ms</p>
<p class="MsoPlainText">> nua(0x18cc2c80): call state changed: init ->
calling, sent offer</p>
<p class="MsoPlainText">> soa_get_local_sdp(static::0x2aaabc072a60,
[0x406f6db8], [0x406f6db0],</p>
<p class="MsoPlainText">> [(nil)]) called</p>
<p class="MsoPlainText">> nua(0x18cc2c80): event i_state INVITE sent</p>
<p class="MsoPlainText">> nua: nua_application_event: entering</p>
<p class="MsoPlainText">> 2011-04-12 15:29:06.285484 [DEBUG] sofia.c:4761
Channel</p>
<p class="MsoPlainText">> sofia/internal/5596@226.59.129.221:5060 entering
state [calling][0]</p>
<p class="MsoPlainText">> nua: nua_handle_magic: entering</p>
<p class="MsoPlainText">> tport_wakeup_pri(0x18c69750): events IN</p>
<p class="MsoPlainText">> tport_recv_event(0x18c69750)</p>
<p class="MsoPlainText">> tport_recv_iovec(0x18c69750) msg 0x18c87620 from
(udp/226.59.129.223:5060)</p>
<p class="MsoPlainText">> has 344 bytes, veclen = 1</p>
<p class="MsoPlainText">> tport_deliver(0x18c69750): msg 0x18c87620 (344
bytes) from</p>
<p class="MsoPlainText">> udp/226.59.129.221:5060/sip next=(nil)</p>
<p class="MsoPlainText">> nta: received 100 Trying for INVITE (10982209)</p>
<p class="MsoPlainText">> nta: 100 Trying is going to a transaction</p>
<p class="MsoPlainText">> nta_outgoing: RTT is 0.765 ms</p>
<p class="MsoPlainText">> tport_release(0x18c69750): 0x2aaaac059c30 by
0x2aaabc05da60 with 0x18c87620</p>
<p class="MsoPlainText">> (preliminary)</p>
<p class="MsoPlainText">> tport_wakeup_pri(0x18c69750): events IN</p>
<p class="MsoPlainText">> tport_recv_event(0x18c69750)</p>
<p class="MsoPlainText">> tport_recv_iovec(0x18c69750) msg 0x2aaabc070b20 from</p>
<p class="MsoPlainText">> (udp/226.59.129.223:5060) has 1235 bytes, veclen = 1</p>
<p class="MsoPlainText">> tport_deliver(0x18c69750): msg 0x2aaabc070b20 (1235
bytes) from</p>
<p class="MsoPlainText">> udp/226.59.129.221:5060/sip next=(nil)</p>
<p class="MsoPlainText">> nta: received 200 OK for INVITE (10982209)</p>
<p class="MsoPlainText">> nta: 200 OK is going to a transaction</p>
<p class="MsoPlainText">> tport_release(0x18c69750): 0x2aaaac059c30 by
0x2aaabc05da60 with</p>
<p class="MsoPlainText">> 0x2aaabc070b20</p>
<p class="MsoPlainText">> soa_set_remote_sdp(static::0x2aaabc072a60, (nil),
0x2aaabc0712ac, 247)</p>
<p class="MsoPlainText">> called</p>
<p class="MsoPlainText">> soa_process_answer(static::0x2aaabc072a60) called</p>
<p class="MsoPlainText">> soa_static_offer_answer_action(0x2aaabc072a60,
soa_process_answer): called</p>
<p class="MsoPlainText">> soa_sdp_mode_set(0x2aaabc070240, 0x2aaabc073500,
""): called</p>
<p class="MsoPlainText">> soa_static(0x2aaabc072a60, soa_process_answer):
upgrade codecs with remote</p>
<p class="MsoPlainText">> description</p>
<p class="MsoPlainText">> soa_static(0x2aaabc072a60, soa_process_answer):
storing local description</p>
<p class="MsoPlainText">> soa_activate(static::0x2aaabc072a60, (nil)) called</p>
<p class="MsoPlainText">> nua(0x18cc2c80): INVITE: processed SDP answer in 200
OK</p>
<p class="MsoPlainText">> nua(0x18cc2c80): event r_invite 200 OK</p>
<p class="MsoPlainText">> nua(0x18cc2c80): call state changed: calling ->
completing, received answer</p>
<p class="MsoPlainText">> soa_get_remote_sdp(static::0x2aaabc072a60,
[0x406f6828], [0x406f6820],</p>
<p class="MsoPlainText">> [(nil)]) called</p>
<p class="MsoPlainText">> soa_get_params(static::0x2aaabc072a60, ...) called</p>
<p class="MsoPlainText">> nua: nua_application_event: entering</p>
<p class="MsoPlainText">> nua(0x18cc2c80): event i_state 200 OK</p>
<p class="MsoPlainText">> 2011-04-12 15:29:06.294311 [INFO] sofia.c:740</p>
<p class="MsoPlainText">> sofia/internal/5596@226.59.129.221:5060 Update
Callee ID to "5596"</p>
<p class="MsoPlainText">> <sip:5596@226.59.129.221></p>
<p class="MsoPlainText">> nta: timer not set</p>
<p class="MsoPlainText">> tport_wakeup_pri(0x18c69750): events IN</p>
<p class="MsoPlainText">> tport_recv_event(0x18c69750)</p>
<p class="MsoPlainText">> tport_recv_iovec(0x18c69750) msg 0x2aaab4022620 from</p>
<p class="MsoPlainText">> (udp/226.59.129.223:5060) has 1235 bytes, veclen = 1</p>
<p class="MsoPlainText">> tport_deliver(0x18c69750): msg 0x2aaab4022620 (1235
bytes) from</p>
<p class="MsoPlainText">> udp/226.59.129.221:5060/sip next=(nil)</p>
<p class="MsoPlainText">> nta: received 200 OK for INVITE (10982209)</p>
<p class="MsoPlainText">> nta: 200 OK is going to a transaction</p>
<p class="MsoPlainText">> nta: 200 OK is duplicate response to 10982209 INVITE</p>
<p class="MsoPlainText">> Via:
SIP/2.0/UDP 226.59.129.223 ;branch=z9hG4bKa9ee6QBjeKFtg</p>
<p class="MsoPlainText">> nta: timer set next to 31009 ms</p>
<p class="MsoPlainText">> tport_wakeup_pri(0x18c69750): events IN</p>
<p class="MsoPlainText">> tport_recv_event(0x18c69750)</p>
<p class="MsoPlainText">> tport_recv_iovec(0x18c69750) msg 0x2aaab4022620 from</p>
<p class="MsoPlainText">> (udp/226.59.129.223:5060) has 1235 bytes, veclen = 1</p>
<p class="MsoPlainText">> tport_deliver(0x18c69750): msg 0x2aaab4022620 (1235
bytes) from</p>
<p class="MsoPlainText">> udp/226.59.129.221:5060/sip next=(nil)</p>
<p class="MsoPlainText">> nta: received 200 OK for INVITE (10982209)</p>
<p class="MsoPlainText">> nta: 200 OK is going to a transaction</p>
<p class="MsoPlainText">> nta: 200 OK is duplicate response to 10982209 INVITE</p>
<p class="MsoPlainText">> Via:
SIP/2.0/UDP 226.59.129.223 ;branch=z9hG4bKa9ee6QBjeKFtg</p>
<p class="MsoPlainText">> tport_wakeup_pri(0x18c69750): events IN</p>
<p class="MsoPlainText">> tport_recv_event(0x18c69750)</p>
<p class="MsoPlainText">> tport_recv_iovec(0x18c69750) msg 0x2aaaac060220 from</p>
<p class="MsoPlainText">> (udp/226.59.129.223:5060) has 1235 bytes, veclen = 1</p>
<p class="MsoPlainText">> tport_deliver(0x18c69750): msg 0x2aaaac060220 (1235
bytes) from</p>
<p class="MsoPlainText">> udp/226.59.129.221:5060/sip next=(nil)</p>
<p class="MsoPlainText">> nta: received 200 OK for INVITE (10982209)</p>
<p class="MsoPlainText">> nta: 200 OK is going to a transaction</p>
<p class="MsoPlainText">> nta: 200 OK is duplicate response to 10982209 INVITE</p>
<p class="MsoPlainText">> Via:
SIP/2.0/UDP 226.59.129.223 ;branch=z9hG4bKa9ee6QBjeKFtg</p>
<p class="MsoPlainText">> tport_wakeup_pri(0x18c69750): events IN</p>
<p class="MsoPlainText">> tport_recv_event(0x18c69750)</p>
<p class="MsoPlainText">> tport_recv_iovec(0x18c69750) msg 0x2aaaac060220 from</p>
<p class="MsoPlainText">> (udp/226.59.129.223:5060) has 1235 bytes, veclen = 1</p>
<p class="MsoPlainText">> tport_deliver(0x18c69750): msg 0x2aaaac060220 (1235
bytes) from</p>
<p class="MsoPlainText">> udp/226.59.129.221:5060/sip next=(nil)</p>
<p class="MsoPlainText">> nta: received 200 OK for INVITE (10982209)</p>
<p class="MsoPlainText">> nta: 200 OK is going to a transaction</p>
<p class="MsoPlainText">> nta: 200 OK is duplicate response to 10982209 INVITE</p>
<p class="MsoPlainText">> Via:
SIP/2.0/UDP 226.59.129.223 ;branch=z9hG4bKa9ee6QBjeKFtg</p>
<p class="MsoPlainText">> tport_wakeup_pri(0x18c69750): events IN</p>
<p class="MsoPlainText">> tport_recv_event(0x18c69750)</p>
<p class="MsoPlainText">> tport_recv_iovec(0x18c69750) msg 0x2aaaac060220 from</p>
<p class="MsoPlainText">> (udp/226.59.129.223:5060) has 1235 bytes, veclen = 1</p>
<p class="MsoPlainText">> tport_deliver(0x18c69750): msg 0x2aaaac060220 (1235
bytes) from</p>
<p class="MsoPlainText">> udp/226.59.129.221:5060/sip next=(nil)</p>
<p class="MsoPlainText">> nta: received 200 OK for INVITE (10982209)</p>
<p class="MsoPlainText">> nta: 200 OK is going to a transaction</p>
<p class="MsoPlainText">> nta: 200 OK is duplicate response to 10982209 INVITE</p>
<p class="MsoPlainText">> Via:
SIP/2.0/UDP 226.59.129.223 ;branch=z9hG4bKa9ee6QBjeKFtg</p>
<p class="MsoPlainText">> tport_wakeup_pri(0x18c69750): events IN</p>
<p class="MsoPlainText">> tport_recv_event(0x18c69750)</p>
<p class="MsoPlainText">> tport_recv_iovec(0x18c69750) msg 0x18c87620 from
(udp/226.59.129.223:5060)</p>
<p class="MsoPlainText">> has 1235 bytes, veclen = 1</p>
<p class="MsoPlainText">> tport_deliver(0x18c69750): msg 0x18c87620 (1235
bytes) from</p>
<p class="MsoPlainText">> udp/226.59.129.221:5060/sip next=(nil)</p>
<p class="MsoPlainText">> nta: received 200 OK for INVITE (10982209)</p>
<p class="MsoPlainText">> nta: 200 OK is going to a transaction</p>
<p class="MsoPlainText">> nta: 200 OK is duplicate response to 10982209 INVITE</p>
<p class="MsoPlainText">> Via:
SIP/2.0/UDP 226.59.129.223 ;branch=z9hG4bKa9ee6QBjeKFtg</p>
<p class="MsoPlainText">> tport_wakeup_pri(0x18c69750): events IN</p>
<p class="MsoPlainText">> tport_recv_event(0x18c69750)</p>
<p class="MsoPlainText">> tport_recv_iovec(0x18c69750) msg 0x18c87620 from
(udp/226.59.129.223:5060)</p>
<p class="MsoPlainText">> has 1235 bytes, veclen = 1</p>
<p class="MsoPlainText">> tport_deliver(0x18c69750): msg 0x18c87620 (1235
bytes) from</p>
<p class="MsoPlainText">> udp/226.59.129.221:5060/sip next=(nil)</p>
<p class="MsoPlainText">> nta: received 200 OK for INVITE (10982209)</p>
<p class="MsoPlainText">> nta: 200 OK is going to a transaction</p>
<p class="MsoPlainText">> nta: 200 OK is duplicate response to 10982209 INVITE</p>
<p class="MsoPlainText">> Via:
SIP/2.0/UDP 226.59.129.223 ;branch=z9hG4bKa9ee6QBjeKFtg</p>
<p class="MsoPlainText">> tport_wakeup_pri(0x18c69750): events IN</p>
<p class="MsoPlainText">> tport_recv_event(0x18c69750)</p>
<p class="MsoPlainText">> tport_recv_iovec(0x18c69750) msg 0x18c87620 from
(udp/226.59.129.223:5060)</p>
<p class="MsoPlainText">> has 1235 bytes, veclen = 1</p>
<p class="MsoPlainText">> tport_deliver(0x18c69750): msg 0x18c87620 (1235
bytes) from</p>
<p class="MsoPlainText">> udp/226.59.129.221:5060/sip next=(nil)</p>
<p class="MsoPlainText">> nta: received 200 OK for INVITE (10982209)</p>
<p class="MsoPlainText">> nta: 200 OK is going to a transaction</p>
<p class="MsoPlainText">> nta: 200 OK is duplicate response to 10982209 INVITE</p>
<p class="MsoPlainText">> Via:
SIP/2.0/UDP 226.59.129.223 ;branch=z9hG4bKa9ee6QBjeKFtg</p>
<p class="MsoPlainText">> tport_wakeup_pri(0x18c4dcc0): events IN</p>
<p class="MsoPlainText">> tport_recv_event(0x18c4dcc0)</p>
<p class="MsoPlainText">> tport_recv_iovec(0x18c4dcc0) msg 0x2aaaac060220 from</p>
<p class="MsoPlainText">> (udp/226.59.129.223:5080) has 876 bytes, veclen = 1</p>
<p class="MsoPlainText">> tport_deliver(0x18c4dcc0): msg 0x2aaaac060220 (876
bytes) from</p>
<p class="MsoPlainText">> udp/226.59.139.61:5080/sip next=(nil)</p>
<p class="MsoPlainText">> nta: received INVITE
sip:5025155596@fs2000.lightyear.net SIP/2.0 (CSeq 1)</p>
<p class="MsoPlainText">> nta: INVITE (1) going to existing INVITE transaction</p>
<p class="MsoPlainText">> nta: re-received INVITE request, retransmitting 100
reply</p>
<p class="MsoPlainText">> tport_tsend(0x18c4dcc0) tpn = UDP/226.59.139.61:5060</p>
<p class="MsoPlainText">> tport_resolve addrinfo = 226.59.139.61:5060</p>
<p class="MsoPlainText">> tport_by_addrinfo(0x18c4dcc0): not found by name
UDP/226.59.139.61:5060</p>
<p class="MsoPlainText">> tport_vsend(0x18c4dcc0): 397 bytes of 397 to
udp/226.59.139.61:5060</p>
<p class="MsoPlainText">> tport_vsend returned 397</p>
<p class="MsoPlainText">> tport_wakeup_pri(0x18c69750): events IN</p>
<p class="MsoPlainText">> tport_recv_event(0x18c69750)</p>
<p class="MsoPlainText">> tport_recv_iovec(0x18c69750) msg 0x18c87620 from
(udp/226.59.129.223:5060)</p>
<p class="MsoPlainText">> has 1235 bytes, veclen = 1</p>
<p class="MsoPlainText">> tport_deliver(0x18c69750): msg 0x18c87620 (1235
bytes) from</p>
<p class="MsoPlainText">> udp/226.59.129.221:5060/sip next=(nil)</p>
<p class="MsoPlainText">> nta: received 200 OK for INVITE (10982209)</p>
<p class="MsoPlainText">> nta: 200 OK is going to a transaction</p>
<p class="MsoPlainText">> nta: 200 OK is duplicate response to 10982209 INVITE</p>
<p class="MsoPlainText">> Via:
SIP/2.0/UDP 226.59.129.223 ;branch=z9hG4bKa9ee6QBjeKFtg</p>
<p class="MsoPlainText">> tport_wakeup_pri(0x18c69750): events IN</p>
<p class="MsoPlainText">> tport_recv_event(0x18c69750)</p>
<p class="MsoPlainText">> tport_recv_iovec(0x18c69750) msg 0x18c87620 from
(udp/226.59.129.223:5060)</p>
<p class="MsoPlainText">> has 1235 bytes, veclen = 1</p>
<p class="MsoPlainText">> tport_deliver(0x18c69750): msg 0x18c87620 (1235
bytes) from</p>
<p class="MsoPlainText">> udp/226.59.129.221:5060/sip next=(nil)</p>
<p class="MsoPlainText">> nta: received 200 OK for INVITE (10982209)</p>
<p class="MsoPlainText">> nta: 200 OK is going to a transaction</p>
<p class="MsoPlainText">> nta: 200 OK is duplicate response to 10982209 INVITE</p>
<p class="MsoPlainText">> Via:
SIP/2.0/UDP 226.59.129.223 ;branch=z9hG4bKa9ee6QBjeKFtg</p>
<p class="MsoPlainText">> 2011-04-12 15:29:36.002806 [DEBUG]
switch_channel.c:2563</p>
<p class="MsoPlainText">> (sofia/internal/5596@226.59.129.221:5060) Callstate
Change RINGING -> HANGUP</p>
<p class="MsoPlainText">> 2011-04-12 15:29:36.002806 [NOTICE]
switch_ivr_originate.c:3329 Hangup</p>
<p class="MsoPlainText">> sofia/internal/5596@226.59.129.221:5060
[CS_CONSUME_MEDIA] [NO_ANSWER]</p>
<p class="MsoPlainText">> 2011-04-12 15:29:36.002806 [DEBUG]
switch_channel.c:2579 Send signal</p>
<p class="MsoPlainText">> sofia/internal/5596@226.59.129.221:5060 [KILL]</p>
<p class="MsoPlainText">> 2011-04-12 15:29:36.002806 [DEBUG]
switch_core_session.c:1116 Send signal</p>
<p class="MsoPlainText">> sofia/internal/5596@226.59.129.221:5060 [BREAK]</p>
<p class="MsoPlainText">> 2011-04-12 15:29:36.002806 [INFO] mod_dptools.c:2647
Originate Failed.</p>
<p class="MsoPlainText">> Cause: NO_ANSWER</p>
<p class="MsoPlainText">> 2011-04-12 15:29:36.002806 [DEBUG]
switch_cpp.cpp:988</p>
<p class="MsoPlainText">> sofia/external/1009@fs2000.lightyear.net
destroy/unlink session from object</p>
<p class="MsoPlainText">> 2011-04-12 15:29:36.002806 [DEBUG]
switch_core_state_machine.c:325</p>
<p class="MsoPlainText">> (sofia/internal/5596@226.59.129.221:5060) Running
State Change CS_HANGUP</p>
<p class="MsoPlainText">> 2011-04-12 15:29:36.002806 [DEBUG]
switch_core_state_machine.c:565</p>
<p class="MsoPlainText">> (sofia/internal/5596@226.59.129.221:5060) State
HANGUP</p>
<p class="MsoPlainText">> EXECUTE sofia/external/1009@fs2000.lightyear.net
answer()</p>
<p class="MsoPlainText">> 2011-04-12 15:29:36.003897 [DEBUG] sofia_glue.c:3014
AUDIO RTP</p>
<p class="MsoPlainText">> [sofia/external/1009@fs2000.lightyear.net]
226.59.129.223 port 27272 -></p>
<p class="MsoPlainText">> 226.59.139.61 port 49420 codec: 3 ms: 20</p>
<p class="MsoPlainText">> nua: nua_handle_magic: entering</p>
<p class="MsoPlainText">> 2011-04-12 15:29:36.003897 [DEBUG] switch_rtp.c:1623
Starting timer [soft]</p>
<p class="MsoPlainText">> 160 bytes per 20ms</p>
<p class="MsoPlainText">> nua: nua_application_event: entering</p>
<p class="MsoPlainText">> 2011-04-12 15:29:36.003897 [DEBUG] mod_sofia.c:457
Channel</p>
<p class="MsoPlainText">> sofia/internal/5596@226.59.129.221:5060 hanging up,
cause: NO_ANSWER</p>
<p class="MsoPlainText">> 2011-04-12 15:29:36.004809 [DEBUG] sofia_glue.c:3276
Set 2833 dtmf send</p>
<p class="MsoPlainText">> payload to 101</p>
<p class="MsoPlainText">> 2011-04-12 15:29:36.004809 [DEBUG] sofia_glue.c:3281
Set 2833 dtmf receive</p>
<p class="MsoPlainText">> payload to 101</p>
<p class="MsoPlainText">> 2011-04-12 15:29:36.004809 [DEBUG] mod_sofia.c:681
Local SDP</p>
<p class="MsoPlainText">> sofia/external/1009@fs2000.lightyear.net:</p>
<p class="MsoPlainText">> v=0</p>
<p class="MsoPlainText">> o=FreeSWITCH 1302609304 1302609305 IN IP4 226.59.129.223</p>
<p class="MsoPlainText">> s=FreeSWITCH</p>
<p class="MsoPlainText">> c=IN IP4 226.59.129.223</p>
<p class="MsoPlainText">> </p>
<p class="MsoPlainText">> </p>
<p class="MsoPlainText">> </p>
<p class="MsoPlainText">> </p>
<p class="MsoPlainText">> </p>
<p class="MsoPlainText">> _______________________________________________</p>
<p class="MsoPlainText">> FreeSWITCH-users mailing list</p>
<p class="MsoPlainText">> FreeSWITCH-users@lists.freeswitch.org</p>
<p class="MsoPlainText">>
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users</p>
<p class="MsoPlainText">> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users</p>
<p class="MsoPlainText">> http://www.freeswitch.org</p>
<p class="MsoPlainText">> </p>
<p class="MsoPlainText">> </p>
<p class="MsoPlainText"> </p>
<p class="MsoPlainText"> </p>
<p class="MsoPlainText"> </p>
<p class="MsoPlainText">-- </p>
<p class="MsoPlainText">Anthony Minessale II</p>
<p class="MsoPlainText"> </p>
<p class="MsoPlainText">FreeSWITCH http://www.freeswitch.org/</p>
<p class="MsoPlainText">ClueCon http://www.cluecon.com/</p>
<p class="MsoPlainText">Twitter: http://twitter.com/FreeSWITCH_wire</p>
<p class="MsoPlainText"> </p>
<p class="MsoPlainText">AIM: anthm</p>
<p class="MsoPlainText">MSN:anthony_minessale@hotmail.com</p>
<p class="MsoPlainText">GTALK/JABBER/PAYPAL:anthony.minessale@gmail.com</p>
<p class="MsoPlainText">IRC: irc.freenode.net #freeswitch</p>
<p class="MsoPlainText"> </p>
<p class="MsoPlainText">FreeSWITCH Developer Conference</p>
<p class="MsoPlainText">sip:888@conference.freeswitch.org</p>
<p class="MsoPlainText">googletalk:conf+888@conference.freeswitch.org</p>
<p class="MsoPlainText">pstn:+19193869900</p>
<p class="MsoPlainText"> </p>
<p class="MsoPlainText">_______________________________________________</p>
<p class="MsoPlainText">FreeSWITCH-users mailing list</p>
<p class="MsoPlainText">FreeSWITCH-users@lists.freeswitch.org</p>
<p class="MsoPlainText">http://lists.freeswitch.org/mailman/listinfo/freeswitch-users</p>
<p class="MsoPlainText">UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users</p>
<p class="MsoPlainText">http://www.freeswitch.org</p>