<html xmlns:v="urn:schemas-microsoft-com:vml" xmlns:o="urn:schemas-microsoft-com:office:office" xmlns:w="urn:schemas-microsoft-com:office:word" xmlns:m="http://schemas.microsoft.com/office/2004/12/omml" xmlns="http://www.w3.org/TR/REC-html40"><head><META HTTP-EQUIV="Content-Type" CONTENT="text/html; charset=us-ascii"><meta name=Generator content="Microsoft Word 14 (filtered medium)"><style><!--
/* Font Definitions */
@font-face
{font-family:Calibri;
panose-1:2 15 5 2 2 2 4 3 2 4;}
@font-face
{font-family:Tahoma;
panose-1:2 11 6 4 3 5 4 4 2 4;}
/* Style Definitions */
p.MsoNormal, li.MsoNormal, div.MsoNormal
{margin:0in;
margin-bottom:.0001pt;
font-size:12.0pt;
font-family:"Times New Roman","serif";}
a:link, span.MsoHyperlink
{mso-style-priority:99;
color:blue;
text-decoration:underline;}
a:visited, span.MsoHyperlinkFollowed
{mso-style-priority:99;
color:purple;
text-decoration:underline;}
span.EmailStyle17
{mso-style-type:personal-reply;
font-family:"Calibri","sans-serif";
color:#1F497D;}
.MsoChpDefault
{mso-style-type:export-only;
font-family:"Calibri","sans-serif";}
@page WordSection1
{size:8.5in 11.0in;
margin:1.0in 1.0in 1.0in 1.0in;}
div.WordSection1
{page:WordSection1;}
--></style><!--[if gte mso 9]><xml>
<o:shapedefaults v:ext="edit" spidmax="1026" />
</xml><![endif]--><!--[if gte mso 9]><xml>
<o:shapelayout v:ext="edit">
<o:idmap v:ext="edit" data="1" />
</o:shapelayout></xml><![endif]--></head><body lang=EN-US link=blue vlink=purple><div class=WordSection1><p class=MsoNormal><span style='font-size:11.0pt;font-family:"Calibri","sans-serif";color:#1F497D'>Just a WAG, but could the “local” channel be causing some kind of problem? Perhaps if you changed local to SIP or DAHDI?<o:p></o:p></span></p><p class=MsoNormal><span style='font-size:11.0pt;font-family:"Calibri","sans-serif";color:#1F497D'><o:p> </o:p></span></p><p class=MsoNormal><b><span style='font-size:10.0pt;font-family:"Tahoma","sans-serif"'>From:</span></b><span style='font-size:10.0pt;font-family:"Tahoma","sans-serif"'> asterisk-users-bounces@lists.digium.com [mailto:asterisk-users-bounces@lists.digium.com] <b>On Behalf Of </b>Rodrigo Lang<br><b>Sent:</b> Wednesday, August 01, 2012 12:45 PM<br><b>To:</b> Asterisk Users Mailing List - Non-Commercial Discussion<br><b>Subject:</b> [asterisk-users] Problem with callfile and CDR<o:p></o:p></span></p><p class=MsoNormal><o:p> </o:p></p><div><p class=MsoNormal>Good afternoon list.<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>I am experiencing a problem with the CDR and callfiles. What is happening is this: When generating a call with a callfile, everything works perfectly, but the CDR is recorded in the table when they answer the call destination. The field disposition is being recorded correctly, but the duration field is marked with the ring time and billsec is marked with 0. This just happens to connections through callfiles. Yes, the call is working usually remains. I did several tests with durations from seconds to 20 minutes.<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>I tested in two servers. With an Asterisk 1.4.44 (debian 5 64bits), and another with Asterisk 1.8.14.0 (debian 6 64bits). In both occurs the same problem. AND I tried using ForkCDR and ResetCDR and both did not help.<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>I'm doing something wrong? Has anyone experienced something similar? Any tips? <o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal style='margin-bottom:12.0pt'><b><u>The callfile:</u></b><o:p></o:p></p><p class=MsoNormal>Channel: local/21411615@test_outgoing<br>CallerID: "ELCO Test" <123456789><br>MaxRetries: 1<br>RetryTime: 30<br>WaitTime: 25<br>Context: test_ivr<br>Extension: 21411615<br>Priority: 1<br>AlwaysDelete: Yes<br>Archive: Yes<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><b><u>The extensions.conf</u></b><o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><blockquote style='border:none;border-left:solid #CCCCCC 1.0pt;padding:0in 0in 0in 6.0pt;margin-left:4.8pt;margin-right:0in'><p class=MsoNormal>[test_outgoingsaida]<br>exten => _X.,1,Dial(khomp/gpstn/${EXTEN},120,Ttr)<br>exten => _X.,2,Hangup()<br>[test_ivr]<br>exten => _X.,1,Answer()<br>exten => _X.,n,Wait(20)<br>exten => _X.,n,Hangup()<o:p></o:p></p></blockquote></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><b><u>Example, console:</u></b><o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><b>Log first channel:</b><o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:29:44] DEBUG[6679] pbx.c: Launching 'Dial'<o:p></o:p></p></div><div><div><p class=MsoNormal>[2012-08-01 14:29:44] VERBOSE[6679] pbx.c: -- Executing [21411615@test_outgoing:1] Dial("Local/21411615@test_outgoing-cb92;2", "khomp/gpstn/21411615,120,Ttr") in new stack<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:29:44] DEBUG[6679] devicestate.c: device 'Khomp/B1C0' state '1'<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:29:44] DEBUG[6679] rtp_engine.c: Can't find native functions for channel 'Khomp/B1C0-0.0'<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:29:44] DEBUG[6679] rtp_engine.c: Seeded SDP of 'Khomp/B1C0-0.0' with that of 'Local/21411615@test_outgoing-cb92;2'<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:29:44] DEBUG[6679] channel.c: Not copying variable DIALEDTIME.<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:29:44] DEBUG[6679] channel.c: Not copying variable ANSWEREDTIME.<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:29:44] DEBUG[6679] channel.c: Not copying variable DIALEDPEERNAME.<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:29:44] DEBUG[6679] channel.c: Not copying variable DIALEDPEERNUMBER.<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:29:44] DEBUG[6679] channel.c: Not copying variable DIALSTATUS.<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:29:44] VERBOSE[6679] app_dial.c: -- Called khomp/gpstn/21411615<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:29:52] VERBOSE[6679] app_dial.c: -- Khomp/B1C0-0.0 is ringing<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:29:53] VERBOSE[6679] app_dial.c: -- Khomp/B1C0-0.0 is making progress passing it to Local/21411615@test_outgoing-cb92;2<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] VERBOSE[6679] app_dial.c: -- Khomp/B1C0-0.0 answered Local/21411615@test_outgoing-cb92;2<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Set channel Khomp/B1C0-0.0 to write format slin<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Set channel Khomp/B1C0-0.0 to read format slin<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] features.c: Removing dialed interfaces datastore on Khomp/B1C0-0.0 since we're bridging<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Planning to masquerade channel Khomp/B1C0-0.0 into the structure of Local/21411615@test_outgoing-cb92;1<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Done planning to masquerade channel Khomp/B1C0-0.0 into the structure of Local/21411615@test_outgoing-cb92;1<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Set channel Khomp/B1C0-0.0 to write format slin<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Set channel Khomp/B1C0-0.0 to read format slin<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Putting channel Khomp/B1C0-0.0 in slin/slin formats<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Done Masquerading Khomp/B1C0-0.0 (6)<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] chan_local.c: Not posting to 'Local/21411615@test_outgoing-cb92;2' queue since already masqueraded out<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Bridge stops because we're zombie or need a soft hangup: c0=Local/21411615@test_outgoing-cb92;2, c1=Local/21411615@test_outgoing-cb92;1<ZOMBIE>, flags: No,Yes,Yes,Yes<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Bridge stops bridging channels Local/21411615@test_outgoing-cb92;2 and Local/21411615@test_outgoing-cb92;1<ZOMBIE><o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] cdr_mysql.c: Inserting a CDR record.<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] cdr_mysql.c: SQL command as follows: INSERT INTO cdr (`calldate`,`clid`,`src`,`dst`,`dcontext`,`channel`,`dstchannel`,`lastapp`,`lastdata`,`duration`,`billsec`,`disposition`,`amaflags`,`uniqueid`) VALUES ('2012-08-01 14:29:44','\"ELCO Test\" <123456789>','123456789','21411615','test_outgoing','Local/21411615@test_outgoing-cb92;2','Khomp/B1C0-0.0','Dial','khomp/gpstn/21411615,120,Ttr','18','0','ANSWERED','3','1343842184.19303')<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Hanging up zombie 'Local/21411615@test_outgoing-cb92;1<ZOMBIE>'<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] app_dial.c: Exiting with DIALSTATUS=ANSWER.<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] pbx.c: Spawn extension (test_outgoing,21411615,1) exited non-zero on 'Local/21411615@test_outgoing-cb92;2'<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] VERBOSE[6679] pbx.c: == Spawn extension (test_outgoing, 21411615, 1) exited non-zero on 'Local/21411615@test_outgoing-cb92;2'<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Soft-Hanging up channel 'Local/21411615@test_outgoing-cb92;2'<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Hanging up channel 'Local/21411615@test_outgoing-cb92;2'<o:p></o:p></p></div></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><b>Log second channel:</b><o:p></o:p></p></div><div><div><p class=MsoNormal>[2012-08-01 14:29:44] VERBOSE[6678] pbx_spool.c: -- Attempting call on local/21411615@test_outgoing for 21411615@test_ivr:1 (Retry 1)<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6678] pbx.c: Launching 'Answer'<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] VERBOSE[6678] pbx.c: -- Executing [21411615@test_ivr:1] Answer("Local/21411615@test_outgoing-cb92;1", "") in new stack<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6678] pbx.c: Launching 'Wait'<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] VERBOSE[6678] pbx.c: -- Executing [21411615@test_ivr:2] Wait("Local/21411615@test_outgoing-cb92;1", "30") in new stack<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6678] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:02] DEBUG[6678] channel.c: Started silence generator on 'Local/21411615@test_outgoing-cb92;1'<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:34] DEBUG[6678] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:34] DEBUG[6678] channel.c: Stopped silence generator on 'Khomp/B1C0-0.0'<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:34] DEBUG[6678] pbx.c: Launching 'Hangup'<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:34] VERBOSE[6678] pbx.c: -- Executing [21411615@test_ivr:3] Hangup("Khomp/B1C0-0.0", "") in new stack<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:34] DEBUG[6678] pbx.c: Spawn extension (test_ivr,21411615,3) exited non-zero on 'Khomp/B1C0-0.0'<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:34] VERBOSE[6678] pbx.c: == Spawn extension (test_ivr, 21411615, 3) exited non-zero on 'Khomp/B1C0-0.0'<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:34] DEBUG[6678] channel.c: Soft-Hanging up channel 'Khomp/B1C0-0.0'<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:34] DEBUG[6678] channel.c: Hanging up channel 'Khomp/B1C0-0.0'<o:p></o:p></p></div><div><p class=MsoNormal>[2012-08-01 14:30:34] NOTICE[6678] pbx_spool.c: Call completed to local/21411615@test_outgoing<o:p></o:p></p></div></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><b><u>Example, table cdr (MySQL):</u></b><o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><div><p class=MsoNormal>*************************** 46. row ***************************<o:p></o:p></p></div><div><p class=MsoNormal> calldate: 2012-08-01 14:29:44<o:p></o:p></p></div><div><p class=MsoNormal> clid: "ELCO Test" <123456789><o:p></o:p></p></div><div><p class=MsoNormal> src: 123456789<o:p></o:p></p></div><div><p class=MsoNormal> dst: 21411615<o:p></o:p></p></div><div><p class=MsoNormal> dcontext: test_outgoing<o:p></o:p></p></div><div><p class=MsoNormal> channel: Local/21411615@test_outgoing-cb92;2<o:p></o:p></p></div><div><p class=MsoNormal> dstchannel: Khomp/B1C0-0.0<o:p></o:p></p></div><div><p class=MsoNormal> lastapp: Dial<o:p></o:p></p></div><div><p class=MsoNormal> lastdata: khomp/gpstn/21411615,120,Ttr<o:p></o:p></p></div><div><p class=MsoNormal> duration: 18<o:p></o:p></p></div><div><p class=MsoNormal> billsec: 0<o:p></o:p></p></div><div><p class=MsoNormal> disposition: ANSWERED<o:p></o:p></p></div><div><p class=MsoNormal> amaflags: 3<o:p></o:p></p></div><div><p class=MsoNormal> accountcode:<o:p></o:p></p></div><div><p class=MsoNormal> uniqueid: 1343842184.19303<o:p></o:p></p></div><div><p class=MsoNormal> userfield:<o:p></o:p></p></div><div><p class=MsoNormal> did:<o:p></o:p></p></div><div><p class=MsoNormal>recordingfile:<o:p></o:p></p></div></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><div><p class=MsoNormal>Best Regards,<o:p></o:p></p></div><div><p class=MsoNormal><o:p> </o:p></p></div><p class=MsoNormal style='margin-bottom:12.0pt'>-- <br>Rodrigo Lang<o:p></o:p></p></div></body></html>