Wrong Total Duration

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

 



Hi,
 
I'm not sure if this is intended behaviour, but in some cases, the %t (total duration) value is set to the unix time equivalent of current time.  This happens when the call duration is 0.
 
I've attached a debug level 6 demonstrating the problem.  The problem occurs when I'm parsing the CDRs, while this doesn't affect the actual call duration, but, doesn't appear to be right either.
 
Is this indended behaviour?  Or, a minor bug?
 
Regards
HASSAAN
 
2007/03/30 01:35:22.753	6	    yasocket.cxx(669)	ipcaller:3670=>ipgnugk:7000 23 bytes sent
2007/03/30 01:35:22.753	5	         job.cxx(417)	JOB	Job StatusCmd debug trc 6 deleted
2007/03/30 01:35:22.753	5	         job.cxx(406)	JOB	Worker threads: 6 total - 5 busy, 1 idle
2007/03/30 01:35:23.534	6	    yasocket.cxx(830)	ProxyRTP(0) waiting...
2007/03/30 01:35:23.581	6	    yasocket.cxx(830)	ProxyH(0) waiting...
2007/03/30 01:35:24.300	5	    yasocket.cxx(771)	RasSrv	1 sockets selected from 2, total 2/0
2007/03/30 01:35:24.300	4	      RasSrv.cxx(230)	RAS	Receiving on ipgnugk:1719(U)
2007/03/30 01:35:24.300	2	      RasSrv.cxx(189)	RAS	Read from ipcaller:3210
2007/03/30 01:35:24.300	3	      RasSrv.cxx(238)	RAS
admissionRequest {
    requestSeqNum = 51906
    callType = pointToPoint <<null>>
    endpointIdentifier =  13 characters {
      0031 0031 0037 0035 005f 0069 0073 0070   1175_isp
      0072 006f 0073 0030 0034                  ros04
    }
    destinationInfo = 1 entries {
      [0]=dialedDigits "0123456789"
    }
    srcInfo = 1 entries {
      [0]=h323_ID  6 characters {
        0063 0061 0072 0074 0065 0073             cartes
      }
    }
    bandWidth = 200000
    callReferenceValue = 2975
    conferenceID =  16 octets {
      fc dd 39 20 52 f8 18 10  95 6b 00 11 2f 2b 87 86   ..9 R....k../+..
    }
    activeMC = FALSE
    answerCall = FALSE
    canMapAlias = TRUE
    callIdentifier = {
      guid =  16 octets {
        fc dd 39 20 52 f8 18 10  95 6a 00 11 2f 2b 87 86   ..9 R....j../+..
      }
    }
    gatekeeperIdentifier =  12 characters {
      0067 006b 0073 006b 0079 0068 0069 0067   gkskyhig
      0068 005f 0030 0034                       h_04
    }
    willSupplyUUIEs = TRUE
    canMapSrcAlias = FALSE
  }
2007/03/30 01:35:24.300	5	         job.cxx(354)	JOB	Worker threads: 6 total - 6 busy, 0 idle
2007/03/30 01:35:24.300	5	         job.cxx(180)	JOB	Starting Job ARQ at Worker thread 15384
2007/03/30 01:35:24.300	1	      RasSrv.cxx(368)	RAS	ARQ Received
2007/03/30 01:35:24.300	3	      RasSrv.cxx(2137)	GK	ARQ will request bandwith of 200000
2007/03/30 01:35:24.300	5	       Routing.h(169)	ROUTING	Checking policy NumberAnalysis for the request ARQ 51906
2007/03/30 01:35:24.300	5	       Routing.h(169)	ROUTING	Checking policy Internal for the request ARQ 51906
2007/03/30 01:35:24.300	2	      RasTbl.cxx(687)	RASTBL	Gateway 1895_gkid04 matched by prefix 8801, priority: 1
2007/03/30 01:35:24.300	4	      RasTbl.cxx(1106)	RASTBL	Prefix match for gateways: 
ipgateway:1720
2007/03/30 01:35:24.300	5	       Routing.h(175)	ROUTING	Policy Internal applied to the request ARQ 51906
2007/03/30 01:35:24.300	2	      RasTbl.cxx(2279)	CallTable::Insert(CALL) Call No. 2, total sessions : 1
2007/03/30 01:35:24.300	2	      RasSrv.cxx(413)	ACF|ipcaller:1720|1175_gkid04|2975|0123456789:dialedDigits|cartes:h323_ID|false;
2007/03/30 01:35:24.300	6	    yasocket.cxx(669)	ipcaller:3670=>ipgnugk:7000 92 bytes sent
2007/03/30 01:35:24.300	3	      RasSrv.cxx(250)	RAS	Send to ipcaller:3210
admissionConfirm {
    requestSeqNum = 51906
    bandWidth = 200000
    callModel = gatekeeperRouted <<null>>
    destCallSignalAddress = ipAddress {
      ip =  4 octets {
        nn nn nn nn                                        H.V.
      }
      port = 1720
    }
    irrFrequency = 120
    willRespondToIRR = FALSE
    uuiesRequested = {
      setup = FALSE
      callProceeding = FALSE
      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>>
      }
    }
  }
2007/03/30 01:35:24.300	5	      RasSrv.cxx(264)	RAS	Sent Successful
2007/03/30 01:35:24.300	5	         job.cxx(417)	JOB	Job ARQ deleted
2007/03/30 01:35:24.300	5	         job.cxx(406)	JOB	Worker threads: 6 total - 5 busy, 1 idle
2007/03/30 01:35:24.534	6	    yasocket.cxx(830)	ProxyRTP(0) waiting...
2007/03/30 01:35:24.581	6	    yasocket.cxx(830)	ProxyH(0) waiting...
2007/03/30 01:35:25.534	6	    yasocket.cxx(830)	ProxyRTP(0) waiting...
2007/03/30 01:35:25.581	6	    yasocket.cxx(830)	ProxyH(0) waiting...
2007/03/30 01:35:26.534	6	    yasocket.cxx(830)	ProxyRTP(0) waiting...
2007/03/30 01:35:26.581	6	    yasocket.cxx(830)	ProxyH(0) waiting...
2007/03/30 01:35:27.534	6	    yasocket.cxx(830)	ProxyRTP(0) waiting...
2007/03/30 01:35:27.581	6	    yasocket.cxx(830)	ProxyH(0) waiting...
2007/03/30 01:35:28.534	6	    yasocket.cxx(830)	ProxyRTP(0) waiting...
2007/03/30 01:35:28.581	6	    yasocket.cxx(830)	ProxyH(0) waiting...
2007/03/30 01:35:29.534	6	    yasocket.cxx(830)	ProxyRTP(0) waiting...
2007/03/30 01:35:29.581	6	    yasocket.cxx(830)	ProxyH(0) waiting...
2007/03/30 01:35:30.534	6	    yasocket.cxx(830)	ProxyRTP(0) waiting...
2007/03/30 01:35:30.581	6	    yasocket.cxx(830)	ProxyH(0) waiting...
2007/03/30 01:35:31.534	6	    yasocket.cxx(830)	ProxyRTP(0) waiting...
2007/03/30 01:35:31.581	6	    yasocket.cxx(830)	ProxyH(0) waiting...
2007/03/30 01:35:32.534	6	    yasocket.cxx(830)	ProxyRTP(0) waiting...
2007/03/30 01:35:32.581	6	    yasocket.cxx(830)	ProxyH(0) waiting...
2007/03/30 01:35:33.534	6	    yasocket.cxx(830)	ProxyRTP(0) waiting...
2007/03/30 01:35:33.581	6	    yasocket.cxx(830)	ProxyH(0) waiting...
2007/03/30 01:35:34.534	6	    yasocket.cxx(830)	ProxyRTP(0) waiting...
2007/03/30 01:35:34.581	6	    yasocket.cxx(830)	ProxyH(0) waiting...
2007/03/30 01:35:34.612	5	    yasocket.cxx(771)	RasSrv	1 sockets selected from 2, total 2/0
2007/03/30 01:35:34.612	4	      RasSrv.cxx(230)	RAS	Receiving on ipgnugk:1719(U)
2007/03/30 01:35:34.612	2	      RasSrv.cxx(189)	RAS	Read from ipcaller:3210
2007/03/30 01:35:34.612	3	      RasSrv.cxx(238)	RAS
disengageRequest {
    requestSeqNum = 51907
    endpointIdentifier =  13 characters {
      0031 0031 0037 0035 005f 0069 0073 0070   1175_isp
      0072 006f 0073 0030 0034                  ros04
    }
    conferenceID =  16 octets {
      fc dd 39 20 52 f8 18 10  95 6b 00 11 2f 2b 87 86   ..9 R....k../+..
    }
    callReferenceValue = 2975
    disengageReason = normalDrop <<null>>
    callIdentifier = {
      guid =  16 octets {
        fc dd 39 20 52 f8 18 10  95 6a 00 11 2f 2b 87 86   ..9 R....j../+..
      }
    }
    gatekeeperIdentifier =  12 characters {
      0067 006b 0073 006b 0079 0068 0069 0067   gkskyhig
      0068 005f 0030 0034                       h_04
    }
    answeredCall = FALSE
    usageInformation = {
      nonStandardUsageFields = 0 entries {
      }
      endTime = 1175196937
    }
    terminationCause = releaseCompleteCauseIE  2 octets {
      80 9b                                              ..
    }
  }
2007/03/30 01:35:34.612	5	         job.cxx(354)	JOB	Worker threads: 6 total - 6 busy, 0 idle
2007/03/30 01:35:34.612	5	         job.cxx(180)	JOB	Starting Job DRQ at Worker thread 15384
2007/03/30 01:35:34.612	1	      RasSrv.cxx(368)	RAS	DRQ Received
2007/03/30 01:35:34.612	4	      RasSrv.cxx(2412)	GK	DRQ: closed conference
2007/03/30 01:35:34.612	6	      RasTbl.cxx(2431)	GK	Removing callptr: fc dd 39 20 52 f8 18 10 95 6a 00 11 2f 2b 87 86
2007/03/30 01:35:34.612	6	    yasocket.cxx(669)	ipcaller:3670=>ipgnugk:7000 206 bytes sent
2007/03/30 01:35:34.612	1	      RasTbl.cxx(2492)	CDR|2|fc dd 39 20 52 f8 18 10 95 6a 00 11 2f 2b 87 86|0|unconnected|20070330013534|ipcaller:1720|1175_gkid04|ipgateway:1720|1895_gkid04|0123456789:dialedDigits|cartes:h323_ID|gk_04;


2007/03/30 01:35:34.612	5	      gkacct.cxx(792)	GKACCT	FileAcct - CDR string for event 2, call no. 2: gk_04,2,0123456789,0,1175196934,10,460c14d900000002,16,1,cartes,ipgnugk,,,,20070330013534,0,ipcaller,1720,ipgateway,1720,cartes:h323_ID,0123456789:dialedDigits,cartes,0123456789,fc dd 39 20 52 f8 18 10 95 6a 00 11 2f 2b 87 86,fc dd 39 20 52 f8 18 10 95 6b 00 11 2f 2b 87 86,1175_gkid04,1895_gkid04,1,0,,
2007/03/30 01:35:34.612	3	      gkacct.cxx(974)	GKACCT	FileAcct logged event 2 for call no. 2
2007/03/30 01:35:34.612	6	    yasocket.cxx(669)	ipcaller:3670=>ipgnugk:7000 91 bytes sent
2007/03/30 01:35:34.612	3	      gkacct.cxx(974)	GKACCT	StatusAcct logged event 2 for call no. 2
2007/03/30 01:35:34.612	2	      gkacct.cxx(1014)	GKACCT	Successfully logged event 2 for call no. 2
2007/03/30 01:35:34.612	2	      RasSrv.cxx(413)	DCF|ipcaller|1175_gkid04|2975|normalDrop;
2007/03/30 01:35:34.612	6	    yasocket.cxx(669)	ipcaller:3670=>ipgnugk:7000 50 bytes sent
2007/03/30 01:35:34.612	3	      RasSrv.cxx(250)	RAS	Send to ipcaller:3210
disengageConfirm {
    requestSeqNum = 51907
  }
2007/03/30 01:35:34.612	5	      RasSrv.cxx(264)	RAS	Sent Successful
2007/03/30 01:35:34.612	5	         job.cxx(417)	JOB	Job DRQ deleted
2007/03/30 01:35:34.612	5	         job.cxx(406)	JOB	Worker threads: 6 total - 5 busy, 1 idle
2007/03/30 01:35:35.534	6	    yasocket.cxx(830)	ProxyRTP(0) waiting...
2007/03/30 01:35:35.581	6	    yasocket.cxx(830)	ProxyH(0) waiting...
2007/03/30 01:35:35.581	3	      RasTbl.cxx(1495)	Gk	Delete Call No. 2
2007/03/30 01:35:36.534	6	    yasocket.cxx(830)	ProxyRTP(0) waiting...
2007/03/30 01:35:36.581	6	    yasocket.cxx(830)	ProxyH(0) waiting...
2007/03/30 01:35:36.940	5	    yasocket.cxx(771)	GkStatus	1 sockets selected from 1, total 1/0
2007/03/30 01:35:36.940	6	    yasocket.cxx(669)	ipcaller:3670=>ipgnugk:7000 12 bytes sent
2007/03/30 01:35:37.284	5	    yasocket.cxx(771)	GkStatus	1 sockets selected from 1, total 1/0
2007/03/30 01:35:37.534	6	    yasocket.cxx(830)	ProxyRTP(0) waiting...
2007/03/30 01:35:37.581	6	    yasocket.cxx(830)	ProxyH(0) waiting...
2007/03/30 01:35:37.597	5	    yasocket.cxx(771)	GkStatus	1 sockets selected from 1, total 1/0
2007/03/30 01:35:37.972	5	    yasocket.cxx(771)	GkStatus	1 sockets selected from 1, total 1/0
2007/03/30 01:35:37.972	6	    yasocket.cxx(669)	ipcaller:3670=>ipgnugk:7000 2 bytes sent
2007/03/30 01:35:37.972	5	         job.cxx(354)	JOB	Worker threads: 6 total - 6 busy, 0 idle
2007/03/30 01:35:37.972	5	    yasocket.cxx(771)	GkStatus	1 sockets selected from 1, total 1/0
2007/03/30 01:35:37.972	5	         job.cxx(180)	JOB	Starting Job StatusCmd debug trc 0 at Worker thread 15384
2007/03/30 01:35:37.972	5	    GkStatus.cxx(1111)	STATUS	Got command debug trc 0 from client ipcaller:3670=>ipgnugk:7000
-------------------------------------------------------------------------
Take Surveys. Earn Cash. Influence the Future of IT
Join SourceForge.net's Techsay panel and you'll get the chance to share your
opinions on IT & business topics through brief surveys-and earn cash
http://www.techsay.com/default.php?page=join.php&p=sourceforge&CID=DEVDEV
_______________________________________________________

Posting: mailto:Openh323gk-users@xxxxxxxxxxxxxxxxxxxxx
Archive: http://sourceforge.net/mailarchive/forum.php?forum_id=8549
Unsubscribe: http://lists.sourceforge.net/lists/listinfo/openh323gk-users
Homepage: http://www.gnugk.org/

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

  Powered by Linux