|
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 0.....J...@..m.h 00 65 00 72 00 62 00 6f 00 6c 00 64 00 40 00 61 .e.r.b.o.l.d.@.a 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 .@.....9......8. 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 = 'mherbold@xxxxxxxxxxxxxxxxx', 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 0.....J...@..m.h 00 65 00 72 00 62 00 6f 00 6c 00 64 00 40 00 61 .e.r.b.o.l.d.@.a 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 |
------------------------------------------------------------------------------ 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: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/
