thr3ads.net - asterisk users - [Asterisk-Users] a2billing problem with call duration [Mar 2006]

If this information is useful, please help other people find it:
Share via:
d_pejic@inecco.net
2006-Mar-07 07:32 UTC
[Asterisk-Users] a2billing problem with call duration

Regards!

During the use of areski a2billing software I'm getting same problem all the
time.
Actually, after 15 minutes of speaking to someone over calling card, connection
brakes.
Installation was as smooth as it could be so I don't think I made same kind
of a mess in that domain. This is the only problem in the aplication.
In the logs everything seems to be fine.
I'am sending You log as an apendix bellow the text.
Is it a asterisk problem or...

a2billing.log



[05/03/2006 19:39:45]:[CallerID:051359687]:[CN:0474]:[CC_asterisk_rate-engine:
Count Total result 1]
[05/03/2006 19:39:45]:[CallerID:051359687]:[CN:0474]:[CC_asterisk_rate-engine:
Count Total result 1]
[05/03/2006 19:39:45]:[CallerID:051359687]:[CN:0474]:[CC_asterisk_rate-engine:
number_trunk 1]
[05/03/2006
19:39:46]:[CallerID:051359687]:[CN:0474]:[CC_RATE_ENGINE_ALL_CALCULTIMEOUT
(81.1667)]
[05/03/2006
19:39:46]:[CallerID:051359687]:[CN:0474]:[CC_RATE_ENGINE_ALL_CALCULTIMEOUT: k=0
- res_calcultimeout:4869]
[05/03/2006 19:52:37]:[CallerID:051359687]:[CN:0474]:DIAL
SIP/odlazni/00436642780018|90|HL(4869000:61000:30000)
[05/03/2006
19:52:37]:[CallerID:051359687]:[CN:0474]:[K=0]:[ANSWEREDTIME=751-DIALSTATUS=ANSWER]
[05/03/2006 19:52:37]:[CallerID:051359687]:[CN:0474]:[USEDRATECARD=0]
[05/03/2006 19:52:37]:[CallerID:051359687]:[CN:0474]:[CC_RATE_ENGINE_CALCULCOST:
K=0 - CALLDURATION:751]
[05/03/2006 19:52:37]:[CallerID:051359687]:[CN:0474]:[TEMP -
CC_RATE_ENGINE_CALCULCOST: 1. COST: -12.516666666667]:[ (751/60) * 1 ]
[05/03/2006 19:52:37]:[CallerID:051359687]:[CN:0474]:[CC_RATE_ENGINE_CALCULCOST:
K=0 - FINAL COST: -12.516666666667]
[05/03/2006
19:52:37]:[CallerID:051359687]:[CN:0474]:[CC_RATE_ENGINE_UPDATESYSTEM:
usedratecard K=0 - (sessiontime=751 :: dialstatus=ANSWER ::
cost=12.516666666667)]
[05/03/2006 19:52:37]:[CallerID:051359687]:[CN:0474]:[CC_asterisk_stop 1.1: SQL:
INSERT INTO call
(uniqueid,sessionid,username,nasipaddress,starttime,sessiontime, calledstation, 
terminatecause, stoptime, calledrate, sessionbill, calledcountry, calledsub,
destination, id_tariffgroup, id_tariffplan, id_ratecard, id_trunk, src) VALUES
('1141583953.47', 'SIP/callingcard-50e8',  '0474',
'', CURRENT_TIMESTAMP - INTERVAL 751 SECOND , '751',
'00436642780018', 'ANSWER', now(), '1',
'12.516666666667',  '', '', 'svet', '1',
'1', '1', '1', '051359687' )]
[05/03/2006 19:52:37]:[CallerID:051359687]:[CN:0474]:[CC_asterisk_stop 1.1: SQL:
DONE]
[05/03/2006 19:52:37]:[CallerID:051359687]:[CN:0474]:[CC_asterisk_stop 1.2: SQL:
UPDATE cc_card SET credit= credit-12.516666666667,
redial='00436642780018', lastuse=now(), nbused=nbused+1 WHERE
username='0474']
[05/03/2006 19:52:37]:[CallerID:051359687]:[CN:0474]:[callingcard_acct_stop]
[05/03/2006 19:52:37]:[CallerID:051359687]:[CN:0474]:[CHANNEL STATUS : 6 = Line
is up]
[05/03/2006 19:52:37]:[CallerID:051359687]:[CN:0474]:[CREDIT STATUS :
68.650033333333]
[05/03/2006 19:52:37]:[CallerID:051359687]:[CN:0474]:[DTMF DESTINATION ::>
-1]
[05/03/2006 19:52:37]:[CallerID:051359687]:[CN:0474]:[CHANNEL STATUS : -1 =
There is no channel that matches SIP/callingcard-50e8]
[05/03/2006 19:52:37]:[CallerID:051359687]:[CN:0474]:[CREDIT STATUS :
68.650033333333]
[05/03/2006 19:52:37]:[CallerID:051359687]:[CN:0474]:[Start: UPDATE cc_card SET
inuse=inuse-1 WHERE username='0474']
[05/03/2006 19:52:37]:[CallerID:051359687]:[CN:0474]:[STOP - EXIT]
[05/03/2006 19:38:06]:[CallerID:051212072]:[CN:4513]:[CC_asterisk_rate-engine:
Count Total result 1]
[05/03/2006 19:38:06]:[CallerID:051212072]:[CN:4513]:[CC_asterisk_rate-engine:
Count Total result 1]
[05/03/2006 19:38:06]:[CallerID:051212072]:[CN:4513]:[CC_asterisk_rate-engine:
number_trunk 1]
[05/03/2006
19:38:06]:[CallerID:051212072]:[CN:4513]:[CC_RATE_ENGINE_ALL_CALCULTIMEOUT
(46.750033333333)]
[05/03/2006
19:38:06]:[CallerID:051212072]:[CN:4513]:[CC_RATE_ENGINE_ALL_CALCULTIMEOUT: k=0
- res_calcultimeout:2804]
[05/03/2006 19:53:58]:[CallerID:051212072]:[CN:4513]:DIAL
SIP/odlazni/00497720954992|90|HL(2804000:61000:30000)
[05/03/2006
19:53:58]:[CallerID:051212072]:[CN:4513]:[K=0]:[ANSWEREDTIME=937-DIALSTATUS=ANSWER]
( my max call duration 937 sec)
[05/03/2006 19:53:58]:[CallerID:051212072]:[CN:4513]:[USEDRATECARD=0]
[05/03/2006 19:53:58]:[CallerID:051212072]:[CN:4513]:[CC_RATE_ENGINE_CALCULCOST:
K=0 - CALLDURATION:937]
[05/03/2006 19:53:58]:[CallerID:051212072]:[CN:4513]:[TEMP -
CC_RATE_ENGINE_CALCULCOST: 1. COST: -15.616666666667]:[ (937/60) * 1 ]
[05/03/2006 19:53:58]:[CallerID:051212072]:[CN:4513]:[CC_RATE_ENGINE_CALCULCOST:
K=0 - FINAL COST: -15.616666666667]
[05/03/2006
19:53:58]:[CallerID:051212072]:[CN:4513]:[CC_RATE_ENGINE_UPDATESYSTEM:
usedratecard K=0 - (sessiontime=937 :: dialstatus=ANSWER ::
cost=15.616666666667)]
[05/03/2006 19:53:58]:[CallerID:051212072]:[CN:4513]:[CC_asterisk_stop 1.1: SQL:
INSERT INTO call
(uniqueid,sessionid,username,nasipaddress,starttime,sessiontime, calledstation, 
terminatecause, stoptime, calledrate, sessionbill, calledcountry, calledsub,
destination, id_tariffgroup, id_tariffplan, id_ratecard, id_trunk, src) VALUES
('2141583490.41', 'SIP/callingcard-31f3',  '4513',
'', CURRENT_TIMESTAMP - INTERVAL 937 SECOND , '937',
'00497720954992', 'ANSWER', now(), '1',
'15.616666666667',  '', '', 'svet', '1',
'1', '1', '1', '051212072' )]
[05/03/2006 19:53:58]:[CallerID:051212072]:[CN:4513]:[CC_asterisk_stop 1.1: SQL:
DONE][05/03/2006 19:53:58]:[CallerID:051212072]:[CN:4513]:[CC_asterisk_stop 1.2:
SQL: UPDATE cc_card SET credit= credit-15.616666666667,
redial='00497720954992', lastuse=now(), nbused=nbused+1 WHERE
username='4513']
[05/03/2006 19:53:58]:[CallerID:051212072]:[CN:4513]:[callingcard_acct_stop]
[05/03/2006 19:53:58]:[CallerID:051212072]:[CN:4513]:[CHANNEL STATUS : 6 = Line
is up][05/03/2006 19:53:58]:[CallerID:051212072]:[CN:4513]:[CREDIT STATUS :
31.133366666667]
[05/03/2006 19:53:58]:[CallerID:051212072]:[CN:4513]:[DTMF DESTINATION ::>
-1][05/03/2006 19:53:58]:[CallerID:051212072]:[CN:4513]:[Start: UPDATE cc_card
SET inuse=inuse-1 WHERE username='4513'][05/03/2006
19:53:58]:[CallerID:051212072]:[CN:4513]:[exit][05/03/2006
19:54:21]:[CallerID:051212072]:[CN:]:EXEC SETLANGUAGE en
[05/03/2006 19:54:21]:[CallerID:051212072]:[CN:]:[CID_ENABLE - CID_CONTROL -
CID:051212072]
[05/03/2006 19:54:21]:[CallerID:051212072]:[CN:]:[CID_CONTROL - STOP - NO
CALLERID][05/03/2006 19:54:34]:[CallerID:051212072]:[CN:4513]:[TRY :
callingcard_ivr_authenticate]
[05/03/2006
19:54:34]:[CallerID:051212072]:[CN:4513]:[callingcard_acct_start_inuse]
[05/03/2006 19:54:34]:[CallerID:051212072]:[CN:4513]:[Start: UPDATE cc_card SET
inuse=inuse+1 WHERE username='4513']
[05/03/2006 19:54:34]:[CallerID:051212072]:[CN:4513]:[CHANNEL STATUS : 6 = Line
is up]
[05/03/2006 19:54:34]:[CallerID:051212072]:[CN:4513]:[CREDIT STATUS : 31.1333]
[05/03/2006 19:54:50]:[CallerID:051212072]:[CN:4513]:[DTMF DESTINATION ::>
00497720954992]
[05/03/2006 19:54:50]:[CallerID:051212072]:[CN:4513]:[CC_asterisk_rate-engine:
(1, 00497720954992)]
[05/03/2006 19:54:50]:[CallerID:051212072]:[CN:4513]:[CC_asterisk_rate-engine:
SUPER QUERY]
SELECT tariffgroupname, lcrtype, idtariffgroup,
cc_tariffgroup_plan.idtariffplan, tariffname, destination,

        cc_ratecard.id,
        dialprefix, destination, buyrate, buyrateinitblock, buyrateincrement,
rateinitial, initblock, billingblock,
        connectcharge, disconnectcharge, stepchargea, chargea, timechargea,
billingblocka, stepchargeb, chargeb,
        timechargeb, billingblockb, stepchargec, chargec, timechargec,
billingblockc,


        cc_tariffplan.id_trunk AS tp_id_trunk, tp_trunk.trunkprefix AS tp_trunk,
tp_trunk.providertech AS tp_providertech,
        tp_trunk.providerip AS tp_providerip, tp_trunk.removeprefix AS
tp_removeprefix,
        cc_ratecard.id_trunk AS rc_id_trunk, rt_trunk.trunkprefix AS
rc_trunkprefix, rt_trunk.providertech AS rc_providertech,
        rt_trunk.providerip AS rc_providerip, rt_trunk.removeprefix AS
rc_removeprefix,
        musiconhold,        tp_trunk.failover_trunk AS tp_failover_trunk,
        rt_trunk.failover_trunk AS rt_failover_trunk,
        tp_trunk.addparameter AS tp_addparameter_trunk,       
rt_trunk.addparameter AS rt_addparameter_trunk

        FROM cc_tariffgroup
        RIGHT JOIN cc_tariffgroup_plan ON cc_tariffgroup.id=1

        INNER JOIN cc_tariffplan ON
(cc_tariffplan.id=cc_tariffgroup_plan.idtariffplan )

        LEFT JOIN cc_ratecard ON cc_ratecard.idtariffplan=cc_tariffplan.id
        LEFT JOIN trunk AS rt_trunk ON cc_ratecard.id_trunk=rt_trunk.id_trunk
        LEFT JOIN trunk AS tp_trunk ON cc_tariffplan.id_trunk=tp_trunk.id_trunk

        WHERE
(dialprefix=SUBSTRING('00497720954992',1,length(dialprefix)) OR
dialprefix='defaultprefix')
        AND startingdate<= CURRENT_TIMESTAMP AND (expirationdate >
CURRENT_TIMESTAMP OR expirationdate IS NULL OR LENGTH(expirationdate)<5)
        AND startdate<= CURRENT_TIMESTAMP AND (stopdate >
CURRENT_TIMESTAMP OR stopdate IS NULL OR LENGTH(stopdate)<5)
         AND sunday='1'
        AND idtariffgroup='1'

        AND ( cc_tariffplan.dnidprefix LIKE '192.168.0.36%' OR
(cc_tariffplan.dnidprefix='all' AND 0 = 0))


        ORDER BY LENGTH(dialprefix) DESC

[05/03/2006 19:55:25]:[CallerID:051379680]:[CN:]:EXEC SETLANGUAGE en
[05/03/2006 19:55:25]:[CallerID:051379680]:[CN:]:[CID_ENABLE - CID_CONTROL -
CID:051379680]
[05/03/2006 19:55:25]:[CallerID:051379680]:[CN:]:[CID_CONTROL - STOP - NO
CALLERID]
[05/03/2006 19:55:43]:[CallerID:051379680]:[CN:4963]:[TRY :
callingcard_ivr_authenticate]
[05/03/2006 19:55:43]:[CallerID:051379680]:[CN:4963]:[AUTHENTICATION FAILED
(cia_res:-1)]
[05/03/2006 19:55:44]:[CallerID:051379680]:[CN:4963]:[exit]
[05/03/2006 19:55:57]:[CallerID:051379680]:[CN:]:EXEC SETLANGUAGE en
[05/03/2006 19:55:57]:[CallerID:051379680]:[CN:]:[CID_ENABLE - CID_CONTROL -
CID:051379680]
[05/03/2006 19:55:57]:[CallerID:051379680]:[CN:]:[CID_CONTROL - STOP - NO
CALLERID]
[05/03/2006 19:56:12]:[CallerID:051379680]:[CN:-1]:[TRY :
callingcard_ivr_authenticate]
[05/03/2006 19:56:12]:[CallerID:051379680]:[CN:-1]:[AUTHENTICATION FAILED
(cia_res:-1)]
[05/03/2006 19:56:12]:[CallerID:051379680]:[CN:-1]:[exit]
[05/03/2006 19:54:50]:[CallerID:051212072]:[CN:4513]:[CC_asterisk_rate-engine:
Count Total result 1]
[05/03/2006 19:54:50]:[CallerID:051212072]:[CN:4513]:[CC_asterisk_rate-engine:
Count Total result 1]
[05/03/2006 19:54:50]:[CallerID:051212072]:[CN:4513]:[CC_asterisk_rate-engine:
number_trunk 1]
[05/03/2006
19:54:50]:[CallerID:051212072]:[CN:4513]:[CC_RATE_ENGINE_ALL_CALCULTIMEOUT
(31.1333)]
[05/03/2006
19:54:50]:[CallerID:051212072]:[CN:4513]:[CC_RATE_ENGINE_ALL_CALCULTIMEOUT: k=0
- res_calcultimeout:1866]
[05/03/2006 20:10:07]:[CallerID:051212072]:[CN:4513]:DIAL
SIP/odlazni/00497720954992|90|HL(1866000:61000:30000)
[05/03/2006
20:10:07]:[CallerID:051212072]:[CN:4513]:[K=0]:[ANSWEREDTIME=903-DIALSTATUS=ANSWER]
[05/03/2006 20:10:07]:[CallerID:051212072]:[CN:4513]:[USEDRATECARD=0]
[05/03/2006 20:10:07]:[CallerID:051212072]:[CN:4513]:[CC_RATE_ENGINE_CALCULCOST:
K=0 - CALLDURATION:903]
[05/03/2006 20:10:07]:[CallerID:051212072]:[CN:4513]:[TEMP -
CC_RATE_ENGINE_CALCULCOST: 1. COST: -15.05]:[ (903/60) * 1 ]
[05/03/2006 20:10:07]:[CallerID:051212072]:[CN:4513]:[CC_RATE_ENGINE_CALCULCOST:
K=0 - FINAL COST: -15.05][05/03/2006
20:10:07]:[CallerID:051212072]:[CN:4513]:[CC_RATE_ENGINE_UPDATESYSTEM:
usedratecard K=0 - (sessiontime=903 :: dialstatus=ANSWER ::
cost=15.05)][05/03/2006
20:10:07]:[CallerID:051212072]:[CN:4513]:[CC_asterisk_stop 1.1: SQL: INSERT INTO
call (uniqueid,sessionid,username,nasipaddress,starttime,sessiontime,
calledstation,  terminatecause, stoptime, calledrate, sessionbill,
calledcountry, calledsub, destination, id_tariffgroup, id_tariffplan,
id_ratecard, id_trunk, src) VALUES ('1141584857.49',
'SIP/callingcard-716f',  '4513', '', CURRENT_TIMESTAMP -
INTERVAL 903 SECOND , '903', '00497720954992', 'ANSWER',
now(), '1', '15.05',  '', '', 'svet',
'1', '1', '1', '1', '051212072' )]
[05/03/2006 20:10:07]:[CallerID:051212072]:[CN:4513]:[CC_asterisk_stop 1.1: SQL:
DONE][05/03/2006 20:10:07]:[CallerID:051212072]:[CN:4513]:[CC_asterisk_stop 1.2:
SQL: UPDATE cc_card SET credit= credit-15.05, redial='00497720954992',
lastuse=now(), nbused=nbused+1 WHERE username='4513']
[05/03/2006 20:10:07]:[CallerID:051212072]:[CN:4513]:[callingcard_acct_stop]
[05/03/2006 20:10:07]:[CallerID:051212072]:[CN:4513]:[CHANNEL STATUS : 6 = Line
is up]
[05/03/2006 20:10:07]:[CallerID:051212072]:[CN:4513]:[CREDIT STATUS : 16.0833]
[05/03/2006 20:10:07]:[CallerID:051212072]:[CN:4513]:[DTMF DESTINATION ::>
-1]
[05/03/2006 20:10:07]:[CallerID:051212072]:[CN:4513]:[CHANNEL STATUS : -1 =
There is no channel that matches SIP/callingcard-716f]
[05/03/2006 20:10:07]:[CallerID:051212072]:[CN:4513]:[CREDIT STATUS : 16.0833]
[05/03/2006 20:10:07]:[CallerID:051212072]:[CN:4513]:[Start: UPDATE cc_card SET
inuse=inuse-1 WHERE username='4513']
[05/03/2006 20:10:07]:[CallerID:051212072]:[CN:4513]:[STOP - EXIT]
-------------- next part --------------
An HTML attachment was scrubbed...
URL:
http://lists.digium.com/pipermail/asterisk-users/attachments/20060307/222ad916/attachment.htm
Apparently Analagous Threads

Search for more apparently analagous threads
asterisk users - Mar 2006 - a2billing problem with call duration

[Asterisk-Users] a2billing problem with call duration

Apparently Analagous Threads

Wisdom of the Ancients
