Thank you Frank Fischer to prompt reply to me.
Here I attach my full log to you to analysis,but I lessen in some places to
reduce the attachment size................
please analysis it and descibe how can I trace the problems of dropping
calls frequently..........
Regards.......
----------------------------------------------------------------------------------------------
2005/12/12 14:30:47.071 3 RasSrv.cxx(219) RAS
// HERE I LESS REGISTRATION REQUEST LOG
2005/12/12 14:30:47.073 1 RasSrv.cxx(343) RAS RRQ Received
2005/12/12 14:30:47.074 3 gkauth.cxx(964) GKAUTH SQLAuth RRQ check ok
2005/12/12 14:30:47.075 1 RasTbl.cxx(79) New EP|210.4.78.146:1720|333560880:dialedDigits|terminal|7939_endp
2005/12/12 14:30:47.077 2 RasSrv.cxx(388) RCF|210.4.78.146:1720|333560880:dialedDigits|terminal|7939_endp;
2005/12/12 14:30:47.077 3 RasSrv.cxx(231) RAS Send to 210.4.78.146:1567
// HERE I LESS REGISTRATION CONFIRM REQUEST LOG
2005/12/12 14:30:55.184 1 RasSrv.cxx(343) RAS ARQ Received
2005/12/ 12 14:30:55.184 3 RasSrv.cxx(1948) GK ARQ will request bandwith of 200000
2005/12/12 14:30:55.184 2 RasTbl.cxx(695) RASTBL Gateway 7569_endp matched by prefix 00028801
2005/12/12 14:30:55.186 2 RasTbl.cxx(2029) CallTable::Insert(CALL) Call No. 500, total sessions : 1
2005/12/12 14:30:55.186 2 RasSrv.cxx(388)
ACF|210.4.78.146:1720|7939_endp|11473|0002880176079334:dialedDigits|333560880:dialedDigits|false;
2005/12/12 14:30:55.186 3 RasSrv.cxx(231) RAS Send to 210.4.78.146:1567< br> admissionConfirm {
requestSeqNum = 30115
bandWidth = 200000
callModel = gatekeeperRouted <<null>>
destCallSignalAddress = ipAddress {
ip = 4 octets {
ca 50 a8 f9 .P..
}
port = 1720
}
irrFrequency = 120
willRespondToIRR = FALSE
uuiesRequested = {
setup = FALSE
callProceeding = FALSE
&nb sp; connect = FALSE
alerting = FALSE
information = FALSE
releaseComplete = FALSE
facility = FALSE
progress = FALSE
empty = FALSE
status = FALSE
statusInquiry = FALSE
setupAcknowledge = FALSE
notify = FALSE
}
serviceControl = 1 entries {
[0]={
sessionId = 0
contents = callCreditServiceControl {
callStartingPoint = connect <<null>>
}
reason = refresh <<null>>
}
}
}
2005/12/12 14:30:56.805 3 ProxyChannel.cxx(542) Proxy 210.4.78.146:1568 read timeout?
2005/12/12 14:30:56.805 3 ProxyChannel.cxx(771) Q931s Received: Setup CRV=11473 from 210.4.78.146:1568
2005/12/12 14:30:56.821 2 gkacct.cxx(984) GKACCT Successfully logged event 1 for call no. 500
2005/12/12 14:30:56.821 3 ProxyChannel.cxx(1776) Q931s Call 500 is NAT type 0
2005/12/12 14:30:56.822 3 ProxyChannel.cxx(637) GK Call 500 proxy enabled
2005/12/12 14:30:56.888 2 RasSrv.cxx(173) RAS Read from 210.4.78.146:1567
2005/12/12 14:30:56.890 3 RasSrv.cxx(219) RAS
// HERE I LESS INFORMATION REQUEST LOG
2005/12/12 14:30:56.891 1 RasSrv.cxx(343) RAS IRR Received
2005/12/12 14:30:57.461 3 ProxyChannel.cxx(2305) Q931 Connect to 210.4.77.66:1720 from 202.80.168.249:0 successful
2005/12/12 14:30:59.063 3 ProxyChannel.cxx(771) Q931d Received: CallProceeding CRV=11473 from 210.4.77.66:1720
2005/12/12 14:31:01.824 3 ProxyChannel.cxx(771) Q931d Received: Progress CRV=11473 from 210.4.77.66:1720
2005/12/12 14:31:01.868 3 ProxyChannel.cxx(771) Q931d Received: Alerting CRV=11473 from 210.4.77.66:1720
2005/12/12 14:31:02.807 3 RasTbl.cxx(163) Gk Delete endpoint: 7939_endp 0
2005/12/12 14:31:21.488 3 ProxyChannel.cxx(771) Q931s Received: ReleaseComplete CRV=11473 from 210.4.78.146:1568
2005/12/12 14:31:21.489 2 RasTbl.cxx(2235) CDR ignore not connected call
2005/12/12 14:31:21.506 3 gkacct.cxx(944) GKACCT SQLAcct logged event 2 for call no. 500
2005/12/12 14:31:21.506 2 gkacct.cxx(984) GKACCT Successfully logged event 2 for call no. 500
2005/12/12 14:31:22.147 2 RasSrv.cxx(173) RAS Read from 210.4.78.146:1567
2005/12/12 14:31:22.148 3 RasSrv.cxx(219) RAS
disengageRequest {
requestSeqNum = 30117
endpointIdentifier = 9 characters {
0037 0039 0033 0039 005f 0065 006e 0064 7939_end
0070 p
}
conferenceID = 16 octets {
13 01 bd b3 9b f4 18 10 89 35 00 02 44 48 b3 45 .........5..DH.E
}
callReferenceValue = 11473
disengageReason = normalDrop <<null>>
callIdentifier = {
guid = 16 octets {
13 01 bd b3 9b f4 18 10 89 34 00 02 44 48 b3 45 .........4..DH.E
}
}
gatekeeperIdentifier = 7 characters {
004 2 0044 0043 004f 004d 0047 004b COMGK
}
cryptoTokens = 1 entries {
[0]=cryptoEPPwdHash {
alias = dialedDigits "333560880"
timeStamp = 1134376563
token = {
algorithmOID = 1.2.840.113549.2.5
paramS = {
}
hash = Hex: d0 60 3f ee b4 2d 95 c0 9f c8 f2 35 b8 a4 ee a7
}
}
}
answeredCall = FALSE
usageInformation = {
nonStandardUsageFields = 0 entries {
}
alertingTime = 1134376544
endTime = 1134376563
}
terminationCause = releaseCompleteCauseIE 2 octets {
80 90 ..
}
}
2005/12/12 14:31:22.149 1 RasSrv.cxx(343) RAS DRQ Received
2005/12/12 14:31:22.149 2 RasSrv.cxx(388)&nbs p; DCF|210.4.78.146|7939_endp|11473|normalDrop;
2005/12/12 14:31:22.150 3 RasSrv.cxx(231) RAS Send to 210.4.78.146:1567
disengageConfirm {
requestSeqNum = 30117
}
2005/12/12 14:31:26.657 3 yasocket.cxx(566) RTP Delete socket
2005/12/12 14:31:26.657 3 yasocket.cxx(566) RTCP Delete socket
2005/12/12 14:31:26.657 3 yasocket.cxx(566) Q931s Delete socket 210.4.78.146:1568
2005/12/12 14:31:26.657 3 yasocket.cxx(566) Q931d Delete socket 210.4.77.66:17 20
2005/12/12 14:31:27.047 3 RasTbl.cxx(1368) Gk Delete Call No. 500
2005/12/12 14:31:29.524 2 RasSrv.cxx(173) RAS Read from 210.4.78.146:1567
2005/12/12 14:31:29.525 3 RasSrv.cxx(219) RAS
unregistrationRequest {
requestSeqNum = 30118
callSignalAddress = 1 entries {
[0]=ipAddress {
ip = 4 octets {
d2 04 4e 92 ..N.
}
port = 1720
}
}
endpointAlias = 1 entries {
[0]=dialedDigits "333560880"
}
endpointIdentifier = 9 characters {
0037 0039 0033 0039 005f 0065 006e 0064 7939_end
0070 p
}
gatekeeperIdentifier = 7 characters {
0042 0044 0043 004f 004d 0047 004b COMGK
}
cryptoTokens = 1 entries {
[0]=cryptoEPPwdHash {
alias = dialedDigits "333560880"
timeStamp = 1134376571
token = {
algorithmOID = 1.2.840.113549.2.5
paramS = {
}
hash = Hex: c9 f1 fc b7 34 a2 75 e7 30 91 a4 45 cc 62 f2 f1
}
}
}
}
2005/12/12 14:31:29.525 1 RasSrv.cxx(343) RAS URQ Received
2005/12/12 14:31:29.525 2 RasSrv.cxx(388) UCF|210.4.78.146|7939_endp;
2005/12/12 14:31:29.526 3 RasSrv.cxx(231) RAS Send to 210.4.78.146:1567
unregistrationConfirm {
requestSeqNum = 30118
}
2005/12/12 14:31:31.807 3 yasocket.cxx(566) RTP Delete socket
210.4.78.146:5000<=>202.80.168.249:2972<=>210.4.77.66:10240
2005/12/12 14:31:31.807 3 yasocket.cxx(566) RTCP Delete socket
210.4.78.146:5001<=>202.80.168.249:2973<=>210.4.77.66:10241
2005/12/12 14:32:03.407 3 RasTbl.cxx(163) Gk Delete endpoint: 7939_endp 0
Here I attach my full log to you to analysis,but I lessen in some places to
reduce the attachment size................
please analysis it and descibe how can I trace the problems of dropping
calls frequently..........
Regards.......
----------------------------------------------------------------------------------------------
2005/12/12 14:30:47.071 3 RasSrv.cxx(219) RAS
// HERE I LESS REGISTRATION REQUEST LOG
2005/12/12 14:30:47.073 1 RasSrv.cxx(343) RAS RRQ Received
2005/12/12 14:30:47.074 3 gkauth.cxx(964) GKAUTH SQLAuth RRQ check ok
2005/12/12 14:30:47.075 1 RasTbl.cxx(79) New EP|210.4.78.146:1720|333560880:dialedDigits|terminal|7939_endp
2005/12/12 14:30:47.077 2 RasSrv.cxx(388) RCF|210.4.78.146:1720|333560880:dialedDigits|terminal|7939_endp;
2005/12/12 14:30:47.077 3 RasSrv.cxx(231) RAS Send to 210.4.78.146:1567
// HERE I LESS REGISTRATION CONFIRM REQUEST LOG
2005/12/12 14:30:55.184 1 RasSrv.cxx(343) RAS ARQ Received
2005/12/ 12 14:30:55.184 3 RasSrv.cxx(1948) GK ARQ will request bandwith of 200000
2005/12/12 14:30:55.184 2 RasTbl.cxx(695) RASTBL Gateway 7569_endp matched by prefix 00028801
2005/12/12 14:30:55.186 2 RasTbl.cxx(2029) CallTable::Insert(CALL) Call No. 500, total sessions : 1
2005/12/12 14:30:55.186 2 RasSrv.cxx(388)
ACF|210.4.78.146:1720|7939_endp|11473|0002880176079334:dialedDigits|333560880:dialedDigits|false;
2005/12/12 14:30:55.186 3 RasSrv.cxx(231) RAS Send to 210.4.78.146:1567< br> admissionConfirm {
requestSeqNum = 30115
bandWidth = 200000
callModel = gatekeeperRouted <<null>>
destCallSignalAddress = ipAddress {
ip = 4 octets {
ca 50 a8 f9 .P..
}
port = 1720
}
irrFrequency = 120
willRespondToIRR = FALSE
uuiesRequested = {
setup = FALSE
callProceeding = FALSE
&nb sp; connect = FALSE
alerting = FALSE
information = FALSE
releaseComplete = FALSE
facility = FALSE
progress = FALSE
empty = FALSE
status = FALSE
statusInquiry = FALSE
setupAcknowledge = FALSE
notify = FALSE
}
serviceControl = 1 entries {
[0]={
sessionId = 0
contents = callCreditServiceControl {
callStartingPoint = connect <<null>>
}
reason = refresh <<null>>
}
}
}
2005/12/12 14:30:56.805 3 ProxyChannel.cxx(542) Proxy 210.4.78.146:1568 read timeout?
2005/12/12 14:30:56.805 3 ProxyChannel.cxx(771) Q931s Received: Setup CRV=11473 from 210.4.78.146:1568
2005/12/12 14:30:56.821 2 gkacct.cxx(984) GKACCT Successfully logged event 1 for call no. 500
2005/12/12 14:30:56.821 3 ProxyChannel.cxx(1776) Q931s Call 500 is NAT type 0
2005/12/12 14:30:56.822 3 ProxyChannel.cxx(637) GK Call 500 proxy enabled
2005/12/12 14:30:56.888 2 RasSrv.cxx(173) RAS Read from 210.4.78.146:1567
2005/12/12 14:30:56.890 3 RasSrv.cxx(219) RAS
// HERE I LESS INFORMATION REQUEST LOG
2005/12/12 14:30:56.891 1 RasSrv.cxx(343) RAS IRR Received
2005/12/12 14:30:57.461 3 ProxyChannel.cxx(2305) Q931 Connect to 210.4.77.66:1720 from 202.80.168.249:0 successful
2005/12/12 14:30:59.063 3 ProxyChannel.cxx(771) Q931d Received: CallProceeding CRV=11473 from 210.4.77.66:1720
2005/12/12 14:31:01.824 3 ProxyChannel.cxx(771) Q931d Received: Progress CRV=11473 from 210.4.77.66:1720
2005/12/12 14:31:01.868 3 ProxyChannel.cxx(771) Q931d Received: Alerting CRV=11473 from 210.4.77.66:1720
2005/12/12 14:31:02.807 3 RasTbl.cxx(163) Gk Delete endpoint: 7939_endp 0
2005/12/12 14:31:21.488 3 ProxyChannel.cxx(771) Q931s Received: ReleaseComplete CRV=11473 from 210.4.78.146:1568
2005/12/12 14:31:21.489 2 RasTbl.cxx(2235) CDR ignore not connected call
2005/12/12 14:31:21.506 3 gkacct.cxx(944) GKACCT SQLAcct logged event 2 for call no. 500
2005/12/12 14:31:21.506 2 gkacct.cxx(984) GKACCT Successfully logged event 2 for call no. 500
2005/12/12 14:31:22.147 2 RasSrv.cxx(173) RAS Read from 210.4.78.146:1567
2005/12/12 14:31:22.148 3 RasSrv.cxx(219) RAS
disengageRequest {
requestSeqNum = 30117
endpointIdentifier = 9 characters {
0037 0039 0033 0039 005f 0065 006e 0064 7939_end
0070 p
}
conferenceID = 16 octets {
13 01 bd b3 9b f4 18 10 89 35 00 02 44 48 b3 45 .........5..DH.E
}
callReferenceValue = 11473
disengageReason = normalDrop <<null>>
callIdentifier = {
guid = 16 octets {
13 01 bd b3 9b f4 18 10 89 34 00 02 44 48 b3 45 .........4..DH.E
}
}
gatekeeperIdentifier = 7 characters {
004 2 0044 0043 004f 004d 0047 004b COMGK
}
cryptoTokens = 1 entries {
[0]=cryptoEPPwdHash {
alias = dialedDigits "333560880"
timeStamp = 1134376563
token = {
algorithmOID = 1.2.840.113549.2.5
paramS = {
}
hash = Hex: d0 60 3f ee b4 2d 95 c0 9f c8 f2 35 b8 a4 ee a7
}
}
}
answeredCall = FALSE
usageInformation = {
nonStandardUsageFields = 0 entries {
}
alertingTime = 1134376544
endTime = 1134376563
}
terminationCause = releaseCompleteCauseIE 2 octets {
80 90 ..
}
}
2005/12/12 14:31:22.149 1 RasSrv.cxx(343) RAS DRQ Received
2005/12/12 14:31:22.149 2 RasSrv.cxx(388)&nbs p; DCF|210.4.78.146|7939_endp|11473|normalDrop;
2005/12/12 14:31:22.150 3 RasSrv.cxx(231) RAS Send to 210.4.78.146:1567
disengageConfirm {
requestSeqNum = 30117
}
2005/12/12 14:31:26.657 3 yasocket.cxx(566) RTP Delete socket
2005/12/12 14:31:26.657 3 yasocket.cxx(566) RTCP Delete socket
2005/12/12 14:31:26.657 3 yasocket.cxx(566) Q931s Delete socket 210.4.78.146:1568
2005/12/12 14:31:26.657 3 yasocket.cxx(566) Q931d Delete socket 210.4.77.66:17 20
2005/12/12 14:31:27.047 3 RasTbl.cxx(1368) Gk Delete Call No. 500
2005/12/12 14:31:29.524 2 RasSrv.cxx(173) RAS Read from 210.4.78.146:1567
2005/12/12 14:31:29.525 3 RasSrv.cxx(219) RAS
unregistrationRequest {
requestSeqNum = 30118
callSignalAddress = 1 entries {
[0]=ipAddress {
ip = 4 octets {
d2 04 4e 92 ..N.
}
port = 1720
}
}
endpointAlias = 1 entries {
[0]=dialedDigits "333560880"
}
endpointIdentifier = 9 characters {
0037 0039 0033 0039 005f 0065 006e 0064 7939_end
0070 p
}
gatekeeperIdentifier = 7 characters {
0042 0044 0043 004f 004d 0047 004b COMGK
}
cryptoTokens = 1 entries {
[0]=cryptoEPPwdHash {
alias = dialedDigits "333560880"
timeStamp = 1134376571
token = {
algorithmOID = 1.2.840.113549.2.5
paramS = {
}
hash = Hex: c9 f1 fc b7 34 a2 75 e7 30 91 a4 45 cc 62 f2 f1
}
}
}
}
2005/12/12 14:31:29.525 1 RasSrv.cxx(343) RAS URQ Received
2005/12/12 14:31:29.525 2 RasSrv.cxx(388) UCF|210.4.78.146|7939_endp;
2005/12/12 14:31:29.526 3 RasSrv.cxx(231) RAS Send to 210.4.78.146:1567
unregistrationConfirm {
requestSeqNum = 30118
}
2005/12/12 14:31:31.807 3 yasocket.cxx(566) RTP Delete socket
210.4.78.146:5000<=>202.80.168.249:2972<=>210.4.77.66:10240
2005/12/12 14:31:31.807 3 yasocket.cxx(566) RTCP Delete socket
210.4.78.146:5001<=>202.80.168.249:2973<=>210.4.77.66:10241
2005/12/12 14:32:03.407 3 RasTbl.cxx(163) Gk Delete endpoint: 7939_endp 0
Yahoo! Shopping
Find Great Deals on Holiday Gifts at Yahoo! Shopping