asterisk users - Nov 2006 - Problem with CDR interpretation

Hello,

I have problem with interpretation of CDR entries.

What happened?
-------------

There was:
1. at 09:00:26 we received call from unknown caller (no callerid) to 
secretary with extension 17
2. secretary answered and after some conversation called to extension 18 
to check if she could transfer customer call
3. at 09:02:55 extension 18 answered and call was forwarded
4. call was very long and finished at 10:15:55
5. during the conversation between customer and extension 18 there were 
some calls to extension 18:
  -at 09:15:30
  -at 09:17:42
  -at 09:26:19

OK.  Now how it looks in CDR .csv file:
"","","18","zcentralki","","Zap/2-1","SIP/8117-e6cb","Dial","SIP/8117&LOCAL/19@sekretariat&LOCAL/24@sekretariat|60|t","2006-11-08
09:00:26","2006-11-08 09:00:26","2006-11-08 
10:15:55",4529,4529,"ANSWERED","DOCUMENTATION","asterisk-1295-1162972826.808",""
"","","18","zcentralki","","SIP/8117-7601","SIP/8118-5cae","Dial","SIP/8118|60|t","2006-11-08
09:02:52","2006-11-08 09:02:55","2006-11-08 
10:15:55",4383,4380,"ANSWERED","DOCUMENTATION","asterisk-1295-1162972972.814",""
"","914247106","s","brak","914247106","Zap/3-1","SIP/8118-40f2","BackGround","linia_jest_chwilowo_zajeta","2006-11-08
09:15:30","2006-11-08 09:15:30","2006-11-08 
09:16:06",36,36,"ANSWERED","DOCUMENTATION","asterisk-1295-1162973730.824",""
"","914247106","18","main_menu","914247106","Zap/3-1","SIP/8118-4f79","Dial","SIP/8118|60|t","2006-11-08
09:17:42","2006-11-08 09:17:42","2006-11-08 
09:18:12",30,30,"ANSWERED","DOCUMENTATION","asterisk-1295-1162973862.826",""
"","914247106","18","main_menu","914247106","Zap/1-1","SIP/8118-3de2","Dial","SIP/8118|60|t","2006-11-08
09:26:19","2006-11-08 09:26:19","2006-11-08 
09:26:38",19,19,"ANSWERED","DOCUMENTATION","asterisk-1295-1162974379.834",""

And more friendly view (at least for me :)

1. src:                   dst: 18
dcontext: zcentralki
clid:
channel: Zap/2-1          dstchannel: SIP/8117-e6cb
lastapp: Dial             lastdata: 
SIP/8117&LOCAL/19@sekretariat&LOCAL/24@sekretariat|60|t
start: 2006-11-08 09:00:26        answer: 2006-11-08 09:00:26       end: 
2006-11-08 10:15:55
duration: 4529            billsec: 4529
disposition: ANSWERED     amaflags: DOCUMENTATION
uniqueid: asterisk-1295-1162972826.808

2. src:                   dst: 18
dcontext: zcentralki
clid:
channel: SIP/8117-7601    dstchannel: SIP/8118-5cae
lastapp: Dial           lastdata: SIP/8118|60|t
start: 2006-11-08 09:02:52        answer: 2006-11-08 09:02:55       end: 
2006-11-08 10:15:55
duration: 4383            billsec: 4380
disposition: ANSWERED     amaflags: DOCUMENTATION
uniqueid: asterisk-1295-1162972972.814

3. src: 914247106         dst: s
dcontext: brak
clid: 914247106
channel: Zap/3-1          dstchannel: SIP/8118-40f2
lastapp: BackGround       lastdata: linia_jest_chwilowo_zajeta
start: 2006-11-08 09:15:30        answer: 2006-11-08 09:15:30       end: 
2006-11-08 09:16:06
duration: 36              billsec: 36
disposition: ANSWERED     amaflags: DOCUMENTATION
uniqueid: asterisk-1295-1162973730.824

4. src: 914247106         dst: 18
dcontext: main_menu
clid: 914247106
channel: Zap/3-1          dstchannel: SIP/8118-4f79
lastapp: Dial             lastdata: SIP/8118|60|t
start: 2006-11-08 09:17:42        answer: 2006-11-08 09:17:42       end: 
2006-11-08 09:18:12
duration: 30              billsec: 30
disposition: ANSWERED     amaflags: DOCUMENTATION
uniqueid: asterisk-1295-1162973862.826

5. src: 914247106         dst: 18
dcontext: main_menu
clid: 914247106
channel: Zap/1-1          dstchannel: SIP/8118-3de2
lastapp: Dial             lastdata: SIP/8118|60|t
start: 2006-11-08 09:26:19        answer: 2006-11-08 09:26:19       end: 
2006-11-08 09:26:38
duration: 19              billsec: 19
disposition: ANSWERED     amaflags: DOCUMENTATION
uniqueid: asterisk-1295-1162974379.834


What are the problems?
----------------------

1. I don't know why there is billsec 4529 in first call?
This call was transfered from extension 17 to extension 18 and next 
record show
the same "end of call" time.  Shouldn't first call show shorter
billsec:
from answer
to transfer?  Now it looks like extesion 18 had two very long calls at 
the same time.

2. How can I raport that 3 calls from 914247106 failed?
They were answered -- our system played some voice samples, but extension 18
was not reached.  Record #3 shows 's' as 'dst', but record #4
and #5
shows '18'.

My version of Asterisk is:
Asterisk 1.2.4-BRIstuffed-0.3.0-PRE-1l

Regards,
Micha? Niklas

Hello,

About my problem with CDR where 2 calls overlaps, and there is no evidence
that 3 other calls failed: after some searching on Asterisk bugs 
database I have found:
http://bugs.digium.com/view.php?id=6762
...
When the Attended Transfer is used the information for call duration and 
who is talking is missed.
...

I think this is the same problem as mine and there is no solution yet :(
I will have to search for some workaround.  Any ideas?

Regards,
Micha? Niklas