Log of failure -- k3b "debug info" and log of the console where k3b ran.

k3b.log (text/plain), 16.67 KB, created by M. Edward Borasky on 2007-07-22 01:32:53 UTC

I've got k3b 1.0.2. When I try to burn a DVD from an ISO image, it writes the DVD and reports success and ejects the DVD. But then, it can't reload it and the subsequent verify aborts as a result.

This happens on two different machines and burners (one built in and the other USB). I haven't tried it with CDs yet, and I haven't tried it with a non-ISO-image burn.

Here's the debugging output:

System
-----------------------
K3b Version: 1.0.2

KDE Version: 3.5.7
QT Version:  3.3.8
Kernel:      2.6.22-gentoo-r1
Devices
-----------------------
ATAPI DVD LS 8X16X8X16 GCAB (/dev/hdb, ) [CD-R, CD-RW, CD-ROM, DVD-ROM, DVD-R, DVD-RW, DVD-R DL, DVD+R, DVD+RW, DVD+R DL] [DVD-ROM, DVD-R Sequential, DVD-R Dual Layer Sequential, DVD-R Dual Layer Jump, DVD-RW Restricted Overwrite, DVD-RW Sequential, DVD+RW, DVD+R, DVD+R Dual Layer, CD-ROM, CD-R, CD-RW] [SAO, TAO, RAW, SAO/R96R, RAW/R16, RAW/R96R, Restricted Overwrite, Layer Jump]

Burned media
-----------------------
DVD-R Sequential

Used versions
-----------------------
growisofs: 7.0

growisofs
-----------------------
Executing 'builtin_dd if=/dev/fd/0 of=/dev/hdb obs=32k seek=0'
/dev/hdb: engaging DVD-R DAO upon user request...
/dev/hdb: reserving 1416310 blocks
/dev/hdb: "Current Write Speed" is 8.2x1352KBps.
   12058624/2900602880 ( 0.4%) @2.6x, remaining 23:57 RBU 100.0% UBU   4.8%
   29655040/2900602880 ( 1.0%) @3.8x, remaining 16:08 RBU 100.0% UBU  85.7%
   51085312/2900602880 ( 1.8%) @4.6x, remaining 12:05 RBU 100.0% UBU  85.7%
   81166336/2900602880 ( 2.8%) @6.5x, remaining 9:15 RBU 100.0% UBU  85.7%
  113213440/2900602880 ( 3.9%) @6.9x, remaining 8:12 RBU 100.0% UBU  85.7%
  141623296/2900602880 ( 4.9%) @6.2x, remaining 7:28 RBU 100.0% UBU  85.7%
  174587904/2900602880 ( 6.0%) @7.1x, remaining 6:45 RBU 100.0% UBU  85.7%
  203259904/2900602880 ( 7.0%) @6.2x, remaining 6:38 RBU 100.0% UBU  85.7%
  237076480/2900602880 ( 8.2%) @7.3x, remaining 6:10 RBU 100.0% UBU  85.7%
  271384576/2900602880 ( 9.4%) @7.4x, remaining 5:48 RBU 100.0% UBU  85.7%
  306184192/2900602880 (10.6%) @7.5x, remaining 5:38 RBU 100.4% UBU  85.7%
  340787200/2900602880 (11.7%) @7.5x, remaining 5:22 RBU 100.0% UBU  85.7%
  347439104/2900602880 (12.0%) @1.4x, remaining 5:38 RBU 100.0% UBU  85.7%
  383320064/2900602880 (13.2%) @7.8x, remaining 5:28 RBU 100.0% UBU  85.7%
  419627008/2900602880 (14.5%) @7.9x, remaining 5:13 RBU 100.0% UBU  85.7%
  456425472/2900602880 (15.7%) @8.0x, remaining 4:59 RBU 100.0% UBU  85.7%
  488013824/2900602880 (16.8%) @6.8x, remaining 4:56 RBU 100.0% UBU  85.7%
  524976128/2900602880 (18.1%) @8.0x, remaining 4:45 RBU 100.0% UBU  85.7%
  561872896/2900602880 (19.4%) @8.0x, remaining 4:34 RBU 100.0% UBU  85.7%
  598802432/2900602880 (20.6%) @8.0x, remaining 4:29 RBU 100.0% UBU  85.7%
  635076608/2900602880 (21.9%) @7.9x, remaining 4:20 RBU 100.0% UBU  85.7%
  667942912/2900602880 (23.0%) @7.1x, remaining 4:14 RBU 100.0% UBU  66.7%
  704872448/2900602880 (24.3%) @8.0x, remaining 4:09 RBU 100.0% UBU  85.7%
  741769216/2900602880 (25.6%) @8.0x, remaining 4:01 RBU 100.0% UBU  85.7%
  778698752/2900602880 (26.8%) @8.0x, remaining 3:54 RBU 100.0% UBU  85.7%
  808222720/2900602880 (27.9%) @6.4x, remaining 3:52 RBU 100.0% UBU  85.7%
  823918592/2900602880 (28.4%) @3.4x, remaining 3:54 RBU 100.0% UBU  85.7%
  858783744/2900602880 (29.6%) @7.6x, remaining 3:48 RBU 100.0% UBU  85.7%
  895680512/2900602880 (30.9%) @8.0x, remaining 3:43 RBU 100.0% UBU  85.7%
  932577280/2900602880 (32.2%) @8.0x, remaining 3:37 RBU 100.0% UBU  85.7%
  969506816/2900602880 (33.4%) @8.0x, remaining 3:31 RBU 100.0% UBU  85.7%
 1001029632/2900602880 (34.5%) @6.8x, remaining 3:28 RBU 100.0% UBU  85.7%
 1037926400/2900602880 (35.8%) @8.0x, remaining 3:22 RBU 100.0% UBU  85.7%
 1074855936/2900602880 (37.1%) @8.0x, remaining 3:17 RBU 100.0% UBU  85.7%
 1111752704/2900602880 (38.3%) @8.0x, remaining 3:13 RBU 100.0% UBU  85.7%
 1148715008/2900602880 (39.6%) @8.0x, remaining 3:07 RBU 100.0% UBU  85.7%
 1185611776/2900602880 (40.9%) @8.0x, remaining 3:02 RBU 100.0% UBU  85.7%
 1193836544/2900602880 (41.2%) @1.8x, remaining 3:05 RBU 100.0% UBU  81.0%
 1230766080/2900602880 (42.4%) @8.0x, remaining 3:00 RBU 100.0% UBU  85.7%
 1267662848/2900602880 (43.7%) @8.0x, remaining 2:55 RBU 100.0% UBU  85.7%
 1304592384/2900602880 (45.0%) @8.0x, remaining 2:51 RBU 100.0% UBU  85.7%
 1341489152/2900602880 (46.2%) @8.0x, remaining 2:46 RBU 100.0% UBU  85.7%
 1378418688/2900602880 (47.5%) @8.0x, remaining 2:41 RBU 100.0% UBU  85.7%
 1397489664/2900602880 (48.2%) @4.1x, remaining 2:41 RBU 100.0% UBU  85.7%
 1421574144/2900602880 (49.0%) @5.2x, remaining 2:39 RBU 100.0% UBU  85.7%
 1458536448/2900602880 (50.3%) @8.0x, remaining 2:34 RBU 100.0% UBU  85.7%
 1495433216/2900602880 (51.6%) @8.0x, remaining 2:30 RBU 100.0% UBU  85.7%
 1532362752/2900602880 (52.8%) @8.0x, remaining 2:25 RBU 100.0% UBU  85.7%
 1569259520/2900602880 (54.1%) @8.0x, remaining 2:20 RBU 100.0% UBU  85.7%
 1606189056/2900602880 (55.4%) @8.0x, remaining 2:17 RBU 100.0% UBU  85.7%
 1638137856/2900602880 (56.5%) @6.9x, remaining 2:13 RBU 100.0% UBU  23.8%
 1675034624/2900602880 (57.7%) @8.0x, remaining 2:08 RBU 100.0% UBU  85.7%
 1711964160/2900602880 (59.0%) @8.0x, remaining 2:04 RBU 100.0% UBU  85.7%
 1748860928/2900602880 (60.3%) @8.0x, remaining 2:00 RBU 100.0% UBU  85.7%
 1785790464/2900602880 (61.6%) @8.0x, remaining 1:56 RBU 100.0% UBU  85.7%
 1822720000/2900602880 (62.8%) @8.0x, remaining 1:52 RBU 100.0% UBU  85.7%
 1859616768/2900602880 (64.1%) @8.0x, remaining 1:48 RBU 100.0% UBU  85.7%
 1891401728/2900602880 (65.2%) @6.9x, remaining 1:44 RBU 100.0% UBU  81.0%
 1928331264/2900602880 (66.5%) @8.0x, remaining 1:40 RBU 100.0% UBU  85.7%
 1965260800/2900602880 (67.8%) @8.0x, remaining 1:36 RBU 100.0% UBU  85.7%
 2002157568/2900602880 (69.0%) @8.0x, remaining 1:32 RBU 100.0% UBU  85.7%
 2021425152/2900602880 (69.7%) @4.2x, remaining 1:31 RBU 100.0% UBU  85.7%
 2048360448/2900602880 (70.6%) @5.8x, remaining 1:28 RBU 100.0% UBU  85.7%
 2085224448/2900602880 (71.9%) @8.0x, remaining 1:24 RBU 100.0% UBU  85.7%
 2116714496/2900602880 (73.0%) @6.8x, remaining 1:21 RBU 100.0% UBU  85.7%
 2153644032/2900602880 (74.2%) @8.0x, remaining 1:17 RBU 100.0% UBU  85.7%
 2190508032/2900602880 (75.5%) @8.0x, remaining 1:13 RBU 100.0% UBU  85.7%
 2227404800/2900602880 (76.8%) @8.0x, remaining 1:09 RBU 100.0% UBU  85.7%
 2264399872/2900602880 (78.1%) @8.0x, remaining 1:05 RBU 100.0% UBU  85.7%
 2301329408/2900602880 (79.3%) @8.0x, remaining 1:01 RBU 100.0% UBU  85.7%
 2338258944/2900602880 (80.6%) @8.0x, remaining 0:57 RBU 100.0% UBU  85.7%
 2375221248/2900602880 (81.9%) @8.0x, remaining 0:53 RBU 100.0% UBU  85.7%
 2406973440/2900602880 (83.0%) @6.9x, remaining 0:50 RBU 100.0% UBU  85.7%
 2443902976/2900602880 (84.3%) @8.0x, remaining 0:46 RBU 100.0% UBU  85.7%
 2480865280/2900602880 (85.5%) @8.0x, remaining 0:42 RBU 100.0% UBU  85.7%
 2517762048/2900602880 (86.8%) @8.0x, remaining 0:38 RBU 100.0% UBU  85.7%
 2554691584/2900602880 (88.1%) @8.0x, remaining 0:35 RBU 100.0% UBU  85.7%
 2581528576/2900602880 (89.0%) @5.8x, remaining 0:32 RBU 100.0% UBU  85.7%
 2598535168/2900602880 (89.6%) @3.7x, remaining 0:30 RBU 100.0% UBU  85.7%
 2635497472/2900602880 (90.9%) @8.0x, remaining 0:27 RBU 100.0% UBU  85.7%
 2672394240/2900602880 (92.1%) @8.0x, remaining 0:23 RBU 100.0% UBU  85.7%
 2704474112/2900602880 (93.2%) @7.0x, remaining 0:20 RBU 100.0% UBU  85.7%
 2741370880/2900602880 (94.5%) @8.0x, remaining 0:16 RBU 100.0% UBU  85.7%
 2778333184/2900602880 (95.8%) @8.0x, remaining 0:12 RBU 100.0% UBU  85.7%
 2815262720/2900602880 (97.1%) @8.0x, remaining 0:08 RBU 100.0% UBU  85.7%
 2852159488/2900602880 (98.3%) @8.0x, remaining 0:04 RBU 100.0% UBU  85.7%
 2889121792/2900602880 (99.6%) @8.0x, remaining 0:01 RBU 100.0% UBU  85.7%
/dev/hdb: flushing cache

growisofs command:
-----------------------
/usr/bin/growisofs -Z /dev/hdb=/dev/fd/0 -use-the-force-luke=notray -use-the-force-luke=tty -use-the-force-luke=tracksize:1416310 -use-the-force-luke=dao:1416310 -dvd-compat -speed=8 -use-the-force-luke=bufsize:32m 




By the way, it appears to be leaving the device in some kind of bizarre state. 

Here's the console log -- the last chunk after the errors happened when I manually reloaded the DVD.


$ kdecore (KAction): WARNING: KActionCollection::KActionCollection( QObject *parent, const char *name, KInstance *instance )
/dev/hdb resolved to /dev/hdb
/dev/hdb is block device (64)
/dev/hdb seems to be cdrom
(K3bDevice::Device) /dev/hdb: init()
(K3bDevice::Device) /dev/hdb feature: CD Mastering
(K3bDevice::Device) /dev/hdb feature: CD Track At Once
(K3bDevice::Device) /dev/hdb feature: DVD Read (MMC5)
(K3bDevice::Device) /dev/hdb feature: DVD+R
(K3bDevice::Device) /dev/hdb feature: DVD+RW
(K3bDevice::Device) /dev/hdb feature: DVD+R Double Layer
(K3bDevice::Device) /dev/hdb feature: DVD-R/-RW Write
(K3bDevice::Device) /dev/hdb feature: Rigid Restricted Overwrite
(K3bDevice::Device) /dev/hdb feature: Layer Jump Recording
(K3bDevice::Device) /dev/hdb unknown profile: -1
(K3bDevice::Device) /dev/hdb: dataLen: 60
(K3bDevice::Device) /dev/hdb: checking for TAO
(K3bDevice::Device) /dev/hdb: checking for SAO
(K3bDevice::Device) /dev/hdb: checking for SAO_R96P
(K3bDevice::ScsiCommand) failed: 
                           command:    MODE SELECT (55)
                           errorcode:  70
                           sense key:  ILLEGAL REQUEST (5)
                           asc:        26
                           ascq:       0
(K3bDevice::Device) /dev/hdb: checking for SAO_R96R
(K3bDevice::Device) /dev/hdb: checking for RAW_R16
(K3bDevice::Device) /dev/hdb: checking for RAW_R96P
(K3bDevice::ScsiCommand) failed: 
                           command:    MODE SELECT (55)
                           errorcode:  70
                           sense key:  ILLEGAL REQUEST (5)
                           asc:        26
                           ascq:       0
(K3bDevice::Device) /dev/hdb: checking for RAW_R96R
(K3bDevice::Device) /dev/hdb:  Number of supported write speeds via GET PERFORMANCE: 3
(K3bDevice::Device) /dev/hdb : 11080 KB/s
(K3bDevice::Device) /dev/hdb : 5540 KB/s
(K3bDevice::Device) /dev/hdb : 3324 KB/s
(K3bDevice::DeviceManager) setting current write speed of device /dev/hdb to 11080
(K3bDevice::DeviceManager) SCANNING FOR GENERIC DEVICES.
(K3bDevice::Device) could not open device /dev/sg0 for reading
                    (Permission denied)
Could not resolve /dev/sg1
(K3bDevice::Device) could not open device /dev/sg1 for reading
                    (No such file or directory)
Could not resolve /dev/sg2
(K3bDevice::Device) could not open device /dev/sg2 for reading
                    (No such file or directory)
Could not resolve /dev/sg3
(K3bDevice::Device) could not open device /dev/sg3 for reading
                    (No such file or directory)
Could not resolve /dev/sg4
(K3bDevice::Device) could not open device /dev/sg4 for reading
                    (No such file or directory)
Could not resolve /dev/sg5
(K3bDevice::Device) could not open device /dev/sg5 for reading
                    (No such file or directory)
Could not resolve /dev/sg6
(K3bDevice::Device) could not open device /dev/sg6 for reading
                    (No such file or directory)
Could not resolve /dev/sg7
(K3bDevice::Device) could not open device /dev/sg7 for reading
                    (No such file or directory)
Could not resolve /dev/sg8
(K3bDevice::Device) could not open device /dev/sg8 for reading
                    (No such file or directory)
Could not resolve /dev/sg9
(K3bDevice::Device) could not open device /dev/sg9 for reading
                    (No such file or directory)
Could not resolve /dev/sg10
(K3bDevice::Device) could not open device /dev/sg10 for reading
                    (No such file or directory)
Could not resolve /dev/sg11
(K3bDevice::Device) could not open device /dev/sg11 for reading
                    (No such file or directory)
Could not resolve /dev/sg12
(K3bDevice::Device) could not open device /dev/sg12 for reading
                    (No such file or directory)
Could not resolve /dev/sg13
(K3bDevice::Device) could not open device /dev/sg13 for reading
                    (No such file or directory)
Could not resolve /dev/sg14
(K3bDevice::Device) could not open device /dev/sg14 for reading
                    (No such file or directory)
Could not resolve /dev/sg15
(K3bDevice::Device) could not open device /dev/sg15 for reading
                    (No such file or directory)
/dev/hdb resolved to /dev/hdb
(K3bDevice::DeviceManager) dev /dev/hdb already found
(K3bDevice::DeviceManager) found config entry for devicetype: ATAPI DVD LS 8X16X8X16
First sec data area: 43:41:33 (LBA 196608) (402653184
Last sec data area: 00:00:00 (LBA 0) (0 Bytes)
Last sec layer 1: 00:00:00 (LBA 0) (0 Bytes)
Layer 1 length: 00:00:01 (LBA 1) (2048 Bytes)
Layer 2 length: 00:00:00 (LBA 0) (0 Bytes)
DiskInfo:
Mediatype:       DVD-R Sequential
Current Profile: DVD-R Sequential
Disk state:      empty
Empty:           1
Rewritable:      0
Appendable:      0
Sessions:        0
Tracks:          0
Layers:          1
Capacity:        510:38:38 (LBA 2297888) (4706074624 Bytes)
Remaining size:  510:38:38 (LBA 2297888) (4706074624 Bytes)
Used Size:       00:00:00 (LBA 0) (0 Bytes)
(K3bDevice::Device) /dev/hdb:  Number of supported write speeds via GET PERFORMANCE: 3
(K3bDevice::Device) /dev/hdb : 11080 KB/s
(K3bDevice::Device) /dev/hdb : 5540 KB/s
(K3bDevice::Device) /dev/hdb : 3324 KB/s
Devices:
------------------------------
Blockdevice:    /dev/hdb
Generic device: 
Vendor:         ATAPI
Description:    DVD LS 8X16X8X16
Version:        GCAB
Write speed:    11080
Profiles:       DVD-ROM, DVD-R Sequential, DVD-R Dual Layer Sequential, DVD-R Dual Layer Jump, DVD-RW Restricted Overwrite, DVD-RW Sequential, DVD+RW, DVD+R, DVD+R Dual Layer, CD-ROM, CD-R, CD-RW
Read Cap:       DVD-ROM, DVD-R, DVD-R Sequential, DVD-R Dual Layer, DVD-R Dual Layer Sequential, DVD-R Dual Layer Jump, DVD-RW, DVD-RW Restricted Overwrite, DVD-RW Sequential, DVD+RW, DVD+R, DVD+RW Dual Layer, DVD+R Dual Layer, CD-ROM, CD-R, CD-RW
Write Cap:      DVD-R, DVD-R Sequential, DVD-R Dual Layer, DVD-R Dual Layer Sequential, DVD-R Dual Layer Jump, DVD-RW, DVD-RW Restricted Overwrite, DVD-RW Sequential, DVD+RW, DVD+R, DVD+R Dual Layer, CD-R, CD-RW
Writing modes:  SAO, TAO, RAW, SAO/R96R, RAW/R16, RAW/R96R, Restricted Overwrite, Layer Jump
Reader aliases: /dev/hdb
------------------------------
kdecore (KAction): WARNING: KActionCollection::operator+=(): function is severely deprecated.
k3b: WARNING: Pixmap not found for mimetype application/x-core
QGDict::hashKeyString: Invalid null key
QGDict::hashKeyString: Invalid null key
k3b: ERROR: : couldn't create slave : Unable to create io-slave:
klauncher said: Unknown protocol ''.
k3b: 
(K3bDevice::Device) /dev/hdb: GET CONFIGURATION length det failed.
(K3bDevice::Device) /dev/hdb: GET CONFIGURATION length det failed.
(K3bDevice::Device) /dev/hdb: GET CONFIGURATION length det failed.
(K3bDevice::Device) /dev/hdb: GET CONFIGURATION length det failed.
(K3bDevice::Device) /dev/hdb:  Number of supported write speeds via GET PERFORMANCE: 3
(K3bDevice::Device) /dev/hdb : 11080 KB/s
(K3bDevice::Device) /dev/hdb : 5540 KB/s
(K3bDevice::Device) /dev/hdb : 3324 KB/s
(K3bDevice::Device) /dev/hdb current profile 0. Checking current profile list instead.
(K3bDevice::Device) /dev/hdb: GET CONFIGURATION length det failed.
First sec data area: 43:41:33 (LBA 196608) (402653184
Last sec data area: 358:25:52 (LBA 1612927) (3303274496 Bytes)
Last sec layer 1: 00:00:00 (LBA 0) (0 Bytes)
Layer 1 length: 00:00:01 (LBA 1) (2048 Bytes)
Layer 2 length: 358:25:52 (LBA 1612927) (3303274496 Bytes)
(K3bDevice::ScsiCommand) failed: 
                           command:    READ TRACK INFORMATION (52)
                           errorcode:  70
                           sense key:  ILLEGAL REQUEST (5)
                           asc:        24
                           ascq:       0
(K3bDevice::Device) /dev/hdb: READ TRACK INFORMATION length det failed.
(K3bDevice::ScsiCommand) failed: 
                           command:    READ TRACK INFORMATION (52)
                           errorcode:  70
                           sense key:  ILLEGAL REQUEST (5)
                           asc:        24
                           ascq:       0
(K3bDevice::Device) /dev/hdb: READ TRACK INFORMATION with real length 36 failed.
DiskInfo:
Mediatype:       DVD-R Sequential
Current Profile: DVD-R Sequential
Disk state:      complete
Empty:           0
Rewritable:      0
Appendable:      0
Sessions:        1
Tracks:          1
Layers:          1
Capacity:        314:44:20 (LBA 1416320) (2900623360 Bytes)
Remaining size:  00:00:00 (LBA 0) (0 Bytes)
Used Size:       314:44:20 (LBA 1416320) (2900623360 Bytes)
(K3bDevice::Device) /dev/hdb:  Number of supported write speeds via GET PERFORMANCE: 3
(K3bDevice::Device) /dev/hdb : 11080 KB/s
(K3bDevice::Device) /dev/hdb : 5540 KB/s
(K3bDevice::Device) /dev/hdb : 3324 KB/s