Skip site navigation (1)Skip section navigation (2)
Date:      Sat, 20 Jun 1998 15:17:09 -0700
From:      brian@worldcontrol.com
To:        freebsd-questions@FreeBSD.ORG
Subject:   cdrecord 1.6 failure writing disk (additional info)
Message-ID:  <19980620151709.A835@top.worldcontrol.com>

next in thread | raw e-mail | index | archive | help
[included below in this email is additional info from /var/log/messages]

At 416MB out of 442MB this burn operation failed.  Is there a reason
why hidden in this output?

The system is a SMP 2xPP150 with 64MB.  The filesystem drives are
on an NCR 825 based SCSI controller, and the Ricoh CD-RW is the
only device on an Adaptec 2940.

The cdrecord command was

nohup rtprio 16 cdrecord -v speed=2 dev=0,1,0 cdimage.raw

cdrecord: Function not implemented. WARNING: Cannot set RR-scheduler
Cdrecord release 1.6 Copyright (C) 1995-1998 Jörg Schilling
TOC Type: 1 = CD-ROM
scsidev: '0,1,0'
scsibus: 0 target: 1 lun: 0
Device type    : Removable CD-ROM
Version        : 2
Response Format: 2
Capabilities   : 
Vendor_info    : 'RICOH   '
Identifikation : 'MP6200S         '
Revision       : '2.20'
Device seems to be: Generic mmc CD-RW.
Using generic SCSI-3/mmc CD-R driver (mmc_cdr).
Driver flags   : SWABAUDIO
cdrecord: Function not implemented. WARNING: Cannot set RR-scheduler
Track 01: data  442 MB        
Total size:     508 MB (50:22.68) = 226701 sectors
Lout start:     508 MB (50:24/51) = 226701 sectors
ATIP info from disk:
  Indicated writing power: 6
  Is not unrestricted
  Is not erasable
  ATIP start of lead in:  -11597 (97:27/28)
  ATIP start of lead out: 336601 (74:50/01)
Disk type: Phthalocyanine or similar
Manufacturer: Princo Corporation
Blocks total: 336601 Blocks remaining: 336601
Starting to write CD at speed 2 in write mode for single session.
Last chance to quit, starting real write in 1 seconds.
Waiting for reader process to fill input-buffer ... input-buffer ready.
Starting new track at sector: 0
Track 01:   0 of 442 MB written.
Track 01:   1 of 442 MB written (fifo 100%).
...
Track 01: 416 of 442 MB written (fifo 100%).
cdrecord: Undefined error: 0. write_g1: scsi sendcmd: cmd timeout after 42.033 (40) s
CDB:  2A 00 00 03 40 26 00 00 1E 00
resid: 61440
cmd finished after 42.033s timeout 40s
cdrecord: Undefined error: 0. request_sense: scsi sendcmd: cmd timeout after 42.036 (40) s

write track data: error after 436285440 bytes
CDB:  03 00 00 00 12 00
resid: 18
cmd finished after 42.036s timeout 40s
cdrecord: Undefined error: 0. flush cache: scsi sendcmd: retryable error
status: 0x2 (CHECK CONDITION)
CDB:  35 00 00 00 00 00 00 00 00 00
Sense Bytes: 70 00 06 00 00 00 00 0A 00 00 00 00 29 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Sense Key: 0x6 Unit Attention, Segment 0
Sense Code: 0x29 Qual 0x00 (power on, reset, or bus device reset occurred) Fru 0x0
Sense flags: Blk 0 (not valid) 
cmd finished after 0.012s timeout 120s
Trouble flushing the cache
Writing  time: 1514.012s
Fixating...
cdrecord: Undefined error: 0. close track/session: scsi sendcmd: retryable error
status: 0x2 (CHECK CONDITION)
CDB:  5B 00 02 00 00 00 00 00 00 00
Sense Bytes: 70 00 05 00 00 00 00 0A 00 00 00 00 71 04 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Sense Key: 0x5 Illegal Request, Segment 0
Sense Code: 0x71 Qual 0x04 (decompression exception long algorithm id) [No matching qualifier] Fru 0x0
Sense flags: Blk 0 (not valid) 
cmd finished after 0.008s timeout 480s
cdrecord: fifo had 7169 puts and 7102 gets.
cdrecord: fifo was 0 times empty and 7088 times full, min fill was 89%.
Fixating time:    0.011s

/var/log/messages:

Jun 20 13:26:01 bls2 /kernel: cmd cdrecord pid 245 tried to use non-present sche
d_get_priority_max
Jun 20 13:26:01 bls2 /kernel: cmd cdrecord pid 245 tried to use non-present sche
d_setscheduler
Jun 20 13:26:07 bls2 /kernel: cmd cdrecord pid 247 tried to use non-present sche
d_get_priority_max
Jun 20 13:26:07 bls2 /kernel: cmd cdrecord pid 247 tried to use non-present sche
d_setscheduler
Jun 20 13:50:40 bls2 cd0: SCB 0x0 - timed out in dataout phase, SCSISIGI == 0xe6
Jun 20 13:50:40 bls2 /kernel: SEQADDR = 0x130 SCSISEQ = 0x12 SSTAT0 = 0x2 SSTAT1
 = 0x13
Jun 20 13:50:40 bls2 cd0: abort message in message buffer
Jun 20 13:50:42 bls2 cd0: SCB 0x0 - timed out in dataout phase, SCSISIGI == 0xf6
Jun 20 13:50:42 bls2 /kernel: SEQADDR = 0x130 SCSISEQ = 0x12 SSTAT0 = 0x2 SSTAT1
 = 0x13
Jun 20 13:50:42 bls2 cd0: no longer in timeout
Jun 20 13:50:42 bls2 ahc0: Issued Channel A Bus Reset. 1 SCBs aborted
Jun 20 13:51:27 bls2 cd0: SCB 0x0 - timed out while idle, LASTPHASE == 0x1, SCSI
SIGI == 0x0
Jun 20 13:51:27 bls2 /kernel: SEQADDR = 0x166 SCSISEQ = 0x12 SSTAT0 = 0x2 SSTAT1
 = 0x0
Jun 20 13:51:27 bls2 cd0: Queueing an Abort SCB
Jun 20 13:51:29 bls2 cd0: SCB 0x0 - timed out while idle, LASTPHASE == 0x1, SCSI
Jun 20 13:51:29 bls2 /kernel: SEQADDR = 0x166 SCSISEQ = 0x12 SSTAT0 = 0x2 SSTAT1
 = 0x0
Jun 20 13:51:29 bls2 cd0: no longer in timeout
Jun 20 13:51:29 bls2 ahc0: Issued Channel A Bus Reset. 1 SCBs aborted
Jun 20 13:51:29 bls2 ahc0:A:1: refuses WIDE negotiation.  Using 8bit transfers
Jun 20 13:52:28 bls2 cd0: BLANK CHECK asc:64,0 Illegal mode for this track
Jun 20 13:52:35 bls2 cd0: BLANK CHECK asc:64,0 Illegal mode for this track

Thanks for your help,
--

Brian Litzinger <brian@litzinger.com>

To Unsubscribe: send mail to majordomo@FreeBSD.org
with "unsubscribe freebsd-questions" in the body of the message



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?19980620151709.A835>