[asterisk-dev] Bug or feature? Additional FAILED disposition cdr entries when call time out dialing

[Leandro Dardini]

Hello,
I just noticed some FAILED disposition entries in the cdr (using
cdr_adaptive_odbc). They are connected to the NO ANSWER entries. For each
NO ANSWER, there is a FAILED entry, so a call generated two entries. In
other words, every time a call got a NO ANSWER, two entries are generated
in the cdr, one with the NO ANSWER and one with the FAILED.

I have reduced the dialplan to minimum and this is the result:

context authenticated {
        3000 => {
             Dial(SIP/flowroute/00390574020713,5,Tt);
             NoOp(Hello);
        }
};

Whatever command I give after the DIAL, it generated an additional FAILED
entry in the cdr. Is it a bug or a feature?

The debug of  the end of the call is the following:

[2015-05-19 14:59:49] VERBOSE[20198][C-0000003d] app_dial.c: Nobody picked
up in 5000 ms
[2015-05-19 14:59:49] DEBUG[27813] cdr.c: Finalized CDR for
SIP/103-DEVEL-0000002c - start 1432040384.527529 answer 0.000000 end
1432040389.540299 dispo NO ANSWER
[2015-05-19 14:59:49] DEBUG[20198][C-0000003d] channel.c: Hanging up
channel 'SIP/flowroute-0000002d'
[2015-05-19 14:59:49] DEBUG[20198][C-0000003d] chan_sip.c: Hangup call
SIP/flowroute-0000002d, SIP callid
29fbb06f5fea158259cee77377389f72 at sip.flowroute.com
[2015-05-19 14:59:49] DEBUG[20198][C-0000003d] res_rtp_asterisk.c: Setting
RTCP address on RTP instance '0x7f54b4007ae8'
[2015-05-19 14:59:49] DEBUG[20198][C-0000003d] res_rtp_asterisk.c: Setting
RTCP address on RTP instance '0x7f54b4006f28'
[2015-05-19 14:59:49] DEBUG[20198][C-0000003d] chan_sip.c: (Provisional)
Stopping retransmission (but retaining packet) on '
29fbb06f5fea158259cee77377389f72 at sip.flowroute.com' Request 103: Found
[2015-05-19 14:59:49] DEBUG[27813] cdr.c: Finalized CDR for
SIP/flowroute-0000002d - start 1432040384.536165 answer 0.000000 end
1432040389.541147 dispo NO ANSWER
[2015-05-19 14:59:49] DEBUG[27813] cdr.c: CDR for SIP/flowroute-0000002d is
dialed and has no Party B; discarding
[2015-05-19 14:59:49] DEBUG[20198][C-0000003d] app_dial.c: Exiting with
DIALSTATUS=NOANSWER.
[2015-05-19 14:59:49] DEBUG[20198][C-0000003d] pbx.c: Launching 'NoOp'
[2015-05-19 14:59:49] VERBOSE[20198][C-0000003d] pbx.c: Executing
[3000 at authenticated:2] NoOp("SIP/103-DEVEL-0000002c", "Hello") in new stack
[2015-05-19 14:59:49] VERBOSE[20198][C-0000003d] pbx.c: Auto fallthrough,
channel 'SIP/103-DEVEL-0000002c' status is 'NOANSWER'
[2015-05-19 14:59:49] DEBUG[20198][C-0000003d] channel.c: Soft-Hanging
(0x10) up channel 'SIP/103-DEVEL-0000002c'
[2015-05-19 14:59:49] DEBUG[20198][C-0000003d] channel.c: Hanging up
channel 'SIP/103-DEVEL-0000002c'
[2015-05-19 14:59:49] DEBUG[20198][C-0000003d] chan_sip.c: Hangup call
SIP/103-DEVEL-0000002c, SIP callid fzgMzOWmal.YvXEhkisbnlWY-azxiIuy
[2015-05-19 14:59:49] DEBUG[20198][C-0000003d] res_rtp_asterisk.c: Setting
RTCP address on RTP instance '0x7f54a4009478'
[2015-05-19 14:59:49] DEBUG[27813] cdr.c: Finalized CDR for
SIP/103-DEVEL-0000002c - start 1432040389.542906 answer 0.000000 end
1432040389.544118 dispo FAILED
[2015-05-19 14:59:49] DEBUG[27872][C-0000003d] chan_sip.c: Stopping
retransmission on 'fzgMzOWmal.YvXEhkisbnlWY-azxiIuy' of Response 4073:
Match Found
*[2015-05-19 14:59:49] DEBUG[27813] cdr_sqlite3_custom.c: About to log:
INSERT INTO cdr
(calldate,clid,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid,userfield,test)
VALUES ('2015-05-19 14:59:44','"Erica V. Watson"
<103>','authenticated','SIP/103-DEVEL-0000002c','SIP/flowroute-0000002d','Dial','SIP/flowroute/00390574020713,5,Tt','5','0','NO
ANSWER','DOCUMENTATION','DEVEL','srv01-1432040384.124','','')*
[2015-05-19 14:59:49] DEBUG[27872][C-0000003d] chan_sip.c: Acked pending
invite 103
[2015-05-19 14:59:49] DEBUG[27872][C-0000003d] chan_sip.c: Stopping
retransmission on '29fbb06f5fea158259cee77377389f72 at sip.flowroute.com' of
Request 103: Match Found
*[2015-05-19 14:59:49] DEBUG[27813] cdr_sqlite3_custom.c: About to log:
INSERT INTO cdr
(calldate,clid,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid,userfield,test)
VALUES ('2015-05-19 14:59:49','"Erica V. Watson"
<103>','authenticated','SIP/103-DEVEL-0000002c','','NoOp','Hello','0','0','FAILED','DOCUMENTATION','DEVEL','srv01-1432040384.124','','')*
[2015-05-19 14:59:49] DEBUG[27872][C-0000003d] chan_sip.c: Stopping
retransmission on '29fbb06f5fea158259cee77377389f72 at sip.flowroute.com' of
Request 103: Match Found
[2015-05-19 14:59:50] DEBUG[27872] chan_sip.c: Allocating new SIP dialog
for 95f5da82-86c2653d-80c7bf1 at 216.115.69.131 - OPTIONS (No RTP)
[2015-05-19 14:59:53] DEBUG[27872] chan_sip.c: Allocating new SIP dialog
for 318709fe45d85ff67679bb2e24afd85f at 213.133.102.85:5060 - OPTIONS (No RTP)
[2015-05-19 14:59:53] DEBUG[27872] chan_sip.c: SIP call-id changed from '
318709fe45d85ff67679bb2e24afd85f at 213.133.102.85:5060' to '
018938114256430516db352c57e87b37 at 213.133.102.85:50
60'
[2015-05-19 14:59:53] DEBUG[27872] chan_sip.c: Initializing initreq for
method OPTIONS - callid 018938114256430516db352c57e87b37 at 213.133.102.85:5060
[2015-05-19 14:59:53] DEBUG[27872] chan_sip.c: Allocating new SIP dialog
for 018938114256430516db352c57e87b37 at 213.133.102.85:5060 - OPTIONS (No RTP)
[2015-05-19 14:59:53] DEBUG[27872] chan_sip.c: Stopping retransmission on '
018938114256430516db352c57e87b37 at 213.133.102.85:5060' of Request 102: Match
Found
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.digium.com/pipermail/asterisk-dev/attachments/20150519/e796c5d0/attachment.html>