cdrecord: KDL When start to burning DVD-RW

[Giova84]

When i start to burn a DVDRW using cdrecord i got a KDL (in the screenshot attached), i type "es" in the KDL, then cdrecord burns fine and without noticeable errors, all data to dvd. But then, again, i got another KDL when the process ends (I escape from KDL, again with "es").

In anyway files are correctly burned to DVD. Here: ​http://pastebin.com/ki6hcAX7 the whole output of cdrecord.

Haiku R1 Alpha 4.1

[Giova84]

cdrecord KDL

[Giova84]

The situation is the same using cdrecord 3.01a08.

[kallisti5]

same crashing issue here using cdrecord:

Cdrecord-ProDVD-ProBD-Clone 3.01a18 (i586-pc-haiku) Copyright (C) 1995-2013 Joerg Schilling

Clearer screenshot attached of KDL attached

hrev46452 gcc2h

[kallisti5]

kdl

[kallisti5]

attempted to burn an iso to a cd (vs a dvd)

no KDL yet, but stuck at "Waiting for reader process to fill input buffer ..."

syslog shows scsi errors:

KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN: atapi 2-1 error: device indicates transfer error after dma
KERN: check_sense: Illegal request
KERN: IOCache::_TransferRequestLine(): Failed to read into cache (offset: 0, length: 4096), trying uncached read (offset: 0, length: 512)
KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN: atapi 2-1 error: device indicates transfer error after dma
KERN: check_sense: Illegal request
KERN: fs_identify_partition: couldn't read boot sector
KERN:   returned: -1
KERN:   trying: file_systems/write_overlay/v1
KERN:   returned: -1
KERN:   trying: file_systems/attribute_overlay/v1
KERN:   returned: -1
KERN:   trying: file_systems/nfs4/v1
KERN:   returned: -1
KERN:   trying: file_systems/nfs/v1
KERN:   returned: -1
KERN:   trying: file_systems/iso9660/v1
KERN: identify(155, 0x9cf6d870)
KERN:   returned: -1
KERN:   trying: file_systems/fat/v1
KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN: atapi 2-1 error: device indicates transfer error after dma
KERN: check_sense: Illegal request
KERN: IOCache::_TransferRequestLine(): Failed to read into cache (offset: 0, length: 4096), trying uncached read (offset: 0, length: 512)
KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN: atapi 2-1 error: device indicates transfer error after dma
KERN: check_sense: Illegal request
KERN:   returned: -1
KERN:   trying: file_systems/ext2/v1
KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN: atapi 2-1 error: device indicates transfer error after dma
KERN: check_sense: Illegal request
KERN: IOCache::_TransferRequestLine(): Failed to read into cache (offset: 0, length: 4096), trying uncached read (offset: 1024, length: 1024)
KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN: atapi 2-1 error: device indicates transfer error after dma
KERN: check_sense: Illegal request
KERN:   returned: -1
KERN:   trying: file_systems/exfat/v1
KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN: atapi 2-1 error: device indicates transfer error after dma
KERN: check_sense: Illegal request
KERN: IOCache::_TransferRequestLine(): Failed to read into cache (offset: 0, length: 4096), trying uncached read (offset: 0, length: 512)
KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN: atapi 2-1 error: device indicates transfer error after dma
KERN: check_sense: Illegal request
KERN:   returned: -1
KERN:   trying: file_systems/cdda/v1
KERN: raw_command: 
KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN:   returned: -1
KERN:   trying: file_systems/btrfs/v1
KERN:   returned: -1
KERN:   trying: file_systems/bindfs/v1
KERN:   returned: -1
KERN: raw_command: 
KERN: Last message repeated 2 times.
KERN: SCSI -- copy_sg_data: buffer = 0x801d2934, virt_addr = 0x40a9d0fb0, bytes = 36, to_buffer = 1
KERN: SCSI -- copy_sg_data: buffer = 0x801d2934, virt_addr = 0x40a9d0fb0, bytes = 36, to_buffer = 0
KERN: raw_command: 
KERN: Last message repeated 2 times.
KERN: SCSI -- copy_sg_data: buffer = 0x801d2934, virt_addr = 0x407611fb0, bytes = 36, to_buffer = 1
KERN: SCSI -- copy_sg_data: buffer = 0x801d2934, virt_addr = 0x407611fb0, bytes = 36, to_buffer = 0
KERN: raw_command: 
KERN: Last message repeated 2 times.
KERN: SCSI -- copy_sg_data: buffer = 0x801d2934, virt_addr = 0x409292fb0, bytes = 36, to_buffer = 1
KERN: SCSI -- copy_sg_data: buffer = 0x801d2934, virt_addr = 0x409292fb0, bytes = 36, to_buffer = 0
KERN: raw_command: 
KERN: Last message repeated 2 times.
KERN: SCSI -- copy_sg_data: buffer = 0x801d2934, virt_addr = 0x3deb28fb0, bytes = 36, to_buffer = 1
KERN: SCSI -- copy_sg_data: buffer = 0x801d2934, virt_addr = 0x3deb28fb0, bytes = 36, to_buffer = 0
KERN: raw_command: 
KERN: Last message repeated 2 times.
KERN: SCSI -- copy_sg_data: buffer = 0x801d2934, virt_addr = 0x40708efb0, bytes = 36, to_buffer = 1
KERN: SCSI -- copy_sg_data: buffer = 0x801d2934, virt_addr = 0x40708efb0, bytes = 36, to_buffer = 0
KERN: raw_command: 
KERN: Last message repeated 2 times.
KERN: SCSI -- copy_sg_data: buffer = 0x801d2934, virt_addr = 0x71f6bfb0, bytes = 36, to_buffer = 1
KERN: SCSI -- copy_sg_data: buffer = 0x801d2934, virt_addr = 0x71f6bfb0, bytes = 36, to_buffer = 0
KERN: raw_command: 
KERN: Last message repeated 11 times.
KERN: SCSI -- copy_sg_data: buffer = 0x801d2934, virt_addr = 0x71f6bfb0, bytes = 36, to_buffer = 1
KERN: SCSI -- copy_sg_data: buffer = 0x801d2934, virt_addr = 0x71f6bfb0, bytes = 36, to_buffer = 0
KERN: raw_command: 
KERN: Last message repeated 11 times.
KERN: SCSI -- copy_sg_data: buffer = 0x801d2934, virt_addr = 0x71f6bfb0, bytes = 36, to_buffer = 1
KERN: SCSI -- copy_sg_data: buffer = 0x801d2934, virt_addr = 0x71f6bfb0, bytes = 36, to_buffer = 0
KERN: raw_command: 
KERN: Last message repeated 38 times.
KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN: raw_command: 
Last message repeated 1 time
KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN: raw_command: 
KERN: Last message repeated 126 times.
KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN: raw_command: 
Last message repeated 1 time
KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN: raw_command: 
KERN: Last message repeated 12 times.
es.
KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN: raw_command: 
Last message repeated 1 time
KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN: raw_command: 
KERN: Last message repeated 12 times.
data source

[kallisti5]

cdrecord output:

/Data> cdrecord -vv -dao archlinux-2013.12.01-dual.iso 
Cdrecord-ProDVD-ProBD-Clone 3.01a18 (i586-pc-haiku) Copyright (C) 1995-2013 Joerg Schilling
TOC Type: 1 = CD-ROM
cdrecord: WARNING: Cannot set priority on this OS.
cdrecord: WARNING: This causes a high risk for buffer underruns.
Using libscg version 'schily-0.9'.
Using libscg transport code version 'schily-scsi-beos.c-1.29'
SCSI buffer size: 64512
No target specified, trying to find one...
Using dev=10,1,0.
atapi: -1
Device type    : Removable CD-ROM
Version        : 2
Response Format: 2
Capabilities   : 
Vendor_info    : 'HL-DT-ST'
Identifikation : 'BDDVDRW UH12LS28'
Revision       : '1.00'
Device seems to be: Generic mmc2 DVD-R/DVD-RW/DVD-RAM.
Current: CD-R
Profile: BD-ROM 
Profile: DVD-RAM 
Profile: DVD-R sequential recording 
Profile: DVD-R/DL sequential recording 
Profile: DVD-R/DL layer jump recording 
Profile: DVD-RW sequential recording 
Profile: DVD-RW restricted overwrite 
Profile: DVD+RW 
Profile: DVD+R 
Profile: DVD+R/DL 
Profile: DVD-ROM 
Profile: CD-R (current)
Profile: CD-RW 
Profile: CD-ROM 
Profile: Removable Disk 
Supported CD-RW media types: 0F
Drive current speed: 40
Drive default speed: 40
Drive max speed    : 40
Selected speed     : 40
Using generic SCSI-3/mmc   CD-R/CD-RW driver (mmc_cdr).
Driver flags   : MMC-3 SWABAUDIO BURNFREE 
Supported modes: TAO PACKET SAO SAO/R96P SAO/R96R RAW/R16 RAW/R96P RAW/R96R LAYER_JUMP
Drive buf size : 2483712 = 2425 KB
cdrecord: Warning: Drive returned invalid buffer size.
cdrecord: Warning: The DMA speed test has been skipped.
FIFO size      : 4194304 = 4096 KB
Track 01: data   522 MB        
track: 1 start: 0 pregap: 150
Total size:      599 MB (59:23.52) = 267264 sectors
Lout start:      599 MB (59:25/39) = 267264 sectors
 41 00 00 14 00 00 00 00
 41 01 00 10 00 00 00 00
 41 01 01 10 00 00 02 00
 41 AA 01 14 00 3B 19 27
Track 1 start 0
Track 2 start 267264
 41 00 A0 00 00 00 00 01 00 00 00 00
 41 00 A1 00 00 00 00 01 00 00 00 00
 41 00 A2 00 00 00 00 59 25 39 00 00
 41 00 01 00 00 00 00 00 02 00 00 00
Current Secsize: 2048
ATIP info from disk:
  Indicated writing power: 5
Disk Is not unrestricted
Disk Is not erasable
  Disk sub type: Medium Type A, high Beta category (A+) (3)
  ATIP start of lead in:  -11634 (97:26/66)
  ATIP start of lead out: 359846 (79:59/71)
Disk type:    Short strategy type (Phthalocyanine or similar)
Manuf. index: 3
Manufacturer: CMC Magnetics Corporation

    Capacity  Blklen/Sparesz.  Format-type  Type
           0             2048         0x00  No Media Present or Unknown Capacity
cdrecord: Disk capacity is unknown.
cdrecord: WARNING: Cannot set priority on this OS.
cdrecord: WARNING: This causes a high risk for buffer underruns.
cdrecord: Panic FIFO addr.
Starting to write CD/DVD/BD at speed 40 in real SAO mode for single session.
Last chance to quit, starting real write    0 seconds. Operation starts.
Waiting for reader process to fill input buffer ... 

[kallisti5]

scanbus:

It looks like the recorder is appearing and disappearing?

/Data> cdrecord --scanbus
Cdrecord-ProDVD-ProBD-Clone 3.01a18 (i586-pc-haiku) Copyright (C) 1995-2013 Joerg Schilling
Using libscg version 'schily-0.9'.
scsibus10:
        10,0,0  1000) *
cdrecord: Warning: controller returns zero sized CD capabilities page.
cdrecord: Warning: controller returns wrong size for CD capabilities page.
cdrecord: Warning: controller returns wrong page 0 for CD capabilities page (2A).
        10,1,0  1001) 'HL-DT-ST' 'BDDVDRW UH12LS28' '1.00' Removable CD-ROM
        10,2,0  1002) *
        10,3,0  1003) *
        10,4,0  1004) *
        10,5,0  1005) *
        10,6,0  1006) *
        10,7,0  1007) *
/Data> cdrecord --scanbus
Cdrecord-ProDVD-ProBD-Clone 3.01a18 (i586-pc-haiku) Copyright (C) 1995-2013 Joerg Schilling
Using libscg version 'schily-0.9'.
scsibus10:
        10,0,0  1000) *
        10,1,0  1001) 'ADAPTEC ' 'ACB-5500        ' 'FAKE' NON CCS Disk
        10,2,0  1002) *
        10,3,0  1003) *
        10,4,0  1004) *
        10,5,0  1005) *
        10,6,0  1006) *
        10,7,0  1007) *
}}]

[kallisti5]

ran cdrecord -vv -tao over and over... when the cdrom finally showed up again, it burned the cd? Odd.

While burning, it seemed to spit out "KERN: raw_command:" over and over...

KERN: Last message repeated 7 times.
KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN: raw_command: 
Last message repeated 1 time
KERN: ata 2 error: command failed, error bit is set. status 0x51, error 0x54
KERN: raw_command: 
KERN: Last message repeated 25 times.
KERN: Last message repeated 160 times.
KERN: Last message repeated 226 times.
KERN: Last message repeated 234 times.
KERN: Last message repeated 243 times.
KERN: Last message repeated 208 times.
KERN: Last message repeated 257 times.
KERN: Last message repeated 200 times.
KERN: Last message repeated 205 times.
KERN: Last message repeated 278 times.
KERN: Last message repeated 216 times.
KERN: Last message repeated 295 times.
KERN: Last message repeated 302 times.
KERN: Last message repeated 311 times.
KERN: Last message repeated 319 times.
KERN: Last message repeated 246 times.
KERN: Last message repeated 268 times.
KERN: Last message repeated 342 times.
KERN: Last message repeated 349 times.

[Giova84]

Replying to kallisti5:

scanbus:

It looks like the recorder is appearing and disappearing?

/Data> cdrecord --scanbus
Cdrecord-ProDVD-ProBD-Clone 3.01a18 (i586-pc-haiku) Copyright (C) 1995-2013 Joerg Schilling
Using libscg version 'schily-0.9'.
scsibus10:
        10,0,0  1000) *
        10,1,0  1001) 'ADAPTEC ' 'ACB-5500        ' 'FAKE' NON CCS Disk
        10,2,0  1002) *
        10,3,0  1003) *
        10,4,0  1004) *
        10,5,0  1005) *
        10,6,0  1006) *
        10,7,0  1007) *

This was reported as different ticket :-) See #8830

[vidrep]

I believe this issue has been resolved with the update to cdrtools 3.01a30. I have successfully burned several iso images, data files and audio compilations to both cd-rw and dvd-rw media from the command line without any errors. Perhaps Giova84 and kallisti5 can verify as well, before closing this ticket as fixed?

[vidrep]

Can this ticket be closed? Current CDRecord version is now 3.02a06.

[vidrep]

After extensive CD/DVD burner testing for the next BurnItnow release, I can confirm that this issue is still present. However, I note that it is far more prevalent on x86_gcc2h builds, compared to x86_64.

[vidrep]

These lines appear in the syslog whenever the drive is identified as "ADAPTEC ACB5500 FAKE"

KERN: SCSI -- copy_sg_data: buffer = 0x827f4934, virt_addr = 0x11a1a8cc8, bytes = 36, to_buffer = 1

KERN: SCSI -- copy_sg_data: buffer = 0x827f4934, virt_addr = 0x11a1a8cc8, bytes = 36, to_buffer = 0

KERN: raw_command:

[vidrep]

Failed burns were usually accompanied by the following lines in the syslog:

KERN: ata 3 error: command failed, error bit is set. status 0x51, error 0x54

KERN: raw_command:

KERN: Last message repeated 39 times.

KERN: atapi 3-0 error: device indicates transfer error after dma

KERN: raw_command:

[vidrep]

I have not seen this issue in nearly 4 years. Please test with latest available Cdrtools 3.02a09, and make sure that AHCI is disabled in the BIOS (see https://dev.haiku-os.org/ticket/13122)

[waddlesplash]

Fixed in hrev58296.