Gina, the call in this trace doesn't connect, because the neighbor isn't answering at all. Its not even rejecting the call. The first thing I would check is if there is a firewall blocking traffic to or from the neighbor. Maybe the firewall only has a rule for your old Cisco gatekeeper ? The next thing would be to check if the neighbor is configured correctly and knows about its new neighbor or maybe expecting a password, but in the later case you would usually get a LocationReject. Regards, Jan Gina Bull wrote: > I haven't had any luck. Recompiling the latest version for our ubuntu environment has not gone well. > :-( We're still working on that. > > I did, however, try something else with our current version. I removed all but one gatekeeper from > the Neighbor section. That should eliminate the replies-from-different-IPs problem. Again, the > connection when registered to the GnuGK did not work, but that pesky Cisco gatekeeper did. GDS calls > to three Internet 2 test sites did work. > > I've attached the log of the failed call and most of the configuration file (deleted the FileAuth > section, primarily). > > If you see anything, I'll be eternally grateful. (Really, really want to get rid of that Cisco box ...) > > Gina > > ---------- > Gina Bull > Network System Engineer > University of Virginia - ITS Network Systems > PO Box 400324, 2015 Ivy Road, Charlottesville, VA 22904 > > > > > On 11/8/2011 4:39 PM, Gina Bull wrote: > > > > Thanks! I will upgrade and report back. > > > > Gina > > > > On 11/8/2011 4:32 PM, Jan Willamowius wrote: > >> Gina, > >> > >> your trace shows that your GnuGk 2.3.0 gives up on the call after > >> receiving the LocationReject from GK2 (207.x.x.x) and doesn't wait for > >> an answer from GK1 (192.x.x.x). Is GK1 where the call is supposed to > >> connect ? > >> > >> That seems like a bug and I don't see that behaviour when I test with a > >> GnuGk 2.3.5 here. Please try again with GnuGk 2.3.5 and let us know if > >> that fixed the problem. > >> > >> Regards, > >> Jan > >> > >> Gina Bull wrote: > >>> Rats. I put in the "AcceptNonNeighborLRQ=1" and changed the "always" to "depends" but the connection > >>> still gets refused. And the Cisco still works (I used it to confirm that the site is still up. I'm > >>> really starting to resent that box ...) > >>> > >>> I'm appending a piece of the log from yesterday that shows what I think are the relevant entries. It > >>> looks to me like the Internet 2 gatekeeper is responding "not registered". (But the same GDS call > >>> when registered to the old Cisco worked.) > >>> > >>> (Also -- I'm getting help from our "master sysadmin" to get v.2.3.5 available so I can try that) > >>> > >>> Gina > >>> ---------- > >>> Gina Bull > >>> Network System Engineer > >>> University of Virginia - ITS Network Systems > >>> PO Box 400324, 2015 Ivy Road, Charlottesville, VA 22904 > >>> > >>> > >>> > >>> Caller is 128.143.13.168 > >>> Destination is 0018128554673 via GDS > >>> This gatekeeper name is UVaGK1 (128.143.2.121) > >>> > >>> 2011/11/07 09:16:10.247 5 RasSrv.cxx(264) RAS Sent Successful > >>> 2011/11/07 09:16:10.247 5 job.cxx(427) JOB Job RRQ deleted > >>> 2011/11/07 09:16:10.247 5 job.cxx(416) JOB Worker threads: 5 total - 3 busy, 2 idle > >>> 2011/11/07 09:16:12.844 5 yasocket.cxx(782) RasSrv 1 sockets selected from 4, total 4/0 > >>> 2011/11/07 09:16:12.844 4 RasSrv.cxx(230) RAS Receiving on 128.143.2.121:1719(U) > >>> 2011/11/07 09:16:12.844 2 RasSrv.cxx(189) RAS Read from 128.143.13.168:1719 > >>> 2011/11/07 09:16:12.844 3 RasSrv.cxx(238) RAS > >>> admissionRequest { > >>> requestSeqNum = 32778 > >>> callType = pointToPoint<<null>> > >>> callModel = gatekeeperRouted<<null>> > >>> endpointIdentifier = 16 characters { > >>> 0038 0031 0043 0038 0042 0043 0038 0043 81C8BC8C > >>> 0030 0030 0030 0030 0030 0030 0030 0041 0000000A > >>> } > >>> destinationInfo = 1 entries { > >>> [0]=dialedDigits "0018128554673" > >>> } > >>> srcInfo = 2 entries { > >>> [0]=h323_ID 12 characters { > >>> 0054 0045 0053 0054 0020 0049 0054 0053 TEST ITS > >>> 0020 0050 0056 0058 PVX > >>> } > >>> [1]=dialedDigits "00117995371" > >>> } > >>> bandWidth = 7680 > >>> callReferenceValue = 27920 > >>> conferenceID = 16 octets { > >>> 02 35 50 55 05 0b de 1c 14 c6 d3 10 09 0e 2f 3d .5PU........../= > >>> } > >>> activeMC = FALSE > >>> answerCall = FALSE > >>> canMapAlias = TRUE > >>> callIdentifier = { > >>> guid = 16 octets { > >>> 02 35 50 55 05 0b de 1c 14 c5 d3 10 09 0e 2f 3d .5PU........../= > >>> } > >>> } > >>> gatekeeperIdentifier = 6 characters { > >>> 0055 0056 0061 0047 004b 0031 UVaGK1 > >>> } > >>> willSupplyUUIEs = FALSE > >>> featureSet = { > >>> replacementFeatureSet = FALSE > >>> supportedFeatures = 1 entries { > >>> [0]={ > >>> id = standard 9 > >>> } > >>> } > >>> } > >>> } > >>> 2011/11/07 09:16:12.844 5 job.cxx(364) JOB Worker threads: 5 total - 4 busy, 1 idle > >>> 2011/11/07 09:16:12.844 5 job.cxx(190) JOB Starting Job ARQ at Worker thread > >>> 139841453688576 > >>> 2011/11/07 09:16:12.845 1 RasSrv.cxx(368) RAS ARQ Received from 128.143.13.168:1719 > >>> 2011/11/07 09:16:12.845 3 RasSrv.cxx(2466) GK ARQ will request bandwith of 7680 > >>> 2011/11/07 09:16:12.845 5 Routing.h(177) ROUTING Checking policy Neighbor for the > >>> request ARQ 32778 > >>> 2011/11/07 09:16:12.845 3 RasSrv.cxx(250) RAS Send to 192.148.244.130:1719 > >>> locationRequest { > >>> requestSeqNum = 1229 > >>> destinationInfo = 1 entries { > >>> [0]=dialedDigits "0018128554673" > >>> } > >>> nonStandardData = { > >>> nonStandardIdentifier = h221NonStandard { > >>> t35CountryCode = 181 > >>> t35Extension = 0 > >>> manufacturerCode = 18 > >>> } > >>> data = 57 octets { > >>> 82 03 90 11 00 02 35 50 55 05 0b de 1c 14 c5 d3 ......5PU....... > >>> 10 09 0e 2f 3d 23 02 40 0b 00 54 00 45 00 53 00 .../=#.@..T.E.S. > >>> 54 00 20 00 49 00 54 00 53 00 20 00 50 00 56 00 T. .I.T.S. .P.V. > >>> 58 05 00 33 44 ac c8 6a 40 X..3D..j@ > >>> } > >>> } > >>> replyAddress = ipAddress { > >>> ip = 4 octets { > >>> 80 8f 02 79 ...y > >>> } > >>> port = 1719 > >>> } > >>> sourceInfo = 1 entries { > >>> [0]=h323_ID 6 characters { > >>> 0055 0056 0061 0047 004b 0031 UVaGK1 > >>> } > >>> } > >>> canMapAlias = TRUE > >>> hopCount = 5 > >>> canMapSrcAlias = FALSE > >>> } > >>> 2011/11/07 09:16:12.845 5 RasSrv.cxx(264) RAS Sent Successful > >>> 2011/11/07 09:16:12.845 3 RasSrv.cxx(250) RAS Send to 207.75.164.39:1719 > >>> locationRequest { > >>> requestSeqNum = 1229 > >>> destinationInfo = 1 entries { > >>> [0]=dialedDigits "0018128554673" > >>> } > >>> nonStandardData = { > >>> nonStandardIdentifier = h221NonStandard { > >>> t35CountryCode = 181 > >>> t35Extension = 0 > >>> manufacturerCode = 18 > >>> } > >>> data = 57 octets { > >>> 82 03 90 11 00 02 35 50 55 05 0b de 1c 14 c5 d3 ......5PU....... > >>> 10 09 0e 2f 3d 23 02 40 0b 00 54 00 45 00 53 00 .../=#.@..T.E.S. > >>> 54 00 20 00 49 00 54 00 53 00 20 00 50 00 56 00 T. .I.T.S. .P.V. > >>> 58 05 00 33 44 ac c8 6a 40 X..3D..j@ > >>> } > >>> } > >>> replyAddress = ipAddress { > >>> ip = 4 octets { > >>> 80 8f 02 79 ...y > >>> } > >>> port = 1719 > >>> } > >>> sourceInfo = 1 entries { > >>> [0]=h323_ID 6 characters { > >>> 0055 0056 0061 0047 004b 0031 UVaGK1 > >>> } > >>> } > >>> canMapAlias = TRUE > >>> hopCount = 5 > >>> canMapSrcAlias = FALSE > >>> } > >>> 2011/11/07 09:16:12.845 5 RasSrv.cxx(264) RAS Sent Successful > >>> 2011/11/07 09:16:12.845 2 Neighbor.cxx(916) NB 2 LRQ(s) sent > >>> 2011/11/07 09:16:12.863 5 yasocket.cxx(782) RasSrv 1 sockets selected from 4, total 4/0 > >>> 2011/11/07 09:16:12.863 4 RasSrv.cxx(230) RAS Receiving on 128.143.2.121:1719(U) > >>> 2011/11/07 09:16:12.863 2 RasSrv.cxx(189) RAS Read from 207.75.164.39:1719 > >>> 2011/11/07 09:16:12.863 3 RasSrv.cxx(238) RAS > >>> requestInProgress { > >>> requestSeqNum = 1229 > >>> delay = 60000 > >>> } > >>> 2011/11/07 09:16:12.863 2 RasSrv.cxx(1393) RAS Trapped RIP > >>> 2011/11/07 09:16:12.863 5 Neighbor.cxx(963) NB Received RIP message matched pending > >>> LRQ for neighbor GK1:192.148.244.130 > >>> 2011/11/07 09:16:12.986 5 yasocket.cxx(782) RasSrv 1 sockets selected from 4, total 4/0 > >>> 2011/11/07 09:16:12.986 4 RasSrv.cxx(230) RAS Receiving on 128.143.2.121:1719(U) > >>> 2011/11/07 09:16:12.987 2 RasSrv.cxx(189) RAS Read from 207.75.164.39:1719 > >>> 2011/11/07 09:16:12.987 3 RasSrv.cxx(238) RAS > >>> locationReject { > >>> requestSeqNum = 1229 > >>> rejectReason = notRegistered<<null>> > >>> genericData = 1 entries { > >>> [0]={ > >>> id = nonStandard 16 octets { > >>> 65 cd 7b 8a dc 67 11 db be d4 00 12 3f 63 4b 1d e.{..g......?cK. > >>> } > >>> parameters = 1 entries { > >>> [0]={ > >>> id = nonStandard 16 octets { > >>> 65 cd 7b 8b dc 67 11 db be d4 00 12 3f 63 4b 1d e.{..g......?cK. > >>> } > >>> content = number32 18 > >>> } > >>> } > >>> } > >>> } > >>> } > >>> 2011/11/07 09:16:12.987 2 RasSrv.cxx(1393) RAS Trapped LRJ > >>> 2011/11/07 09:16:12.987 5 Neighbor.cxx(963) NB Received LRJ message matched pending > >>> LRQ for neighbor GK1:192.148.244.130 > >>> 2011/11/07 09:16:12.987 5 Neighbor.cxx(998) NB LRQ rejected for neighbor > >>> GK1:192.148.244.130 > >>> 2011/11/07 09:16:17.844 5 yasocket.cxx(782) RasSrv 1 sockets selected from 4, total 4/0 > >>> 2011/11/07 09:16:17.845 4 RasSrv.cxx(230) RAS Receiving on 128.143.2.121:1719(U) > >>> 2011/11/07 09:16:17.845 2 RasSrv.cxx(189) RAS Read from 128.143.13.168:1719 > >>> 2011/11/07 09:16:17.845 3 RasSrv.cxx(238) RAS > >>> admissionRequest { > >>> requestSeqNum = 32778 > >>> callType = pointToPoint<<null>> > >>> callModel = gatekeeperRouted<<null>> > >>> endpointIdentifier = 16 characters { > >>> 0038 0031 0043 0038 0042 0043 0038 0043 81C8BC8C > >>> 0030 0030 0030 0030 0030 0030 0030 0041 0000000A > >>> } > >>> destinationInfo = 1 entries { > >>> [0]=dialedDigits "0018128554673" > >>> } > >>> srcInfo = 2 entries { > >>> [0]=h323_ID 12 characters { > >>> 0054 0045 0053 0054 0020 0049 0054 0053 TEST ITS > >>> 0020 0050 0056 0058 PVX > >>> } > >>> [1]=dialedDigits "00117995371" > >>> } > >>> bandWidth = 7680 > >>> callReferenceValue = 27920 > >>> conferenceID = 16 octets { > >>> 02 35 50 55 05 0b de 1c 14 c6 d3 10 09 0e 2f 3d .5PU........../= > >>> } > >>> activeMC = FALSE > >>> answerCall = FALSE > >>> canMapAlias = TRUE > >>> callIdentifier = { > >>> guid = 16 octets { > >>> 02 35 50 55 05 0b de 1c 14 c5 d3 10 09 0e 2f 3d .5PU........../= > >>> } > >>> } > >>> gatekeeperIdentifier = 6 characters { > >>> 0055 0056 0061 0047 004b 0031 UVaGK1 > >>> } > >>> willSupplyUUIEs = FALSE > >>> featureSet = { > >>> replacementFeatureSet = FALSE > >>> supportedFeatures = 1 entries { > >>> [0]={ > >>> id = standard 9 > >>> } > >>> } > >>> } > >>> } > >>> 2011/11/07 09:16:17.845 2 RasSrv.cxx(1382) RAS Duplicate ARQ, deleted > >>> 2011/11/07 09:16:22.840 5 yasocket.cxx(782) RasSrv 1 sockets selected from 4, total 4/0 > >>> 2011/11/07 09:16:22.840 4 RasSrv.cxx(230) RAS Receiving on 128.143.2.121:1719(U) > >>> 2011/11/07 09:16:22.840 2 RasSrv.cxx(189) RAS Read from 128.143.13.168:1719 > >>> 2011/11/07 09:16:22.840 3 RasSrv.cxx(238) RAS > >>> admissionRequest { > >>> requestSeqNum = 32778 > >>> callType = pointToPoint<<null>> > >>> callModel = gatekeeperRouted<<null>> > >>> endpointIdentifier = 16 characters { > >>> 0038 0031 0043 0038 0042 0043 0038 0043 81C8BC8C > >>> 0030 0030 0030 0030 0030 0030 0030 0041 0000000A > >>> } > >>> destinationInfo = 1 entries { > >>> [0]=dialedDigits "0018128554673" > >>> } > >>> srcInfo = 2 entries { > >>> [0]=h323_ID 12 characters { > >>> 0054 0045 0053 0054 0020 0049 0054 0053 TEST ITS > >>> 0020 0050 0056 0058 PVX > >>> } > >>> [1]=dialedDigits "00117995371" > >>> } > >>> bandWidth = 7680 > >>> callReferenceValue = 27920 > >>> conferenceID = 16 octets { > >>> 02 35 50 55 05 0b de 1c 14 c6 d3 10 09 0e 2f 3d .5PU........../= > >>> } > >>> activeMC = FALSE > >>> answerCall = FALSE > >>> canMapAlias = TRUE > >>> callIdentifier = { > >>> guid = 16 octets { > >>> 02 35 50 55 05 0b de 1c 14 c5 d3 10 09 0e 2f 3d .5PU........../= > >>> } > >>> } > >>> gatekeeperIdentifier = 6 characters { > >>> 0055 0056 0061 0047 004b 0031 UVaGK1 > >>> } > >>> willSupplyUUIEs = FALSE > >>> featureSet = { > >>> replacementFeatureSet = FALSE > >>> supportedFeatures = 1 entries { > >>> [0]={ > >>> id = standard 9 > >>> } > >>> } > >>> } > >>> } > >>> 2011/11/07 09:16:22.840 2 RasSrv.cxx(1382) RAS Duplicate ARQ, deleted > >>> 2011/11/07 09:16:24.340 3 RasTbl.cxx(494) Gk Delete endpoint: 81C8BC8C0000000A 0 > >>> 2011/11/07 09:16:24.340 3 RasTbl.cxx(494) Gk Delete endpoint: 6489_endp 0 > >>> 2011/11/07 09:16:26.156 5 yasocket.cxx(782) RasSrv 1 sockets selected from 4, total 4/0 > >>> 2011/11/07 09:16:26.156 4 RasSrv.cxx(230) RAS Receiving on 128.143.2.121:1719(U) > >>> 2011/11/07 09:16:26.156 2 RasSrv.cxx(189) RAS Read from 128.143.13.168:1719 > >>> 2011/11/07 09:16:26.156 3 RasSrv.cxx(238) RAS > >>> disengageRequest { > >>> requestSeqNum = 32779 > >>> endpointIdentifier = 16 characters { > >>> 0038 0031 0043 0038 0042 0043 0038 0043 81C8BC8C > >>> 0030 0030 0030 0030 0030 0030 0030 0041 0000000A > >>> } > >>> conferenceID = 16 octets { > >>> 02 35 50 55 05 0b de 1c 14 c6 d3 10 09 0e 2f 3d .5PU........../= > >>> } > >>> callReferenceValue = 27920 > >>> disengageReason = normalDrop<<null>> > >>> callIdentifier = { > >>> guid = 16 octets { > >>> 02 35 50 55 05 0b de 1c 14 c5 d3 10 09 0e 2f 3d .5PU........../= > >>> } > >>> } > >>> gatekeeperIdentifier = 6 characters { > >>> 0055 0056 0061 0047 004b 0031 UVaGK1 > >>> } > >>> answeredCall = FALSE > >>> } > >>> 2011/11/07 09:16:26.156 5 job.cxx(364) JOB Worker threads: 5 total - 5 busy, 0 idle > >>> 2011/11/07 09:16:26.156 5 job.cxx(190) JOB Starting Job DRQ at Worker thread > >>> 139841453954816 > >>> 2011/11/07 09:16:26.156 1 RasSrv.cxx(368) RAS DRQ Received from 128.143.13.168:1719 > >>> 2011/11/07 09:16:26.156 4 RasSrv.cxx(2832) GK DRQ: closed conference > >>> 2011/11/07 09:16:26.156 2 RasSrv.cxx(408) > >>> DCF|128.143.13.168|81C8BC8C0000000A|27920|normalDrop|02-35-50-55-05-0b-de-1c-14-c5-d3-10-09-0e-2f-3d; > >>> 2011/11/07 09:16:26.156 3 RasSrv.cxx(250) RAS Send to 128.143.13.168:1719 > >>> disengageConfirm { > >>> requestSeqNum = 32779 > >>> } > >>> 2011/11/07 09:16:26.156 5 RasSrv.cxx(264) RAS Sent Successful > >>> 2011/11/07 09:16:26.156 5 job.cxx(427) JOB Job DRQ deleted > >>> 2011/11/07 09:16:26.156 5 job.cxx(416) JOB Worker threads: 5 total - 4 busy, 1 idle > >>> 2011/11/07 09:16:30.986 5 yasocket.cxx(782) RasSrv 1 sockets selected from 4, total 4/0 > >>> 2011/11/07 09:16:30.986 4 RasSrv.cxx(230) RAS Receiving on 128.143.2.121:1719(U) > >>> 2011/11/07 09:16:30.986 2 RasSrv.cxx(189) RAS Read from 192.148.244.130:1719 > >>> 2011/11/07 09:16:30.986 3 RasSrv.cxx(238) RAS > >>> locationReject { > >>> requestSeqNum = 1229 > >>> rejectReason = requestDenied<<null>> > >>> } > >>> 2011/11/07 09:16:30.986 2 RasSrv.cxx(1393) RAS Trapped LRJ > >>> 2011/11/07 09:16:30.986 5 Neighbor.cxx(963) NB Received LRJ message matched pending > >>> LRQ for neighbor GK2:207.75.164.39 > >>> 2011/11/07 09:16:30.986 5 Neighbor.cxx(998) NB LRQ rejected for neighbor > >>> GK2:207.75.164.39 > >>> 2011/11/07 09:16:30.986 5 Routing.h(177) ROUTING Checking policy Explicit for the > >>> request ARQ 32778 > >>> 2011/11/07 09:16:30.986 5 Routing.h(177) ROUTING Checking policy Internal for the > >>> request ARQ 32778 > >>> 2011/11/07 09:16:30.986 2 RasSrv.cxx(408) > >>> ARJ|128.143.13.168:1720|0018128554673:dialedDigits|TEST ITS > >>> PVX:h323_ID=00117995371:dialedDigits|false|calledPartyNotRegistered|02-35-50-55-05-0b-de-1c-14-c5-d3-10-09-0e-2f-3d; > >>> 2011/11/07 09:16:30.986 3 RasSrv.cxx(250) RAS Send to 128.143.13.168:1719 > >>> admissionReject { > >>> requestSeqNum = 32778 > >>> rejectReason = calledPartyNotRegistered<<null>> > >>> } > >>> 2011/11/07 09:16:30.986 5 RasSrv.cxx(264) RAS Sent Successful > >>> 2011/11/07 09:16:30.986 5 job.cxx(427) JOB Job ARQ deleted > >>> 2011/11/07 09:16:30.986 5 job.cxx(416) JOB Worker threads: 5 total - 3 busy, 2 idle > >>> > >>> > >>> On 11/8/2011 11:49 AM, Jan Willamowius wrote: > >>>> You might need both. A look into your trace should tell you if the LRQ > >>>> for the inbound call that fails is coming from a direct neighbor or not. > >>>> > >>>> Also, with ForwardLRQ=always its rather easy to create LRQ loops. I > >>>> would leave it as "depends" unless you are really sure you need > >>>> otherwise. > >>>> > >>>> Regards, > >>>> Jan > >>>> > >>>> Gina Bull wrote: > >>>>> Oooo. Here is what I had put in: > >>>>> > >>>>> [RasSrv::LRQFeatures] > >>>>> AcceptNonNeighborLCF=1 > >>>>> > >>>>> Did I confuse LCF with LRQ, or do I need both? > >>>>> > >>>>> Gina > >>>>> > >>>>> On 11/8/2011 11:25 AM, Jan Willamowius wrote: > >>>>>> Hi Gina, > >>>>>> > >>>>>> I think GDS might need a switch to accept non-neighbor LRQs: > >>>>>> > >>>>>> [RasSrv::LRQFeatures] > >>>>>> AcceptNonNeighborLRQ=1 > >>>>>> > >>>>>> But you really should check the logs at which point the incoming call > >>>>>> fails. > >>>>>> > >>>>>> Also, if you are installing a fresh GnuGk, you shouldn't start with an > >>>>>> old version. I would suggest you use the last release 2.3.5. That > >>>>>> probably won't fix your current issue, but why keep all the old bugs in > >>>>>> place that we have already fixed during the last couple of years ? > >>>>>> > >>>>>> Regards, > >>>>>> Jan -- Jan Willamowius, jan@xxxxxxxxxxxxxx, http://www.gnugk.org/ ------------------------------------------------------------------------------ RSA(R) Conference 2012 Save $700 by Nov 18 Register now http://p.sf.net/sfu/rsa-sfdev2dev1 _______________________________________________________ Posting: mailto:Openh323gk-users@xxxxxxxxxxxxxxxxxxxxx Archive: http://sourceforge.net/mailarchive/forum.php?forum_name=openh323gk-users Unsubscribe: http://lists.sourceforge.net/lists/listinfo/openh323gk-users Homepage: http://www.gnugk.org/