[OpenSIPS-Users] callcontrol/dialog/mediaproxy issue

[Om Bikram Thapa]

Hi Luci,

Here is the detail log entries of that particular problematic CallID:

-----------------------
May 27 11:35:55 proxy cdrtool[3447]: MaxSessionTime From=sip:x at x
Lock=1 To=sip:00971559874973 at x CallId=63670069-30bf40f9 at 192.168.3.5
Duration=36000 Gateway=x
May 27 11:35:55 proxy cdrtool[3447]: MaxSessionTime=5414 Type=prepaid
CallId=63670069-30bf40f9 at 192.168.3.5 BillingParty=x at x DestId=97155
Balance=1055.82 Spans=1
May 27 11:35:55 proxy media-relay[2298]: [RelayClientProtocol,client]
created new session 63670069-30bf40f9 at 192.168.3.5: x at x
(f9c979a9c37d18b9o0) --> 00971559874973 at x
May 27 11:35:56 proxy media-relay[2298]: [RelayClientProtocol,client]
updating existing session 63670069-30bf40f9 at 192.168.3.5: x at x
(f9c979a9c37d18b9o0) --> 00971559874973 at x
May 27 11:36:20 proxy call-control[2176]: [CallControlProtocol,1374,]
Call id 63670069-30bf40f9 at 192.168.3.5 of x at x to sip:00971559874973 at x
started for maximum 5414 seconds
May 27 11:36:20 proxy cdrtool[3447]: MaxSessionTime From=sip:x at x
Lock=1 To=sip:00971559874973 at x State=Connected
CallId=63670069-30bf40f9 at 192.168.3.5 Duration=36000 Gateway=x
May 27 11:36:20 proxy media-relay[2298]: [RelayClientProtocol,client]
updating existing session 63670069-30bf40f9 at 192.168.3.5: x at x
(f9c979a9c37d18b9o0) --> 00971559874973 at x
May 27 11:36:20 proxy cdrtool[3447]: Maximum duration for session
63670069-30bf40f9 at 192.168.3.5 of x at x to destination 97155 having
balance=1055.82 is 5414
May 27 11:36:20 proxy cdrtool[3447]: MaxSessionTime=5414 Type=prepaid
CallId=63670069-30bf40f9 at 192.168.3.5 BillingParty=x at x DestId=97155
Balance=1055.82 Spans=1
May 27 11:36:20 proxy call-control[2176]:
[RatingEngineProtocol,client] Call id 63670069-30bf40f9 at 192.168.3.5 of
x at x to sip:00971559874973 at x connected for maximum 5414 seconds
May 27 11:39:58 proxy media-relay[2298]: [-] expired session
63670069-30bf40f9 at 192.168.3.5: x at x (f9c979a9c37d18b9o0) -->
00971559874973 at x
May 27 11:39:58 proxy media-dispatcher[2288]:
[RelayServerProtocol,0,127.0.0.1] session with call_id
63670069-30bf40f9 at 192.168.3.5 from relay 127.0.0.1 did timeout
May 27 11:39:58 proxy media-dispatcher[2288]:
[RelayServerProtocol,0,127.0.0.1] Got statistics: {'from_tag':
'f9c979a9c37d18b9o0', 'dialog_id': '1156:1871900963', 'start_time':
1243403456.1500001, 'timed_out': True, 'call_id':
'63670069-30bf40f9 at 192.168.3.5', 'to_tag':
'c91710acc92b10ac4a0ab1d3343d3f', 'streams': [{'status': 'conntrack
timeout', 'caller_codec': 'G723', 'post_dial_delay':
0.69514417648299998, 'callee_codec': 'G723', 'start_time': 0,
'caller_bytes': 128000, 'callee_bytes': 103616, 'caller_packets':
2000, 'end_time': 242, 'callee_remote': 'y:24680', 'caller_remote':
'z:16434', 'media_type': 'audio', 'callee_local': 'x:50882',
'timeout_wait': 180, 'caller_local': 'x:50880', 'callee_packets':
1619}], 'duration': 242, 'to_uri': '00971559874973 at x', 'from_uri':
'x at x', 'callee_ua': '(Very nice Sip Registrar/Proxy Server)',
'caller_ua': 'Linksys/PAP2T-3.1.15(LS)'}

--> Called did time out here but the callcontrol session goes on!
--> New call starts from the same endpoint

May 27 11:40:01 proxy cdrtool[29554]: ConnectFee=0.00
CallId=63670069-30bf40f9 at 192.168.3.5 Span=1 Duration=246 DestId=97155
subscriber=x at x Profile=200 Period=weekday Rate=200 Interval=0-24
Cost=11.70/60 Price=47.9700 Price in=42.9680
May 27 11:50:47 proxy cdrtool[3447]: ConnectFee=0.00
CallId=63670069-30bf40f9 at 192.168.3.5 Span=1 Duration=870 DestId=97155
subscriber=x at x Profile=200 Period=weekday Rate=200 Interval=0-24
Cost=11.70/60 Price=169.6500 Price in=151.9600
May 27 11:50:47 proxy cdrtool[3447]: Ongoing prepaid session
63670069-30bf40f9 at 192.168.3.5 for x at x to +971559874973: duration=867,
price=169.6500
May 27 11:50:47 proxy cdrtool[3447]: Maximum duration for session
63670069-30bf40f9 at 192.168.3.5 of x at x to destination 97155 having
balance=886.17 is 4544
May 27 11:50:48 proxy cdrtool[3447]: ConnectFee=0.00
CallId=63670069-30bf40f9 at 192.168.3.5 Span=1 Duration=870 DestId=97155
subscriber=x at x Profile=200 Period=weekday Rate=200 Interval=0-24
Cost=11.70/60 Price=169.6500 Price in=151.9600
May 27 11:50:48 proxy cdrtool[3447]: Ongoing prepaid session
63670069-30bf40f9 at 192.168.3.5 for x at x to +971559874973: duration=868,
price=169.6500
May 27 11:50:48 proxy cdrtool[3447]: Maximum duration for session
63670069-30bf40f9 at 192.168.3.5 of x at x to destination 97155 having
balance=886.17 is 4544
May 27 11:50:48 proxy call-control[2176]:
[RatingEngineProtocol,client] Call id 63670069-30bf40f9 at 192.168.3.5 of
x at x to sip:00971559874973 at x previously connected set to 4544 seconds
-
-
-
May 27 11:52:57 proxy call-control[2176]: [CallControlProtocol,1371,]
Call id 63670069-30bf40f9 at 192.168.3.5 of x at x to sip:00971559874973 at x
also set to 1456 seconds
May 27 11:52:57 proxy cdrtool[3447]: Balance for x at x having 2 ongoing
sessions: database=1055.8200, due=210.6000, real=845.2200
May 27 11:52:57 proxy cdrtool[3447]: Maximum duration for session
63670069-30bf40f9 at 192.168.3.5 of x at x to destination 97155 having
balance=845.22 is 4334
May 27 11:52:57 proxy call-control[2176]:
[RatingEngineProtocol,client] Call id 63670069-30bf40f9 at 192.168.3.5 of
x at x to sip:00971559874973 at viatalk.com.np previously connected set to
maximum 1444 seconds
May 27 12:25:31 proxy cdrtool[3447]: ConnectFee=0.00
CallId=63670069-30bf40f9 at 192.168.3.5 Span=1 Duration=2952 DestId=97155
subscriber=x at x Profile=200 Period=weekday Rate=200 Interval=0-24
Cost=11.70/60 Price=575.6400 Price in=515.6160
-
-
May 27 12:25:58 proxy call-control[2176]: [CallControlProtocol,1370,]
Call id a159cac8-fe0ced2d at 192.168.3.5 of x at x to sip:009746890611 at x
started for maximum 96 seconds
May 27 12:25:58 proxy call-control[2176]: [CallControlProtocol,1370,]
Call id 63670069-30bf40f9 at 192.168.3.5 of x at x to sip:00971559874973 at x
also set to 96 seconds
May 27 12:25:58 proxy call-control[2176]: [CallControlProtocol,1370,]
Call id a26c132a-4a8c511d at 192.168.3.5 of x at x to sip:00971559874973 at x
also set to 96 seconds
-
-
-
May 27 12:27:20 proxy cdrtool[3447]: Maximum duration for session
63670069-30bf40f9 at 192.168.3.5 of x at x to destination 97155 having
balance=-0.3 is 0

-->Balance goes to zero here

-
-
-
May 27 12:27:20 proxy call-control[2176]:
[RatingEngineProtocol,client] Call id 276070c2-ee4104fa at 192.168.3.5 of
x at x to sip:009746890611 at x disconnected by call control after 43
seconds, call price is 11.2800
May 27 12:27:20 proxy cdrtool[3447]: DebitBalance
Duration=3060.02832103 CallId=63670069-30bf40f9 at 192.168.3.5
From=sip:x at x Gateway=z To=sip:00971559874973 at x

May 27 12:27:20 proxy cdrtool[3447]: ConnectFee=0.00
CallId=63670069-30bf40f9 at 192.168.3.5 Span=1 Duration=3060 DestId=97155
subscriber=x at x Profile=200 Period=weekday Rate=200 Interval=0-24
Cost=11.70/60 Price=596.7000 Price in=534.4800
May 27 12:27:20 proxy cdrtool[3447]: Price=596.7000 Duration=3060
CallId=63670069-30bf40f9 at 192.168.3.5 BillingParty=x at x DestId=97155
MaxSessionTime=0

May 27 12:27:20 proxy call-control[2176]:
[RatingEngineProtocol,client] Call id 63670069-30bf40f9 at 192.168.3.5 of
x at x to sip:00971559874973 at x disconnected by call control after 3060
seconds, call price is 596.7000
----------------------------------

Thanks a lot.
Om.


On Wed, May 27, 2009 at 1:59 PM, Luci Stanescu <luci at ag-projects.com> wrote:
> Om Bikram Thapa wrote:
>> Hello,
>
> Hi Om,
>
>> I have a strange issue whenever there is a media timeout issue.
>> Mediaproxy media timeout is working fine and radius CDR also records
>> the "Media_Info" as timeout, however, it doesn't terminate the
>> corresponding "callcontrol" session. As a result, the session remains
>> on until the prepaid balance is exhausted.
>
> Could you paste the corresponding log entries of opensips, mediaproxy
> and callcontrol, up until callcontrol terminates the call when the
> user's balance is exhausted?
>
>> Will setting callcontrol timeout detection to "radius" help? I assume
>> "dialog" is the recommended setting.
>
> It is indeed the recommended way if you are using mediaproxy 2.
>
>> Thanks,
>> Om.
>
> --
> Luci Stanescu
>