RE: Re: Log contents of Automatic call drop

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



Hi
 
what i can read from that trace is, that the calling endpoint sends a DRQ with reason normalDrop. The reason for this seems to be that the gateway is not responding to the Q.931 Setup sent by the calling Endpoint. Either the gateway did not receive it or was unable (or unwilling) to send a response.
 
If you can trace the gateway i would have a look at it to see if the Q.931 Setup arrives. Since the gateway is not registered with the gatekeeper you need to make sure the gateway accepts Q.931 Call Setups without H.225 call signalling.
 
Why are you using Proxy Mode? Wouldn't it be enogh to use routed signalling mode?
 
- Frank
 


From: openh323gk-users-admin@xxxxxxxxxxxxxxxxxxxxx [mailto:openh323gk-users-admin@xxxxxxxxxxxxxxxxxxxxx] On Behalf Of Code Nirmata
Sent: Monday, December 12, 2005 9:46 AM
To: GnuGK User Forum
Subject: Re: Log contents of Automatic call drop [Frank Fischer]

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


Yahoo! Shopping
Find Great Deals on Holiday Gifts at Yahoo! Shopping

[Index of Archives]     [SIP]     [Open H.323]     [Gnu Gatekeeper]     [Asterisk PBX]     [ISDN Cause Codes]     [Yosemite News]

  Powered by Linux