FS#41343 - [cdrtools] problems burning iso to cd-r and cd-rw
Attached to Project:
Community Packages
Opened by jb (jb.1234abcd) - Friday, 25 July 2014, 09:51 GMT
Last edited by Sven-Hendrik Haase (Svenstaro) - Thursday, 28 August 2014, 05:34 GMT
Opened by jb (jb.1234abcd) - Friday, 25 July 2014, 09:51 GMT
Last edited by Sven-Hendrik Haase (Svenstaro) - Thursday, 28 August 2014, 05:34 GMT
|
Details
Description:
This has been verified on two different laptops (HP and Lenovo TP) with different CD-R/CD-RW drives, with veriaties of CD and CD-RW manufacturers, over long period of time of unstable (god, bad) behavior. It has been experienced by various users and equipment and it can not be realistically assumed that all the drives or CDs were faulty or of questionable quality. It applies to cdrecord or wodim equally (cdrtools or cdrkit packages). Additional info: * package version(s) * config and/or log files etc. linux-lts 3.10.46-1 cdrtools 3.01a24-2 Ref: Arch Linux forums searches for cdrecord or wodim iso burn problems https://bbs.archlinux.org/search.php?search_id=1161404992 https://bbs.archlinux.org/search.php?search_id=505754006 Steps to reproduce: # mkisofs -v -RJ -o some.iso /dir # cdrecord -scanbus Examples for CD or CD-RW burning: # cdrecord -v dev=0,1,0 some.iso # cdrecord -v blank=fast dev=/dev/sr0 some.iso |
This task depends upon
Closed by Sven-Hendrik Haase (Svenstaro)
Thursday, 28 August 2014, 05:34 GMT
Reason for closing: Fixed
Additional comments about closing: 2014-07-31: A task closure has been requested. Reason for request: Damaged cd-rw drives.
Thursday, 28 August 2014, 05:34 GMT
Reason for closing: Fixed
Additional comments about closing: 2014-07-31: A task closure has been requested. Reason for request: Damaged cd-rw drives.
Note: iso verified with isoinfo(8) utilities.
# cdrecord -scanbus
Cdrecord-ProDVD-ProBD-Clone 3.01a24 (i686-pc-linux-gnu) Copyright (C) 1995-2014 Joerg Schilling
Linux sg driver version: 3.5.34
Using libscg version 'schily-0.9'.
scsibus0:
0,0,0 0) 'ATA ' 'TOSHIBA MK4026GA' 'PA10' Disk
0,1,0 1) 'SONY ' 'CD-RW CRX835E ' 'KPK4' Removable CD-ROM
0,2,0 2) *
0,3,0 3) *
0,4,0 4) *
0,5,0 5) *
0,6,0 6) *
0,7,0 7) *
# cdrecord -v blank=fast dev=0,1,0 prod_disc.iso
cdrecord: No write mode specified.
cdrecord: Assuming -sao mode.
cdrecord: If your drive does not accept -sao, try -tao.
cdrecord: Future versions of cdrecord may have different drive dependent defaults.
Cdrecord-ProDVD-ProBD-Clone 3.01a24 (i686-pc-linux-gnu) Copyright (C) 1995-2014 Joerg Schilling
TOC Type: 1 = CD-ROM
scsidev: '0,1,0'
scsibus: 0 target: 1 lun: 0
Linux sg driver version: 3.5.34
Using libscg version 'schily-0.9'.
SCSI buffer size: 64512
atapi: 1
Device type : Removable CD-ROM
Version : 5
Response Format: 2
Capabilities :
Vendor_info : 'SONY '
Identifikation : 'CD-RW CRX835E '
Revision : 'KPK4'
Device seems to be: Generic mmc2 DVD-ROM.
Current: CD-RW
Profile: DVD-ROM
Profile: CD-RW (current)
Profile: CD-R
Profile: CD-ROM
Using generic SCSI-3/mmc CD-R/CD-RW driver (mmc_cdr).
Driver flags : MMC-3 SWABAUDIO BURNFREE FORCESPEED
Supported modes: TAO PACKET SAO SAO/R96P SAO/R96R RAW/R16 RAW/R96P RAW/R96R
Drive buf size : 1895168 = 1850 KB
cdrecord: Warning: Cannot read drive buffer.
cdrecord: Warning: The DMA speed test has been skipped.
FIFO size : 4194304 = 4096 KB
Track 01: data 406 MB
Total size: 466 MB (46:12.92) = 207969 sectors
Lout start: 466 MB (46:14/69) = 207969 sectors
Current Secsize: 2048
ATIP info from disk:
Indicated writing power: 2
Reference speed: 6
Disk Is not unrestricted
Disk Is erasable
Disk sub type: High speed Rewritable (CAV) media (1)
ATIP start of lead in: -12900 (97:10/00)
ATIP start of lead out: 359849 (79:59/74)
1T speed low: 4 1T speed high: 10
2T speed low: 4 2T speed high: 0 (reserved val 6)
power mult factor: 1 5
recommended erase/write power: 5
A1 values: 24 1A D8
A2 values: 26 B2 48
Disk type: unknown dye (reserved id code)
Manuf. index: -1
Manufacturer: unknown (not in table)
Manufacturer is unknown because of the orange forum embargo.
As the orange forum likes to get money for recent information,
it may be that this media does not use illegal manufacturer coding.
Capacity Blklen/Sparesz. Format-type Type
295264 2048 0x00 Unformated or Blank Media
295264 32 0x10 Reserved (0)
Forcespeed is OFF.
Starting to write CD/DVD/BD at speed 10 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 ... input buffer ready.
Performing OPC...
Blanking PMA, TOC, pregap
Blanking time: 22.310s (00:00:22.310)
Blocks total: 359849 Blocks current: 359849 Blocks remaining: 151880
BURN-Free is ON.
Turning BURN-Free off
Performing OPC...
Sending CUE sheet...
Writing pregap for track 1 at -150
Starting new track at sector: 0
Track 01: 406 of 406 MB written (fifo 100%) [buf 99%] 10.6x.
Track 01: Total bytes read/written: 425920512/425920512 (207969 sectors).
Writing time: 304.125s (00:05:04.125)
Average write speed 9.4x.
Min drive buffer fill was 98%
Fixating...
Fixating time: 12.170s (00:00:12.170)
cdrecord: fifo had 6709 puts and 6709 gets.
cdrecord: fifo was 0 times empty and 6568 times full, min fill was 59%.
#
Errors logged during CD recording:
# journalctl -b
...
Jul 25 12:15:05 myhost kernel: sr 0:0:1:0: [sr0] CDB:
Jul 25 12:15:05 myhost kernel: cdb[0]=0x28: 28 00 00 00 00 00 00 00 01 00
Jul 25 12:15:05 myhost kernel: end_request: I/O error, dev sr0, sector 0
Jul 25 12:15:05 myhost kernel: Buffer I/O error on device sr0, logical block 0
Jul 25 12:15:05 myhost kernel: sr 0:0:1:0: [sr0] Unhandled sense code
Jul 25 12:15:06 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:15:06 myhost kernel: Result: hostbyte=0x00 driverbyte=0x08
Jul 25 12:15:06 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:15:06 myhost kernel: Sense Key : 0x3 [current]
Jul 25 12:15:06 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:15:06 myhost kernel: ASC=0x11 ASCQ=0x5
Jul 25 12:15:06 myhost kernel: sr 0:0:1:0: [sr0] CDB:
Jul 25 12:15:06 myhost kernel: cdb[0]=0x28: 28 00 00 00 00 01 00 00 01 00
Jul 25 12:15:06 myhost kernel: end_request: I/O error, dev sr0, sector 4
Jul 25 12:15:06 myhost kernel: Buffer I/O error on device sr0, logical block 1
Jul 25 12:15:06 myhost kernel: sr 0:0:1:0: [sr0] Unhandled sense code
Jul 25 12:15:06 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:15:06 myhost kernel: Result: hostbyte=0x00 driverbyte=0x08
Jul 25 12:15:06 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:15:06 myhost kernel: Sense Key : 0x3 [current]
Jul 25 12:15:06 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:15:06 myhost kernel: ASC=0x11 ASCQ=0x5
Jul 25 12:15:06 myhost kernel: sr 0:0:1:0: [sr0] CDB:
Jul 25 12:15:06 myhost kernel: cdb[0]=0x28: 28 00 00 00 00 00 00 00 02 00
Jul 25 12:15:06 myhost kernel: end_request: I/O error, dev sr0, sector 0
Jul 25 12:15:06 myhost kernel: Buffer I/O error on device sr0, logical block 0
Jul 25 12:15:06 myhost kernel: Buffer I/O error on device sr0, logical block 1
Jul 25 12:15:06 myhost kernel: sr 0:0:1:0: [sr0] Unhandled sense code
Jul 25 12:15:06 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:15:06 myhost kernel: Result: hostbyte=0x00 driverbyte=0x08
Jul 25 12:15:06 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:15:06 myhost kernel: Sense Key : 0x3 [current]
Jul 25 12:15:06 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:15:06 myhost kernel: ASC=0x11 ASCQ=0x5
Jul 25 12:15:06 myhost kernel: sr 0:0:1:0: [sr0] CDB:
Jul 25 12:15:06 myhost kernel: cdb[0]=0x28: 28 00 00 00 00 00 00 00 02 00
Jul 25 12:15:06 myhost kernel: end_request: I/O error, dev sr0, sector 0
Jul 25 12:15:06 myhost kernel: Buffer I/O error on device sr0, logical block 0
Jul 25 12:15:06 myhost kernel: Buffer I/O error on device sr0, logical block 1
Jul 25 12:15:04 myhost systemd-udevd[118]: worker [4595] /devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:1/0:0:1:0/bloc
Jul 25 12:15:04 myhost systemd-udevd[118]: seq 1593 '/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:1/0:0:1:0/block/sr
Jul 25 12:15:05 myhost systemd-udevd[118]: worker [4595] terminated by signal 9 (Killed)
#
$ lspci
00:00.0 Host bridge: Intel Corporation Mobile 915GM/PM/GMS/910GML Express Processor to DRAM Controller (rev 03)
00:02.0 VGA compatible controller: Intel Corporation Mobile 915GM/GMS/910GML Express Graphics Controller (rev 03)
00:02.1 Display controller: Intel Corporation Mobile 915GM/GMS/910GML Express Graphics Controller (rev 03)
00:1d.0 USB controller: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) USB UHCI #1 (rev 03)
00:1d.1 USB controller: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) USB UHCI #2 (rev 03)
00:1d.2 USB controller: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) USB UHCI #3 (rev 03)
00:1d.3 USB controller: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) USB UHCI #4 (rev 03)
00:1d.7 USB controller: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) USB2 EHCI Controller (rev 03)
00:1e.0 PCI bridge: Intel Corporation 82801 Mobile PCI Bridge (rev d3)
00:1e.2 Multimedia audio controller: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) AC'97 Audio Controller (rev 03)
00:1e.3 Modem: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) AC'97 Modem Controller (rev 03)
00:1f.0 ISA bridge: Intel Corporation 82801FBM (ICH6M) LPC Interface Bridge (rev 03)
00:1f.1 IDE interface: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) IDE Controller (rev 03)
02:06.0 CardBus bridge: Texas Instruments PCIxx21/x515 Cardbus Controller
02:06.2 FireWire (IEEE 1394): Texas Instruments OHCI Compliant IEEE 1394 Host Controller
02:06.3 Mass storage controller: Texas Instruments PCIxx21 Integrated FlashMedia Controller
02:06.4 SD Host controller: Texas Instruments PCI6411/6421/6611/6621/7411/7421/7611/7621 Secure Digital Controller
02:0e.0 Ethernet controller: Broadcom Corporation BCM4401-B0 100Base-TX (rev 02)
$
$ lspci -vv
...
00:1f.1 IDE interface: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) IDE Controller (rev 03) (prog-if 8a [Master SecP PriP])
Subsystem: Hewlett-Packard Company NX6110/NC6120
Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
Latency: 0
Interrupt: pin A routed to IRQ 16
Region 0: I/O ports at 01f0 [size=8]
Region 1: I/O ports at 03f4
Region 2: I/O ports at 0170 [size=8]
Region 3: I/O ports at 0374
Region 4: I/O ports at 2580 [size=16]
Kernel driver in use: ata_piix
Kernel modules: ata_piix, pata_acpi, ata_generic
$
After "eject" and re-insertion of burned CD-RW:
# mount -t auto /dev/sr0 /media
mount: /dev/sr0 is write-protected, mounting read-only
mount: wrong fs type, bad option, bad superblock on /dev/sr0,
missing codepage or helper program, or other error
In some cases useful info is found in syslog - try
dmesg | tail or so.
# journalctl -b
...
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Result: hostbyte=0x00 driverbyte=0x08
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Sense Key : 0x5 [current]
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: ASC=0x21 ASCQ=0x0
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0] CDB:
Jul 25 12:28:54 myhost kernel: cdb[0]=0x28: 28 00 00 00 00 00 00 00 01 00
Jul 25 12:28:54 myhost kernel: end_request: I/O error, dev sr0, sector 0
Jul 25 12:28:54 myhost kernel: Buffer I/O error on device sr0, logical block 0
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Result: hostbyte=0x00 driverbyte=0x08
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Sense Key : 0x5 [current]
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: ASC=0x21 ASCQ=0x0
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0] CDB:
Jul 25 12:28:54 myhost kernel: cdb[0]=0x28: 28 00 00 00 00 00 00 00 01 00
Jul 25 12:28:54 myhost kernel: end_request: I/O error, dev sr0, sector 0
Jul 25 12:28:54 myhost kernel: Buffer I/O error on device sr0, logical block 0
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Result: hostbyte=0x00 driverbyte=0x08
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Sense Key : 0x5 [current]
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: ASC=0x21 ASCQ=0x0
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0] CDB:
Jul 25 12:28:54 myhost kernel: cdb[0]=0x28: 28 00 00 00 00 00 00 00 01 00
Jul 25 12:28:54 myhost kernel: end_request: I/O error, dev sr0, sector 0
Jul 25 12:28:54 myhost kernel: Buffer I/O error on device sr0, logical block 0
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Result: hostbyte=0x00 driverbyte=0x08
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Sense Key : 0x5 [current]
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: ASC=0x21 ASCQ=0x0
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0] CDB:
Jul 25 12:28:54 myhost kernel: cdb[0]=0x28: 28 00 00 00 00 00 00 00 01 00
Jul 25 12:28:54 myhost kernel: end_request: I/O error, dev sr0, sector 0
Jul 25 12:28:54 myhost kernel: Buffer I/O error on device sr0, logical block 0
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Result: hostbyte=0x00 driverbyte=0x08
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Sense Key : 0x5 [current]
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: ASC=0x21 ASCQ=0x0
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0] CDB:
Jul 25 12:28:54 myhost kernel: cdb[0]=0x28: 28 00 00 00 00 00 00 00 01 00
Jul 25 12:28:54 myhost kernel: end_request: I/O error, dev sr0, sector 0
Jul 25 12:28:54 myhost kernel: Buffer I/O error on device sr0, logical block 0
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Result: hostbyte=0x00 driverbyte=0x08
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Sense Key : 0x5 [current]
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: ASC=0x21 ASCQ=0x0
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0] CDB:
Jul 25 12:28:54 myhost kernel: cdb[0]=0x28: 28 00 00 00 00 00 00 00 01 00
Jul 25 12:28:54 myhost kernel: end_request: I/O error, dev sr0, sector 0
Jul 25 12:28:54 myhost kernel: Buffer I/O error on device sr0, logical block 0
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Result: hostbyte=0x00 driverbyte=0x08
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Sense Key : 0x5 [current]
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: ASC=0x21 ASCQ=0x0
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0] CDB:
Jul 25 12:28:54 myhost kernel: cdb[0]=0x28: 28 00 00 00 00 00 00 00 01 00
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Result: hostbyte=0x00 driverbyte=0x08
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Sense Key : 0x5 [current]
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: ASC=0x21 ASCQ=0x0
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0] CDB:
Jul 25 12:28:54 myhost kernel: cdb[0]=0x28: 28 00 00 00 00 00 00 00 01 00
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Result: hostbyte=0x00 driverbyte=0x08
...
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0] CDB:
Jul 25 12:28:54 myhost kernel: cdb[0]=0x28: 28 00 00 00 00 00 00 00 01 00
Jul 25 12:28:54 myhost kernel: EXT4-fs (sr0): unable to read superblock
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Result: hostbyte=0x00 driverbyte=0x08
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Sense Key : 0x5 [current]
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: ASC=0x21 ASCQ=0x0
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0] CDB:
Jul 25 12:28:54 myhost kernel: cdb[0]=0x28: 28 00 00 00 00 00 00 00 01 00
Jul 25 12:28:54 myhost kernel: EXT4-fs (sr0): unable to read superblock
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Result: hostbyte=0x00 driverbyte=0x08
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: Sense Key : 0x5 [current]
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0]
Jul 25 12:28:54 myhost kernel: ASC=0x21 ASCQ=0x0
Jul 25 12:28:54 myhost kernel: sr 0:0:1:0: [sr0] CDB:
Jul 25 12:28:54 myhost kernel: cdb[0]=0x28: 28 00 00 00 00 00 00 00 01 00
Jul 25 12:28:54 myhost kernel: EXT4-fs (sr0): unable to read superblock
$
The links you posted don't work, because search result expire.
Note: this is a data CD-RW.
# readcd -c2scan
No target specified, trying to find one...
Using dev=0,1,0.
Read speed: 1411 kB/s (CD 8x, DVD 1x, BD 0x).
Write speed: 1764 kB/s (CD 10x, DVD 1x, BD 0x).
Capacity: 1 Blocks = 2 kBytes = 0 MBytes = 0 prMB
Sectorsize: 2048 Bytes
Copy from SCSI (0,1,0) disk to file '/dev/null'
end: 1
readcd: Input/output error. read_cd: scsi sendcmd: no error
CDB: BE 00 00 00 00 00 00 00 01 FA 00 00
status: 0x2 (CHECK CONDITION)
Sense Bytes: 70 00 05 00 00 00 00 0A 00 00 00 00 21 00 00 00
Sense Key: 0x5 Illegal Request, Segment 0
Sense Code: 0x21 Qual 0x00 (logical block address out of range) Fru 0x0
Sense flags: Blk 0 (not valid)
cmd finished after 0.001s timeout 40s
readcd: Input/output error. Cannot read source disk
readcd: Retrying from sector 0.
.
readcd: Input/output error. Error on sector 0 not corrected. Total of 1 errors.
readcd: -noerror set, continuing ...
C2 in sector: 0 first at byte: 97 (0x54) total: 666 errors
addr: 1
Time total: 0.016sec
Read 2.58 kB at 161.5 kB/sec.
Max corected retry count was 0 (limited to 10).
The following 1 sector(s) could not be read correctly:
0
Total of 1 hard read errors.
C2 errors total: 666 bytes in 1 sectors on disk
C2 errors rate: (Infinity)% per byte, (Infinity)% per sector
C2 errors on worst sector: 666, sectors with 100+ C2 errors: 1
#
# readcd -fulltoc
No target specified, trying to find one...
Using dev=0,1,0.
Read speed: 1411 kB/s (CD 8x, DVD 1x, BD 0x).
Write speed: 1764 kB/s (CD 10x, DVD 1x, BD 0x).
readcd: Input/output error. read toc: scsi sendcmd: no error
CDB: 43 00 02 00 00 00 00 00 04 00
status: 0x2 (CHECK CONDITION)
Sense Bytes: 70 00 05 00 00 00 00 0A 00 00 00 00 24 00 00 00
Sense Key: 0x5 Illegal Request, Segment 0
Sense Code: 0x24 Qual 0x00 (invalid field in cdb) Fru 0x0
Sense flags: Blk 0 (not valid)
cmd finished after 0.000s timeout 40s
readcd: Cannot read TOC header
#
# cdrecord -v blank=fast -sao dev=0,1,0 prod_disc.iso
this produced errors.
# cdrecord -v blank=fast -tao dev=0,1,0 prod_disc.iso
this produced errors.
# cdrecord -v blank=fast -raw96r dev=0,1,0 prod_disc.iso
this did not produce errors during recording, but CD-RW unreadable with errors.
# cdrecord -v blank=fast -raw16r dev=0,1,0 prod_disc.iso
this did not produce errors during recording, but CD-RW unreadable with errors.
[Fri Jul 25 16:03:18 2014] ACPI: bus type ATA registered
[Fri Jul 25 16:03:18 2014] libata version 3.00 loaded.
[Fri Jul 25 16:03:19 2014] ata_piix 0000:00:1f.1: version 2.13
[Fri Jul 25 16:03:19 2014] ata_piix 0000:00:1f.1: setting latency timer to 64
[Fri Jul 25 16:03:19 2014] scsi0 : ata_piix
[Fri Jul 25 16:03:19 2014] scsi1 : ata_piix
[Fri Jul 25 16:03:19 2014] ata1: PATA max UDMA/100 cmd 0x1f0 ctl 0x3f6 bmdma 0x2580 irq 14
[Fri Jul 25 16:03:19 2014] ata2: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0x2588 irq 15
[Fri Jul 25 16:03:19 2014] ata2: port disabled--ignoring
[Fri Jul 25 16:03:19 2014] ata1.00: ATA-6: TOSHIBA MK4026GAX, PA103G, max UDMA/100
[Fri Jul 25 16:03:19 2014] ata1.00: 78140160 sectors, multi 16: LBA
[Fri Jul 25 16:03:19 2014] ata1.01: ATAPI: SONY CD-RW/DVD-ROM CRX835E, KPK4, max MWDMA2
[Fri Jul 25 16:03:19 2014] ata1.00: configured for UDMA/100
[Fri Jul 25 16:03:19 2014] ata1.01: configured for MWDMA2
[Fri Jul 25 16:03:19 2014] scsi 0:0:0:0: Direct-Access ATA TOSHIBA MK4026GA PA10 PQ: 0 ANSI: 5
[Fri Jul 25 23:04:09 2014] ata_piix 0000:00:1f.1: setting latency timer to 64
[Fri Jul 25 23:04:09 2014] ata1.01: _GTF evaluation failed (AE 0x1001)
[Fri Jul 25 23:04:09 2014] ata2: port disabled--ignoring
[Fri Jul 25 23:04:10 2014] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out
[Fri Jul 25 23:04:10 2014] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out
[Fri Jul 25 23:04:10 2014] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
[Fri Jul 25 23:04:10 2014] ata1.00: ACPI cmd b1/c1:00:00:00:00:a0 (DEVICE CONFIGURATION OVERLAY) filtered out
[Fri Jul 25 23:04:10 2014] ata1.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded
[Fri Jul 25 23:04:10 2014] ata1.00: configured for UDMA/100
[Fri Jul 25 23:04:10 2014] ata1.01: configured for MWDMA2
[Fri Jul 25 23:04:19 2014] cdrom sd_mod ata_generic pata_acpi ata_piix tifm_7xx1 libata scsi_mod tifm_core firewire_ohci sdhci_pci sdhci mmc_core firewire_core crc_itu_t ehci_pci uhci_hcd ehci_hcd usbcore usb_common
[Sat Jul 26 02:39:05 2014] ata_piix 0000:00:1f.1: setting latency timer to 64
[Sat Jul 26 02:39:05 2014] ata1.01: _GTF evaluation failed (AE 0x1001)
[Sat Jul 26 02:39:05 2014] ata2: port disabled--ignoring
[Sat Jul 26 02:39:06 2014] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out
[Sat Jul 26 02:39:06 2014] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out
[Sat Jul 26 02:39:06 2014] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
[Sat Jul 26 02:39:06 2014] ata1.00: ACPI cmd b1/c1:00:00:00:00:a0 (DEVICE CONFIGURATION OVERLAY) filtered out
[Sat Jul 26 02:39:06 2014] ata1.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded
[Sat Jul 26 02:39:06 2014] ata1.00: configured for UDMA/100
[Sat Jul 26 02:39:06 2014] ata1.01: configured for MWDMA2
$
$ dmesg -T |grep -i scsi
[Fri Jul 25 16:03:18 2014] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[Fri Jul 25 16:03:18 2014] SCSI subsystem initialized
[Fri Jul 25 16:03:19 2014] scsi0 : ata_piix
[Fri Jul 25 16:03:19 2014] scsi1 : ata_piix
[Fri Jul 25 16:03:19 2014] scsi 0:0:0:0: Direct-Access ATA TOSHIBA MK4026GA PA10 PQ: 0 ANSI: 5
[Fri Jul 25 16:03:19 2014] scsi 0:0:1:0: CD-ROM SONY CD-RW CRX835E KPK4 PQ: 0 ANSI: 5
[Fri Jul 25 16:03:19 2014] sr0: scsi3-mmc drive: 0x/24x writer cd/rw xa/form2 cdda tray
[Fri Jul 25 16:03:19 2014] sr 0:0:1:0: Attached scsi CD-ROM sr0
[Fri Jul 25 16:03:19 2014] sd 0:0:0:0: [sda] Attached SCSI disk
[Fri Jul 25 16:03:28 2014] sd 0:0:0:0: Attached scsi generic sg0 type 0
[Fri Jul 25 16:03:28 2014] sr 0:0:1:0: Attached scsi generic sg1 type 5
[Fri Jul 25 23:04:09 2014] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[Fri Jul 25 23:04:19 2014] cdrom sd_mod ata_generic pata_acpi ata_piix tifm_7xx1 libata scsi_mod tifm_core firewire_ohci sdhci_pci sdhci mmc_core firewire_core crc_itu_t ehci_pci uhci_hcd ehci_hcd usbcore usb_common
[Sat Jul 26 02:39:04 2014] sd 0:0:0:0: [sda] Synchronizing SCSI cache
$
Debugging ON:
# ls -al /dev/cdrom
lrwxrwxrwx 1 root root 3 Jul 26 12:45 /dev/cdrom -> sr0
[jb@myhost ~]$ ls -al /dev/sr0
brw-rw----+ 1 root optical 11, 0 Jul 26 12:45 /dev/sr0
# echo 1 > /proc/sys/dev/cdrom/debug
inserting CD-RW
# journalctl -b
Jul 26 12:45:06 myhost kernel: cdrom: entering cdrom_open
Jul 26 12:45:06 myhost kernel: cdrom: Use count for "/dev/sr0" now 1
Jul 26 12:45:06 myhost kernel: cdrom: entering cdrom_open
Jul 26 12:45:06 myhost kernel: cdrom: Use count for "/dev/sr0" now 2
Jul 26 12:45:06 myhost kernel: cdrom: entering CDROM_GET_CAPABILITY
Jul 26 12:45:06 myhost kernel: cdrom: entering CDROM_DRIVE_STATUS
Jul 26 12:45:06 myhost kernel: cdrom: entering CDROM_CLEAR_OPTIONS
Jul 26 12:45:06 myhost kernel: cdrom: Locking door.
Jul 26 12:45:06 myhost kernel: cdrom: entering cdrom_release
Jul 26 12:45:06 myhost kernel: cdrom: entering cdrom_open
Jul 26 12:45:06 myhost kernel: cdrom: Use count for "/dev/sr0" now 2
Jul 26 12:45:06 myhost kernel: cdrom: entering cdrom_release
Jul 26 12:45:06 myhost kernel: cdrom: entering cdrom_release
Jul 26 12:45:06 myhost kernel: cdrom: Use count for "/dev/sr0" now zero
Jul 26 12:45:06 myhost kernel: cdrom: sr0: No DVD+RW
Jul 26 12:45:06 myhost kernel: cdrom: entering cdrom_open
Jul 26 12:45:06 myhost kernel: cdrom: Use count for "/dev/sr0" now 1
Jul 26 12:45:06 myhost kernel: cdrom: entering CDROM_GET_CAPABILITY
Jul 26 12:45:06 myhost kernel: cdrom: entering cdrom_release
Jul 26 12:45:06 myhost kernel: cdrom: Use count for "/dev/sr0" now zero
Jul 26 12:45:06 myhost kernel: cdrom: sr0: No DVD+RW
# cdrecord -v blank=fast dev=0,1,0 test.iso
Jul 26 13:05:04 myhost kernel: cdrom: entering cdrom_open
Jul 26 13:05:04 myhost kernel: cdrom: Use count for "/dev/sr0" now 1
Jul 26 13:05:04 myhost kernel: cdrom: entering cdrom_open
Jul 26 13:05:04 myhost kernel: cdrom: Use count for "/dev/sr0" now 2
Jul 26 13:05:04 myhost kernel: cdrom: entering CDROM_GET_CAPABILITY
Jul 26 13:05:04 myhost kernel: cdrom: entering CDROM_DRIVE_STATUS
Jul 26 13:05:04 myhost kernel: cdrom: entering CDROM_CLEAR_OPTIONS
Jul 26 13:05:04 myhost kernel: cdrom: Locking door.
Jul 26 13:05:04 myhost kernel: cdrom: entering cdrom_release
Jul 26 13:05:04 myhost kernel: cdrom: entering cdrom_open
Jul 26 13:05:04 myhost kernel: cdrom: Use count for "/dev/sr0" now 2
Jul 26 13:05:04 myhost kernel: cdrom: entering cdrom_release
Jul 26 13:05:04 myhost kernel: cdrom: entering cdrom_open
Jul 26 13:05:04 myhost kernel: cdrom: entering open_for_data
Jul 26 13:05:04 myhost kernel: cdrom: drive_status=4
Jul 26 13:05:04 myhost kernel: cdrom: entering cdrom_count_tracks
Jul 26 13:05:04 myhost kernel: cdrom: track 1: format=2, ctrl=4
Jul 26 13:05:04 myhost kernel: cdrom: disc has 1 tracks: 0=audio 1=data 0=Cd-I 0=XA
Jul 26 13:05:04 myhost kernel: cdrom: all seems well, opening the device.
Jul 26 13:05:04 myhost kernel: cdrom: opening the device gave me 0.
Jul 26 13:05:04 myhost kernel: cdrom: device opened successfully.
Jul 26 13:05:04 myhost kernel: cdrom: Use count for "/dev/sr0" now 2
Jul 26 13:05:07 myhost kernel: sr 0:0:1:0: [sr0] Unhandled sense code
Jul 26 13:05:07 myhost kernel: sr 0:0:1:0: [sr0]
Jul 26 13:05:07 myhost kernel: Result: hostbyte=0x00 driverbyte=0x08
Jul 26 13:05:07 myhost kernel: sr 0:0:1:0: [sr0]
Jul 26 13:05:07 myhost kernel: Sense Key : 0x3 [current]
Jul 26 13:05:07 myhost kernel: sr 0:0:1:0: [sr0]
Jul 26 13:05:07 myhost kernel: ASC=0x11 ASCQ=0x5
Jul 26 13:05:07 myhost kernel: sr 0:0:1:0: [sr0] CDB:
Jul 26 13:05:07 myhost kernel: cdb[0]=0x28: 28 00 00 00 00 00 00 00 02 00
Jul 26 13:05:07 myhost kernel: end_request: I/O error, dev sr0, sector 0
Jul 26 13:05:07 myhost kernel: Buffer I/O error on device sr0, logical block 0
...
Jul 26 13:05:37 myhost kernel: cdrom: entering cdrom_release
...
Jul 26 13:05:37 myhost kernel: cdrom: entering cdrom_release
Jul 26 13:05:37 myhost kernel: cdrom: Use count for "/dev/sr0" now zero
Jul 26 13:05:37 myhost kernel: cdrom: sr0: No DVD+RW
Jul 26 13:05:34 myhost systemd-udevd[117]: worker [2879] /devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:1/0:0:1:0/bloc
Jul 26 13:05:34 myhost systemd-udevd[117]: seq 1619 '/devices/pci0000:00/0000:00:1f.1/ata1/host0/target0:0:1/0:0:1:0/block/sr
Jul 26 13:05:37 myhost systemd-udevd[117]: worker [2879] terminated by signal 9 (Killed)
#
Does the above show confusion about multiple "open" and "use count" ? The drive was loud accordingly ...
...
sg 21558 0
sr_mod 13119 0
cdrom 30188 1 sr_mod
sd_mod 28566 3
ata_generic 2466 0
pata_acpi 2431 0
ata_piix 21208 2
libata 151799 3 pata_acpi,ata_generic,ata_piix
scsi_mod 110531 4 sg,libata,sd_mod,sr_mod
$
Btw, I noticed that the mailing lists
http://lists.berlios.de/mailman/listinfo/cdrecord-developers
http://lists.berlios.de/mailman/listinfo/cdrecord-support
and e-mail address
cdrecord-developers@berlios.de
as stated in cdrecord(1) man page are not valid.
Am I missing something or this needs an update ?
https://lists.fedoraproject.org/pipermail/users/2014-July/452350.html
Arch Linux forums keyword searches for "cdrecord" or "wodim" (check off forums below)
Newbie Corner
Kernel & Hardware
Applications & Desktop Environments
will give more similar bug results for the last 2 years.
https://bbs.archlinux.org/search.php