Attachment 307349 Details for Bug 424801 – /var/log/messages, failing to mount a dvd

/var/log/messages, failing to mount a dvd

hal.txt (text/plain), 16.19 KB, created by Mike Pope on 2008-06-02 11:50:46 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Mike Pope

Created: 2008-06-02 11:50:46 UTC

Size: 16.19 KB

patch

obsolete

>Jun  2 21:10:56 malbec orage: polling /dev/sr0 (every 2 sec): [18637]: 21:10:56.129 [D] addon-storage.c:542: Media insertion detected on /dev/sr0
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.130 [D] hald_dbus.c:1652: udi=/org/freedesktop/Hal/devices/storage_model_DVDRAM_GSA_H62N, key=storage.removable.media_available
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.132 [I] hald_dbus.c:3052: entering, local_interface=1
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.133 [D] hald_dbus.c:3061: udi=/org/freedesktop/Hal/devices/storage_model_DVDRAM_GSA_H62N
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.133 [I] blockdev.c:1666: blockdev_rescan_device: udi=/org/freedesktop/Hal/devices/storage_model_DVDRAM_GSA_H62N
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.135 [I] osspec.c:241: SEQNUM=1477,ACTION=change, SUBSYSTEM=scsi, DEVPATH=/sys/devices/pci0000:00/0000:00:0e.0/host1/target1:0:0/1:0:0:0, DEVNAME=, IFINDEX=0
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.135 [I] hotplug.c:114: /sys/devices/pci0000:00/0000:00:0e.0/host1/target1:0:0/1:0:0:0 is a device (store)
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.135 [I] device.c:4522: refresh_dev: subsys=scsi
>Jun  2 21:10:56 malbec hald-probe-storage: [18736]: 21:10:56.140 [D] probe-storage.c:155: Doing probe-storage for /dev/sr0 (bus scsi) (drive_type cdrom) (udi=/org/freedesktop/Hal/devices/storage_model_DVDRAM_GSA_H62N) (--only-check-for-fs==1)
>Jun  2 21:10:56 malbec hald-probe-storage: [18736]: 21:10:56.141 [D] probe-storage.c:163: Doing open ("/dev/sr0", O_RDONLY | O_NONBLOCK)
>Jun  2 21:10:56 malbec hald-probe-storage: [18736]: 21:10:56.142 [D] probe-storage.c:169: Returned from open(2)
>Jun  2 21:10:56 malbec hald-probe-storage: [18736]: 21:10:56.147 [D] probe-storage.c:310: Checking for optical disc on /dev/sr0
>Jun  2 21:10:56 malbec hald-probe-storage: [18736]: 21:10:56.148 [D] probe-storage.c:318: Doing open ("/dev/sr0", O_RDONLY | O_NONBLOCK | O_EXCL)
>Jun  2 21:10:56 malbec hald-probe-storage: [18736]: 21:10:56.149 [D] probe-storage.c:348: PROBE HAS EXCLUSIVE LOCK ON CDROM
>Jun  2 21:10:56 malbec hald-probe-storage: [18736]: 21:10:56.153 [D] probe-storage.c:390: media size = 4700372992
>Jun  2 21:10:56 malbec hald-probe-storage: [18736]: 21:10:56.153 [D] probe-storage.c:398: PROBE CLOSED LOCK ON CDROM
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.155 [I] hald_dbus.c:1342: storage.cdrom.read_speed -> 18005
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.155 [I] hald_dbus.c:1342: storage.cdrom.write_speed -> 5540
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.155 [I] hald_dbus.c:1320:  strlistelem storage.cdrom.write_speeds -> 5540
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.155 [I] hald_dbus.c:1320:  strlistelem storage.cdrom.write_speeds -> 3324
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.155 [I] hald_dbus.c:1366: storage.removable.media_available -> True
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.155 [I] hald_dbus.c:1350: storage.removable.media_size -> 4700372992
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.159 [I] blockdev.c:1622: hald-probe-storage --only-check-for-media returned 2 (exit_type=0)
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.159 [I] blockdev.c:1638: Media insertion detected with file system on main block device; synthesizing hotplug add
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.159 [I] osspec.c:966: hal_util_find_known_parent: '/sys/devices/pci0000:00/0000:00:0e.0/host1/target1:0:0/1:0:0:0/block/sr0/fakevolume'->'/sys/devices/pci0000:00/0000:00:0e.0/host1/target1:0:0/1:0:0:0/block/sr0'
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.159 [I] blockdev.c:874: block_add:sysfs_path=/sys/devices/pci0000:00/0000:00:0e.0/host1/target1:0:0/1:0:0:0/block/sr0/fakevolume dev=/dev/sr0 is_part=0, parent=0x01327da0
>Jun  2 21:10:56 malbec hald[18589]: 21:10:56.159 [I] blockdev.c:882: Handling /dev/sr0 as fakevolume - sysfs_path_real=(null)
>Jun  2 21:10:56 malbec hald-probe-volume: [18737]: 21:10:56.165 [D] probe-volume.c:377: Doing probe-volume for /dev/sr0#012
>Jun  2 21:10:56 malbec hald-probe-volume: [18737]: 21:10:56.177 [D] probe-volume.c:388: volume.block_size = 2048
>Jun  2 21:10:56 malbec hald-probe-volume: [18737]: 21:10:56.177 [D] probe-volume.c:392: volume.size = 4700372992
>Jun  2 21:10:56 malbec hald-probe-volume: [18737]: 21:10:56.183 [D] probe-volume.c:436: Disc in /dev/sr0 has data
>Jun  2 21:10:57 malbec hald-probe-volume: [18737]: 21:10:57.684 [D] probe-volume.c:469: get_disc_type returned 0x1a
>Jun  2 21:10:57 malbec hald-probe-volume: [18737]: 21:10:57.687 [D] probe-volume.c:540: volume.disc.capacity = 4700372992
>Jun  2 21:10:57 malbec hald-probe-volume: [18737]: 21:10:57.692 [D] probe-volume.c:574: volume_session_count = 1
>Jun  2 21:10:57 malbec hald-probe-volume: [18737]: 21:10:57.694 [D] probe-volume.c:582: last session starts at block = 0
>Jun  2 21:10:57 malbec hald-probe-volume: [18737]: 21:10:57.695 [I] probe-volume.c:621: invoking volume_id_probe_all, offset=0, size=0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.695: util.c:356 get buffer off 0x0(0), len 0x200
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.696: util.c:369 read sbbuf len:0x200
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.973: util.c:379 got 0x200 (512) bytes
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.974: util.c:356 get buffer off 0x0(0), len 0x200
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.974: volume_id.c:362 probing at offset 0x0, size 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.974: linux_raid.c:70 probing at offset 0xffffffffffff0000, size 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.974: linux_raid.c:124probing at offset 0xffffffffffffe000, size 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.974: util.c:356 get buffer off 0xffffffffffffe000(18446744073709543424), len 0x800
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.974: util.c:405 read seekbuf off:0xffffffffffffe000 len:0x800
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.974: util.c:407 lseekfailed (Invalid argument)
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.974: linux_raid.c:124probing at offset 0x0, size 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.974: util.c:356 get buffer off 0x0(0), len 0x800
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.975: util.c:369 read sbbuf len:0x800
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.975: util.c:379 got 0x800 (2048) bytes
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.975: linux_raid.c:124probing at offset 0x1000, size 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.975: util.c:356 get buffer off 0x1000(4096), len 0x800
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.975: util.c:369 read sbbuf len:0x1800
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.976: util.c:379 got 0x1800 (6144) bytes
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.976: ddf_raid.c:49 probing at offset 0x0, size 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.976: isw_raid.c:47 probing at offset 0x0, size 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.976: lsi_raid.c:42 probing at offset 0x0, size 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.976: via_raid.c:67 probing at offset 0x0, size 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.976: silicon_raid.c:57 probing at offset 0x0, size 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.976: nvidia_raid.c:45probing at offset 0x0, size 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.976: promise_raid.c:46 probing at offset 0x0, size 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.976: highpoint.c:77 probing at offset 0x0, size 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.977: adaptec_raid.c:90 probing at offset 0x0, size 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.977: jmicron_raid.c:43 probing at offset 0x0, size 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.977: lvm.c:54 probingat offset 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.977: util.c:356 get buffer off 0x400(1024), len 0x800
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.977: lvm.c:81 probingat offset 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.977: util.c:356 get buffer off 0x0(0), len 0x800
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.977: highpoint.c:52 probing at offset 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.977: util.c:356 get buffer off 0x1200(4608), len 0x200
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.977: util.c:356 get buffer off 0x0(0), len 0x200
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.977: volume_id.c:396 probing at offset 0x0, size 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.977: fat.c:273 probing at offset 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.977: util.c:356 get buffer off 0x0(0), len 0x400
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.978: linux_swap.c:46 probing at offset 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.978: util.c:356 get buffer off 0xff6(4086), len 0xa
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.978: util.c:356 get buffer off 0x1ff6(8182), len 0xa
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.978: util.c:369 read sbbuf len:0x2000
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.978: util.c:379 got 0x2000 (8192) bytes
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.978: util.c:356 get buffer off 0x3ff6(16374), len 0xa
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.978: util.c:369 read sbbuf len:0x4000
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.978: util.c:379 got 0x4000 (16384) bytes
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.978: util.c:356 get buffer off 0x0(0), len 0x2
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.979: xfs.c:48 probingat offset 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.979: util.c:356 get buffer off 0x0(0), len 0x200
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.979: ext.c:135 probing at offset 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.979: util.c:356 get buffer off 0x400(1024), len 0x200
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.979: reiserfs.c:63 probing at offset 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.979: util.c:356 get buffer off 0x10000(65536), len 0x200
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.979: util.c:369 read sbbuf len:0x10200
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.980: util.c:379 got 0x10200 (66048) bytes
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.980: util.c:356 get buffer off 0x2000(8192), len 0x200
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.980: jfs.c:48 probingat offset 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.980: util.c:356 get buffer off 0x8000(32768), len 0x200
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.980: udf.c:75 probingat offset 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.980: util.c:356 get buffer off 0x8000(32768), len 0x200
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.980: util.c:356 get buffer off 0x8800(34816), len 0x800
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.980: udf.c:103 test for blocksize: 0x800
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.980: util.c:356 get buffer off 0x8000(32768), len 0x800
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.980: udf.c:117 vsd: CD001
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.981: util.c:356 get buffer off 0x8800(34816), len 0x800
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.981: udf.c:117 vsd: CD001
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.981: util.c:356 get buffer off 0x9000(36864), len 0x800
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.981: udf.c:117 vsd:
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.981: iso9660.c:62 probing at offset 0x0
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.981: util.c:356 get buffer off 0x8000(32768), len 0x200
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.981: iso9660.c:74 read label from PVD
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.981: iso9660.c:78 looking for SVDs
>Jun  2 21:10:57 malbec hald-probe-volume: 18737: 21:10:57.981: util.c:356 get buffer off 0x8800(34816), len 0x200
>Jun  2 21:10:57 malbec hald-probe-volume: [18737]: 21:10:57.981 [I] probe-volume.c:623: volume_id_probe_all returned 0
>Jun  2 21:10:57 malbec hald-probe-volume: [18737]: 21:10:57.981 [D] probe-volume.c:107: volume.fsusage = 'filesystem'
>Jun  2 21:10:57 malbec hald-probe-volume: [18737]: 21:10:57.981 [D] probe-volume.c:113: volume.fstype = 'iso9660'
>Jun  2 21:10:57 malbec hald-probe-volume: [18737]: 21:10:57.982 [D] probe-volume.c:118: volume.fsversion = ''
>Jun  2 21:10:57 malbec hald-probe-volume: [18737]: 21:10:57.982 [D] probe-volume.c:123: volume.uuid = ''
>Jun  2 21:10:57 malbec hald-probe-volume: [18737]: 21:10:57.982 [D] probe-volume.c:135: volume.label = 'root'
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.982 [I] hald_dbus.c:1342: volume.block_size -> 2048
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.983 [I] hald_dbus.c:1350: volume.size -> 4700372992
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.983 [I] hald_dbus.c:1334: volume.disc.type -> unknown
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.983 [I] hald_dbus.c:1366: volume.disc.has_audio -> False
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.983 [I] hald_dbus.c:1366: volume.disc.has_data -> False
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.983 [I] hald_dbus.c:1366: volume.disc.is_blank -> False
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.983 [I] hald_dbus.c:1366: volume.disc.is_appendable -> False
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.983 [I] hald_dbus.c:1366: volume.disc.is_rewritable -> False
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.983 [I] hald_dbus.c:1366: volume.disc.has_data -> True
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.983 [I] hald_dbus.c:1366: volume.disc.is_videodvd -> False
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.983 [I] hald_dbus.c:1366: volume.disc.is_blurayvideo -> False
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.983 [I] hald_dbus.c:1366: volume.disc.is_vcd -> False
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.983 [I] hald_dbus.c:1366: volume.disc.is_svcd -> False
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.983 [I] hald_dbus.c:1334: volume.disc.type -> dvd_plus_rw
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.984 [I] hald_dbus.c:1366: volume.disc.is_rewritable -> True
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.984 [I] hald_dbus.c:1350: volume.disc.capacity -> 4700372992
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.984 [I] hald_dbus.c:1366: volume.disc.is_rewritable -> True
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.984 [I] hald_dbus.c:1334: volume.fsusage -> filesystem
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.984 [I] hald_dbus.c:1334: volume.fstype -> iso9660
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.984 [I] hald_dbus.c:1334: volume.fsversion ->
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.984 [I] hald_dbus.c:1334: volume.uuid ->
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.984 [I] hald_dbus.c:1334: volume.label -> root
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.984 [I] hald_dbus.c:1334: info.product -> root
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.986 [I] blockdev.c:387: entering; exit_type=0, return_code=0
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.993 [I] blockdev.c:141: Add callouts completed udi=/org/freedesktop/Hal/devices/volume_label_root
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.993 [D] device_store.c:516: adding0x12c8520 to (linux.sysfs_path,/sys/devices/pci0000:00/0000:00:0e.0/host1/target1:0:0/1:0:0:0/block/sr0/fakevolume)
>Jun  2 21:10:57 malbec hald[18589]: 21:10:57.993 [I] hald.c:108: Added device to GDL; udi=/org/freedesktop/Hal/devices/volume_label_root
>Jun  2 21:10:58 malbec orage: polling /dev/sr0 (every 2 sec): [18637]: 21:10:58.000 [I] addon-storage.c:355: Checking whether device /dev/sr0 is locked on HAL
>Jun  2 21:10:58 malbec orage: polling /dev/sr0 (every 2 sec): [18637]: 21:10:58.008 [I] addon-storage.c:363: ... device /dev/sr0 is not locked on HAL
>Jun  2 21:11:07 malbec hald[18589]: 21:11:07.131 [I] device.c:1894: Removing locks from ':1.257'

Actions: View

Attachments on bug 424801: 307349