[prev in list] [next in list] [prev in thread] [next in thread]
List: asterisk-dev
Subject: [asterisk-dev] Bug or feature? Additional FAILED disposition cdr entries when call time out dialing
From: Leandro Dardini <ldardini () gmail ! com>
Date: 2015-05-19 13:08:26
Message-ID: CAOoW2hZtU+eX1VprTAdfek=K7t-H1y3FR-QG-nHyyprRTK=FLg () mail ! gmail ! com
[Download RAW message or body]
[Attachment #2 (multipart/alternative)]
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@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@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@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@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@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@216.115.69.131 - OPTIONS (No RTP)
[2015-05-19 14:59:53] DEBUG[27872] chan_sip.c: Allocating new SIP dialog
for 318709fe45d85ff67679bb2e24afd85f@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@213.133.102.85:5060' to '
018938114256430516db352c57e87b37@213.133.102.85:50
60'
[2015-05-19 14:59:53] DEBUG[27872] chan_sip.c: Initializing initreq for
method OPTIONS - callid 018938114256430516db352c57e87b37@213.133.102.85:5060
[2015-05-19 14:59:53] DEBUG[27872] chan_sip.c: Allocating new SIP dialog
for 018938114256430516db352c57e87b37@213.133.102.85:5060 - OPTIONS (No RTP)
[2015-05-19 14:59:53] DEBUG[27872] chan_sip.c: Stopping retransmission on '
018938114256430516db352c57e87b37@213.133.102.85:5060' of Request 102: Match
Found
[Attachment #5 (text/html)]
<div dir="ltr">Hello,<div>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.</div><div><br></div><div>I have reduced the \
dialplan to minimum and this is the result:</div><div><br></div><div><div>context \
authenticated {</div><div> 3000 => {</div><div> \
Dial(SIP/flowroute/00390574020713,5,Tt);</div><div> \
NoOp(Hello);</div><div> \
}</div><div>};<br></div></div><div><br></div><div>Whatever command I give after the \
DIAL, it generated an additional FAILED entry in the cdr. Is it a bug or a \
feature?</div><div><br></div><div>The debug of the end of the call is the \
following:</div><div><br></div><div><div>[2015-05-19 14:59:49] \
VERBOSE[20198][C-0000003d] app_dial.c: Nobody picked up in 5000 \
ms</div><div>[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</div><div>[2015-05-19 14:59:49] \
DEBUG[20198][C-0000003d] channel.c: Hanging up channel \
'SIP/flowroute-0000002d'</div><div>[2015-05-19 14:59:49] \
DEBUG[20198][C-0000003d] chan_sip.c: Hangup call SIP/flowroute-0000002d, SIP callid \
<a href="mailto:29fbb06f5fea158259cee77377389f72@sip.flowroute.com">29fbb06f5fea158259cee77377389f72@sip.flowroute.com</a></div><div>[2015-05-19 \
14:59:49] DEBUG[20198][C-0000003d] res_rtp_asterisk.c: Setting RTCP address on RTP \
instance '0x7f54b4007ae8'</div><div>[2015-05-19 14:59:49] \
DEBUG[20198][C-0000003d] res_rtp_asterisk.c: Setting RTCP address on RTP instance \
'0x7f54b4006f28'</div><div>[2015-05-19 14:59:49] DEBUG[20198][C-0000003d] \
chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '<a \
href="mailto:29fbb06f5fea158259cee77377389f72@sip.flowroute.com">29fbb06f5fea158259cee77377389f72@sip.flowroute.com</a>' \
Request 103: Found</div><div>[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</div><div>[2015-05-19 14:59:49] DEBUG[27813] cdr.c: \
CDR for SIP/flowroute-0000002d is dialed and has no Party B; \
discarding</div><div>[2015-05-19 14:59:49] DEBUG[20198][C-0000003d] app_dial.c: \
Exiting with DIALSTATUS=NOANSWER.</div><div>[2015-05-19 14:59:49] \
DEBUG[20198][C-0000003d] pbx.c: Launching 'NoOp'</div><div>[2015-05-19 \
14:59:49] VERBOSE[20198][C-0000003d] pbx.c: Executing [3000@authenticated:2] \
NoOp("SIP/103-DEVEL-0000002c", "Hello") in new \
stack</div><div>[2015-05-19 14:59:49] VERBOSE[20198][C-0000003d] pbx.c: Auto \
fallthrough, channel 'SIP/103-DEVEL-0000002c' status is \
'NOANSWER'</div><div>[2015-05-19 14:59:49] DEBUG[20198][C-0000003d] \
channel.c: Soft-Hanging (0x10) up channel \
'SIP/103-DEVEL-0000002c'</div><div>[2015-05-19 14:59:49] \
DEBUG[20198][C-0000003d] channel.c: Hanging up channel \
'SIP/103-DEVEL-0000002c'</div><div>[2015-05-19 14:59:49] \
DEBUG[20198][C-0000003d] chan_sip.c: Hangup call SIP/103-DEVEL-0000002c, SIP callid \
fzgMzOWmal.YvXEhkisbnlWY-azxiIuy</div><div>[2015-05-19 14:59:49] \
DEBUG[20198][C-0000003d] res_rtp_asterisk.c: Setting RTCP address on RTP instance \
'0x7f54a4009478'</div><div>[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</div><div>[2015-05-19 14:59:49] \
DEBUG[27872][C-0000003d] chan_sip.c: Stopping retransmission on \
'fzgMzOWmal.YvXEhkisbnlWY-azxiIuy' of Response 4073: Match \
Found</div><div><b>[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/flo \
wroute-0000002d','Dial','SIP/flowroute/00390574020713,5,Tt','5','0','NO \
ANSWER','DOCUMENTATION','DEVEL','srv01-1432040384.124','','')</b></div><div>[2015-05-19 \
14:59:49] DEBUG[27872][C-0000003d] chan_sip.c: Acked pending invite \
103</div><div>[2015-05-19 14:59:49] DEBUG[27872][C-0000003d] chan_sip.c: Stopping \
retransmission on '<a \
href="mailto:29fbb06f5fea158259cee77377389f72@sip.flowroute.com">29fbb06f5fea158259cee77377389f72@sip.flowroute.com</a>' \
of Request 103: Match Found</div><div><b>[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','',& \
#39;NoOp','Hello','0','0','FAILED','DOCUMENTAT \
ION','DEVEL','srv01-1432040384.124','','')</b></div><div>[2015-05-19 \
14:59:49] DEBUG[27872][C-0000003d] chan_sip.c: Stopping retransmission on '<a \
href="mailto:29fbb06f5fea158259cee77377389f72@sip.flowroute.com">29fbb06f5fea158259cee77377389f72@sip.flowroute.com</a>' \
of Request 103: Match Found</div><div>[2015-05-19 14:59:50] DEBUG[27872] chan_sip.c: \
Allocating new SIP dialog for <a \
href="mailto:95f5da82-86c2653d-80c7bf1@216.115.69.131">95f5da82-86c2653d-80c7bf1@216.115.69.131</a> \
- OPTIONS (No RTP)</div><div>[2015-05-19 14:59:53] DEBUG[27872] chan_sip.c: \
Allocating new SIP dialog for <a \
href="http://318709fe45d85ff67679bb2e24afd85f@213.133.102.85:5060">318709fe45d85ff67679bb2e24afd85f@213.133.102.85:5060</a> \
- OPTIONS (No RTP)</div><div>[2015-05-19 14:59:53] DEBUG[27872] chan_sip.c: SIP \
call-id changed from '<a \
href="http://318709fe45d85ff67679bb2e24afd85f@213.133.102.85:5060">318709fe45d85ff67679bb2e24afd85f@213.133.102.85:5060</a>' \
to '<a href="http://018938114256430516db352c57e87b37@213.133.102.85:50">0189381142 \
56430516db352c57e87b37@213.133.102.85:50</a></div><div>60'</div><div>[2015-05-19 \
14:59:53] DEBUG[27872] chan_sip.c: Initializing initreq for method OPTIONS - callid \
<a href="http://018938114256430516db352c57e87b37@213.133.102.85:5060">018938114256430516db352c57e87b37@213.133.102.85:5060</a></div><div>[2015-05-19 \
14:59:53] DEBUG[27872] chan_sip.c: Allocating new SIP dialog for <a \
href="http://018938114256430516db352c57e87b37@213.133.102.85:5060">018938114256430516db352c57e87b37@213.133.102.85:5060</a> \
- OPTIONS (No RTP)</div></div><div>[2015-05-19 14:59:53] DEBUG[27872] chan_sip.c: \
Stopping retransmission on '<a \
href="http://018938114256430516db352c57e87b37@213.133.102.85:5060">018938114256430516db352c57e87b37@213.133.102.85:5060</a>' \
of Request 102: Match \
Found<br></div><div><br></div><div><br></div><div><br></div><div><br></div><div><br></div><div><br></div><div><br></div></div>
--
_____________________________________________________________________
-- Bandwidth and Colocation Provided by http://www.api-digital.com --
asterisk-dev mailing list
To UNSUBSCRIBE or update options visit:
http://lists.digium.com/mailman/listinfo/asterisk-dev
[prev in list] [next in list] [prev in thread] [next in thread]
Configure |
About |
News |
Add a list |
Sponsored by KoreLogic