Hello guys. It's been about a year since I last posted to this mailing
list. At the time I had gotten everything to work beautifully. I have
not updated Gnugk since and have not touched it. Today we needed to
test this system out and I was surprised that calls no longer works.
When calling the endpoint directly, it connects. When trying to call
through Gnugk it does not. I looked in the log and see some kind of
connection timed out and a "didn't accept the call" error. Can someone
explain to me what is happening? 74.208.147.64 is our gatekeeper IP
address. I am confused as to who is trying to connect to "Q.931 socket
at 74.208.147.64:0". If it is relevant, caller is behind a firewall and
callee is not. I tried both with h.245 tunneling and without.
2013/02/01 14:09:56.598 4 yasocket.cxx(1116) TCPSrv Accept
request on 74.208.147.64:1720
2013/02/01 14:09:56.599 5 job.cxx(351) JOB Worker
threads: 10 total - 10 busy, 0 idle
2013/02/01 14:09:56.599 5 job.cxx(191) JOB Starting Job
Acceptor at Worker thread 140518605829888
2013/02/01 14:09:56.603 5 ProxyChannel.cxx(802) Q931s Reading
from 68.14.209.154:4003
2013/02/01 14:09:56.603 3 ProxyChannel.cxx(1162) Q931s Received:
Setup CRV=3378 from 68.14.209.154:4003
2013/02/01 14:09:56.604 4 ProxyChannel.cxx(1109) Q931 Received: {
q931pdu = {
protocolDiscriminator = 8
callReference = 3378
from = originator
messageType = Setup
IE: Bearer-Capability = {
88 18 90 a5 ....
}
IE: User-User = {
30 a8 06 00 08 91 4a 00 06 01 40 19 00 6d 00 68 [email protected]
00 65 00 72 00 62 00 6f 00 6c 00 64 00 40 00 61 [email protected]
00 69 00 6e 00 74 00 65 00 72 00 70 00 72 00 65 .i.n.t.e.r.p.r.e
00 74 00 69 00 6e 00 67 00 2e 00 63 00 6f 00 6d .t.i.n.g...c.o.m
22 c0 59 00 44 4c 13 4c 69 66 65 53 69 7a 65 20 ".Y.DL.LifeSize
53 6f 66 74 70 68 6f 6e 65 00 00 10 38 2e 31 2e Softphone...8.1.
35 20 28 57 69 6e 64 6f 77 73 29 00 00 00 4a d0 5 (Windows)...J.
93 40 06 b8 00 c6 07 39 fd 1a 09 19 10 96 38 00 [email protected].
24 e8 1c 86 17 00 5d 0d 80 07 00 44 0e d1 9a 0f $.....]....D....
a3 11 00 c6 07 39 fd 1a 09 19 10 96 37 00 24 e8 .....9......7.$.
1c 86 17 01 00 01 00 01 00 01 00 40 00 00 00 00 ...........@....
0a 31 30 37 39 35 32 31 33 34 31 02 80 01 80 .1079521341....
}
}
h225pdu = {
h323_uu_pdu = {
h323_message_body = setup {
protocolIdentifier = 0.0.8.2250.0.6
sourceAddress = 1 entries {
[0]=h323_ID 26 characters {
006d 0068 0065 0072 0062 006f 006c 0064 mherbold
0040 0061 0069 006e 0074 0065 0072 0070 @ainterp
0072 0065 0074 0069 006e 0067 002e 0063 reting.c
006f 006d om
}
}
sourceInfo = {
vendor = {
vendor = {
t35CountryCode = 89
t35Extension = 0
manufacturerCode = 17484
}
productId = 20 octets {
4c 69 66 65 53 69 7a 65 20 53 6f 66 74 70 68 6f LifeSize
Softpho
6e 65 00 00 ne..
}
versionId = 17 octets {
38 2e 31 2e 35 20 28 57 69 6e 64 6f 77 73 29 00 8.1.5
(Windows).
00 .
}
}
terminal = {
}
mc = false
undefinedNode = false
}
destCallSignalAddress = ipAddress {
ip = 4 octets {
4a d0 93 40 J..@
}
port = 1720
}
activeMC = false
conferenceID = 16 octets {
c6 07 39 fd 1a 09 19 10 96 38 00 24 e8 1c 86 17 ..9......8.$....
}
conferenceGoal = create <<null>>
callType = pointToPoint <<null>>
sourceCallSignalAddress = ipAddress {
ip = 4 octets {
44 0e d1 9a D...
}
port = 4003
}
callIdentifier = {
guid = 16 octets {
c6 07 39 fd 1a 09 19 10 96 37 00 24 e8 1c 86 17
..9......7.$....
}
}
mediaWaitForConnect = false
canOverlapSend = false
multipleCalls = false
maintainConnection = false
}
nonStandardData = {
nonStandardIdentifier = h221NonStandard {
t35CountryCode = 0
t35Extension = 0
manufacturerCode = 0
}
data = 10 octets {
31 30 37 39 35 32 31 33 34 31 1079521341
}
}
h245Tunneling = true
}
}
}
2013/02/01 14:09:56.605 5 Routing.cxx(200) ROUTING Checking
policy SqlPolicy for request Setup CRV=3378
2013/02/01 14:09:56.605 5 gksql.cxx(321) SqlPolicy
Executing query: SELECT ( SELECT vp_ip_address FROM accounts WHERE id =
account_id ) FROM pool ORDER BY priority
2013/02/01 14:09:56.605 5 Routing.cxx(1747) SqlPolicy
Query result : 65.84.19.45
2013/02/01 14:09:56.606 5 Routing.cxx(203) ROUTING Policy
SqlPolicy applied to the request Setup CRV=3378
2013/02/01 14:09:56.606 4 ProxyChannel.cxx(2917) Q931s
Unregistered party is not NATed
2013/02/01 14:09:56.606 2 RasTbl.cxx(4013)
CallTable::Insert(CALL) Call No. 5, total sessions : 1
2013/02/01 14:09:56.606 5 gksql.cxx(321) SQLAcct
Executing query: INSERT INTO gnugk SET id = '510c110000000005',
caller_ip = '68.14.209.154', caller_port = '4003', caller_id =
'[email protected]', ring_duration = '0', call_duration = '0',
total_duration = '0', callee_ip = '65.84.19.45', account_id = ( SELECT
id FROM accounts WHERE vp_ip_address = '65.84.19.45' ), call_id = 'c6 07
39 fd 1a 09 19 10 96 37 00 24 e8 1c 86 17'
2013/02/01 14:09:56.607 3 gkacct.cxx(914) GKACCT SQLAcct
logged event 1 for call no. 5
2013/02/01 14:09:56.607 2 gkacct.cxx(950) GKACCT
Successfully logged event 1 for call no. 5
2013/02/01 14:09:56.607 3 ProxyChannel.cxx(3393) Q931s Call 5
is NAT type 0
2013/02/01 14:09:56.607 5 Toolkit.cxx(664) ModeSelection for
68.14.209.154 -> 65.84.19.45 default=1
2013/02/01 14:09:56.607 1 ProxyChannel.cxx(997) Call 5:
h245Routed=0 proxy=0
2013/02/01 14:09:56.608 4 ProxyChannel.cxx(1109) Q931 Send to
65.84.19.45:1720 {
q931pdu = {
protocolDiscriminator = 8
callReference = 3378
from = originator
messageType = Setup
IE: Bearer-Capability = {
88 18 90 a5 ....
}
IE: User-User = {
30 a8 06 00 08 91 4a 00 06 01 40 19 00 6d 00 68 [email protected]
00 65 00 72 00 62 00 6f 00 6c 00 64 00 40 00 61 [email protected]
00 69 00 6e 00 74 00 65 00 72 00 70 00 72 00 65 .i.n.t.e.r.p.r.e
00 74 00 69 00 6e 00 67 00 2e 00 63 00 6f 00 6d .t.i.n.g...c.o.m
22 c0 59 00 44 4c 13 4c 69 66 65 53 69 7a 65 20 ".Y.DL.LifeSize
53 6f 66 74 70 68 6f 6e 65 00 00 10 38 2e 31 2e Softphone...8.1.
35 20 28 57 69 6e 64 6f 77 73 29 00 00 00 41 54 5 (Windows)...AT
13 2d 06 b8 00 c6 07 39 fd 1a 09 19 10 96 38 00 .-.....9......8.
24 e8 1c 86 17 00 5d 0d 80 07 00 4a d0 93 40 06 $.....]....J..@.
b8 11 00 c6 07 39 fd 1a 09 19 10 96 37 00 24 e8 .....9......7.$.
1c 86 17 01 00 01 00 01 00 01 00 40 00 00 00 00 ...........@....
0a 31 30 37 39 35 32 31 33 34 31 02 80 01 80 .1079521341....
}
}
h225pdu = {
h323_uu_pdu = {
h323_message_body = setup {
protocolIdentifier = 0.0.8.2250.0.6
sourceAddress = 1 entries {
[0]=h323_ID 26 characters {
006d 0068 0065 0072 0062 006f 006c 0064 mherbold
0040 0061 0069 006e 0074 0065 0072 0070 @ainterp
0072 0065 0074 0069 006e 0067 002e 0063 reting.c
006f 006d om
}
}
sourceInfo = {
vendor = {
vendor = {
t35CountryCode = 89
t35Extension = 0
manufacturerCode = 17484
}
productId = 20 octets {
4c 69 66 65 53 69 7a 65 20 53 6f 66 74 70 68 6f LifeSize
Softpho
6e 65 00 00 ne..
}
versionId = 17 octets {
38 2e 31 2e 35 20 28 57 69 6e 64 6f 77 73 29 00 8.1.5
(Windows).
00 .
}
}
terminal = {
}
mc = false
undefinedNode = false
}
destCallSignalAddress = ipAddress {
ip = 4 octets {
41 54 13 2d AT.-
}
port = 1720
}
activeMC = false
conferenceID = 16 octets {
c6 07 39 fd 1a 09 19 10 96 38 00 24 e8 1c 86 17 ..9......8.$....
}
conferenceGoal = create <<null>>
callType = pointToPoint <<null>>
sourceCallSignalAddress = ipAddress {
ip = 4 octets {
4a d0 93 40 J..@
}
port = 1720
}
callIdentifier = {
guid = 16 octets {
c6 07 39 fd 1a 09 19 10 96 37 00 24 e8 1c 86 17
..9......7.$....
}
}
mediaWaitForConnect = false
canOverlapSend = false
multipleCalls = false
maintainConnection = false
}
nonStandardData = {
nonStandardIdentifier = h221NonStandard {
t35CountryCode = 0
t35Extension = 0
manufacturerCode = 0
}
data = 10 octets {
31 30 37 39 35 32 31 33 34 31 1079521341
}
}
h245Tunneling = true
}
}
}
2013/02/01 14:10:02.619 1 ProxyChannel.cxx(5477) Q931d Could not
open/connect Q.931 socket at 74.208.147.64:0 - error 9/110: Connection
timed out
2013/02/01 14:10:02.619 3 ProxyChannel.cxx(5144) Q931
65.84.19.45:1720 DIDN'T ACCEPT THE CALL
2013/02/01 14:10:02.619 4 ProxyChannel.cxx(1109) Q931 Send to
68.14.209.154:4003 {
q931pdu = {
protocolDiscriminator = 8
callReference = 36146
from = destination
messageType = ReleaseComplete
IE: Cause - No route to destination = {
80 83 ..
}
IE: User-User = {
25 c0 06 00 08 91 4a 00 02 10 08 11 00 c6 07 39 %.....J........9
fd 1a 09 19 10 96 37 00 24 e8 1c 86 17 02 80 01 ......7.$.......
00 .
}
}
h225pdu = {
h323_uu_pdu = {
h323_message_body = releaseComplete {
protocolIdentifier = 0.0.8.2250.0.2
reason = unreachableDestination <<null>>
callIdentifier = {
guid = 16 octets {
c6 07 39 fd 1a 09 19 10 96 37 00 24 e8 1c 86 17
..9......7.$....
}
}
}
h245Tunneling = false
}
}
}
2013/02/01 14:10:02.619 2 RasTbl.cxx(4488) CDR ignore not
connected call
2013/02/01 14:10:02.620 5 gksql.cxx(321) SQLAcct
Executing query: UPDATE gnugk SET ring_duration = '0', call_duration =
'0', total_duration = '6', disconnect_time = '2013-02-01 14:10:02',
disconnect_cause = '3', who_disconnected = '0' WHERE id = '510c110000000005'
2013/02/01 14:10:02.621 3 gkacct.cxx(914) GKACCT SQLAcct
logged event 2 for call no. 5
2013/02/01 14:10:02.621 2 gkacct.cxx(950) GKACCT
Successfully logged event 2 for call no. 5
2013/02/01 14:10:02.621 3 yasocket.cxx(794) Q931d Delete
socket 65.84.19.45:1720
2013/02/01 14:10:02.621 3 yasocket.cxx(794) Q931s Delete
socket 68.14.209.154:4003
2013/02/01 14:10:02.621 5 job.cxx(403) JOB Job
Acceptor deleted
2013/02/01 14:10:02.621 5 job.cxx(393) JOB Worker
threads: 10 total - 9 busy, 1 idle
2013/02/01 14:10:02.844 3 RasTbl.cxx(2370) Gk Delete Call No. 5
--
Marvin Herbold
[email protected]
480-347-0326
------------------------------------------------------------------------------
Everyone hates slow websites. So do we.
Make your web apps faster with AppDynamics
Download AppDynamics Lite for free today:
http://p.sf.net/sfu/appdyn_d2d_jan
_______________________________________________________
Posting: mailto:[email protected]
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/
