View previous topic :: View next topic |
Author |
Message |
SandStar Tux's lil' helper


Joined: 24 Jan 2005 Posts: 77
|
Posted: Mon Jul 20, 2009 5:24 pm Post subject: hal & scsi audio cd's |
|
|
I'm having a problem with a scsi cdrom tower.
The tower hosts 7 Pioneer scsi cdroms and is connected via scsi cable to a Adeptec AIC-7892A.
When I insert a data cd all is fine. Hal automounts it and KDE asks me what I want with it.
However, when I insert an audio cd hal seems to automount it, then decides it doesn't like and unmounts it after which it tries to automount it again. Creating a loop.
My dmesg logs are filled with this:
Code: |
scsi0: Issued Channel A Bus Reset. 4 SCBs aborted
sr0: CDROM not ready. Make sure there is a disc in the drive.
sr0: CDROM not ready. Make sure there is a disc in the drive.
sr0: CDROM not ready. Make sure there is a disc in the drive.
(scsi0:A:3:0): No or incomplete CDB sent to device.
scsi0: Issued Channel A Bus Reset. 1 SCBs aborted
sr3: CDROM not ready. Make sure there is a disc in the drive.
sr3: CDROM not ready. Make sure there is a disc in the drive.
(scsi0:A:5:0): No or incomplete CDB sent to device.
scsi0: Issued Channel A Bus Reset. 1 SCBs aborted
sr5: CDROM not ready. Make sure there is a disc in the drive.
sr5: CDROM not ready. Make sure there is a disc in the drive.
|
I've managed to stop hal before it decided to unmount a few cd's and the ones that are currently mounted play absolute fine with any audio software.
So the problem (I think) lies in the way hal responds to audio cd's on scsi. I've got another dvd-drive on sata that is handled perfectly fine by hal.
any suggestions to fix this?
further info:
Code: |
05:02.0 SCSI storage controller: Adaptec AIC-7892A U160/m (rev 02)
|
Code: |
ACPI: PCI Interrupt 0000:05:02.0[A] -> GSI 18 (level, low) -> IRQ 18
scsi0 : Adaptec AIC7XXX EISA/VLB/PCI SCSI HBA DRIVER, Rev 7.0
<Adaptec 29160 Ultra160 SCSI adapter>
aic7892: Ultra160 Wide Channel A, SCSI Id=7, 32/253 SCBs
scsi 0:0:0:0: CD-ROM PIONEER CD-ROM DR-U16S 1.01 PQ: 0 ANSI: 2
target0:0:0: Beginning Domain Validation
target0:0:0: FAST-20 SCSI 20.0 MB/s ST (50 ns, offset 16)
target0:0:0: Domain Validation detected failure, dropping back
target0:0:0: FAST-10 SCSI 10.0 MB/s ST (100 ns, offset 16)
target0:0:0: Domain Validation skipping write tests
target0:0:0: Ending Domain Validation
scsi 0:0:1:0: CD-ROM PIONEER CD-ROM DR-U16S 1.01 PQ: 0 ANSI: 2
target0:0:1: Beginning Domain Validation
target0:0:1: FAST-20 SCSI 20.0 MB/s ST (50 ns, offset 16)
target0:0:1: Domain Validation skipping write tests
target0:0:1: Ending Domain Validation
scsi 0:0:2:0: CD-ROM PIONEER CD-ROM DR-U16S 1.01 PQ: 0 ANSI: 2
target0:0:2: Beginning Domain Validation
target0:0:2: FAST-20 SCSI 20.0 MB/s ST (50 ns, offset 16)
target0:0:2: Domain Validation skipping write tests
target0:0:2: Ending Domain Validation
|
|
|
Back to top |
|
 |
SandStar Tux's lil' helper


Joined: 24 Jan 2005 Posts: 77
|
Posted: Tue Jul 21, 2009 4:55 pm Post subject: |
|
|
I've been going trough the working of hal and am very slowly beginning to understand how it works. Still can't figure out where the problem lies exactly but here's a log from hald:
Code: |
18:51:38.038 [I] osspec.c:241: SEQNUM=3374, ACTION=change, SUBSYSTEM=scsi, DEVPATH=/sys/devices/pci0000:00/0000:00:1e.0/0000:05:02.0/host0/target0:0:0/0:0:0:0, DEVNAME=, IFINDEX=0
18:51:38.038 [I] hotplug.c:114: /sys/devices/pci0000:00/0000:00:1e.0/0000:05:02.0/host0/target0:0:0/0:0:0:0 is a device (store)
18:51:38.038 [I] device.c:4531: refresh_dev: subsys=scsi
[11611]: 18:51:38.039 [D] addon-storage.c:542: Media insertion detected on /dev/sr0
18:51:38.039 [D] hald_dbus.c:1652: udi=/org/freedesktop/Hal/devices/storage_model_CD_ROM_DR_U16S, key=storage.removable.media_available
18:51:38.039 [I] hald_dbus.c:3052: entering, local_interface=1
18:51:38.039 [D] hald_dbus.c:3061: udi=/org/freedesktop/Hal/devices/storage_model_CD_ROM_DR_U16S
18:51:38.039 [I] blockdev.c:1666: blockdev_rescan_device: udi=/org/freedesktop/Hal/devices/storage_model_CD_ROM_DR_U16S
Run started hald-probe-storage (10000) (0)
! full path is '/usr/libexec/hald-probe-storage', program_dir is '/usr/libexec'
woohoo
[13740]: 18:51:38.041 [D] probe-storage.c:155: Doing probe-storage for /dev/sr0 (bus scsi) (drive_type cdrom) (udi=/org/freedesktop/Hal/devices/storage_model_CD_ROM_DR_U16S) (--only-check-for-fs==1)
[13740]: 18:51:38.041 [D] probe-storage.c:163: Doing open ("/dev/sr0", O_RDONLY | O_NONBLOCK)
[13740]: 18:51:38.041 [D] probe-storage.c:169: Returned from open(2)
[13740]: 18:51:38.043 [D] probe-storage.c:310: Checking for optical disc on /dev/sr0
[13740]: 18:51:38.043 [D] probe-storage.c:318: Doing open ("/dev/sr0", O_RDONLY | O_NONBLOCK | O_EXCL)
[13740]: 18:51:38.043 [D] probe-storage.c:348: PROBE HAS EXCLUSIVE LOCK ON CDROM
[13740]: 18:51:38.044 [D] probe-storage.c:390: media size = 720285696
[13740]: 18:51:38.044 [D] probe-storage.c:398: PROBE CLOSED LOCK ON CDROM
18:51:38.044 [I] hald_dbus.c:1342: storage.cdrom.read_speed -> 5644
18:51:38.044 [I] hald_dbus.c:1342: storage.cdrom.write_speed -> 0
18:51:38.044 [I] hald_dbus.c:1366: storage.removable.media_available -> True
18:51:38.044 [I] hald_dbus.c:1350: storage.removable.media_size -> 720285696
pid 13740: rc=2 signaled=0: /usr/libexec/hald-probe-storage
18:51:38.045 [I] blockdev.c:1622: hald-probe-storage --only-check-for-media returned 2 (exit_type=0)
18:51:38.045 [I] blockdev.c:1638: Media insertion detected with file system on main block device; synthesizing hotplug add
18:51:38.045 [I] osspec.c:966: hal_util_find_known_parent: '/sys/block/sr0/fakevolume'->'/sys/block/sr0'
18:51:38.045 [I] blockdev.c:874: block_add: sysfs_path=/sys/block/sr0/fakevolume dev=/dev/sr0 is_part=0, parent=0x0066f960
18:51:38.045 [I] blockdev.c:882: Handling /dev/sr0 as fakevolume - sysfs_path_real=(null)
Run started hald-probe-volume (10000) (0)
! full path is '/usr/libexec/hald-probe-volume', program_dir is '/usr/libexec'
[13741]: 18:51:38.047 [D] probe-volume.c:377: Doing probe-volume for /dev/sr0
[13741]: 18:51:38.060 [D] probe-volume.c:388: volume.block_size = 2048
[13741]: 18:51:38.060 [D] probe-volume.c:392: volume.size = 720285696
[13741]: 18:51:38.075 [D] probe-volume.c:423: Disc in /dev/sr0 has audio
Could not fully determine drive profile 0: Error reading disc information
[13741]: 18:51:38.079 [D] probe-volume.c:469: get_disc_type returned 0xffffffff
[13741]: 18:51:38.083 [D] probe-volume.c:574: volume_session_count = 21
18:51:38.084 [I] hald_dbus.c:1342: volume.block_size -> 2048
18:51:38.084 [I] hald_dbus.c:1350: volume.size -> 720285696
18:51:38.084 [I] hald_dbus.c:1334: volume.disc.type -> unknown
18:51:38.084 [I] hald_dbus.c:1366: volume.disc.has_audio -> False
18:51:38.084 [I] hald_dbus.c:1366: volume.disc.has_data -> False
18:51:38.084 [I] hald_dbus.c:1366: volume.disc.is_blank -> False
18:51:38.084 [I] hald_dbus.c:1366: volume.disc.is_appendable -> False
18:51:38.084 [I] hald_dbus.c:1366: volume.disc.is_rewritable -> False
18:51:38.084 [I] hald_dbus.c:1366: volume.disc.has_audio -> True
pid 13741: rc=0 signaled=0: /usr/libexec/hald-probe-volume
18:51:38.084 [I] blockdev.c:387: entering; exit_type=0, return_code=0
18:51:38.087 [I] blockdev.c:141: Add callouts completed udi=/org/freedesktop/Hal/devices/volume_part_1_size_720285696
18:51:38.087 [D] device_store.c:516: adding 0x6d64a0 to (linux.sysfs_path,/sys/block/sr0/fakevolume)
18:51:38.087 [I] hald.c:108: Added device to GDL; udi=/org/freedesktop/Hal/devices/volume_part_1_size_720285696
18:51:38.798 [I] osspec.c:241: SEQNUM=3375, ACTION=remove, SUBSYSTEM=uids, DEVPATH=/sys/kernel/uids/22, DEVNAME=, IFINDEX=0
18:51:38.798 [I] device.c:4485: remove_dev: subsys=uids sysfs_path=/sys/kernel/uids/22
18:51:38.798 [W] device.c:4489: Error removing device
[11617]: 18:51:39.002 [I] addon-storage.c:355: Checking whether device /dev/sr3 is locked on HAL
[11613]: 18:51:39.003 [I] addon-storage.c:355: Checking whether device /dev/sr1 is locked on HAL
[11615]: 18:51:39.003 [I] addon-storage.c:355: Checking whether device /dev/sr2 is locked on HAL
[11617]: 18:51:39.003 [I] addon-storage.c:363: ... device /dev/sr3 is not locked on HAL
[11615]: 18:51:39.003 [I] addon-storage.c:363: ... device /dev/sr2 is not locked on HAL
[11613]: 18:51:39.003 [I] addon-storage.c:363: ... device /dev/sr1 is not locked on HAL
18:51:39.516 [I] osspec.c:241: SEQNUM=3376, ACTION=add, SUBSYSTEM=uids, DEVPATH=/sys/kernel/uids/22, DEVNAME=, IFINDEX=0
18:51:39.517 [I] device.c:4415: add_dev: subsys=uids sysfs_path=/sys/kernel/uids/22 dev= parent_dev=0x00000000
[11621]: 18:51:40.005 [I] addon-storage.c:355: Checking whether device /dev/sr5 is locked on HAL
[11621]: 18:51:40.005 [I] addon-storage.c:363: ... device /dev/sr5 is not locked on HAL
[11619]: 18:51:40.006 [I] addon-storage.c:355: Checking whether device /dev/sr4 is locked on HAL
[11619]: 18:51:40.006 [I] addon-storage.c:363: ... device /dev/sr4 is not locked on HAL
[11625]: 18:51:40.006 [I] addon-storage.c:355: Checking whether device /dev/sr7 is locked on HAL
[11625]: 18:51:40.006 [I] addon-storage.c:363: ... device /dev/sr7 is not locked on HAL
[11611]: 18:51:40.006 [I] addon-storage.c:355: Checking whether device /dev/sr0 is locked on HAL
[11611]: 18:51:40.006 [I] addon-storage.c:363: ... device /dev/sr0 is not locked on HAL
[11623]: 18:51:40.007 [I] addon-storage.c:355: Checking whether device /dev/sr6 is locked on HAL
[11623]: 18:51:40.007 [I] addon-storage.c:363: ... device /dev/sr6 is not locked on HAL
18:51:41.858 [I] osspec.c:241: SEQNUM=3377, ACTION=remove, SUBSYSTEM=uids, DEVPATH=/sys/kernel/uids/22, DEVNAME=, IFINDEX=0
18:51:41.858 [I] device.c:4485: remove_dev: subsys=uids sysfs_path=/sys/kernel/uids/22
18:51:41.858 [W] device.c:4489: Error removing device
|
The last 3 lines are repeated every second with "SEQNUM" increasing in value by 1.
This machine is a dual boot with Vista (seperate hd) and it's playing audio cd's in Vista just fine. |
|
Back to top |
|
 |
SandStar Tux's lil' helper


Joined: 24 Jan 2005 Posts: 77
|
Posted: Fri Jul 24, 2009 12:07 pm Post subject: |
|
|
small bump.
Problem not yet resolved. I've googled for hours, finding a few people with the same problem and a minority had it fixed after kernel 2.6.17.
I'm running 2.6.25-r7... Updating the kernel is a bit of a bitch since vmware server doesn't like all kernels... |
|
Back to top |
|
 |
|
|
You cannot post new topics in this forum You cannot reply to topics in this forum You cannot edit your posts in this forum You cannot delete your posts in this forum You cannot vote in polls in this forum
|
|