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