[Kamailio-Users] "Failed" acc log when 183 Session Progress

Samuel Muller sml at 720.fr
Mon Dec 29 10:17:28 CET 2008


Oh !

this is just this parameter ???
arghhhh .... I forgot this one ...

you're (again ;) right, Daniel ... many thanks !!!


# -- ACC --
loadmodule "acc.so"
modparam("acc", "log_level", 3)
modparam("acc", "log_flag", 1)
modparam("acc", "log_missed_flag", 2)
modparam("acc", "failed_transaction_flag", 3)
modparam("acc", "report_ack", 0)
modparam("acc", "report_cancels", 1)
modparam("acc", "early_media", 0)
modparam("acc", "detect_direction", 1)

happy holidays too !


Bonnes fêtes à tous :)

.Sam.


On Sun, Dec 28, 2008 at 10:53 AM, Daniel-Constantin Mierla <
miconda at gmail.com> wrote:

> Hello,
>
> do you have the parameter to account the early media signaling set?
> http://kamailio.org/docs/modules/devel/acc.html#id2467932
>
> Happy holidays!
> Daniel
>
>
> On 12/24/08 16:32, Samuel Muller wrote:
>
>> Hello all,
>>
>> I just have a little question about this thing :
>>
>> when I start a call, my Radius receives before the session is established
>> a "failed" in its accounting log.
>> If I move the setflag() for rad acc and rad failed acc (or one of each,
>> then the other one), the "Start" disappear - but I get the Stop.
>>
>> How to not take acc in charge when there is a 183 Session Progress ?
>>
>> here is the radius acc log :
>>
>> Code:       Accounting-Request
>> Identifier: 102
>> Authentic:  Z3z<16>^<230><251>i<233>[6Q<196><12><140><25>
>> Attributes:
>>    Acct-Status-Type = Failed
>>    Service-Type = Sip-Session
>>    Sip-Response-Code = 183
>>    Sip-Method = "<1>"
>>    Event-Timestamp = 1230128211
>>    Sip-From-Tag = "a3addce43738cc27o0"
>>    Sip-To-Tag = "1c782477072"
>>    Acct-Session-Id = "10bdf568-2821ed73 at 192.168.1.7 <mailto:
>> 10bdf568-2821ed73 at 192.168.1.7>"
>>    Sip-Method = "INVITE"
>>    SER-From = "0170645745"
>>    SER-To = "0663128505"
>>    Sip-User-Digest-URI = "sip:0663128505 at sip.720.fr<sip%3A0663128505 at sip.720.fr><mailto:
>> sip%3A0663128505 at sip.720.fr <sip%253A0663128505 at sip.720.fr>>"
>>
>>    Sip-Req-URI = "sip:0663128505 at 77.246.81.136:5060;transport=udp"
>>    Sip-Source-IP-Address = "87.90.4.63"
>>    Sip-Source-Port = "5060"
>>    NAS-Port = 5060
>>    Acct-Delay-Time = 0
>>    NAS-IP-Address = 192.168.60.9
>>
>> the setflag() are here :
>>
>> if (is_method("INVITE"))
>>        {
>>                setflag(4);    # flag "radius acc"
>>                setflag(5);    # flag "radius failed acc"
>>                setflag(8);     # flag "dialog"
>>                setflag(9);     # flag "session timer"
>>                route(13);
>>        }
>>
>> the only place I found the related "183" taken in charge in the
>> kamailio.cfg is here :
>>
>> onreply_route[2]
>> {
>>    if (nat_uac_test("1"))
>>    { fix_nated_contact(); }
>>
>>    if (isbflagset(14) && status =~ "(180|183|2[0-9][0-9])")        #?
>> branch flag nat
>>    {
>>                if (!search("^Content-Length:[ ]*0"))
>>                { force_rtp_proxy(); }
>>    }
>>    exit;
>> }
>>
>> in the kamailio log for this same call :
>>
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_msg: SIP Reply
>>  (status):
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_msg:  version:
>> <SIP/2.0>
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_msg:  status:  <100>
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_msg:  reason:
>>  <Trying>
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_headers: flags=2
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_via_param: found param
>> type 232, <branch> = <z9hG4bKbe63.1eddc1f3.0>; state=16
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_via: end of header
>> reached, state=5
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_headers: via found,
>> flags=2
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_headers: this is the
>> first via
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:receive_msg: After
>> parse_msg...
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:grep_sock_info: checking if
>> host==us: 13==13 &&  [77.246.81.132] == [77.246.81.132]
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:grep_sock_info: checking if
>> port 5060 matches port 5060
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:forward_reply: found module
>> tm, passing reply to it
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:tm:t_check:
>> start=0xffffffffffffffff
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_headers: flags=22
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_via_param: found param
>> type 235, <rport> = <5060>; state=6
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_via_param: found param
>> type 234, <received> = <87.90.4.63>; state=6
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_via_param: found param
>> type 232, <branch> = <z9hG4bK-8aeb8bb>; state=16
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_via: end of header
>> reached, state=5
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_headers: via found,
>> flags=22
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_headers:
>> parse_headers: this is the second via
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_to_param:
>> tag=1c782477072
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_to: end of header
>> reached, state=29
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_to:
>> display={"0663128505"}, ruri={sip:0663128505 at sip.720.fr<sip%3A0663128505 at sip.720.fr><mailto:
>> sip%3A0663128505 at sip.720.fr <sip%253A0663128505 at sip.720.fr>>}
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:get_hdr_field: <To> [58];
>> uri=[sip:0663128505 at sip.720.fr <sip%3A0663128505 at sip.720.fr> <mailto:
>> sip%3A0663128505 at sip.720.fr <sip%253A0663128505 at sip.720.fr>>] Dec 24
>> 15:16:51 ser0 kamailio[7748]: DBG:core:get_hdr_field: to body ["0663128505"
>> <sip:0663128505 at sip.720.fr <sip%3A0663128505 at sip.720.fr> <mailto:
>> sip%3A0663128505 at sip.720.fr <sip%253A0663128505 at sip.720.fr>>>]
>>
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:get_hdr_field: cseq <CSeq>:
>> <102> <INVITE>
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_headers: flags=8
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:tm:t_reply_matching: hash 14059
>> label 1058856417 branch 0
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:tm:t_reply_matching: REF_UNSAFE:
>> after is 1
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:tm:t_reply_matching: reply
>> matched (T=0x7fcc45ebb090)!
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:tm:run_trans_callbacks:
>> trans=0x7fcc45ebb090, callback type 2, id 1 entered
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:tm:t_check: end=0x7fcc45ebb090
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:tm:reply_received: org. status
>> uas=0, uac[0]=0 local=0 is_invite=1)
>> Dec 24 15:16:51 ser0 kamailio[7748]:
>>  -NAT-ONREPLY-----------------------------
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:parse_headers: flags=80
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:get_hdr_field:
>> content_length=0
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:get_hdr_field: found end of
>> header
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:tm:t_should_relay_response:
>> T_code=0, new_code=100
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:tm:relay_reply: branch=0, save=0,
>> relay=-1
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:tm:set_timer: relative timeout is
>> 60
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:tm:insert_timer_unsafe: [1]:
>> 0x7fcc45ebb2e0 (95)
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:tm:t_unref: UNREF_UNSAFE: after
>> is 0
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:destroy_avp_list: destroying
>> list (nil)
>> Dec 24 15:16:51 ser0 kamailio[7748]: DBG:core:receive_msg: cleaning up
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_msg: SIP Reply
>>  (status):
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_msg:  version:
>> <SIP/2.0>
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_msg:  status:  <183>
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_msg:  reason:
>>  <Session Progress>
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_headers: flags=2
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_via_param: found param
>> type 232, <branch> = <z9hG4bKbe63.1eddc1f3.0>; state=16
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_via: end of header
>> reached, state=5
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_headers: via found,
>> flags=2
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_headers: this is the
>> first via
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:receive_msg: After
>> parse_msg...
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:grep_sock_info: checking if
>> host==us: 13==13 &&  [77.246.81.132] == [77.246.81.132]
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:grep_sock_info: checking if
>> port 5060 matches port 5060
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:forward_reply: found module
>> tm, passing reply to it
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:tm:t_check:
>> start=0xffffffffffffffff
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_headers: flags=22
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_via_param: found param
>> type 235, <rport> = <5060>; state=6
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_via_param: found param
>> type 234, <received> = <87.90.4.63>; state=6
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_via_param: found param
>> type 232, <branch> = <z9hG4bK-8aeb8bb>; state=16
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_via: end of header
>> reached, state=5
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_headers: via found,
>> flags=22
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_headers:
>> parse_headers: this is the second via
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_to_param:
>> tag=1c782477072
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_to: end of header
>> reached, state=29
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_to:
>> display={"0663128505"}, ruri={sip:0663128505 at sip.720.fr<sip%3A0663128505 at sip.720.fr><mailto:
>> sip%3A0663128505 at sip.720.fr <sip%253A0663128505 at sip.720.fr>>}
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:get_hdr_field: <To> [58];
>> uri=[sip:0663128505 at sip.720.fr <sip%3A0663128505 at sip.720.fr> <mailto:
>> sip%3A0663128505 at sip.720.fr <sip%253A0663128505 at sip.720.fr>>] Dec 24
>> 15:16:51 ser0 kamailio[7747]: DBG:core:get_hdr_field: to body ["0663128505"
>> <sip:0663128505 at sip.720.fr <sip%3A0663128505 at sip.720.fr> <mailto:
>> sip%3A0663128505 at sip.720.fr <sip%253A0663128505 at sip.720.fr>>>]
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:get_hdr_field: cseq <CSeq>:
>> <102> <INVITE>
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_headers: flags=8
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:tm:t_reply_matching: hash 14059
>> label 1058856417 branch 0
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:tm:t_reply_matching: REF_UNSAFE:
>> after is 1
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:tm:t_reply_matching: reply
>> matched (T=0x7fcc45ebb090)!
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:tm:run_trans_callbacks:
>> trans=0x7fcc45ebb090, callback type 2, id 1 entered
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_headers: flags=8
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:tm:t_check: end=0x7fcc45ebb090
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:tm:reply_received: org. status
>> uas=0, uac[0]=100 local=0 is_invite=1)
>> Dec 24 15:16:51 ser0 kamailio[7747]:
>>  -NAT-ONREPLY-----------------------------
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_headers: flags=80
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_headers:
>> flags=ffffffffffffffff
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:get_hdr_field:
>> content_length=293
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:get_hdr_field: found end of
>> header
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:nathelper:check_content_type:
>> type <application/sdp> found valid
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_headers: flags=40
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_to_param:
>> tag=a3addce43738cc27o0
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_to: end of header
>> reached, state=29
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_to:
>> display={"0170645745"}, ruri={sip:0170645745 at sip.720.fr<sip%3A0170645745 at sip.720.fr><mailto:
>> sip%3A0170645745 at sip.720.fr <sip%253A0170645745 at sip.720.fr>>}
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:nathelper:force_rtp_proxy2_f:
>> proxy reply: 35934 77.246.81.133 Dec 24 15:16:51 ser0 kamailio[7747]:
>> DBG:tm:t_should_relay_response: T_code=0, new_code=183
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:tm:relay_reply: branch=0, save=0,
>> relay=0
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:tm:run_trans_callbacks:
>> trans=0x7fcc45ebb090, callback type 16, id 0 entered
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:dialog:run_dlg_callbacks:
>> dialog=0x7fcc45ebc7c0, type=256
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:sst:sst_dialog_response_fwded_CB:
>> Dialog seen REPLY 183 Session Progress
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_headers: flags=2000
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_headers:
>> flags=ffffffffffffffff
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:clen_builder:
>> content-length: 311 (311)
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:build_res_buf_from_sip_res:
>>  old size: 1027, new size: 983
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:build_res_buf_from_sip_res:
>> copied size: orig:1025, new: 981, rest: 2 msg= SIP/2.0 183 Session
>> Progress^M Via: SIP/2.0/UDP 192.168.1.7:5060;rport=5060;received=87.90.4.63;branch=z9hG4bK-8aeb8bb^M
>> From: "0170645745" <sip:0170645745 at sip.720.fr<sip%3A0170645745 at sip.720.fr><mailto:
>> sip%3A0170645745 at sip.720.fr <sip%253A0170645745 at sip.720.fr>>>;tag=a3addce43738cc27o0^M
>> To: "0663128505" <sip:0663128505 at sip.720.fr <sip%3A0663128505 at sip.720.fr><mailto:
>> sip%3A0663128505 at sip.720.fr <sip%253A0663128505 at sip.720.fr>>>;tag=1c782477072^M
>> Call-ID: 10bdf568-2821ed73 at 192.168.1.7 <mailto:
>> 10bdf568-2821ed73 at 192.168.1.7>^M CSeq: 102 INVITE^M Contact: <
>> sip:61 at 77.246.81.136 <sip%3A61 at 77.246.81.136> <mailto:
>> sip%3A61 at 77.246.81.136 <sip%253A61 at 77.246.81.136>>>^M Record-Route:
>> <sip:77.246.81.132;lr=on;ftag=a3addce43738cc27o0;nat=yes;did=17a.36b075d2>^M
>> Supported: em,timer,replaces,path,resource-priority^M Allow:
>> REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE^M
>> Server: 720 DEGRES PSTN GW/v.5.40A.030.002^M Content-Type: application/sdp^M
>> Content-Length: 311^M ^M v=0^M o=AudiocodesGW 782585440 782585110 IN IP4
>> 77.246.81.136^M s=Phone-Call^M c=IN IP4 77.246.81.133^M t=0 0^M m=audio
>> 35934 RTP/AVP 18 101^M a=rtpmap:18 G729/8000^M a=fmtp:18 annexb=yes^M
>> a=rtpmap:101 telephone-event/
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:_shm_resize: resize(0)
>> called
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:tm:relay_reply: sent
>> buf=0x7936d8: SIP/2.0 1..., shmem=0x7fcc45ebef00: SIP/2.0 1
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:tm:run_trans_callbacks:
>> trans=0x7fcc45ebb090, callback type 128, id 1 entered
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:parse_headers: flags=14000
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:tm:run_trans_callbacks:
>> trans=0x7fcc45ebb090, callback type 128, id 0 entered
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:dialog:next_state_dlg: dialog
>> 0x7fcc45ebc7c0 changed from state 1 to state 2, due event 2
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:tm:set_timer: relative timeout is
>> 60
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:tm:insert_timer_unsafe: [1]:
>> 0x7fcc45ebb2e0 (96)
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:tm:t_unref: UNREF_UNSAFE: after
>> is 0
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:destroy_avp_list: destroying
>> list (nil)
>> Dec 24 15:16:51 ser0 kamailio[7747]: DBG:core:receive_msg: cleaning up
>> Dec 24 15:16:51 ser0 kamailio[7753]: DBG:tm:utimer_routine: timer
>> routine:4,tl=0x7fcc45ebb2b0 next=(nil), timeout=36400000
>>
>>
>>
>>
>> --
>> Samuel MULLER
>> sml at 720.fr <mailto:sml at 720.fr>
>> ------------------------------------------------------------------------
>>
>> _______________________________________________
>> Users mailing list
>> Users at lists.kamailio.org
>> http://lists.kamailio.org/cgi-bin/mailman/listinfo/users
>>
>>
>
> --
> Daniel-Constantin Mierla
> http://www.asipto.com
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.kamailio.org/pipermail/users/attachments/20081229/a434d5b0/attachment.htm 


More information about the Users mailing list