Bug 104355

Summary: CDROMREADTOCENTRY returns strange cdte_addr.lba
Product: [openSUSE] SUSE Linux 10.1 Reporter: Stanislav Brabec <sbrabec>
Component: BasesystemAssignee: Kay Sievers <kasievers>
Status: RESOLVED DUPLICATE QA Contact: E-mail List <qa-bugs>
Severity: Normal    
Priority: P5 - None CC: dkukawka
Version: unspecified   
Target Milestone: ---   
Hardware: Other   
OS: All   
Whiteboard:
Found By: Other Services Priority:
Business Priority: Blocker: ---
Marketing QA Status: --- IT Deployment: ---
Attachments: lshal output
strace output from hald
debug output from hald
log from HAL on RC1

Description Stanislav Brabec 2005-08-12 10:22:19 UTC 
Inserting CD-ROM into external ATAPI-over-USB drive, CD-ROM is correctly mounted
by hal/d-bus/gnome-volume-manager. Inserting UDF formatted DVD-RAM, it is not
mounted.

Manual mounting works without problems.
Comment 1 Stanislav Brabec 2005-08-12 10:32:21 UTC 
Looks like udf and media was module was not loaded. Will try again later in
fresh beta2 installation.
Comment 2 Danny Al-Gaaf 2005-08-12 10:53:35 UTC 
Same with internal DVD device? Was this the LG DVD burner?
Comment 3 Stanislav Brabec 2005-08-12 11:41:25 UTC 
My internal drive can not read DVD-RAM. Yes, the external is the LG DVD burner.
Medium was tried to mount as ext2. I will recheck in beta2 (my beta1 is not a
fresh installation).
Comment 4 Stanislav Brabec 2005-08-19 13:48:47 UTC 
Confirming the problem on fresh installation of beta2. Bug 105798 can be related.
Comment 5 Danny Al-Gaaf 2005-08-21 13:40:58 UTC 
Please add output of lshal and the part of /var/log/messages since you inserted 
the related DVD-RAM in the device.
Comment 6 Michal Marek 2005-08-22 12:53:38 UTC 
Created attachment 46902 [details]
lshal output

Nothing appears in /var/log/messages when the DVD is inserted
Comment 7 Danny Al-Gaaf 2005-08-22 13:01:14 UTC 
can you please do this as root:
 * rchal stop
 * strace -fqrv -s 512 -o /tmp/hal_strace hald --retain-privileges --verbose=yes 
--daemon=no
 * wait 30 sec
 * attach the device
 * wait 10 sec
 * stop strace and attach the file /tmp/hal_strace to this bug (please tar -cjf 
the file)
Comment 8 Michal Marek 2005-08-22 14:18:54 UTC 
Created attachment 46935 [details]
strace output from hald
Comment 9 Danny Al-Gaaf 2005-08-22 14:46:12 UTC 
Can you please to the same as above but with this command instead of strace:
haldaemon --daemon=no --verbose=yes --retain-privileges=yes > /tmp/log_haldaemon 
2>&1
Comment 10 Michal Marek 2005-08-22 15:47:22 UTC 
Created attachment 46977 [details]
debug output from hald
Comment 11 Danny Al-Gaaf 2005-08-22 21:42:10 UTC 
Hm ... we cant debug or fix this without to have _this_ DVD-RAM. We tested also 
other DVD-RAM media and it works. How many sessions are on the media?

I see currently only to ways:
* wait until 20.09.2005 for the SUSE Labs Conference. Kay Sievers is on the 
conference and he can try to debug this in Prag (he wrote the volume_id code)
* you backup the data on the device and delete/reformat the media. If this work, 
fine for you, but we maybe can't reproduce this.
Comment 12 Michal Marek 2005-08-23 07:31:38 UTC 
I did reformat it several times, but to change. It might be related to bug
105798 (ie. it can be some problem with the driver or hardware)
Comment 13 Danny Al-Gaaf 2005-08-23 09:30:35 UTC 
I know from #3 the machine has no internal drive with DVD-RAM read support. Is 
there any other machine with internal drive to test if the DVD get mountet?
Comment 15 Danny Al-Gaaf 2005-09-09 16:05:13 UTC 
The volume is correct found, but the filesystem is not detected. I attach the 
log of hal start. Kay should take a look at this if he is 20th this month at the 
 labs conference if possible.
Comment 17 Stanislav Brabec 2005-09-20 13:27:36 UTC 
Additional info: DVD+RW in the same drive is not mounted, too.
Comment 18 Stanislav Brabec 2005-09-26 16:42:25 UTC 
And finally, I have installed 10.0 on my home machine with nearly the same model
of LG DVD burner (GSA-4160B), and everything works there correctly. So it's
related only to burner connected via USB.
Comment 19 Kay Sievers 2005-10-16 12:30:24 UTC 
Hmm, can we close the bug? I have no idea were to look for the failure.
Comment 20 Stanislav Brabec 2005-10-17 11:45:18 UTC 
This problem still occurs. Feel free to log-in to the machine for more debugging. You can ping me via sbrabec Novell Groupwise IM for testing.

It can be also related to bug 91189.
Comment 22 Stanislav Brabec 2005-10-18 10:27:05 UTC 
Empty DVD is detected.

DVD-RAM is not.

21410: 12:24:55.529: addon-storage.c:316: Media insertion detected on /dev/sr0
12:24:55.530 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/storage_model_DVDRAM_GSA_4120B interface=org.freedesktop.Hal.Device method=Rescan
12:24:55.530 [I] hald_dbus.c:2087: entering, local_interface=1
12:24:55.530 [D] hald_dbus.c:2096: udi=/org/freedesktop/Hal/devices/storage_model_DVDRAM_GSA_4120B
12:24:55.530 [I] blockdev.c:1217: Entering, udi=/org/freedesktop/Hal/devices/storage_model_DVDRAM_GSA_4120B
15805: 12:24:55.532: probe-storage.c:143: Doing probe-storage for /dev/sr0 (bus usb) (drive_type cdrom) (udi=/org/freedesktop/Hal/devices/storage_model_DVDRAM_GSA_4120B) (--only-check-for-fs==1)
15805: 12:24:55.540: probe-storage.c:310: Checking for optical disc on /dev/sr0
15805: 12:24:55.540: probe-storage.c:318: Doing open ("/dev/sr0", O_RDONLY | O_NONBLOCK | O_EXCL)
12:24:55.540 [I] hald_dbus.c:3007: 21380: Got a connection
12:24:55.540 [I] hald_dbus.c:3008: dbus_connection_get_is_connected = 1
12:24:55.564 [I] hald_dbus.c:2969: local_server_message_handler: destination=(null) obj_path=/org/freedesktop/DBus/Local interface=org.freedesktop.DBus.Local method=Disconnected
12:24:55.564 [I] hald_dbus.c:2985: Client to local_server was disconnected
12:24:55.564 [I] hald_dbus.c:2995: unregistered
12:24:55.565 [I] util.c:554: child exited for pid 15805
12:24:55.565 [I] blockdev.c:1175: hald-probe-storage --only-check-for-media returned 2 (timed_out=0)
12:24:55.565 [I] blockdev.c:547: block_add: sysfs_path=/sys/block/sr0/fakevolume dev=/dev/sr0 is_part=1, parent=0x0054a940
12:24:55.565 [I] blockdev.c:557: Handling /dev/sr0 as fakevolume - sysfs_path_real=/sys/block/sr0
scandir: No such file or directory
15806: 12:24:55.593: probe-volume.c:340: Doing probe-volume for /dev/sr0

12:24:55.594 [I] hald_dbus.c:3007: 21380: Got a connection
12:24:55.594 [I] hald_dbus.c:3008: dbus_connection_get_is_connected = 1
15806: 12:24:55.616: probe-volume.c:348: volume.block_size = 2048
12:24:55.617 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/107 interface=org.freedesktop.Hal.Device method=SetPropertyInteger
12:24:55.617 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/107, key=volume.block_size
15806: 12:24:55.617: probe-volume.c:352: volume.size = 3571744768
12:24:55.617 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/107 interface=org.freedesktop.Hal.Device method=SetPropertyInteger
12:24:55.617 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/107, key=volume.size
12:24:55.618 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/107 interface=org.freedesktop.Hal.Device method=SetPropertyString
12:24:55.618 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/107, key=volume.disc.type
12:24:55.641 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/107 interface=org.freedesktop.Hal.Device method=SetPropertyBoolean
12:24:55.641 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/107, key=volume.disc.has_audio
12:24:55.641 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/107 interface=org.freedesktop.Hal.Device method=SetPropertyBoolean
12:24:55.641 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/107, key=volume.disc.has_data
12:24:55.641 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/107 interface=org.freedesktop.Hal.Device method=SetPropertyBoolean
12:24:55.641 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/107, key=volume.disc.is_blank
12:24:55.642 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/107 interface=org.freedesktop.Hal.Device method=SetPropertyBoolean
12:24:55.642 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/107, key=volume.disc.is_appendable
12:24:55.642 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/107 interface=org.freedesktop.Hal.Device method=SetPropertyBoolean
12:24:55.643 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/107, key=volume.disc.is_rewritable
12:24:55.659 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/107 interface=org.freedesktop.Hal.Device method=SetPropertyBoolean
12:24:55.670 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/107, key=volume.disc.has_data
15806: 12:24:55.670: probe-volume.c:395: Disc in /dev/sr0 has data
12:24:55.670 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/107 interface=org.freedesktop.Hal.Device method=SetPropertyBoolean
12:24:55.670 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/107, key=volume.disc.is_videodvd
12:24:55.671 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/107 interface=org.freedesktop.Hal.Device method=SetPropertyBoolean
12:24:55.671 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/107, key=volume.disc.is_vcd
12:24:55.671 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/107 interface=org.freedesktop.Hal.Device method=SetPropertyBoolean
12:24:55.671 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/107, key=volume.disc.is_svcd
15806: 12:24:58.647: probe-volume.c:415: get_disc_type returned 0x10
12:24:58.648 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/107 interface=org.freedesktop.Hal.Device method=SetPropertyString
12:24:58.648 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/107, key=volume.disc.type
15806: 12:24:58.654: probe-volume.c:476: volume_session_count = 1
15806: 12:24:58.657: probe-volume.c:484: last session starts at block = 4294967146
15806: 12:24:58.657: volume_id_probe_lvm1: probing at offset 0xfffb5000
15806: 12:24:58.657: volume_id_get_buffer: get buffer off 0xfffb5400(4294661120), len 0x800
15806: 12:24:58.657: volume_id_get_buffer: read seekbuf off:0xfffb5400 len:0x800
15806: 12:24:58.657: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.657: volume_id_probe_lvm2: probing at offset 0xfffb5000
15806: 12:24:58.657: volume_id_get_buffer: get buffer off 0xfffb5000(4294660096), len 0x800
15806: 12:24:58.657: volume_id_get_buffer: read seekbuf off:0xfffb5000 len:0x800
15806: 12:24:58.657: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.657: volume_id_probe_highpoint_37x_raid: probing at offset 0xfffb5000
15806: 12:24:58.657: volume_id_get_buffer: get buffer off 0xfffb6200(4294664704), len 0x200
15806: 12:24:58.657: volume_id_get_buffer: read seekbuf off:0xfffb6200 len:0x200
15806: 12:24:58.657: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.657: volume_id_get_buffer: get buffer off 0xfffb5000(4294660096), len 0x2
15806: 12:24:58.657: volume_id_get_buffer: read seekbuf off:0xfffb5000 len:0x2
15806: 12:24:58.657: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.657: volume_id_probe_vfat: probing at offset 0xfffb5000
15806: 12:24:58.657: volume_id_get_buffer: get buffer off 0xfffb5000(4294660096), len 0x200
15806: 12:24:58.657: volume_id_get_buffer: read seekbuf off:0xfffb5000 len:0x200
15806: 12:24:58.657: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.657: volume_id_probe_xfs: probing at offset 0xfffb5000
15806: 12:24:58.657: volume_id_get_buffer: get buffer off 0xfffb5000(4294660096), len 0x200
15806: 12:24:58.657: volume_id_get_buffer: read seekbuf off:0xfffb5000 len:0x200
15806: 12:24:58.657: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.657: volume_id_get_buffer: get buffer off 0x0(0), len 0x11000
15806: 12:24:58.657: volume_id_get_buffer: read sbbuf len:0x11000
15806: 12:24:58.714: volume_id_get_buffer: got 0x11000 (69632) bytes
15806: 12:24:58.714: volume_id_probe_linux_swap: probing at offset 0xfffb5000
15806: 12:24:58.714: volume_id_get_buffer: get buffer off 0xfffb5ff6(4294664182), len 0xa
15806: 12:24:58.714: volume_id_get_buffer: read seekbuf off:0xfffb5ff6 len:0xa
15806: 12:24:58.714: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.714: volume_id_probe_ext: probing at offset 0xfffb5000
15806: 12:24:58.714: volume_id_get_buffer: get buffer off 0xfffb5400(4294661120), len 0x200
15806: 12:24:58.714: volume_id_get_buffer: read seekbuf off:0xfffb5400 len:0x200
15806: 12:24:58.715: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.715: volume_id_probe_reiserfs: probing at offset 0xfffb5000
15806: 12:24:58.715: volume_id_get_buffer: get buffer off 0xfffc5000(4294725632), len 0x200
15806: 12:24:58.715: volume_id_get_buffer: read seekbuf off:0xfffc5000 len:0x200
15806: 12:24:58.715: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.715: volume_id_probe_jfs: probing at offset 0xfffb5000
15806: 12:24:58.715: volume_id_get_buffer: get buffer off 0xfffbd000(4294692864), len 0x200
15806: 12:24:58.715: volume_id_get_buffer: read seekbuf off:0xfffbd000 len:0x200
15806: 12:24:58.715: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.715: volume_id_probe_udf: probing at offset 0xfffb5000
15806: 12:24:58.715: volume_id_get_buffer: get buffer off 0xfffbd000(4294692864), len 0x200
15806: 12:24:58.715: volume_id_get_buffer: read seekbuf off:0xfffbd000 len:0x200
15806: 12:24:58.715: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.715: volume_id_probe_iso9660: probing at offset 0xfffb5000
15806: 12:24:58.715: volume_id_get_buffer: get buffer off 0xfffbd000(4294692864), len 0x200
15806: 12:24:58.715: volume_id_get_buffer: read seekbuf off:0xfffbd000 len:0x200
15806: 12:24:58.715: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.715: volume_id_probe_hfs_hfsplus: probing at offset 0xfffb5000
15806: 12:24:58.715: volume_id_get_buffer: get buffer off 0xfffb5400(4294661120), len 0x200
15806: 12:24:58.715: volume_id_get_buffer: read seekbuf off:0xfffb5400 len:0x200
15806: 12:24:58.716: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.716: volume_id_probe_ufs: probing at offset 0xfffb5000
15806: 12:24:58.716: volume_id_get_buffer: get buffer off 0xfffb5000(4294660096), len 0x800
15806: 12:24:58.716: volume_id_get_buffer: read seekbuf off:0xfffb5000 len:0x800
15806: 12:24:58.716: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.716: volume_id_probe_ntfs: probing at offset 0xfffb5000
15806: 12:24:58.716: volume_id_get_buffer: get buffer off 0xfffb5000(4294660096), len 0x200
15806: 12:24:58.716: volume_id_get_buffer: read seekbuf off:0xfffb5000 len:0x200
15806: 12:24:58.716: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.716: volume_id_probe_cramfs: probing at offset 0xfffb5000
15806: 12:24:58.716: volume_id_get_buffer: get buffer off 0xfffb5000(4294660096), len 0x200
15806: 12:24:58.716: volume_id_get_buffer: read seekbuf off:0xfffb5000 len:0x200
15806: 12:24:58.716: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.716: volume_id_probe_romfs: probing at offset 0xfffb5000
15806: 12:24:58.716: volume_id_get_buffer: get buffer off 0xfffb5000(4294660096), len 0x200
15806: 12:24:58.716: volume_id_get_buffer: read seekbuf off:0xfffb5000 len:0x200
15806: 12:24:58.716: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.716: volume_id_probe_hpfs: probing at offset 0xfffb5000
15806: 12:24:58.716: volume_id_get_buffer: get buffer off 0xfffb7000(4294668288), len 0x200
15806: 12:24:58.716: volume_id_get_buffer: read seekbuf off:0xfffb7000 len:0x200
15806: 12:24:58.716: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.716: volume_id_probe_sysv: probing at offset 0xfffb5000
15806: 12:24:58.716: volume_id_get_buffer: get buffer off 0xfffb5200(4294660608), len 0x200
15806: 12:24:58.716: volume_id_get_buffer: read seekbuf off:0xfffb5200 len:0x200
15806: 12:24:58.716: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.716: volume_id_probe_minix: probing at offset 0xfffb5000
15806: 12:24:58.716: volume_id_get_buffer: get buffer off 0xfffb5400(4294661120), len 0x200
15806: 12:24:58.716: volume_id_get_buffer: read seekbuf off:0xfffb5400 len:0x200
15806: 12:24:58.716: volume_id_get_buffer: lseek failed (Invalid argument)
15806: 12:24:58.716: volume_id_probe_ocfs2: probing at offset 0xfffb5000
15806: 12:24:58.716: volume_id_get_buffer: get buffer off 0xfffb7000(4294668288), len 0x200
15806: 12:24:58.716: volume_id_get_buffer: read seekbuf off:0xfffb7000 len:0x200
15806: 12:24:58.716: volume_id_get_buffer: lseek failed (Invalid argument)
12:24:58.729 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/107 interface=org.freedesktop.Hal.Device method=SetPropertyString
12:24:58.729 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/107, key=info.product
12:24:58.731 [I] hald_dbus.c:2969: local_server_message_handler: destination=(null) obj_path=/org/freedesktop/DBus/Local interface=org.freedesktop.DBus.Local method=Disconnected
12:24:58.731 [I] hald_dbus.c:2985: Client to local_server was disconnected
12:24:58.731 [I] hald_dbus.c:2995: unregistered
12:24:58.731 [I] util.c:554: child exited for pid 15806
12:24:58.731 [I] blockdev.c:281: entering; timed_out=0, return_code=0
12:24:58.731 [I] blockdev.c:179: Update mount point for /org/freedesktop/Hal/devices/volume_part_1_size_3571744768 (device_file /dev/sr0)
12:24:58.731 [I] blockdev.c:208: Clearing mount point for /dev/sr0
scandir: No such file or directory
scandir: No such file or directory
12:24:58.742 [I] blockdev.c:139: Add callouts completed udi=/org/freedesktop/Hal/devices/volume_part_1_size_3571744768
12:24:58.742 [I] hald.c:89: Added device to GDL; udi=/org/freedesktop/Hal/devices/volume_part_1_size_3571744768
12:24:58.758 [W] hald_dbus.c:86: No property info.callouts.add on device with id /org/freedesktop/Hal/devices/volume_part_1_size_3571744768
Comment 23 Stanislav Brabec 2005-10-18 10:29:12 UTC 
Empty DVD:

21410: 12:28:51.563: addon-storage.c:316: Media insertion detected on /dev/sr0
12:28:51.564 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/storage_model_DVDRAM_GSA_4120B interface=org.freedesktop.Hal.Device method=Rescan
12:28:51.564 [I] hald_dbus.c:2087: entering, local_interface=1
12:28:51.564 [D] hald_dbus.c:2096: udi=/org/freedesktop/Hal/devices/storage_model_DVDRAM_GSA_4120B
12:28:51.564 [I] blockdev.c:1217: Entering, udi=/org/freedesktop/Hal/devices/storage_model_DVDRAM_GSA_4120B
15890: 12:28:51.565: probe-storage.c:143: Doing probe-storage for /dev/sr0 (bus usb) (drive_type cdrom) (udi=/org/freedesktop/Hal/devices/storage_model_DVDRAM_GSA_4120B) (--only-check-for-fs==1)
15890: 12:28:51.566: probe-storage.c:310: Checking for optical disc on /dev/sr0
15890: 12:28:51.566: probe-storage.c:318: Doing open ("/dev/sr0", O_RDONLY | O_NONBLOCK | O_EXCL)
12:28:51.567 [I] hald_dbus.c:3007: 21380: Got a connection
12:28:51.567 [I] hald_dbus.c:3008: dbus_connection_get_is_connected = 1
12:28:51.574 [I] hald_dbus.c:2969: local_server_message_handler: destination=(null) obj_path=/org/freedesktop/DBus/Local interface=org.freedesktop.DBus.Local method=Disconnected
12:28:51.574 [I] hald_dbus.c:2985: Client to local_server was disconnected
12:28:51.574 [I] hald_dbus.c:2995: unregistered
12:28:51.574 [I] util.c:554: child exited for pid 15890
12:28:51.574 [I] blockdev.c:1175: hald-probe-storage --only-check-for-media returned 2 (timed_out=0)
12:28:51.575 [I] blockdev.c:547: block_add: sysfs_path=/sys/block/sr0/fakevolume dev=/dev/sr0 is_part=1, parent=0x0054a940
12:28:51.575 [I] blockdev.c:557: Handling /dev/sr0 as fakevolume - sysfs_path_real=/sys/block/sr0
scandir: No such file or directory
15891: 12:28:51.578: probe-volume.c:340: Doing probe-volume for /dev/sr0

12:28:51.579 [I] hald_dbus.c:3007: 21380: Got a connection
12:28:51.579 [I] hald_dbus.c:3008: dbus_connection_get_is_connected = 1
15891: 12:28:51.591: probe-volume.c:348: volume.block_size = 2048
12:28:51.592 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/108 interface=org.freedesktop.Hal.Device method=SetPropertyInteger
12:28:51.592 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/108, key=volume.block_size
15891: 12:28:51.592: probe-volume.c:352: volume.size = 2048
12:28:51.592 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/108 interface=org.freedesktop.Hal.Device method=SetPropertyInteger
12:28:51.592 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/108, key=volume.size
12:28:51.592 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/108 interface=org.freedesktop.Hal.Device method=SetPropertyString
12:28:51.593 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/108, key=volume.disc.type
12:28:51.593 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/108 interface=org.freedesktop.Hal.Device method=SetPropertyBoolean
12:28:51.593 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/108, key=volume.disc.has_audio
12:28:51.593 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/108 interface=org.freedesktop.Hal.Device method=SetPropertyBoolean
12:28:51.593 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/108, key=volume.disc.has_data
12:28:51.593 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/108 interface=org.freedesktop.Hal.Device method=SetPropertyBoolean
12:28:51.593 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/108, key=volume.disc.is_blank
12:28:51.593 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/108 interface=org.freedesktop.Hal.Device method=SetPropertyBoolean
12:28:51.593 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/108, key=volume.disc.is_appendable
12:28:51.594 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/108 interface=org.freedesktop.Hal.Device method=SetPropertyBoolean
12:28:51.594 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/108, key=volume.disc.is_rewritable
12:28:51.600 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/108 interface=org.freedesktop.Hal.Device method=SetPropertyBoolean
12:28:51.600 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/108, key=volume.disc.is_blank
15891: 12:28:51.600: probe-volume.c:400: Disc in /dev/sr0 is blank
15891: 12:28:51.602: probe-volume.c:415: get_disc_type returned 0x1b
12:28:51.602 [I] hald_dbus.c:2969: local_server_message_handler: destination=org.freedesktop.Hal obj_path=/org/freedesktop/Hal/devices/temp/108 interface=org.freedesktop.Hal.Device method=SetPropertyString
12:28:51.602 [D] hald_dbus.c:1191: udi=/org/freedesktop/Hal/devices/temp/108, key=volume.disc.type
12:28:51.611 [I] util.c:554: child exited for pid 15891
12:28:51.611 [I] blockdev.c:281: entering; timed_out=0, return_code=0
12:28:51.611 [I] blockdev.c:179: Update mount point for /org/freedesktop/Hal/devices/volume_empty_dvd_plus_r (device_file /dev/sr0)
12:28:51.612 [I] blockdev.c:208: Clearing mount point for /dev/sr0
scandir: No such file or directory
scandir: No such file or directory
12:28:51.622 [I] blockdev.c:139: Add callouts completed udi=/org/freedesktop/Hal/devices/volume_empty_dvd_plus_r
12:28:51.623 [I] hald.c:89: Added device to GDL; udi=/org/freedesktop/Hal/devices/volume_empty_dvd_plus_r
12:28:51.623 [I] hald_dbus.c:2969: local_server_message_handler: destination=(null) obj_path=/org/freedesktop/DBus/Local interface=org.freedesktop.DBus.Local method=Disconnected
12:28:51.623 [I] hald_dbus.c:2985: Client to local_server was disconnected
12:28:51.623 [I] hald_dbus.c:2995: unregistered
Comment 24 Stanislav Brabec 2005-10-18 11:07:50 UTC 
Comparing internal and external drive result, I guess I have found the bug. This is the first real difference in the logs:

internal:
PID3: xx:xx:xx.xxx: probe-volume.c:484: last session starts at block = 0

external:
PID3: xx:xx:xx.xxx: probe-volume.c:484: last session starts at block = 4294967146


And there is a dvd+rw-mediainfo of the same medium (SuSE Linux 9.0 DVD side 1):

 GET [CURRENT] CONFIGURATION:
  Mounted Media:         10h, DVD-ROM
 READ DVD STRUCTURE[#0h]:
  Media Book Type:       01h, DVD-ROM book [revision 1]
  Legacy lead-out at:    2058736*2KB=4216291328
 READ DISC INFORMATION:
  Disc status:           complete
  Number of Sessions:    1
  State of Last Session: complete
  Number of Tracks:      1
 READ TRACK INFORMATION[#1]:
  Track State:           complete
  Track Start Address:   0*2KB
  Free Blocks:           0*2KB
  Track Size:            2058736*2KB
- Last Recorded Address: 2058735*2KB
 FABRICATED TOC:
  Track#1  :             14@0
  Track#AA :             14@2058736
  Multi-session Info:    #1@0
 READ CAPACITY:          2058736*2048=4216291328

Please note that the only difference is missing "Last Recorded Address" for external drive.
Comment 25 Stanislav Brabec 2005-10-18 11:51:11 UTC 
There is a code from hal-0.5.4/hald/linux2/probing/probe-volume.c, which returns different value for the same medium for ATAPI and for ATAPI-over-USB. If tha HAL and ATAPI implementations are correct, it should contain last session start address, but for ATAPI-over-USB it returns something like fist free address.

           struct cdrom_tocentry toc_entr;

           memset (&toc_entr, 0x00, sizeof (toc_entr));
           toc_entr.cdte_track = vol_session_count;
           toc_entr.cdte_format = CDROM_LBA;
           if (ioctl (fd, CDROMREADTOCENTRY, &toc_entr) == 0)
                   if ((toc_entr.cdte_ctrl & CDROM_DATA_TRACK) == 4) {
                           dbg ("last session starts at block = %u", toc_entr.cdte_addr.lba);
                           vol_probe_offset = toc_entr.cdte_addr.lba * block_size;
                   }
   }
Comment 26 Stanislav Brabec 2005-11-02 12:52:30 UTC 
Loooking at bug 130210, it seems to do nothing with ATAPI-over-USB, but is drive/firmware specific.

Reassigning. Bug 130210 is most probably a duplicate.
Comment 27 Kay Sievers 2005-11-03 02:41:11 UTC 
I've committed a possible fix to upstream HAL CVS now.
Next package will show if we got it.
Comment 28 Kay Sievers 2005-11-08 15:24:28 UTC 
Please try the HAL version from the suse "edge" tree, we have a new package there
which is expected to fix this problem.
Comment 29 Stanislav Brabec 2005-11-08 16:23:25 UTC 
Yes, medium is now auto mounted.
Comment 30 Kay Sievers 2005-11-08 16:30:39 UTC 
Great. Thanks for testing.
Comment 31 Danny Al-Gaaf 2005-11-09 20:12:50 UTC 
I mark this bug as dublicate for maintenance / YOU.
Comment 32 Danny Al-Gaaf 2005-11-09 20:13:23 UTC 

*** This bug has been marked as a duplicate of 130210 ***