Hi,
it appears to show that there are 2 calls made but the captured log is for
1 test call. The logs were taken from a setup that consists of only a
gnugk and 1 registered endpoint making only 1 call to a public skycam at
203.174.20.80.
A ethereal trace on the calling endpoint for the "first" call showed that
prior to the calling endpoint disconnecting the call (H.225.0 CS:
releaseComplete), the gatekeeper had sent it a "H.225.0 CS: facility".
This is confirmed in the gnugk trace logs:
2008/10/30 17:15:27.534 3 ProxyChannel.cxx(910) Q931d Received:
Facility CRV=3769 from 203.174.20.80:1720
2008/10/30 17:15:27.535 4 ProxyChannel.cxx(853) Q931 Received:
{
2008/10/30 17:15:27.535 4 ProxyChannel.cxx(853) Q931 Send to
130.194.xx.xx:3230 {
2008/10/30 17:15:27.540 5 yasocket.cxx(783) ProxyH(14) 1
sockets selected from 2, total 2/0
2008/10/30 17:15:27.540 5 ProxyChannel.cxx(619) Q931s Reading
from 130.194.xx.xx:3230
2008/10/30 17:15:27.540 3 ProxyChannel.cxx(910) Q931s Received:
ReleaseComplete CRV=3769 from 130.194.xx.xx:3230
I am unsure why the calling endpoint disconnect the call.
Regarding the "second" call, 202.177.196.34 maybe related to the "first"
call as there was only 1 DCF from the "first" call and the "second" call
is just the NATed called endpoint of the "first" attempting to setup call.
Also, test call to the skycam (203.174.20.80) via our Codian gatekeeper
works but fails on the gnugk.
Any ideas?
Thanks,
Paul
Date: Fri, 31 Oct 2008 09:01:35 +0100
From: "Zygmuntowicz Michal" <[EMAIL PROTECTED]>
Subject: Re: [Openh323gk-users] gnugk drop call for nated ip
For the second call check the line:
2008/10/30 17:15:27.629 3 ProxyChannel.cxx(1926) Q931s No
destination for unregistered call 02 2b d8 03 b2 69 f5 1a 04 93 f6 f7 64
cd 82 93 from 202.177.196.34:2640
It seems no endpoint/gateway matched the dialed number.
For the first problem, your calling endpoint disconnects the call - check
for the problem here (Release Complete Cause, codecs etc., H.245
tunnelling being disabled, Facility with startH245 and a private address
being sent, ...)
----- Original Message -----
From: Paul Hii
To: openh323gk-users@lists.sourceforge.net
Sent: Friday, October 31, 2008 7:42 AM
Subject: [Openh323gk-users] gnugk drop call for nated ip
Hi,
our gnugk is unable to set up call to a public skycam at 203.174.20.80.
The gnugk is in proxy routed mode:
[RoutedMode]
GKRouted=1
H245Routed=1
CallSignalHandlerNumber=15
AcceptNeighborsCalls=1
AcceptUnregisteredCalls=1
RemoveH245AddressOnTunneling=1
RemoveCallOnDRQ==0
DropCallsByReleaseComplete=1
SendReleaseCompleteOnDRQ=1
SupportNATedEndpoints=1
SupportCallingNATedEndpoints=1
TreatUnregisteredNAT=1
TranslateFacility=1
ActivateFailover=1
EnableH450.2=1
[Proxy]
Enable=1
ProxyForNAT=1
[RasSrv::ARQFeatures]
CallUnregisteredEndpoints=1
ArjReasonRouteCallToGatekeeper=0
The trace logs initially indicated that the call is NAT type 0 and so
continues to normal call setup but very quickly the call drops and CDR
generated. Only after this the gnugk drops another called ip which is
behind NAT and generate another CDR. Please see below trace logs:
2008/10/30 17:15:27.301 2 RasTbl.cxx(2656)
CallTable::Insert(CALL) Call No. 15, total sessions : 1
2008/10/30 17:15:27.301 2 RasSrv.cxx(415)
ACF|130.194.xx.xx:1720|00000000000000000000000082C2550F06B8jNXq|3769|203.174.20.80:1720|Paul
Hii:h323_ID=51172:dialedDigits|false|02-2b-d8-03-b2-00-00-10-04-92-f6-f7-64-cd-82-93;
2008/10/30 17:15:27.301 3 RasSrv.cxx(252) RAS Send to
130.194.xx.xx:1719
2008/10/30 17:15:27.302 5 RasSrv.cxx(266) RAS Sent
Successful
2008/10/30 17:15:27.302 5 job.cxx(426) JOB Job ARQ
deleted
2008/10/30 17:15:27.302 5 job.cxx(415) JOB Worker
threads: 20 total - 19 busy, 1 idle
2008/10/30 17:15:27.322 5 yasocket.cxx(783) TCPSrv 1
sockets selected from 2, total 2/0
2008/10/30 17:15:27.322 4 yasocket.cxx(905) TCPSrv Accept
request on 130.194.gk.ip:1721
2008/10/30 17:15:27.322 5 job.cxx(363) JOB Worker
threads: 20 total - 20 busy, 0 idle
2008/10/30 17:15:27.323 5 job.cxx(189) JOB Starting
Job Acceptor at Worker thread 25021344
2008/10/30 17:15:27.357 5 ProxyChannel.cxx(619) Q931s Reading
from 130.194.xx.xx:3230
2008/10/30 17:15:27.357 3 ProxyChannel.cxx(910) Q931s Received:
Setup CRV=3769 from 130.194.xx.xx:3230
2008/10/30 17:15:27.359 4 ProxyChannel.cxx(853) Q931 Received: {
2008/10/30 17:15:27.359 2 gkacct.cxx(1028) GKACCT
Successfully logged event 1 for call no. 15
2008/10/30 17:15:27.359 3 ProxyChannel.cxx(2161) Q931s Call 15
is NAT type 0
2008/10/30 17:15:27.359 3 ProxyChannel.cxx(776) GK Call 15
proxy enabled
2008/10/30 17:15:27.361 4 ProxyChannel.cxx(853) Q931 Send to
203.174.20.80:1720 {
2008/10/30 17:15:27.384 3 ProxyChannel.cxx(3374) Q931 Connect
to 203.174.20.80:1720 from 130.194.gk.ip:0 successful
2008/10/30 17:15:27.533 5 ProxyChannel.cxx(5103) ProxyH(14) total
sockets 2
2008/10/30 17:15:27.534 5 job.cxx(426) JOB Job
Acceptor deleted
2008/10/30 17:15:27.534 5 job.cxx(415) JOB Worker
threads: 20 total - 19 busy, 1 idle
2008/10/30 17:15:27.534 5 yasocket.cxx(783) ProxyH(14) 1
sockets selected from 2, total 2/0
2008/10/30 17:15:27.534 5 ProxyChannel.cxx(619) Q931d Reading
from 203.174.20.80:1720
2008/10/30 17:15:27.534 3 ProxyChannel.cxx(910) Q931d Received:
Facility CRV=3769 from 203.174.20.80:1720
2008/10/30 17:15:27.535 4 ProxyChannel.cxx(853) Q931 Received: {
2008/10/30 17:15:27.535 4 ProxyChannel.cxx(853) Q931 Send to
130.194.xx.xx:3230 {
2008/10/30 17:15:27.540 5 yasocket.cxx(783) ProxyH(14) 1
sockets selected from 2, total 2/0
2008/10/30 17:15:27.540 5 ProxyChannel.cxx(619) Q931s Reading
from 130.194.xx.xx:3230
2008/10/30 17:15:27.540 3 ProxyChannel.cxx(910) Q931s Received:
ReleaseComplete CRV=3769 from 130.194.xx.xx:3230
2008/10/30 17:15:27.540 4 ProxyChannel.cxx(853) Q931 Received: {
2008/10/30 17:15:27.541 2 RasTbl.cxx(3063) CDR ignore
not connected call
2008/10/30 17:15:27.541 5 gkacct.cxx(806) GKACCT FileAcct
- CDR string for event 2, call no. 15: CDR|15|02 2b d8 03 b2 00 00 10 04
92 f6 f7 64 cd 82 93|0||17:15:27.000 EST Thu Oct 30
2008|130.194.xx.xx:3230|00000000000000000000000082C2550F06B8jNXq|203.174.20.80:1720||203.174.20.80:1720|Paul
Hii:h323_ID=51172:dialedDigits|16
2008/10/30 17:15:27.541 3 gkacct.cxx(988) GKACCT FileAcct
logged event 2 for call no. 15
2008/10/30 17:15:27.541 2 gkacct.cxx(1028) GKACCT
Successfully logged event 2 for call no. 15
2008/10/30 17:15:27.542 4 ProxyChannel.cxx(853) Q931 Send to
203.174.20.80:1720 {
2008/10/30 17:15:27.582 5 yasocket.cxx(783) ProxyH(14) 1
sockets selected from 1, total 0/2
2008/10/30 17:15:27.582 5 ProxyChannel.cxx(619) Q931d Reading
from 203.174.20.80:1720
2008/10/30 17:15:27.582 5 yasocket.cxx(660) Q931d
203.174.20.80:1720 closed by remote
2008/10/30 17:15:27.625 5 yasocket.cxx(783) TCPSrv 1
sockets selected from 2, total 2/0
2008/10/30 17:15:27.625 4 yasocket.cxx(905) TCPSrv Accept
request on 130.194.gk.ip:1721
2008/10/30 17:15:27.625 5 job.cxx(363) JOB Worker
threads: 20 total - 20 busy, 0 idle
2008/10/30 17:15:27.625 5 job.cxx(189) JOB Starting
Job Acceptor at Worker thread 25021344
2008/10/30 17:15:27.626 5 ProxyChannel.cxx(619) Q931s Reading
from 202.177.196.34:2640
2008/10/30 17:15:27.626 3 ProxyChannel.cxx(910) Q931s Received:
Setup CRV=3989 from 202.177.196.34:2640
2008/10/30 17:15:27.628 4 ProxyChannel.cxx(853) Q931 Received: {
2008/10/30 17:15:27.629 5 Routing.cxx(201) ROUTING Checking
policy Explicit for request Setup CRV=3989
2008/10/30 17:15:27.629 5 Routing.cxx(201) ROUTING Checking
policy Internal for request Setup CRV=3989
2008/10/30 17:15:27.629 5 Routing.cxx(201) ROUTING Checking
policy ENUM for request Setup CRV=3989
2008/10/30 17:15:27.629 5 Routing.cxx(201) ROUTING Checking
policy DNS for request Setup CRV=3989
2008/10/30 17:15:27.629 5 Routing.cxx(201) ROUTING Checking
policy Internal for request Setup CRV=3989
2008/10/30 17:15:27.629 5 Routing.cxx(201) ROUTING Checking
policy Neighbor for request Setup CRV=3989
2008/10/30 17:15:27.629 3 ProxyChannel.cxx(1926) Q931s No
destination for unregistered call 02 2b d8 03 b2 69 f5 1a 04 93 f6 f7 64
cd 82 93 from 202.177.196.34:2640
2008/10/30 17:15:27.629 4 ProxyChannel.cxx(1956) Q931s Source
address 130.194.xx.xx peer address 202.177.196.34 caller is behind NAT
2008/10/30 17:15:27.629 2 RasTbl.cxx(2656)
CallTable::Insert(CALL) Call No. 16, total sessions : 1
2008/10/30 17:15:27.629 2 gkacct.cxx(1028) GKACCT
Successfully logged event 1 for call no. 16
2008/10/30 17:15:27.630 2 RasTbl.cxx(3063) CDR ignore
not connected call
2008/10/30 17:15:27.630 5 gkacct.cxx(806) GKACCT FileAcct
- CDR string for event 2, call no. 16: CDR|16|02 2b d8 03 b2 69 f5 1a 04
93 f6 f7 64 cd 82 93|0||17:15:27.000 EST Thu Oct 30
2008|202.177.196.34:2640||0.0.0.0:0|||Paul
Hii:h323_ID=51172:dialedDigits|20
2008/10/30 17:15:27.630 3 gkacct.cxx(988) GKACCT FileAcct
logged event 2 for call no. 16
2008/10/30 17:15:27.631 2 gkacct.cxx(1028) GKACCT
Successfully logged event 2 for call no. 16
2008/10/30 17:15:27.631 4 ProxyChannel.cxx(853) Q931 Send to
202.177.196.34:2640 {
2008/10/30 17:15:27.631 3 yasocket.cxx(577) Q931s Delete
socket 202.177.196.34:2640
2008/10/30 17:15:27.631 5 job.cxx(426) JOB Job
Acceptor deleted
2008/10/30 17:15:27.632 5 job.cxx(415) JOB Worker
threads: 20 total - 19 busy, 1 idle
2008/10/30 17:15:27.663 5 yasocket.cxx(783) RasSrv 1
sockets selected from 3, total 3/0
This is suspicious but i'm unsure if it's normal call dropping. Thanks
for your advice.
Cheers,
Paul
End of Openh323gk-users Digest, Vol 29, Issue 9
***********************************************
-------------------------------------------------------------------------
This SF.Net email is sponsored by the Moblin Your Move Developer's challenge
Build the coolest Linux based applications with Moblin SDK & win great prizes
Grand prize is a trip for two to an Open Source event anywhere in the world
http://moblin-contest.org/redirect.php?banner_id=100&url=/
_______________________________________________________
Posting: mailto:Openh323gk-users@lists.sourceforge.net
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/
