Asterisk agent prepaid incorrect call times and attributeS not being applied to CDRs for unanswered calls

34 views
Skip to first unread message

Enzo D'amato

unread,
Jul 15, 2025, 1:23:26 PMJul 15
to CGRateS
Hi. I'm working on building out a new CGRateS deployment, but I'm having issues with the call durations being recorded off of prepaid calls for asterisk. Calls lasting 40+ minutes are being billed out and charged as lasting only 1-2 minutes, and I can't figure out why. Additionally, though completed calls are processed correctly, when a call does not answer, the attributeS are not applied to the call, resulting in an incorrect account and subject field in the CDR. Do you know where to start looking to debug these issues?

I've included an ngrep of an incorrectly timed call below, along with the CDR that was produced. This call lasted for ~4m30s, but was only recorded as lasting ~3m30s.

NGREP:
T 2025/07/14 01:10:39.958450 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #147
{"method":"SessionSv1.InitiateSession","params":[{"GetAttributes":true,"AllocateResources":false,"InitSession":true,"ForceDuration":false,"ProcessThresholds":false
,"ProcessStats":false,"AttributeIDs":null,"ThresholdIDs":null,"StatIDs":null,"Tenant":"cgrates.org","ID":"a9eed01","Time":"2025-07-13T21:10:29.936-04:00","Event":{
"Account":"54.172.60.3","Ani":"+11112223333","AnswerTime":"2025-07-13T21:10:39.914-0400","Category":"intrastateIntralataCall","Destination":"+18458548000","Did":"+
18455676651","Direction":"in","EventName":"SMA_SESSION_START","OriginHost":"*redacted*","OriginID":"1752455429.126","RequestType":"*prepaid","Ser
vice":"dial","SetupTime":"2025-07-13T21:10:29.605-0400","Source":"AsteriskAgent","Uuid":"","cgr_flags":"*accounts+*attributes"},"APIOpts":{}}],"id":468}

#
T 2025/07/14 01:10:39.958666 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #148
{"method":"AttributeSv1.ProcessEvent","params":[{"Tenant":"cgrates.org","ID":"a9eed01","Time":"2025-07-13T21:10:29.936-04:00","Event":{"Account":"54.172.60.3","Ani
":"+11112223333","AnswerTime":"2025-07-13T21:10:39.914-0400","Category":"intrastateIntralataCall","Destination":"+18458548000","Did":"+11114445555","Direction":"in
","EventName":"SMA_SESSION_START","OriginHost":"*redacted*","OriginID":"1752455429.126","RequestType":"*prepaid","Service":"dial","SetupTime":"20
25-07-13T21:10:29.605-0400","Source":"AsteriskAgent","Uuid":"","cgr_flags":"*accounts+*attributes"},"APIOpts":{"*attrProfileIDs":null,"*context":"*sessions","*subs
ys":"*sessions"}}],"id":469}

##
T 2025/07/14 01:10:39.959004 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #150
{"id":469,"result":{"MatchedProfiles":["cgrates.org:attr_acctAlias_twilio"],"AlteredFields":["*req.Account","*req.Subject"],"Tenant":"cgrates.org","ID":"a9eed01","
Time":"2025-07-13T21:10:29.936-04:00","Event":{"Account":"acct_supplier_twilio","Ani":"+11112223333","AnswerTime":"2025-07-13T21:10:39.914-0400","Category":"intras
tateIntralataCall","Destination":"+18458548000","Did":"+11114445555","Direction":"in","EventName":"SMA_SESSION_START","OriginHost":"*redacted*","
OriginID":"1752455429.126","RequestType":"*prepaid","Service":"dial","SetupTime":"2025-07-13T21:10:29.605-0400","Source":"AsteriskAgent","Subject":"acct_supplier_t
wilio","Uuid":"","cgr_flags":"*accounts+*attributes"},"APIOpts":{"*attrProfileIDs":null,"*context":"*sessions","*subsys":"*sessions"}},"error":null}

#
T 2025/07/14 01:10:39.959156 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #151
{"method":"ChargerSv1.ProcessEvent","params":[{"Tenant":"cgrates.org","ID":"a9eed01","Time":"2025-07-13T21:10:29.936-04:00","Event":{"Account":"acct_supplier_twili
o","Ani":"+11112223333","AnswerTime":"2025-07-13T21:10:39.914-0400","CGRID":"1decf587829646e866a3c5649b0d27a91a6e644f","Category":"intrastateIntralataCall","Destin
ation":"+18458548000","Did":"+11114445555","Direction":"in","EventName":"SMA_SESSION_START","OriginHost":"*redacted*","OriginID":"1752455429.126"
,"RequestType":"*prepaid","Service":"dial","SetupTime":"2025-07-13T21:10:29.605-0400","Source":"AsteriskAgent","Subject":"acct_supplier_twilio","Uuid":"","cgr_flag
s":"*accounts+*attributes"},"APIOpts":{"*attrProfileIDs":null,"*subsys":"*sessions"}}],"id":470}

#
T 2025/07/14 01:10:39.960066 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #152
{"id":470,"result":[{"ChargerSProfile":"default","AttributeSProfiles":null,"AlteredFields":["*req.RunID"],"CGREvent":{"Tenant":"cgrates.org","ID":"a9eed01","Time":
"2025-07-13T21:10:29.936-04:00","Event":{"Account":"acct_supplier_twilio","Ani":"+11112223333","AnswerTime":"2025-07-13T21:10:39.914-0400","CGRID":"1decf587829646e
866a3c5649b0d27a91a6e644f","Category":"intrastateIntralataCall","Destination":"+18458548000","Did":"+11114445555","Direction":"in","EventName":"SMA_SESSION_START",
"OriginHost":"*redacted*","OriginID":"1752455429.126","RequestType":"*prepaid","RunID":"","Service":"dial","SetupTime":"2025-07-13T21:10:29.605-0
400","Source":"AsteriskAgent","Subject":"acct_supplier_twilio","Uuid":"","cgr_flags":"*accounts+*attributes"},"APIOpts":{"*attrProfileIDs":null,"*subsys":"*charger
s"}}}],"error":null}

#
T 2025/07/14 01:10:39.960290 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #153
{"id":468,"result":{"Attributes":{"MatchedProfiles":["cgrates.org:attr_acctAlias_twilio"],"AlteredFields":["*req.Account","*req.Subject"],"Tenant":"cgrates.org","I
D":"a9eed01","Time":"2025-07-13T21:10:29.936-04:00","Event":{"Account":"acct_supplier_twilio","Ani":"+11112223333","AnswerTime":"2025-07-13T21:10:39.914-0400","CGR
ID":"1decf587829646e866a3c5649b0d27a91a6e644f","Category":"intrastateIntralataCall","Destination":"+18458548000","Did":"+11114445555","Direction":"in","EventName":
"SMA_SESSION_START","OriginHost":"*redacted*","OriginID":"1752455429.126","RequestType":"*prepaid","Service":"dial","SetupTime":"2025-07-13T21:10
:29.605-0400","Source":"AsteriskAgent","Subject":"acct_supplier_twilio","Uuid":"","cgr_flags":"*accounts+*attributes"},"APIOpts":{"*attrProfileIDs":null,"*subsys":
"*sessions"}},"MaxUsage":10800000000000},"error":null}

##
T 2025/07/14 01:10:39.960360 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #155
{"method":"Responder.MaxDebit","params":[{"Category":"intrastateIntralataCall","Tenant":"cgrates.org","Subject":"acct_supplier_twilio","Account":"acct_supplier_twi
lio","Destination":"+18458548000","TimeStart":"2025-07-13T21:10:39.914-04:00","TimeEnd":"2025-07-13T21:10:44.914-04:00","LoopIndex":0,"DurationIndex":5000000000,"F
allbackSubject":"","RatingInfos":null,"Increments":null,"ToR":"","ExtraFields":{"Ani":"+11112223333","Did":"+11114445555","Direction":"in","EventName":"SMA_SESSION
_START","Service":"dial","Uuid":"","cgr_flags":"*accounts+*attributes"},"MaxRate":0,"MaxRateUnit":0,"MaxCostSoFar":0,"CgrID":"1decf587829646e866a3c5649b0d27a91a6e6
44f","RunID":"","ForceDuration":false,"PerformRounding":false,"DenyNegativeAccount":false,"DryRun":false,"APIOpts":{"*attrProfileIDs":null,"*subsys":"*sessions"}}]
,"id":471}

#
T 2025/07/14 01:10:39.961967 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #156
{"method":"ThresholdSv1.ProcessEvent","params":[{"Tenant":"cgrates.org","ID":"5969724a-e2fb-4993-baac-6400fa4c00da","Time":"2025-07-14T01:10:39.961910783Z","Event"
:{"AllowNegative":true,"BalanceSummaries":[{"UUID":"7d7ee136-2d00-404d-96a1-8a822dadee01","ID":"bal_twilio_balance","Type":"*monetary","Initial":99.9864,"Value":99
.983,"Weight":10,"Disabled":false}],"Disabled":false,"ID":"acct_supplier_twilio","Tenant":"cgrates.org"},"APIOpts":{"*eventType":"AccountUpdate"}}],"id":472}

##
T 2025/07/14 01:10:39.962131 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #158
{"id":472,"result":null,"error":"NOT_FOUND"}

#
T 2025/07/14 01:10:39.962211 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #159
{"method":"StatSv1.ProcessEvent","params":[{"Tenant":"cgrates.org","ID":"5969724a-e2fb-4993-baac-6400fa4c00da","Time":"2025-07-14T01:10:39.961910783Z","Event":{"Al
lowNegative":true,"BalanceSummaries":[{"UUID":"7d7ee136-2d00-404d-96a1-8a822dadee01","ID":"bal_twilio_balance","Type":"*monetary","Initial":99.9864,"Value":99.983,
"Weight":10,"Disabled":false}],"Disabled":false,"ID":"acct_supplier_twilio","Tenant":"cgrates.org"},"APIOpts":{"*eventType":"AccountUpdate"}}],"id":473}

#
T 2025/07/14 01:10:39.962339 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #160
{"id":473,"result":null,"error":"NOT_FOUND"}

#
T 2025/07/14 01:10:39.963882 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #161
{"id":471,"result":{"Category":"intrastateIntralataCall","Tenant":"cgrates.org","Subject":"acct_supplier_twilio","Account":"acct_supplier_twilio","Destination":"+1
8458548000","ToR":"*voice","Cost":0.0034,"Timespans":[{"TimeStart":"2025-07-13T21:10:39.914-04:00","TimeEnd":"2025-07-13T21:11:39.914-04:00","Cost":0.0034,"RateInt
erval":{"Timing":{"ID":"*any","Years":[],"Months":[],"MonthDays":[],"WeekDays":[],"StartTime":"00:00:00","EndTime":""},"Rating":{"ConnectFee":0,"RoundingMethod":"*
up","RoundingDecimals":4,"MaxCost":10,"MaxCostStrategy":"*free","Rates":[{"GroupIntervalStart":0,"Value":0.0034,"RateIncrement":60000000000,"RateUnit":60000000000}
]},"Weight":10},"DurationIndex":60000000000,"Increments":[{"Duration":60000000000,"Cost":0.0034,"BalanceInfo":{"Unit":null,"Monetary":{"UUID":"7d7ee136-2d00-404d-9
6a1-8a822dadee01","ID":"bal_twilio_balance","Value":99.983,"RateInterval":null},"AccountID":"cgrates.org:acct_supplier_twilio"},"CompressFactor":1}],"RoundIncremen
t":null,"MatchedSubject":"*out:cgrates.org:intrastateIntralataCall:acct_supplier_twilio","MatchedPrefix":"+1","MatchedDestId":"dest_usGeneric","RatingPlanId":"rp_t
wilioOrig_0.34c60sec","CompressFactor":1}],"RatedUsage":60000000000,"AccountSummary":{"Tenant":"cgrates.org","ID":"acct_supplier_twilio","BalanceSummaries":[{"UUID
":"7d7ee136-2d00-404d-96a1-8a822dadee01","ID":"bal_twilio_balance","Type":"*monetary","Initial":99.9864,"Value":99.983,"Weight":10,"Disabled":false}],"AllowNegativ
e":true,"Disabled":false}},"error":null}

###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
##############
T 2025/07/14 01:11:39.971150 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #1805
{"method":"Responder.MaxDebit","params":[{"Category":"intrastateIntralataCall","Tenant":"cgrates.org","Subject":"acct_supplier_twilio","Account":"acct_supplier_twi
lio","Destination":"+18458548000","TimeStart":"2025-07-13T21:11:39.914-04:00","TimeEnd":"2025-07-13T21:11:44.914-04:00","LoopIndex":1,"DurationIndex":65000000000,"
FallbackSubject":"","RatingInfos":null,"Increments":null,"ToR":"","ExtraFields":{"Ani":"+11112223333","Did":"+11114445555","Direction":"in","EventName":"SMA_SESSIO
N_START","Service":"dial","Uuid":"","cgr_flags":"*accounts+*attributes"},"MaxRate":0,"MaxRateUnit":0,"MaxCostSoFar":0.0034,"CgrID":"1decf587829646e866a3c5649b0d27a
91a6e644f","RunID":"","ForceDuration":false,"PerformRounding":false,"DenyNegativeAccount":false,"DryRun":false,"APIOpts":{"*attrProfileIDs":null,"*subsys":"*sessio
ns"}}],"id":474}

#
T 2025/07/14 01:11:39.973024 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #1806
{"method":"ThresholdSv1.ProcessEvent","params":[{"Tenant":"cgrates.org","ID":"f2551502-dac8-43c9-8e83-a4ed825d05d5","Time":"2025-07-14T01:11:39.972946232Z","Event"
:{"AllowNegative":true,"BalanceSummaries":[{"UUID":"7d7ee136-2d00-404d-96a1-8a822dadee01","ID":"bal_twilio_balance","Type":"*monetary","Initial":99.983,"Value":99.
9796,"Weight":10,"Disabled":false}],"Disabled":false,"ID":"acct_supplier_twilio","Tenant":"cgrates.org"},"APIOpts":{"*eventType":"AccountUpdate"}}],"id":475}

##
T 2025/07/14 01:11:39.973217 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #1808
{"id":475,"result":null,"error":"NOT_FOUND"}

#
T 2025/07/14 01:11:39.973302 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #1809
{"method":"StatSv1.ProcessEvent","params":[{"Tenant":"cgrates.org","ID":"f2551502-dac8-43c9-8e83-a4ed825d05d5","Time":"2025-07-14T01:11:39.972946232Z","Event":{"Al
lowNegative":true,"BalanceSummaries":[{"UUID":"7d7ee136-2d00-404d-96a1-8a822dadee01","ID":"bal_twilio_balance","Type":"*monetary","Initial":99.983,"Value":99.9796,
"Weight":10,"Disabled":false}],"Disabled":false,"ID":"acct_supplier_twilio","Tenant":"cgrates.org"},"APIOpts":{"*eventType":"AccountUpdate"}}],"id":476}

#
T 2025/07/14 01:11:39.973444 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #1810
{"id":476,"result":null,"error":"NOT_FOUND"}

#
T 2025/07/14 01:11:39.974865 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #1811
{"id":474,"result":{"Category":"intrastateIntralataCall","Tenant":"cgrates.org","Subject":"acct_supplier_twilio","Account":"acct_supplier_twilio","Destination":"+1
8458548000","ToR":"*voice","Cost":0.0034,"Timespans":[{"TimeStart":"2025-07-13T21:11:39.914-04:00","TimeEnd":"2025-07-13T21:12:39.914-04:00","Cost":0.0034,"RateInt
erval":{"Timing":{"ID":"*any","Years":[],"Months":[],"MonthDays":[],"WeekDays":[],"StartTime":"00:00:00","EndTime":""},"Rating":{"ConnectFee":0,"RoundingMethod":"*
up","RoundingDecimals":4,"MaxCost":10,"MaxCostStrategy":"*free","Rates":[{"GroupIntervalStart":0,"Value":0.0034,"RateIncrement":60000000000,"RateUnit":60000000000}
]},"Weight":10},"DurationIndex":120000000000,"Increments":[{"Duration":60000000000,"Cost":0.0034,"BalanceInfo":{"Unit":null,"Monetary":{"UUID":"7d7ee136-2d00-404d-
96a1-8a822dadee01","ID":"bal_twilio_balance","Value":99.9796,"RateInterval":null},"AccountID":"cgrates.org:acct_supplier_twilio"},"CompressFactor":1}],"RoundIncrem
ent":null,"MatchedSubject":"*out:cgrates.org:intrastateIntralataCall:acct_supplier_twilio","MatchedPrefix":"+1","MatchedDestId":"dest_usGeneric","RatingPlanId":"rp
_twilioOrig_0.34c60sec","CompressFactor":1}],"RatedUsage":60000000000,"AccountSummary":{"Tenant":"cgrates.org","ID":"acct_supplier_twilio","BalanceSummaries":[{"UU
ID":"7d7ee136-2d00-404d-96a1-8a822dadee01","ID":"bal_twilio_balance","Type":"*monetary","Initial":99.983,"Value":99.9796,"Weight":10,"Disabled":false}],"AllowNegat
ive":true,"Disabled":false}},"error":null}

###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
################
T 2025/07/14 01:12:39.983646 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #3457
{"method":"Responder.MaxDebit","params":[{"Category":"intrastateIntralataCall","Tenant":"cgrates.org","Subject":"acct_supplier_twilio","Account":"acct_supplier_twi
lio","Destination":"+18458548000","TimeStart":"2025-07-13T21:12:39.914-04:00","TimeEnd":"2025-07-13T21:12:44.914-04:00","LoopIndex":2,"DurationIndex":125000000000,
"FallbackSubject":"","RatingInfos":null,"Increments":null,"ToR":"","ExtraFields":{"Ani":"+11112223333","Did":"+11114445555","Direction":"in","EventName":"SMA_SESSI
ON_START","Service":"dial","Uuid":"","cgr_flags":"*accounts+*attributes"},"MaxRate":0,"MaxRateUnit":0,"MaxCostSoFar":0.0068,"CgrID":"1decf587829646e866a3c5649b0d27
a91a6e644f","RunID":"","ForceDuration":false,"PerformRounding":false,"DenyNegativeAccount":false,"DryRun":false,"APIOpts":{"*attrProfileIDs":null,"*subsys":"*sessi
ons"}}],"id":477}

#
T 2025/07/14 01:12:39.985514 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #3458
{"method":"ThresholdSv1.ProcessEvent","params":[{"Tenant":"cgrates.org","ID":"128f571c-bc04-436f-b567-023dae8aacd1","Time":"2025-07-14T01:12:39.985414424Z","Event"
:{"AllowNegative":true,"BalanceSummaries":[{"UUID":"7d7ee136-2d00-404d-96a1-8a822dadee01","ID":"bal_twilio_balance","Type":"*monetary","Initial":99.9796,"Value":99
.9762,"Weight":10,"Disabled":false}],"Disabled":false,"ID":"acct_supplier_twilio","Tenant":"cgrates.org"},"APIOpts":{"*eventType":"AccountUpdate"}}],"id":478}

##
T 2025/07/14 01:12:39.985752 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #3460
{"id":478,"result":null,"error":"NOT_FOUND"}

#
T 2025/07/14 01:12:39.985941 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #3461
{"method":"StatSv1.ProcessEvent","params":[{"Tenant":"cgrates.org","ID":"128f571c-bc04-436f-b567-023dae8aacd1","Time":"2025-07-14T01:12:39.985414424Z","Event":{"Al
lowNegative":true,"BalanceSummaries":[{"UUID":"7d7ee136-2d00-404d-96a1-8a822dadee01","ID":"bal_twilio_balance","Type":"*monetary","Initial":99.9796,"Value":99.9762
,"Weight":10,"Disabled":false}],"Disabled":false,"ID":"acct_supplier_twilio","Tenant":"cgrates.org"},"APIOpts":{"*eventType":"AccountUpdate"}}],"id":479}

#
T 2025/07/14 01:12:39.986129 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #3462
{"id":479,"result":null,"error":"NOT_FOUND"}

#
T 2025/07/14 01:12:39.987660 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #3463
{"id":477,"result":{"Category":"intrastateIntralataCall","Tenant":"cgrates.org","Subject":"acct_supplier_twilio","Account":"acct_supplier_twilio","Destination":"+1
8458548000","ToR":"*voice","Cost":0.0034,"Timespans":[{"TimeStart":"2025-07-13T21:12:39.914-04:00","TimeEnd":"2025-07-13T21:13:39.914-04:00","Cost":0.0034,"RateInt
erval":{"Timing":{"ID":"*any","Years":[],"Months":[],"MonthDays":[],"WeekDays":[],"StartTime":"00:00:00","EndTime":""},"Rating":{"ConnectFee":0,"RoundingMethod":"*
up","RoundingDecimals":4,"MaxCost":10,"MaxCostStrategy":"*free","Rates":[{"GroupIntervalStart":0,"Value":0.0034,"RateIncrement":60000000000,"RateUnit":60000000000}
]},"Weight":10},"DurationIndex":180000000000,"Increments":[{"Duration":60000000000,"Cost":0.0034,"BalanceInfo":{"Unit":null,"Monetary":{"UUID":"7d7ee136-2d00-404d-
96a1-8a822dadee01","ID":"bal_twilio_balance","Value":99.9762,"RateInterval":null},"AccountID":"cgrates.org:acct_supplier_twilio"},"CompressFactor":1}],"RoundIncrem
ent":null,"MatchedSubject":"*out:cgrates.org:intrastateIntralataCall:acct_supplier_twilio","MatchedPrefix":"+1","MatchedDestId":"dest_usGeneric","RatingPlanId":"rp
_twilioOrig_0.34c60sec","CompressFactor":1}],"RatedUsage":60000000000,"AccountSummary":{"Tenant":"cgrates.org","ID":"acct_supplier_twilio","BalanceSummaries":[{"UU
ID":"7d7ee136-2d00-404d-96a1-8a822dadee01","ID":"bal_twilio_balance","Type":"*monetary","Initial":99.9796,"Value":99.9762,"Weight":10,"Disabled":false}],"AllowNega
tive":true,"Disabled":false}},"error":null}

###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
########
T 2025/07/14 01:13:39.995351 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #5101
{"method":"Responder.MaxDebit","params":[{"Category":"intrastateIntralataCall","Tenant":"cgrates.org","Subject":"acct_supplier_twilio","Account":"acct_supplier_twi
lio","Destination":"+18458548000","TimeStart":"2025-07-13T21:13:39.914-04:00","TimeEnd":"2025-07-13T21:13:44.914-04:00","LoopIndex":3,"DurationIndex":185000000000,
"FallbackSubject":"","RatingInfos":null,"Increments":null,"ToR":"","ExtraFields":{"Ani":"+11112223333","Did":"+11114445555","Direction":"in","EventName":"SMA_SESSI
ON_START","Service":"dial","Uuid":"","cgr_flags":"*accounts+*attributes"},"MaxRate":0,"MaxRateUnit":0,"MaxCostSoFar":0.010199999999999999,"CgrID":"1decf587829646e8
66a3c5649b0d27a91a6e644f","RunID":"","ForceDuration":false,"PerformRounding":false,"DenyNegativeAccount":false,"DryRun":false,"APIOpts":{"*attrProfileIDs":null,"*s
ubsys":"*sessions"}}],"id":480}

#
T 2025/07/14 01:13:39.997079 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #5102
{"method":"ThresholdSv1.ProcessEvent","params":[{"Tenant":"cgrates.org","ID":"190053e3-d21a-4233-aa8a-dec2f1b5a8ef","Time":"2025-07-14T01:13:39.997007062Z","Event"
:{"AllowNegative":true,"BalanceSummaries":[{"UUID":"7d7ee136-2d00-404d-96a1-8a822dadee01","ID":"bal_twilio_balance","Type":"*monetary","Initial":99.9762,"Value":99
.9728,"Weight":10,"Disabled":false}],"Disabled":false,"ID":"acct_supplier_twilio","Tenant":"cgrates.org"},"APIOpts":{"*eventType":"AccountUpdate"}}],"id":481}

##
T 2025/07/14 01:13:39.997242 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #5104
{"id":481,"result":null,"error":"NOT_FOUND"}

#
T 2025/07/14 01:13:39.997326 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #5105
{"method":"StatSv1.ProcessEvent","params":[{"Tenant":"cgrates.org","ID":"190053e3-d21a-4233-aa8a-dec2f1b5a8ef","Time":"2025-07-14T01:13:39.997007062Z","Event":{"Al
lowNegative":true,"BalanceSummaries":[{"UUID":"7d7ee136-2d00-404d-96a1-8a822dadee01","ID":"bal_twilio_balance","Type":"*monetary","Initial":99.9762,"Value":99.9728
,"Weight":10,"Disabled":false}],"Disabled":false,"ID":"acct_supplier_twilio","Tenant":"cgrates.org"},"APIOpts":{"*eventType":"AccountUpdate"}}],"id":482}

#
T 2025/07/14 01:13:39.997448 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #5106
{"id":482,"result":null,"error":"NOT_FOUND"}

#
T 2025/07/14 01:13:39.999090 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #5107
{"id":480,"result":{"Category":"intrastateIntralataCall","Tenant":"cgrates.org","Subject":"acct_supplier_twilio","Account":"acct_supplier_twilio","Destination":"+1
8458548000","ToR":"*voice","Cost":0.0034,"Timespans":[{"TimeStart":"2025-07-13T21:13:39.914-04:00","TimeEnd":"2025-07-13T21:14:39.914-04:00","Cost":0.0034,"RateInt
erval":{"Timing":{"ID":"*any","Years":[],"Months":[],"MonthDays":[],"WeekDays":[],"StartTime":"00:00:00","EndTime":""},"Rating":{"ConnectFee":0,"RoundingMethod":"*
up","RoundingDecimals":4,"MaxCost":10,"MaxCostStrategy":"*free","Rates":[{"GroupIntervalStart":0,"Value":0.0034,"RateIncrement":60000000000,"RateUnit":60000000000}
]},"Weight":10},"DurationIndex":240000000000,"Increments":[{"Duration":60000000000,"Cost":0.0034,"BalanceInfo":{"Unit":null,"Monetary":{"UUID":"7d7ee136-2d00-404d-
96a1-8a822dadee01","ID":"bal_twilio_balance","Value":99.9728,"RateInterval":null},"AccountID":"cgrates.org:acct_supplier_twilio"},"CompressFactor":1}],"RoundIncrem
ent":null,"MatchedSubject":"*out:cgrates.org:intrastateIntralataCall:acct_supplier_twilio","MatchedPrefix":"+1","MatchedDestId":"dest_usGeneric","RatingPlanId":"rp
_twilioOrig_0.34c60sec","CompressFactor":1}],"RatedUsage":60000000000,"AccountSummary":{"Tenant":"cgrates.org","ID":"acct_supplier_twilio","BalanceSummaries":[{"UU
ID":"7d7ee136-2d00-404d-96a1-8a822dadee01","ID":"bal_twilio_balance","Type":"*monetary","Initial":99.9762,"Value":99.9728,"Weight":10,"Disabled":false}],"AllowNega
tive":true,"Disabled":false}},"error":null}

###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
#######################################################################
T 2025/07/14 01:14:12.415277 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #5993
{"method":"Responder.RefundIncrements","params":[{"Category":"intrastateIntralataCall","Tenant":"cgrates.org","Subject":"acct_supplier_twilio","Account":"acct_supp
lier_twilio","Destination":"+18458548000","TimeStart":"0001-01-01T00:00:00Z","TimeEnd":"0001-01-01T00:00:00Z","LoopIndex":0,"DurationIndex":0,"FallbackSubject":"",
"RatingInfos":null,"Increments":null,"ToR":"*voice","ExtraFields":null,"MaxRate":0,"MaxRateUnit":0,"MaxCostSoFar":0,"CgrID":"1decf587829646e866a3c5649b0d27a91a6e64
4f","RunID":"","ForceDuration":false,"PerformRounding":false,"DenyNegativeAccount":false,"DryRun":false,"APIOpts":{"*attrProfileIDs":null,"*subsys":"*sessions"}}],
"id":483}

#
T 2025/07/14 01:14:12.415487 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #5994
{"id":483,"result":{"ID":"","BalanceMap":null,"UnitCounters":null,"ActionTriggers":null,"AllowNegative":false,"Disabled":false,"UpdateTime":"0001-01-01T00:00:00Z"}
,"error":null}

#
T 2025/07/14 01:14:12.419804 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #5995
{"method":"StatSv1.ProcessEvent","params":[{"Tenant":"cgrates.org","ID":"347b037","Time":null,"Event":{"Account":"acct_supplier_twilio","Ani":"+11112223333","Answe
rTime":"2025-07-13T21:10:39.914-04:00","CGRID":"1decf587829646e866a3c5649b0d27a91a6e644f","Category":"intrastateIntralataCall","Cost":0.0136,"CostDetails":{"CGRID"
:"1decf587829646e866a3c5649b0d27a91a6e644f","RunID":"","StartTime":"2025-07-13T21:10:39.914-04:00","Usage":240000000000,"Cost":0.0136,"Charges":[{"RatingID":"e8ed2
9e","Increments":[{"Usage":60000000000,"Cost":0.0034,"AccountingID":"9f49d0d","CompressFactor":1}],"CompressFactor":4}],"AccountSummary":{"Tenant":"cgrates.org","I
D":"acct_supplier_twilio","BalanceSummaries":[{"UUID":"7d7ee136-2d00-404d-96a1-8a822dadee01","ID":"bal_twilio_balance","Type":"*monetary","Initial":99.9864,"Value"
:99.9728,"Weight":10,"Disabled":false}],"AllowNegative":true,"Disabled":false},"Rating":{"e8ed29e":{"ConnectFee":0,"RoundingMethod":"*up","RoundingDecimals":4,"Max
Cost":10,"MaxCostStrategy":"*free","TimingID":"db54b3b","RatesID":"17be580","RatingFiltersID":"0490bd9"}},"Accounting":{"9f49d0d":{"AccountID":"cgrates.org:acct_su
pplier_twilio","BalanceUUID":"7d7ee136-2d00-404d-96a1-8a822dadee01","RatingID":"","Units":0.0034,"ExtraChargeID":""}},"RatingFilters":{"0490bd9":{"DestinationID":"
dest_usGeneric","DestinationPrefix":"+1","RatingPlanID":"rp_twilioOrig_0.34c60sec","Subject":"*out:cgrates.org:intrastateIntralataCall:acct_supplier_twilio"}},"Rat
es":{"17be580":[{"GroupIntervalStart":0,"Value":0.0034,"RateIncrement":60000000000,"RateUnit":60000000000}]},"Timings":{"db54b3b":{"Years":[],"Months":[],"MonthDay
s":[],"WeekDays":[],"StartTime":"00:00:00"}}},"CostSource":"*sessions","Destination":"+18458548000","Did":"+11114445555","Direction":"in","EventName":"SMA_SESSION_
START","ExtraInfo":"","OrderID":0,"OriginHost":"*redacted*","OriginID":"1752455429.126","Partial":false,"PreRated":false,"RequestType":"*prepaid"
,"RunID":"*default","Service":"dial","SetupTime":"2025-07-13T21:10:29.605-04:00","Source":"AsteriskAgent","Subject":"acct_supplier_twilio","Tenant":"cgrates.org","
ToR":"*voice","Usage":215000000000,"Uuid":"","cgr_flags":"*accounts+*attributes"},"APIOpts":{"*attrProfileIDs":null,"*subsys":"*sessions"}}],"id":484}

#
T 2025/07/14 01:14:12.420034 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #5996
{"id":484,"result":null,"error":"NOT_FOUND"}

#
T 2025/07/14 01:14:12.420141 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #5997
{"method":"ResourceSv1.ReleaseResources","params":[{"Tenant":"cgrates.org","ID":"8eacf581-7cf0-43bb-8827-e1ba5df684e1","Time":null,"Event":{"Account":"acct_supplie
r_twilio","Ani":"+11112223333","AnswerTime":"2025-07-13T21:10:39.914-0400","CGRID":"1decf587829646e866a3c5649b0d27a91a6e644f","Category":"intrastateIntralataCall",
"Destination":"+18458548000","Did":"+11114445555","Direction":"in","EventName":"SMA_SESSION_START","OriginHost":"*redacted*","OriginID":"17524554
29.126","RequestType":"*prepaid","Service":"dial","SetupTime":"2025-07-13T21:10:29.605-0400","Source":"AsteriskAgent","Subject":"acct_supplier_twilio","Usage":2150
00000000,"Uuid":"","cgr_flags":"*accounts+*attributes"},"APIOpts":{"*attrProfileIDs":null,"*rsUnits":1,"*rsUsageID":"1752455429.126","*subsys":"*sessions"}}],"id":
485}

#
T 2025/07/14 01:14:12.420304 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #5998
{"id":485,"result":null,"error":"NOT_FOUND"}

###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###################################################################################################################################################################
###############################################################################################################################################################
T 2025/07/14 01:15:11.819673 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #7624
{"method":"SessionSv1.TerminateSession","params":[{"TerminateSession":true,"ForceDuration":false,"ReleaseResources":false,"ProcessThresholds":false,"ProcessStats":
false,"ThresholdIDs":null,"StatIDs":null,"Tenant":"cgrates.org","ID":"a9eed01","Time":"2025-07-13T21:10:29.936-04:00","Event":{"Account":"54.172.60.3","Ani":"+1845
2488078","AnswerTime":"2025-07-13T21:10:39.914-0400","Category":"intrastateIntralataCall","Destination":"+18458548000","Did":"+11114445555","Direction":"in","Disco
nnectCause":"Normal Clearing","EventName":"SMA_SESSION_TERMINATE","OriginHost":"*redacted*","OriginID":"1752455429.126","RequestType":"*prepaid",
"Service":"dial","SetupTime":"2025-07-13T21:10:29.605-0400","Source":"AsteriskAgent","Usage":"4m31.805s","Uuid":"","cgr_flags":"*accounts+*attributes"},"APIOpts":{
}}],"id":486}

#
T 2025/07/14 01:15:11.827557 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #7625
{"method":"ChargerSv1.ProcessEvent","params":[{"Tenant":"cgrates.org","ID":"a9eed01","Time":"2025-07-13T21:10:29.936-04:00","Event":{"Account":"54.172.60.3","Ani":
"+11112223333","AnswerTime":"2025-07-13T21:10:39.914-0400","CGRID":"1decf587829646e866a3c5649b0d27a91a6e644f","Category":"intrastateIntralataCall","Destination":"+
18458548000","Did":"+11114445555","Direction":"in","DisconnectCause":"Normal Clearing","EventName":"SMA_SESSION_TERMINATE","OriginHost":"ssw1.min.sip.ricetelecom.n
et","OriginID":"1752455429.126","RequestType":"*prepaid","Service":"dial","SetupTime":"2025-07-13T21:10:29.605-0400","Source":"AsteriskAgent","Usage":"4m31.805s","
Uuid":"","cgr_flags":"*accounts+*attributes"},"APIOpts":{}}],"id":487}

##
T 2025/07/14 01:15:11.830459 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #7627
{"id":487,"result":[{"ChargerSProfile":"default","AttributeSProfiles":null,"AlteredFields":["*req.RunID"],"CGREvent":{"Tenant":"cgrates.org","ID":"a9eed01","Time":
"2025-07-13T21:10:29.936-04:00","Event":{"Account":"54.172.60.3","Ani":"+11112223333","AnswerTime":"2025-07-13T21:10:39.914-0400","CGRID":"1decf587829646e866a3c564
9b0d27a91a6e644f","Category":"intrastateIntralataCall","Destination":"+18458548000","Did":"+11114445555","Direction":"in","DisconnectCause":"Normal Clearing","Even
tName":"SMA_SESSION_TERMINATE","OriginHost":"*redacted*","OriginID":"1752455429.126","RequestType":"*prepaid","RunID":"","Service":"dial","SetupT
ime":"2025-07-13T21:10:29.605-0400","Source":"AsteriskAgent","Usage":"4m31.805s","Uuid":"","cgr_flags":"*accounts+*attributes"},"APIOpts":{"*subsys":"*chargers"}}}
],"error":null}

##
T 2025/07/14 01:15:11.830624 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #7629
{"method":"Responder.MaxDebit","params":[{"Category":"intrastateIntralataCall","Tenant":"cgrates.org","Subject":"54.172.60.3","Account":"54.172.60.3","Destination"
:"+18458548000","TimeStart":"2025-07-13T21:10:39.914-04:00","TimeEnd":"2025-07-13T21:15:11.719-04:00","LoopIndex":0,"DurationIndex":271805000000,"FallbackSubject":
"","RatingInfos":null,"Increments":null,"ToR":"","ExtraFields":{"Ani":"+11112223333","Did":"+11114445555","Direction":"in","DisconnectCause":"Normal Clearing","Eve
ntName":"SMA_SESSION_TERMINATE","Service":"dial","Uuid":"","cgr_flags":"*accounts+*attributes"},"MaxRate":0,"MaxRateUnit":0,"MaxCostSoFar":0,"CgrID":"1decf58782964
6e866a3c5649b0d27a91a6e644f","RunID":"","ForceDuration":false,"PerformRounding":false,"DenyNegativeAccount":false,"DryRun":false,"APIOpts":{}}],"id":488}

#
T 2025/07/14 01:15:11.832256 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #7630
{"id":488,"result":null,"error":"ACCOUNT_NOT_FOUND"}

#
T 2025/07/14 01:15:11.832366 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #7631
{"id":486,"result":null,"error":"ACCOUNT_NOT_FOUND"}

####
T 2025/07/14 01:15:11.832500 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #7635
{"method":"SessionSv1.ProcessCDR","params":[{"Tenant":"cgrates.org","ID":"a9eed01","Time":"2025-07-13T21:10:29.936-04:00","Event":{"Account":"54.172.60.3","Ani":"+
18452488078","AnswerTime":"2025-07-13T21:10:39.914-0400","Category":"intrastateIntralataCall","Destination":"+18458548000","Did":"+11114445555","Direction":"in","D
isconnectCause":"Normal Clearing","EventName":"SMA_SESSION_TERMINATE","OriginHost":"*redacted*","OriginID":"1752455429.126","RequestType":"*prepa
id","Service":"dial","SetupTime":"2025-07-13T21:10:29.605-0400","Source":"AsteriskAgent","Usage":"4m31.805s","Uuid":"","cgr_flags":"*accounts+*attributes"},"APIOpt
s":{}}],"id":489}

#
T 2025/07/14 01:15:11.832703 127.0.0.1:41874 -> 127.0.0.1:2012 [AP] #7636
{"method":"AttributeSv1.ProcessEvent","params":[{"Tenant":"cgrates.org","ID":"a9eed01","Time":"2025-07-13T21:10:29.936-04:00","Event":{"Account":"54.172.60.3","Ani
":"+11112223333","AnswerTime":"2025-07-13T21:10:39.914-0400","CGRID":"1decf587829646e866a3c5649b0d27a91a6e644f","Category":"intrastateIntralataCall","Destination":
"+18458548000","Did":"+11114445555","Direction":"in","DisconnectCause":"Normal Clearing","EventName":"SMA_SESSION_TERMINATE","OriginHost":"ssw1.min.sip.ricetelecom
.net","OriginID":"1752455429.126","RequestType":"*prepaid","Service":"dial","SetupTime":"2025-07-13T21:10:29.605-0400","Source":"AsteriskAgent","Usage":"4m31.805s"
,"Uuid":"","cgr_flags":"*accounts+*attributes"},"APIOpts":{"*context":"*cdrs","*subsys":"*cdrs"}}],"id":490}

##
T 2025/07/14 01:15:11.844956 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #7638
{"id":490,"result":{"MatchedProfiles":["cgrates.org:attr_acctAlias_twilio"],"AlteredFields":["*req.Account","*req.Subject"],"Tenant":"cgrates.org","ID":"a9eed01","
Time":"2025-07-13T21:10:29.936-04:00","Event":{"Account":"acct_supplier_twilio","Ani":"+11112223333","AnswerTime":"2025-07-13T21:10:39.914-0400","CGRID":"1decf5878
29646e866a3c5649b0d27a91a6e644f","Category":"intrastateIntralataCall","Destination":"+18458548000","Did":"+11114445555","Direction":"in","DisconnectCause":"Normal
Clearing","EventName":"SMA_SESSION_TERMINATE","OriginHost":"*redacted*","OriginID":"1752455429.126","RequestType":"*prepaid","Service":"dial","Se
tupTime":"2025-07-13T21:10:29.605-0400","Source":"AsteriskAgent","Subject":"acct_supplier_twilio","Usage":"4m31.805s","Uuid":"","cgr_flags":"*accounts+*attributes"
},"APIOpts":{"*context":"*cdrs","*subsys":"*cdrs"}},"error":null}

#
T 2025/07/14 01:15:11.845209 127.0.0.1:2012 -> 127.0.0.1:41874 [AP] #7639
{"id":489,"result":null,"error":"EXISTS"}

Final CDR:
        {
            "CGRID": "1decf587829646e866a3c5649b0d27a91a6e644f",
            "RunID": "*default",
            "OrderID": 128,
            "OriginHost": "*redacted*",
            "Source": "AsteriskAgent",
            "OriginID": "1752455429.126",
            "ToR": "*voice",
            "RequestType": "*prepaid",
            "Tenant": "cgrates.org",
            "Category": "intrastateIntralataCall",
            "Account": "acct_supplier_twilio",
            "Subject": "acct_supplier_twilio",
            "Destination": "+18458548000",
            "SetupTime": "2025-07-14T01:10:29Z",
            "AnswerTime": "2025-07-14T01:10:39Z",
            "Usage": "3m35s",
            "ExtraFields": {
                "Ani": "+11112223333",
                "Did": "+11114445555",
                "Direction": "in",
                "EventName": "SMA_SESSION_START",
                "Service": "dial",
                "Uuid": "",
                "cgr_flags": "*accounts+*attributes"
            },
            "CostSource": "*sessions",
            "Cost": 0.0136,
            "CostDetails": "{\"CGRID\":\"1decf587829646e866a3c5649b0d27a91a6e644f\",\"RunID\":\"\",\"StartTime\":\"2025-07-13T21:10:39.914-04:00\",\"Usage\":240000000000,\"Cost\":0.0136,\"Charges\":[{\"RatingID\":\"e8ed29e\",\"Increments\":[{\"Usage\":60000000000,\"Cost\":0.0034,\"AccountingID\":\"9f49d0d\",\"CompressFactor\":1}],\"CompressFactor\":4}],\"AccountSummary\":{\"Tenant\":\"cgrates.org\",\"ID\":\"acct_supplier_twilio\",\"BalanceSummaries\":[{\"UUID\":\"7d7ee136-2d00-404d-96a1-8a822dadee01\",\"ID\":\"bal_twilio_balance\",\"Type\":\"*monetary\",\"Initial\":99.9864,\"Value\":99.9728,\"Weight\":10,\"Disabled\":false}],\"AllowNegative\":true,\"Disabled\":false},\"Rating\":{\"e8ed29e\":{\"ConnectFee\":0,\"RoundingMethod\":\"*up\",\"RoundingDecimals\":4,\"MaxCost\":10,\"MaxCostStrategy\":\"*free\",\"TimingID\":\"db54b3b\",\"RatesID\":\"17be580\",\"RatingFiltersID\":\"0490bd9\"}},\"Accounting\":{\"9f49d0d\":{\"AccountID\":\"cgrates.org:acct_supplier_twilio\",\"BalanceUUID\":\"7d7ee136-2d00-404d-96a1-8a822dadee01\",\"RatingID\":\"\",\"Units\":0.0034,\"ExtraChargeID\":\"\"}},\"RatingFilters\":{\"0490bd9\":{\"DestinationID\":\"dest_usGeneric\",\"DestinationPrefix\":\"+1\",\"RatingPlanID\":\"rp_twilioOrig_0.34c60sec\",\"Subject\":\"*out:cgrates.org:intrastateIntralataCall:acct_supplier_twilio\"}},\"Rates\":{\"17be580\":[{\"GroupIntervalStart\":0,\"Value\":0.0034,\"RateIncrement\":60000000000,\"RateUnit\":60000000000}]},\"Timings\":{\"db54b3b\":{\"Years\":[],\"Months\":[],\"MonthDays\":[],\"WeekDays\":[],\"StartTime\":\"00:00:00\"}}}",
            "ExtraInfo": "",
            "PreRated": false
        }

Enzo D'amato

unread,
Jul 18, 2025, 5:29:50 AMJul 18
to CGRateS
So, I've fixed the attributeS issue. This was being caused by the ChargerS profile that I was using having attributes set to *none rather than an empty list. I've also narrowed down the incorrect call time issue further. A CDR is being generated for the session extermy early into the call, around the 1 minute mark. When the call completes, and CGRateS attempts to send the correct, processed CDR out to the StorDB, I get an error saying the CDR exists. I still can't figure out what's causing this premature CDR generation however.

Armir Veliaj

unread,
Jul 21, 2025, 10:22:40 AMJul 21
to CGRateS
Hello Enzo,

I would suggest starting with a fresh engine and flushing both databases.

Also, please provide the full updated ngrep output from the beginning of the call, including any errors shown, together with your CGRateS JSON configuration and the engine logs shown during the call.

Thanks,
Armir
Reply all
Reply to author
Forward
0 new messages