[Freeswitch-users] FS does not repose to SIP OK message.

Gary Chen gchen00 at insightbb.com
Wed Apr 13 16:33:15 MSD 2011



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 at fs2000.lightyear.net SIP/2.0..Via: SIP/2.0/UDP 226.59.139.61;branch=z9hG4bKd8318b3d000007494da459a
  900006fac00000273;rport..From: "unknown" <sip:1009 at fs2000.lightyear.net>;tag=a344c583a24..To: <sip:5025155596 at fs2000.lig
  htyear.net>..Contact: <sip:1009 at 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 at fs2000.lightyear.net>;tag=a344c583a24..To: <sip:5025155596 at 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 at 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 at 226.59.129.223>;tag=KQKpXe0m39X6e..To: <sip:5596 at 226.59.129.221:5060>..Call-ID: 4639
  3d91-dfaf-122e-d1b0-e9d83e422dc6..CSeq: 10972180 INVITE..Contact: <sip:mod_sofia at 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 at 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 at 21
  6.49.129.223>;tag=KQKpXe0m39X6e..To: <sip:5596 at 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 at 226.59
  .129.223>;tag=KQKpXe0m39X6e..To: <sip:5596 at 226.59.129.221:5060>;tag=7r593SDymv51S..Call-ID: 46393d91-dfaf-122e-d1b0-e9d8
  3e422dc6..CSeq: 10972180 INVITE..Contact: <sip:5596 at 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 at 226.59.129.221..X-FS-Support: update_display..Remote-Party-ID: "5596" <sip:5596 at 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 at 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 at 226.59.129.223>;tag=KQKpXe0m39X6e..To: <sip:5596 at 226.59.129.221:5060>;tag
  =7r593SDymv51S..Call-ID: 46393d91-dfaf-122e-d1b0-e9d83e422dc6..CSeq: 10972180 ACK..Contact: <sip:mod_sofia at 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 at fs2000.lightyear.net>;tag=a344c583a24..To: <sip:5025155596 at fs2000.lightyear.net>;tag=KB4NB13DD35eg..
  Call-ID: 56BF61B3189F4EDEB8C9067D5823C8480xd8318b3d..CSeq: 1 INVITE..Contact: <sip:5025155596 at 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 at 226.59.129.221..X-FS-Support: update_display..Remote-Par
  ty-ID: "5596" <sip:5596 at 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 at 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 at fs2000.lightyear.net>;tag=a344c583a24..To: <sip:5025155596@
  fs2000.lightyear.net>;tag=KB4NB13DD35eg..Contact: <sip:1009 at 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 at 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 at 226.59.129.223>;tag=KQKpXe0m39X6e..To: <sip:5596 at 226.59.129.221:5060>;ta
  g=7r593SDymv51S..Call-ID: 46393d91-dfaf-122e-d1b0-e9d83e422dc6..CSeq: 10972181 INFO..Contact: <sip:mod_sofia at 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 at 226.59
  .129.223>;tag=KQKpXe0m39X6e..To: <sip:5596 at 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 at 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 at fs2000.lightyear.net>;tag=a344c583a24..To: <sip:5025155596@
  fs2000.lightyear.net>;tag=KB4NB13DD35eg..Contact: <sip:1009 at 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 at fs2000.lightyear.net>;tag=a344c583a24..To: <sip:5025155596 at 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 at 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 at 226.59.129.223>;tag=KQKpXe0m39X6e..To: <sip:5596 at 226.59.129.221:5060>;tag
  =7r593SDymv51S..Call-ID: 46393d91-dfaf-122e-d1b0-e9d83e422dc6..CSeq: 10972182 BYE..Contact: <sip:mod_sofia at 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 at 226.59
  .129.223>;tag=KQKpXe0m39X6e..To: <sip:5596 at 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 at fs2000.lightyear.net SIP/2.0..Via: SIP/2.0/UDP 226.59.139.61;branch=z9hG4bKd8318b3d000007324da4586
  e0000031200000260;rport..From: "unknown" <sip:1009 at fs2000.lightyear.net>;tag=60654c536a55..To: <sip:5025155596 at fs2000.li
  ghtyear.net>..Contact: <sip:1009 at 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 at fs2000.lightyear.net>;tag=60654c536a55..To: <sip:5025155596 at 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 at 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 at 226.59.129.223>;tag=gv7BrXDacFUec..To: <sip:5596 at 226.59.129.221:5060>..Call-ID: 8a42
  e048-dfae-122e-d1b0-e9d83e422dc6..CSeq: 10972023 INVITE..Contact: <sip:mod_sofia at 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 at 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 at 21
  6.49.129.223>;tag=gv7BrXDacFUec..To: <sip:5596 at 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 at 226.59
  .129.223>;tag=gv7BrXDacFUec..To: <sip:5596 at 226.59.129.221:5060>;tag=4XSZy8tKX129p..Call-ID: 8a42e048-dfae-122e-d1b0-e9d8
  3e422dc6..CSeq: 10972023 INVITE..Contact: <sip:5596 at 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 at 226.59.129.221..X-FS-Support: update_display..Remote-Party-ID: "5596" <sip:5596 at 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 at 226.59
  .129.223>;tag=gv7BrXDacFUec..To: <sip:5596 at 226.59.129.221:5060>;tag=4XSZy8tKX129p..Call-ID: 8a42e048-dfae-122e-d1b0-e9d8
  3e422dc6..CSeq: 10972023 INVITE..Contact: <sip:5596 at 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 at 226.59.129.221..X-FS-Support: update_display..Remote-Party-ID: "5596" <sip:5596 at 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 at 226.59
  .129.223>;tag=gv7BrXDacFUec..To: <sip:5596 at 226.59.129.221:5060>;tag=4XSZy8tKX129p..Call-ID: 8a42e048-dfae-122e-d1b0-e9d8
  3e422dc6..CSeq: 10972023 INVITE..Contact: <sip:5596 at 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 at 226.59.129.221..X-FS-Support: update_display..Remote-Party-ID: "5596" <sip:5596 at 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 at 226.59
  .129.223>;tag=gv7BrXDacFUec..To: <sip:5596 at 226.59.129.221:5060>;tag=4XSZy8tKX129p..Call-ID: 8a42e048-dfae-122e-d1b0-e9d8
  3e422dc6..CSeq: 10972023 INVITE..Contact: <sip:5596 at 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 at 226.59.129.221..X-FS-Support: update_display..Remote-Party-ID: "5596" <sip:5596 at 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 at 226.59
  .129.223>;tag=gv7BrXDacFUec..To: <sip:5596 at 226.59.129.221:5060>;tag=4XSZy8tKX129p..Call-ID: 8a42e048-dfae-122e-d1b0-e9d8
  3e422dc6..CSeq: 10972023 INVITE..Contact: <sip:5596 at 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 at 226.59.129.221..X-FS-Support: update_display..Remote-Party-ID: "5596" <sip:5596 at 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 at 226.59
  .129.223>;tag=gv7BrXDacFUec..To: <sip:5596 at 226.59.129.221:5060>;tag=4XSZy8tKX129p..Call-ID: 8a42e048-dfae-122e-d1b0-e9d8
  3e422dc6..CSeq: 10972023 INVITE..Contact: <sip:5596 at 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 at 226.59.129.221..X-FS-Support: update_display..Remote-Party-ID: "5596" <sip:5596 at 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 at 226.59
  .129.223>;tag=gv7BrXDacFUec..To: <sip:5596 at 226.59.129.221:5060>;tag=4XSZy8tKX129p..Call-ID: 8a42e048-dfae-122e-d1b0-e9d8
  3e422dc6..CSeq: 10972023 INVITE..Contact: <sip:5596 at 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 at 226.59.129.221..X-FS-Support: update_display..Remote-Party-ID: "5596" <sip:5596 at 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 at lists.freeswitch.org
[mailto:freeswitch-users-bounces at 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 at 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 at 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 at 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 at 226.59.129.221:5060 [BREAK]

> 2011-04-12 15:29:06.285484 [DEBUG]
switch_core_state_machine.c:361

> (sofia/internal/5596 at 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 at 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 at 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 at 226.59.129.221:5060) State
ROUTING

> 2011-04-12 15:29:06.285484 [DEBUG] mod_sofia.c:147

> sofia/internal/5596 at 226.59.129.221:5060 SOFIA
ROUTING

> 2011-04-12 15:29:06.285484 [DEBUG]
switch_ivr_originate.c:66

> (sofia/internal/5596 at 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 at 226.59.129.221:5060 [BREAK]

> 2011-04-12 15:29:06.285484 [DEBUG]
switch_core_state_machine.c:364

> (sofia/internal/5596 at 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 at 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 at 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 at 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 at 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 at 226.59.129.221:5060 Update
Callee ID to "5596"

> <sip:5596 at 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 at 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 at 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 at 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 at 226.59.129.221:5060 [KILL]

> 2011-04-12 15:29:36.002806 [DEBUG]
switch_core_session.c:1116 Send signal

> sofia/internal/5596 at 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 at 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 at 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 at 226.59.129.221:5060) State
HANGUP

> EXECUTE sofia/external/1009 at fs2000.lightyear.net
answer()

> 2011-04-12 15:29:36.003897 [DEBUG] sofia_glue.c:3014
AUDIO RTP

> [sofia/external/1009 at 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 at 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 at 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 at 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 at hotmail.com

GTALK/JABBER/PAYPAL:anthony.minessale at gmail.com

IRC: irc.freenode.net #freeswitch

 

FreeSWITCH Developer Conference

sip:888 at conference.freeswitch.org

googletalk:conf+888 at conference.freeswitch.org

pstn:+19193869900

 

_______________________________________________

FreeSWITCH-users mailing list

FreeSWITCH-users at lists.freeswitch.org

http://lists.freeswitch.org/mailman/listinfo/freeswitch-users

UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users

http://www.freeswitch.org


-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20110413/1ba22d1d/attachment-0001.html 


More information about the FreeSWITCH-users mailing list