1. Looks like the issue is not a bug at all.
2. No offense, but only people with a lot of experience behind their back can
use the word "bug".
3. If you watch closely your debug, the endpoint 172.16.1.1 replies with
"RELEASE COMPLETE" to a "SETUP" request, sent by GnuGK on behalf of
192.168.1.7.
So, you better check the config and run a debug at 172.16.1.1 and see why it
rejects the call.
On Monday 21 November 2005 12:37, Dmitry Rassohin wrote:
> Hello.
>
> I have trouble with call distribution on GnuGK and ACD with Cisco ATA.
> When call come from PSTN via Lucent Max 6000 (and via GnuGK) to Cisco
> ATA - we get busy, but line is clear.
> Anybody have same trouble?
> Is it Cisco bug?
>
> === Gnugk Trace === :
> 2005/11/18 11:19:01.523 1 gk.cxx(909) GK Logging restarted
> 2005/11/18 11:19:01.546 2 Toolkit.cxx(262)
> Network=192.168.1.0/255.255.255.0, IP=192.168.1.5
> 2005/11/18 11:19:01.546 2 Toolkit.cxx(262)
> Network=172.16.1.0/255.255.255.0, IP=172.16.1.1
> 2005/11/18 11:19:01.546 2 Toolkit.cxx(263) Default IP=192.168.1.5
> 2005/11/18 11:19:01.546 2 Toolkit.cxx(262)
> Network=192.168.1.0/255.255.255.0, IP=192.168.1.1
> 2005/11/18 11:19:01.546 2 Toolkit.cxx(262)
> Network=172.16.1.0/255.255.255.0, IP=172.16.1.1
> 2005/11/18 11:19:01.546 2 Toolkit.cxx(263) Default IP=192.168.1.1
> 2005/11/18 11:19:01.546 2 Toolkit.cxx(344) GK H.323 Proxy enabled
> 2005/11/18 11:19:01.549 2 Toolkit.cxx(525) GK Loaded per GW rewrite
> data:
> 2005/11/18 11:19:01.549 2 Toolkit.cxx(528) GK No per GW data loaded
> 2005/11/18 11:19:01.575 2 ProxyChannel.cxx(109) Q931PortRange: 20000-20999
> 2005/11/18 11:19:01.575 2 ProxyChannel.cxx(109) H245PortRange: 30000-30999
> 2005/11/18 11:19:01.575 2 ProxyChannel.cxx(109) RTPPortRange: 50000-59999
> 2005/11/18 11:19:01.576 2 RasSrv.cxx(716) GK Using Routed Signalling
> 2005/11/18 11:19:01.576 2 RasSrv.cxx(717) GK H.245 Routed Enabled
> 2005/11/18 11:19:01.576 2 RasSrv.cxx(753) GK Home =
> 81.222.160.200,172.16.6.1,192.168.99.1,172.16.255.1,127.0.0.1
> 2005/11/18 11:19:01.578 1 gkauth.cxx(328) GKAUTH RadAliasAuth rule
> added to check RAS: ARQ RRQ, OTHER: NONE
> 2005/11/18 11:19:01.580 1 gkauth.cxx(328) GKAUTH default rule
> added to check RAS: ARQ BRQ DRQ GRQ IRQ LRQ RRQ URQ, OTHER: SETUP
> SETUPUNREG 2005/11/18 11:19:01.580 1
> gkauth.cxx(335) GKAUTH RadAliasAuth rule removed
> 2005/11/18 11:19:01.580 1 gkauth.cxx(335) GKAUTH default rule removed
> 2005/11/18 11:19:01.580 1 gkacct.cxx(170) GKACCT Destroyed module
> RadAcct
> 2005/11/18 11:19:01.581 1 gkacct.cxx(165) GKACCT Created module
> RadAcct with event mask 23
> 2005/11/18 11:19:01.582 2 Routing.cxx(533) VQueue (CTI) Virtual
> queues enabled (aliases:1112233), request timeout: 10 s
> 2005/11/18 11:19:01.583 1 Routing.cxx(211) RoutingPolicy::OnARQ add
> policy vqueue,explicit,internal for prefix *
> 2005/11/18 11:19:01.583 1 Routing.cxx(211) RoutingPolicy::OnLRQ add
> policy explicit,internal for prefix *
> 2005/11/18 11:19:01.583 1 Routing.cxx(211) RoutingPolicy::OnSetup
> add policy explicit,internal for prefix *
> 2005/11/18 11:19:01.584 1 Routing.cxx(211)
> RoutingPolicy::OnFacility add policy explicit,internal for prefix *
> 2005/11/18 11:19:01.592 3 gk.cxx(171) GK Carry on current calls.
> 2005/11/18 11:19:08.925 2 RasSrv.cxx(173) RAS Read from
> 192.168.1.7:1025
> 2005/11/18 11:19:08.928 3 RasSrv.cxx(219) RAS
> admissionRequest {
> requestSeqNum = 14144
> callType = pointToPoint <<null>>
> callModel = direct <<null>>
> endpointIdentifier = 9 characters {
> 0034 0031 0037 0035 005f 0065 006e 0064 4175_end
> 0070 p
> }
> destinationInfo = 1 entries {
> [0]=dialedDigits "1112233"
> }
> srcInfo = 1 entries {
> [0]=dialedDigits "8124445566"
> }
> srcCallSignalAddress = ipAddress {
> ip = 4 octets {
> c0 a8 01 07
> }
> port = 1720
> }
> bandWidth = 640
> callReferenceValue = 5075
> nonStandardData = {
> nonStandardIdentifier = h221NonStandard {
> t35CountryCode = 181
> t35Extension = 0
> manufacturerCode = 20
> }
> data = 31 octets {
> 30 30 30 31 50 31 30 3d 25 32 44 25 32 46 25 32
> 0001P10=%2D%2F%2 44 25 32 46 25 32 44 25 32 46 31 25 32 46 33
> D%2F%2D%2F1%2F3 }
> }
> conferenceID = 16 octets {
> 00 d0 52 02 4a b9 b8 9d 11 da 63 50 ea c9 9d 1c ..R.J.....cP....
> }
> activeMC = FALSE
> answerCall = FALSE
> canMapAlias = TRUE
> callIdentifier = {
> guid = 16 octets {
> 00 d0 52 02 4a b9 b8 9d 11 da 63 50 ea c9 9d 1c
> ..R.J.....cP.... }
> }
> willSupplyUUIEs = FALSE
> }
> 2005/11/18 11:19:08.929 1 RasSrv.cxx(343) RAS ARQ Received
> 2005/11/18 11:19:08.931 3 radproto.cxx(2141) RADIUS Sending PDU to
> RADIUS server radius.domain.com (192.168.1.6:1645) from
> port:48738[active requests: 0, ID space: 208-209], PDU: Access-Request,
> id 208
> 2005/11/18 11:19:09.019 3 radproto.cxx(2180) RADIUS Received PDU
> from RADIUS server radius.domain.com (81.222.160.3:1645) by socket
> port:48738[active requests: 0, ID space: 208-209], PDU: Access-Accept,
> id 208
> 2005/11/18 11:19:09.019 3 gkauth.cxx(1005) GKAUTH RadAliasAuth ARQ
> check ok
> 2005/11/18 11:19:09.019 3 gkauth.cxx(1005) GKAUTH default ARQ check
> ok 2005/11/18 11:19:09.019 3 RasSrv.cxx(1948) GK ARQ will request
> bandwith of 640
> 2005/11/18 11:19:09.020 2 Routing.cxx(605)
> RouteRequest|192.168.1.7:1720|4175_endp|5075|4381701|8124445566:dialedDigit
>s; 2005/11/18 11:19:09.067 2 Routing.cxx(670) VQueue Route request
> (EPID :4175_endp, CRV=5075) accepted by agent h11122331:h323_ID
> 2005/11/18 11:19:09.071 2 RasTbl.cxx(2029) CallTable::Insert(CALL)
> Call No. 109, total sessions : 4
> 2005/11/18 11:19:09.071 2 RasSrv.cxx(388)
> ACF|172.16.1.7:1720|4175_endp|5075|1112233:dialedDigits|8124445566:dialedDi
>gits|false; 2005/11/18 11:19:09.073 3 RasSrv.cxx(231) RAS Send to
> 192.168.1.7:1025 admissionConfirm {
> requestSeqNum = 14144
> bandWidth = 640
> callModel = gatekeeperRouted <<null>>
> destCallSignalAddress = ipAddress {
> ip = 4 octets {
> c0 a8 01 07 Q...
> }
> port = 1720
> }
> irrFrequency = 120
> destinationInfo = 1 entries {
> [0]=h323_ID 8 characters {
> 0068 0034 0033 0038 0031 0037 0030 0031 h11122331
> }
> }
> willRespondToIRR = FALSE
> uuiesRequested = {
> setup = FALSE
> callProceeding = FALSE
> connect = FALSE
> alerting = FALSE
> information = FALSE
> releaseComplete = FALSE
> facility = FALSE
> progress = FALSE
> empty = FALSE
> status = FALSE
> statusInquiry = FALSE
> setupAcknowledge = FALSE
> notify = FALSE
> }
> }
> 2005/11/18 11:19:09.103 3 ProxyChannel.cxx(778) Q931s Received: Setup
> CRV=5075 from 192.168.1.7:16164=>192.168.1.1:1720
> 2005/11/18 11:19:09.111 3 gkauth.cxx(1047) GKAUTH default Setup
> check ok
> 2005/11/18 11:19:09.112 3 radproto.cxx(2141) RADIUS Sending PDU to
> RADIUS server radius.domain.com (192.168.1.6:1646) from
> port:27514[active requests: 0, ID space: 159-160], PDU:
> Accounting-Request, id 159
> 2005/11/18 11:19:09.139 3 radproto.cxx(2180) RADIUS Received PDU
> from RADIUS server radius.domain.com (192.168.1.6:1646) by socket
> port:27514[active requests: 0, ID space: 159-160], PDU:
> Accounting-Response, id 159
> 2005/11/18 11:19:09.139 3 gkacct.cxx(944) GKACCT RadAcct logged
> event 1 for call no. 109
> 2005/11/18 11:19:09.139 2 gkacct.cxx(984) GKACCT Successfully
> logged event 1 for call no. 109
> 2005/11/18 11:19:09.140 3 ProxyChannel.cxx(1783) Q931s Call 109 is NAT
> type 0
> 2005/11/18 11:19:09.140 3 ProxyChannel.cxx(644) GK Call 109 proxy enabled
> 2005/11/18 11:19:09.153 3 ProxyChannel.cxx(2312) Q931 Connect to
> 172.16.1.1:20108=>172.16.1.2:1720 from 172.16.1.1:20108 successful
> 2005/11/18 11:19:09.204 3 ProxyChannel.cxx(778) Q931d Received:
> ReleaseComplete CRV=5075 from 172.16.1.1:20108=>172.16.1.2:1720
> 2005/11/18 11:19:09.207 2 RasTbl.cxx(2235) CDR ignore not
> connected call
> 2005/11/18 11:19:09.207 3 radproto.cxx(2141) RADIUS Sending PDU to
> RADIUS server radius.domain.com (192.168.1.6:1646) from
> port:27514[active requests: 0, ID space: 159-161], PDU:
> Accounting-Request, id 160
> 2005/11/18 11:19:09.209 3 radproto.cxx(2180) RADIUS Received PDU
> from RADIUS server radius.domain.com (192.168.1.6:1646) by socket
> port:27514[active requests: 0, ID space: 159-161], PDU:
> Accounting-Response, id 160
> 2005/11/18 11:19:09.210 3 gkacct.cxx(944) GKACCT RadAcct logged
> event 2 for call no. 109
> 2005/11/18 11:19:09.210 2 gkacct.cxx(984) GKACCT Successfully
> logged event 2 for call no. 109
> 2005/11/18 11:19:09.251 2 RasSrv.cxx(173) RAS Read from
> 192.168.1.7:1025
> 2005/11/18 11:19:09.252 3 RasSrv.cxx(219) RAS
> disengageRequest {
> requestSeqNum = 14145
> endpointIdentifier = 9 characters {
> 0034 0031 0037 0035 005f 0065 006e 0064 4175_end
> 0070 p
> }
> conferenceID = 16 octets {
> 00 d0 52 02 4a b9 b8 9d 11 da 63 50 ea c9 9d 1c ..R.J.....cP....
> }
> callReferenceValue = 5075
> disengageReason = normalDrop <<null>>
> nonStandardData = {
> nonStandardIdentifier = h221NonStandard {
> t35CountryCode = 181
> t35Extension = 0
> manufacturerCode = 20
> }
> data = 43 octets {
> 30 30 30 31 50 31 30 3d 25 32 44 25 32 46 25 32
> 0001P10=%2D%2F%2 44 25 32 46 25 32 44 25 32 46 31 25 32 46 33 26
> D%2F%2D%2F1%2F3& 50 32 36 3d 33 26 50 32 38 3d 30
> P26=3&P28=0 }
> }
> callIdentifier = {
> guid = 16 octets {
> 00 d0 52 02 4a b9 b8 9d 11 da 63 50 ea c9 9d 1c
> ..R.J.....cP.... }
> }
> answeredCall = FALSE
> }
> 2005/11/18 11:19:09.255 1 RasSrv.cxx(343) RAS DRQ Received
> 2005/11/18 11:19:09.255 3 gkauth.h(830) GKAUTH default DRQ check ok
> 2005/11/18 11:19:09.256 2 RasSrv.cxx(388)
> DCF|192.168.1.7|4175_endp|5075|normalDrop;
> 2005/11/18 11:19:09.258 3 RasSrv.cxx(231) RAS Send to
> 192.168.1.7:1025 disengageConfirm {
> requestSeqNum = 14145
> }
>
> === Gnugk Trace end ===
> === GateKeeper Version ===:
> Gatekeeper(GNU) Version(2.2.3)
> Ext(pthreads=1,radius=1,mysql=1,pgsql=1,large_fdset=0) Build(Aug 18
> 2005, 14:02:01) Sys(Linux i686 2.6.9-5.0.3.EL)
> GkStatus: Version(2.0) Ext()
> Toolkit: Version(1.0) Ext(basic)
> === GateKeeper Version end ===
>
> GnugkACD 1.3
> Java 1.4.2
>
> === GnuGK config ===:
>
> [Gatekeeper::Main]
> Fourtytwo=42
> Name=OpenH323GK
> Home=192.168.1.1;172.16.1.1;127.0.0.1
> NetworkInterfaces=192.168.1.1/24,172.16.1.1/24,127.0.0.1/8
> TimestampFormat=MySQL
> Rotate=weekly
> [RoutedMode]
> GKRouted=1
> H245Routed=1
> CallSignalPort=1720
> CallSignalHandlerNumber=9
> RemoveH245AddressOnTunneling=1
> AcceptNeighborsCalls=1
> AcceptUnregisteredCalls=0
> SupportNATedEndpoints=1
> DropCallsByReleaseComplete=1
> RemoveCallOnDRQ=1
> SendReleaseCompleteOnDRQ=0
> ForwardOnFacility=1
> ShowForwarderNumber=0
> Q931PortRange=20000-20999
> H245PortRange=30000-30999
> TcpKeepAlive=1
> TranslateFacility=1
> [Proxy]
> Enable=1
> RTPPortRange=50000-59999
> ProxyForNAT=1
> ProxyForSameNAT=1
> [Endpoint::RewriteE164]
> [RasSrv::GWPrefixes]
> gw1=*
> [RasSrv::RRQFeatures]
> [RasSrv::ARQFeatures]
> ArjReasonRouteCallToSCN=1
> ArjReasonRouteCallToGatekeeper=1
> CallUnregisteredEndpoints=1
> RemoveTrailingChar=#
> RoundRobinGateways=0
> [RoutingPolicy]
> default=explicit,internal
> [RoutingPolicy::OnARQ]
> default=vqueue,explicit,internal
> [RasSrv::RRQAuth]
> default=confirm
> [GkStatus::Auth]
> rule=explicit
> 127.0.0.1=allow
> default=forbid
> [RasSrv::RewriteE164]
> [RasSrv::LRQFeatures]
> [RasSrv::PermanentEndpoints]
> [Gatekeeper::Auth]
> RadAliasAuth=required;RRQ,ARQ
> default=allow
> [RadAuth]
> Servers=radius.domain.com
> LocalInterface=192.168.1.1
> DefaultAuthPort=1645
> DefaultAcctPort=1645
> SharedSecret=testing
> AppendCiscoAttributes=1
> IncludeTerminalAliases=1
> [RadAliasAuth]
> Servers=radius.domain.com
> LocalInterface=192.168.1.1
> DefaultAuthPort=1645
> DefaultAcctPort=1646
> SharedSecret=testing
> AppendCiscoAttributes=1
> IncludeTerminalAliases=1
> FixedUsername=
> FixedPassword=
> [CallTable]
> GenerateNBCDR=FALSE
> GenerateUCCDR=FALSE
> DefaultCallDurationLimit=65000
> AcctUpdateInterval=0
> TimestampFormat=RFC822
> [Gatekeeper::Acct]
> RadAcct=required;start,connect,stop
> [NATedEndpoints]
> [CTI::Agents]
> VirtualQueueAliases=1112233
> RequestTimeout=10
> [RadAcct]
> Servers=radius.domain.com
> LocalInterface=192.168.1.1
> DefaultAuthPort=1645
> DefaultAcctPort=1646
> SharedSecret=testing
> AppendCiscoAttributes=1
> IncludeEndpointIP=1
> [RewriteCLI]
> out:172.16.1.2=any cli:any=1112233
> === GnuGK config end ===
>
> === ACDConfig.properties ===:
>
> gnugk_host=127.0.0.1
> gnugk_port=7000
> listen_port=7001
> distribution_algorithm=LONGEST_IDLE
> logfile=/var/log/acd.log
> sanity_interval=90
> debug=10
> 1112233_queueing_mode=REJECT
> 1112233_ring_timeout=120
> 1112233_size=2
> === ACDConfig.properties end ===
>
> === ACDAgentConfig.properties ===:
> h11122331=1112233
> h11122332=1112233
> === ACDAgentConfig.properties end ===
>
> === cisco ata config ===:
> Cisco ATA 186 (Version: v3.1.2 atah323 (Build 040927A)) config:
> #txt
> ToConfig:0
> UIPassword:ghbrjK
> UseTftp:0
> TftpURL:192.168.1.1
> cfgInterval:3600
> Dhcp:0
> StaticRoute:172.16.1.1
> StaticNetMask:255.255.255.0
> NTPIP:0
> AltNTPIP:0.0.0.0
> DNS1IP:192.168.1.3
> DNS2IP:192.168.1.4
> VLANSetting:0x0000002b
> GkOrProxy:172.16.1.1
> GkId:OpenH323GK
> GkTimeToLive:300
> AltGk:0
> AltGkTimeOut:0
> UseLoginID:0
> UID0: h11122331
> UID1: h11122332
> LoginID0:0
> LoginID1:0
> PWD0:0
> PWD1:0
> AutMethod:0x00000000
> GateWay:0
> MediaPort:16384
> RxCodec:1
> TxCodec:1
> LBRCodec:1
> AudioMode:0x00020002
> NumTxFrames:1
> TOS:0x0000A8B8
> CallFeatures:0xffcfffcf
> PaidFeatures:0xffffffff
> CallCmd:Af;AH;BS;NA;CS;NA;Df;EB;Ff;EP;Kf;EFh;HH;Jf;AFh;HQ;I*67;gA*82;fA#90v
>#;OI;H#72v#;bA#74v#;cA#75v#;dA#73;eA*67;gA*82;fA*70;iA*69;DA*99;xA;Uh;GQ;
> FeatureTimer:0x00000000
> FeatureTimer2:0x0000001e
> SigTimer:0x01418564
> ConnectMode:0x000c800a
> OpFlags:0x00008062
> TimeZone:4
> CallerIdMethod:0x006aff61
> Polarity: 0
> FXSInputLevel:-6
> FXSOutputLevel:-6
> DialTone:1,30958,0,3889,0,1,0,0,0
> BusyTone:1,30958,0,1757,0,0,3200,3200,0
> ReorderTone:1,30958,0,1757,0,0,1600,1600,0
> RingBackTone:1,30958,0,1927,0,0,8000,40000,0,0,0
> CallWaitTone:1,30831,0,5493,0,0,2400,2400,4800
> AlertTone:1,30467,0,5970,0,0,480,480,1920
> RingOnOffTime:1,5,25
> DialPlan:0>#t4..|1>#.r5|2>#.r5|3>#.r5|4>#.r5|5>#.r5|6>#.r5|7>#.r5|9>#.r5
> IPDialPlan: 0
> NPrintf:0
> TraceFlags:0x00000000
> SyslogIP:172.16.1.1.514
> SyslogCtrl:0x00000618
> === cisco ata config end ===
-------------------------------------------------------
This SF.Net email is sponsored by the JBoss Inc. Get Certified Today
Register for a JBoss Training Course. Free Certification Exam
for All Training Attendees Through End of 2005. For more info visit:
http://ads.osdn.com/?ad_id=7628&alloc_id=16845&op=click
_______________________________________________________
Posting: mailto:Openh323gk-users@xxxxxxxxxxxxxxxxxxxxx
Archive: http://sourceforge.net/mailarchive/forum.php?forum_id=8549
Unsubscribe: http://lists.sourceforge.net/lists/listinfo/openh323gk-users
Homepage: http://www.gnugk.org/
