Bug 481346

Summary: udev prevents growisofs working
Product: [Fedora] Fedora Reporter: Mario Mikocevic <mario.mikocevic>
Component: udevAssignee: Harald Hoyer <harald>
Status: CLOSED ERRATA QA Contact: Fedora Extras Quality Assurance <extras-qa>
Severity: high Docs Contact:
Priority: low    
Version: 12CC: harald, sanjay.ankur, wally
Target Milestone: ---   
Target Release: ---   
Hardware: i386   
OS: Linux   
Whiteboard:
Fixed In Version: udev-145-20.fc12 Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of:
: 582559 (view as bug list) Environment:
Last Closed: 2010-04-23 22:58:32 UTC Type: ---
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:

Description Mario Mikocevic 2009-01-23 16:48:43 UTC
Description of problem:

growisofs hangs after ->

# growisofs -dvd-compat -speed=8 -Z /dev/scd1 -quiet -joliet -joliet-long -rational-rock -volid "Archive" -preparer Mozz -publisher Mozz .

growisofs -v driveropts=burnfree speed=8 -dao -fs 10485760  -data -tsize 2174252x2048
Insert blank media, starting recording in 2 seconds...
Executing 'mkisofs -quiet -joliet -joliet-long -rational-rock -volid Archive -preparer Mozz -publisher Mozz . | builtin_dd of=/dev/scd1 obs=32k seek=0'
/dev/scd1: "Current Write Speed" is 8.2x1352KBps.
**HANG**

dvdburner at that moment goes green and stops burning,
dvd media is unseable, there is short track burned

Version-Release number of selected component (if applicable):
dvd+rw-tools-7.1-2.fc10.i386
udev-127-3.fc10.i386

How reproducible:
always

Additional info:
it *STILL* *WORKS* with old version of udev -
udev-120-5.20080421git.fc9.i386
but does *NOT* yet work with the latest -
udev-135-3.fc11.i386

Comment 1 Harald Hoyer 2009-01-23 18:28:18 UTC
what about udev-136-2 from rawhide?

Comment 2 Harald Hoyer 2009-01-23 18:29:56 UTC
and does it work if you:

Put in the empty DVD, wait until udev/hal/gnome recognized the media and then run growisofs?

Comment 3 Walter Francis 2009-09-24 00:58:49 UTC
Same here, Fedora 11 64 bit.  growisofs, wodim, etc, all hang as soon as udev appears to launch vol_id while burning and boom, burn freezes, disk is coastered.

udev-141-4.fc11.x86_64

During a hang, I did lsof and dicovered vol_id was accessing /dev/sr0..

cdrecord  3519     wally    3u      BLK               11,0        0t0        763 /dev/sr0
cdrecord  3521     wally    3u      BLK               11,0        0t0        763 /dev/sr0
vol_id    3555    nobody    3r      BLK               11,0        0t0        763 /dev/sr0

So then I figured out that killing udevd stopped my problem, so I turned on debug mode..

fork /sys/devices/pci0000:00/0000:00:0e.0/host0/target0:0:0/0:0:0:0 (1361)
exit /sys/devices/pci0000:00/0000:00:0e.0/host0/target0:0:0/0:0:0:0 (1361)
fork /sys/devices/pci0000:00/0000:00:0e.0/host0/target0:0:0/0:0:0:0/block/sr0 (1362)
exit /sys/devices/pci0000:00/0000:00:0e.0/host0/target0:0:0/0:0:0:0/block/sr0 (1362)
# Before it actually started burning, I hit enter to get some space..  after it failed, I see this..

fork /sys/devices/pci0000:00/0000:00:0e.0/host0/target0:0:0/0:0:0:0 (1363)
exit /sys/devices/pci0000:00/0000:00:0e.0/host0/target0:0:0/0:0:0:0 (1363)
fork /sys/devices/pci0000:00/0000:00:0e.0/host0/target0:0:0/0:0:0:0/block/sr0 (1364)

And here's ps auxwf output..

root      3506  0.0  0.0  10724  1468 pts/0    S<+  19:37   0:00              |       \_ u
devd --debug-trace
root      3547  0.0  0.0  10724   884 pts/0    S<+  19:37   0:00              |           
\_ udevd --debug-trace
nobody    3555  0.0  0.0   8096   648 pts/0    D<+  19:37   0:00              |           
    \_ /lib/udev/vol_id --export --skip-raid --offset= /dev/block/11:0

Comment 4 Harald Hoyer 2009-11-03 12:45:15 UTC
Ok, it seems to open the device writable and closes it in between somehow.
The close() causes the udev change event, which triggers the vol_id to be run.

Comment 5 Harald Hoyer 2009-11-03 12:48:19 UTC
One way to fix this would be to remove the close() from these tools.
The other one would be to add O_EXCL to the vol_id open().

Comment 6 Walter Francis 2009-11-03 13:06:53 UTC
I've found it happens in wodim, growisofs, brasero, etc..  All fail about the same in this regard, it writes perhaps 5-15M of data (I assume some buffer) and then it stalls.  I don't know why it doesn't happen on every system, however, as I have a similar system at work and I don't have any problems burning except for occationally the device is locked and in use, but at least in that case I'm not making coasters.

Comment 7 Ankur Sinha (FranciscoD) 2009-11-03 13:29:21 UTC
hi,

Just a query; Would this bug cause this? 

output from dmesg:

"usb 1-1: new high speed USB device using ehci_hcd and address 8
usb 1-1: New USB device found, idVendor=05e3, idProduct=0701
usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
usb 1-1: Product: USB TO IDE
usb 1-1: configuration #1 chosen from 1 choice
scsi10 : SCSI emulation for USB Mass Storage devices
usb-storage: device found at 8
usb-storage: waiting for device to settle before scanning
usb-storage: device scan complete
scsi 10:0:0:0: CD-ROM            SONY     DVD+-RW DW-D56A  PDS7 PQ: 0 ANSI: 0
sr1: scsi3-mmc drive: 62x/24x writer cd/rw xa/form2 cdda tray
sr 10:0:0:0: Attached scsi CD-ROM sr1
sr 10:0:0:0: Attached scsi generic sg3 type 5
sr 10:0:0:0: [sr1] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
sr 10:0:0:0: [sr1] Sense Key : Illegal Request [current] 
sr 10:0:0:0: [sr1] Add. Sense: Logical block address out of range
end_request: I/O error, dev sr1, sector 0
Buffer I/O error on device sr1, logical block 0
sr 10:0:0:0: [sr1] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE
sr 10:0:0:0: [sr1] Sense Key : Illegal Request [current] 
sr 10:0:0:0: [sr1] Add. Sense: Logical block address out of range
end_request: I/O error, dev sr1, sector 0
Buffer I/O error on device sr1, logical block 0
sr 10:0:0:0: Device offlined - not ready after error recovery
sr 10:0:0:0: rejecting I/O to offline device
sr 10:0:0:0: rejecting I/O to offline device
sr 10:0:0:0: rejecting I/O to offline device"

I'm having trouble burning media too. However, my problem persists for a USB dvd drive. I'm still burning coasters. 20 of them compared to 12 dvds :(
Tried wodim, brasero, k3b.

regards,
Ankur

Comment 8 Harald Hoyer 2009-11-03 13:39:49 UTC
well.. try it.. 

# killall udevd

and try to burn..

Comment 9 Ankur Sinha (FranciscoD) 2009-11-03 17:01:50 UTC
(In reply to comment #8)
> well.. try it.. 
> 
> # killall udevd
> 
> and try to burn..  

hi,

I did the test. It's still offlining my USB drive, so udev isnt causing it. 

regards,

Ankur

Comment 10 Walter Francis 2009-11-04 12:43:29 UTC
Ankur,

I'm afraid you probably need to log a separate bug, your issue seems different than this one and we wouldn't want bug creep.

As for the originally logged problem, yes, it does go away when udevd is killed on the system.  I just did several dummy burns in wodim then a real burn using growisofs and all worked fine, where otherwise (even wodim -dummy) would fail at the first 5-10M of data.

Comment 11 Bug Zapper 2009-11-18 09:46:24 UTC
This message is a reminder that Fedora 10 is nearing its end of life.
Approximately 30 (thirty) days from now Fedora will stop maintaining
and issuing updates for Fedora 10.  It is Fedora's policy to close all
bug reports from releases that are no longer maintained.  At that time
this bug will be closed as WONTFIX if it remains open with a Fedora 
'version' of '10'.

Package Maintainer: If you wish for this bug to remain open because you
plan to fix it in a currently maintained version, simply change the 'version' 
to a later Fedora version prior to Fedora 10's end of life.

Bug Reporter: Thank you for reporting this issue and we are sorry that 
we may not be able to fix it before Fedora 10 is end of life.  If you 
would still like to see this bug fixed and are able to reproduce it 
against a later version of Fedora please change the 'version' of this 
bug to the applicable version.  If you are unable to change the version, 
please add a comment here and someone will do it for you.

Although we aim to fix as many bugs as possible during every release's 
lifetime, sometimes those efforts are overtaken by events.  Often a 
more recent Fedora release includes newer upstream software that fixes 
bugs or makes them obsolete.

The process we are following is described here: 
http://fedoraproject.org/wiki/BugZappers/HouseKeeping

Comment 12 Walter Francis 2009-11-18 12:57:25 UTC
This is clearly a Fedora 11 issue as well, please update the version appropriately.  I can't say yet if this is an F12 issue as well, as I have not yet upgraded my system affected and do not plan to in the immediate future.

Comment 13 Walter Francis 2009-11-24 04:16:43 UTC
(In reply to comment #8)
> well.. try it.. 
> 
> # killall udevd
> 
> and try to burn..  

BTW, this does workaround it..

Comment 14 Harald Hoyer 2009-11-24 14:37:52 UTC
can someone not kill udevd and run instead in a terminal

# udevadm monitor

Comment 15 Harald Hoyer 2009-11-24 14:38:39 UTC
(In reply to comment #14)
> can someone not kill udevd and run instead in a terminal
> 
> # udevadm monitor  

and burn a coaster..

and paste the output here in this bug :)

Comment 16 Walter Francis 2009-12-24 01:35:05 UTC
Sorry I didn't notice this bug needed more info, here it is..  I have inserted a DVD, and start up cdrecord..

cdrecord dev=/dev/cdrw speed=24 driveropts=burnproof -v -dummy CD-FullImages.iso

# udevadm monitor
monitor will print the received events for:
UDEV - the event which udev sends out after rule processing
KERNEL - the kernel uevent

# here it's sitting at 0 for about 10s, always does this regardless..

KERNEL[1261618355.217927] change   /devices/pci0000:00/0000:00:0e.0/host0/target0:0:0/0:0:0:0 (scsi)
KERNEL[1261618355.221408] change   /devices/pci0000:00/0000:00:0e.0/host0/target0:0:0/0:0:0:0/block/sr0 (block)
UDEV  [1261618355.223366] change   /devices/pci0000:00/0000:00:0e.0/host0/target0:0:0/0:0:0:0 (scsi)
UDEV  [1261618355.266674] change   /devices/pci0000:00/0000:00:0e.0/host0/target0:0:0/0:0:0:0/block/sr0 (block)

# burning has started, is burning..

KERNEL[1261618378.028739] change   /devices/pci0000:00/0000:00:0e.0/host0/target0:0:0/0:0:0:0 (scsi)
KERNEL[1261618378.029386] change   /devices/pci0000:00/0000:00:0e.0/host0/target0:0:0/0:0:0:0/block/sr0 (block)
UDEV  [1261618378.029575] change   /devices/pci0000:00/0000:00:0e.0/host0/target0:0:0/0:0:0:0 (scsi)

# now it has stalled at 6% and is dead..

Comment 17 Mario Mikocevic 2010-04-02 12:46:26 UTC
# rpm -qa | grep udev 
udev-145-15.fc12.i686
libgudev1-145-15.fc12.i686
libudev-145-15.fc12.i686

Still makes coasters, thankfuly with kill-dash-nine works afterwards.

Comment 18 Harald Hoyer 2010-04-06 09:24:12 UTC
blkid still has to be patched to use open(2) with O_EXCL

Comment 19 Fedora Update System 2010-04-13 16:41:02 UTC
udev-145-20.fc12 has been submitted as an update for Fedora 12.
http://admin.fedoraproject.org/updates/udev-145-20.fc12

Comment 20 Mario Mikocevic 2010-04-14 14:03:02 UTC
update udev-145-20 finally works

thank you

.. can be closed ..

Comment 21 Fedora Update System 2010-04-15 03:16:16 UTC
udev-145-20.fc12 has been pushed to the Fedora 12 testing repository.  If problems still persist, please make note of it in this bug report.
 If you want to test the update, you can install it with 
 su -c 'yum --enablerepo=updates-testing update udev'.  You can provide feedback for this update here: http://admin.fedoraproject.org/updates/udev-145-20.fc12

Comment 22 Fedora Update System 2010-04-23 22:58:07 UTC
udev-145-20.fc12 has been pushed to the Fedora 12 stable repository.  If problems still persist, please make note of it in this bug report.