<div>Good afternoon list.</div><div><br></div><div>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.</div>
<div><br></div><div>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.</div>
<div><br></div><div>I'm doing something wrong? Has anyone experienced something similar? Any tips?
</div><div><br></div><div><br></div><div><b><u>The callfile:</u></b><br><br><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left-width:1px;border-left-color:rgb(204,204,204);border-left-style:solid;padding-left:1ex">
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</blockquote></div><div><br></div><div><br></div><div><b><u>The extensions.conf</u></b></div><div><br></div><div><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left-width:1px;border-left-color:rgb(204,204,204);border-left-style:solid;padding-left:1ex">
[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()</blockquote>
</div><div><br></div><div><br></div><div><b><u>Example, console:</u></b></div><div><br></div><div><b>Log first channel:</b></div><div>[2012-08-01 14:29:44] DEBUG[6679] pbx.c: Launching 'Dial'</div><div><div>[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</div>
<div>[2012-08-01 14:29:44] DEBUG[6679] devicestate.c: device 'Khomp/B1C0' state '1'</div><div>[2012-08-01 14:29:44] DEBUG[6679] rtp_engine.c: Can't find native functions for channel 'Khomp/B1C0-0.0'</div>
<div>[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'</div><div>[2012-08-01 14:29:44] DEBUG[6679] channel.c: Not copying variable DIALEDTIME.</div>
<div>[2012-08-01 14:29:44] DEBUG[6679] channel.c: Not copying variable ANSWEREDTIME.</div><div>[2012-08-01 14:29:44] DEBUG[6679] channel.c: Not copying variable DIALEDPEERNAME.</div><div>[2012-08-01 14:29:44] DEBUG[6679] channel.c: Not copying variable DIALEDPEERNUMBER.</div>
<div>[2012-08-01 14:29:44] DEBUG[6679] channel.c: Not copying variable DIALSTATUS.</div><div>[2012-08-01 14:29:44] VERBOSE[6679] app_dial.c: -- Called khomp/gpstn/21411615</div><div>[2012-08-01 14:29:52] VERBOSE[6679] app_dial.c: -- Khomp/B1C0-0.0 is ringing</div>
<div>[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</div><div>[2012-08-01 14:30:02] VERBOSE[6679] app_dial.c: -- Khomp/B1C0-0.0 answered Local/21411615@test_outgoing-cb92;2</div>
<div>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Set channel Khomp/B1C0-0.0 to write format slin</div><div>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Set channel Khomp/B1C0-0.0 to read format slin</div><div>[2012-08-01 14:30:02] DEBUG[6679] features.c: Removing dialed interfaces datastore on Khomp/B1C0-0.0 since we're bridging</div>
<div>[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</div><div>[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</div>
<div>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Set channel Khomp/B1C0-0.0 to write format slin</div><div>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Set channel Khomp/B1C0-0.0 to read format slin</div><div>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Putting channel Khomp/B1C0-0.0 in slin/slin formats</div>
<div>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Done Masquerading Khomp/B1C0-0.0 (6)</div><div>[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</div>
<div>[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</div>
<div>[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></div><div>[2012-08-01 14:30:02] DEBUG[6679] cdr_mysql.c: Inserting a CDR record.</div>
<div>[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')</div>
<div>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Hanging up zombie 'Local/21411615@test_outgoing-cb92;1<ZOMBIE>'</div><div>[2012-08-01 14:30:02] DEBUG[6679] app_dial.c: Exiting with DIALSTATUS=ANSWER.</div>
<div>[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'</div><div>[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'</div>
<div>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Soft-Hanging up channel 'Local/21411615@test_outgoing-cb92;2'</div><div>[2012-08-01 14:30:02] DEBUG[6679] channel.c: Hanging up channel 'Local/21411615@test_outgoing-cb92;2'</div>
</div><div><br></div><div><b>Log second channel:</b></div><div><div>[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)</div><div>[2012-08-01 14:30:02] DEBUG[6678] pbx.c: Launching 'Answer'</div>
<div>[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</div><div>[2012-08-01 14:30:02] DEBUG[6678] pbx.c: Launching 'Wait'</div>
<div>[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</div><div>[2012-08-01 14:30:02] DEBUG[6678] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second</div>
<div>[2012-08-01 14:30:02] DEBUG[6678] channel.c: Started silence generator on 'Local/21411615@test_outgoing-cb92;1'</div><div>[2012-08-01 14:30:34] DEBUG[6678] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second</div>
<div>[2012-08-01 14:30:34] DEBUG[6678] channel.c: Stopped silence generator on 'Khomp/B1C0-0.0'</div><div>[2012-08-01 14:30:34] DEBUG[6678] pbx.c: Launching 'Hangup'</div><div>[2012-08-01 14:30:34] VERBOSE[6678] pbx.c: -- Executing [21411615@test_ivr:3] Hangup("Khomp/B1C0-0.0", "") in new stack</div>
<div>[2012-08-01 14:30:34] DEBUG[6678] pbx.c: Spawn extension (test_ivr,21411615,3) exited non-zero on 'Khomp/B1C0-0.0'</div><div>[2012-08-01 14:30:34] VERBOSE[6678] pbx.c: == Spawn extension (test_ivr, 21411615, 3) exited non-zero on 'Khomp/B1C0-0.0'</div>
<div>[2012-08-01 14:30:34] DEBUG[6678] channel.c: Soft-Hanging up channel 'Khomp/B1C0-0.0'</div><div>[2012-08-01 14:30:34] DEBUG[6678] channel.c: Hanging up channel 'Khomp/B1C0-0.0'</div><div>[2012-08-01 14:30:34] NOTICE[6678] pbx_spool.c: Call completed to local/21411615@test_outgoing</div>
</div><div><br></div><div><br></div><div><b><u>Example, table cdr (MySQL):</u></b></div><div><br></div><div><div>*************************** 46. row ***************************</div><div> calldate: 2012-08-01 14:29:44</div>
<div> clid: "ELCO Test" <123456789></div><div> src: 123456789</div><div> dst: 21411615</div><div> dcontext: test_outgoing</div><div> channel: Local/21411615@test_outgoing-cb92;2</div>
<div> dstchannel: Khomp/B1C0-0.0</div><div> lastapp: Dial</div><div> lastdata: khomp/gpstn/21411615,120,Ttr</div><div> duration: 18</div><div> billsec: 0</div><div> disposition: ANSWERED</div><div> amaflags: 3</div>
<div> accountcode:</div><div> uniqueid: 1343842184.19303</div><div> userfield:</div><div> did:</div><div>recordingfile:</div></div><div><br></div><div><br></div><div><br></div><div><br></div><div>Best Regards,</div>
<div><br></div>-- <br>Rodrigo Lang<br><br>