Помогите выловить баг

[Karapuzz]

Суть бага заключается вот в чем. Есть у меня mp3 девайс sony walkman. Он может работать как usb флэшка так и по mtp протоколу. Вот загрузил я компьютер, подключаю плеер, определяется как флэшка и все работает. Запукаю amarok, он плеер перехватывает, и тот начинает работать как mtp устройство. Закрываю amarok, перевтыкаю плеер, он опять как флэшка. Все хорошо. Но вот через некоторое время плеер перестает работаь как флэшка. То есть подключаю, amarok закрыт, система его определят, но тут же дисконнектит и включает как mtp устройство. Вот логи

Код: Выделить всё

Jul 21 11:58:24 home kernel: [70460.942038] usb 1-4: new high speed USB device using ehci_hcd and address 56
Jul 21 11:58:24 home kernel: [70461.056888] usb 1-4: New USB device found, idVendor=054c, idProduct=0385
Jul 21 11:58:24 home kernel: [70461.056891] usb 1-4: New USB device strings: Mfr=1, Product=2, SerialNumber=5
Jul 21 11:58:24 home kernel: [70461.056894] usb 1-4: Product: WALKMAN
Jul 21 11:58:24 home kernel: [70461.056896] usb 1-4: Manufacturer: Sony
Jul 21 11:58:24 home kernel: [70461.056898] usb 1-4: SerialNumber: 10FA1625862595
Jul 21 11:58:24 home kernel: [70461.057449] scsi57 : usb-storage 1-4:1.0
Jul 21 11:58:26 home kernel: [70462.377791] scsi 57:0:0:0: Direct-Access     SONY     WALKMAN          1.00 PQ: 0 ANSI: 4
Jul 21 11:58:26 home kernel: [70462.377965] sd 57:0:0:0: Attached scsi generic sg2 type 0
Jul 21 11:58:26 home kernel: [70462.382410] sd 57:0:0:0: [sdb] 1918976 2048-byte logical blocks: (3.93 GB/3.66 GiB)
Jul 21 11:58:26 home kernel: [70462.383162] sd 57:0:0:0: [sdb] Write Protect is off
Jul 21 11:58:26 home kernel: [70462.383165] sd 57:0:0:0: [sdb] Mode Sense: 3e 00 00 00
Jul 21 11:58:26 home kernel: [70462.383167] sd 57:0:0:0: [sdb] Assuming drive cache: write through
Jul 21 11:58:26 home kernel: [70462.384272] sd 57:0:0:0: [sdb] 1918976 2048-byte logical blocks: (3.93 GB/3.66 GiB)
Jul 21 11:58:26 home kernel: [70462.384773] sd 57:0:0:0: [sdb] Assuming drive cache: write through
Jul 21 11:58:26 home kernel: [70462.384778]  sdb: sdb1
Jul 21 11:58:26 home kernel: [70462.405290] sd 57:0:0:0: [sdb] 1918976 2048-byte logical blocks: (3.93 GB/3.66 GiB)
Jul 21 11:58:26 home kernel: [70462.405773] sd 57:0:0:0: [sdb] Assuming drive cache: write through
Jul 21 11:58:26 home kernel: [70462.405776] sd 57:0:0:0: [sdb] Attached SCSI removable disk
Jul 21 11:58:26 home hald: mounted /dev/sdb1 on behalf of uid 1000
Jul 21 11:58:28 home hald[1588]: forcibly attempting to lazy unmount /dev/sdb1 as enclosing drive was disconnected
Jul 21 11:58:28 home hald: unmounted /dev/sdb1 from '/media/WALKMAN' on behalf of uid 0
Jul 21 11:58:33 home kernel: [70470.044483] usb 1-4: USB disconnect, address 56

Получается, что какая-то сволочь перехватывает плеер, но вот как понять какая?
Еще есть фотоаппарат, которые прекрасно монтируется как флэшка, но так же как и в первом случае, через некоторое время перестает это делать.

Код: Выделить всё

Jul 21 12:18:34 home dhclient: XMT: Solicit on eth0, interval 131570ms.
Jul 21 12:20:03 home kernel: [71759.206013] usb 5-2: new full speed USB device using uhci_hcd and address 30
Jul 21 12:20:03 home kernel: [71759.415042] usb 5-2: New USB device found, idVendor=07b4, idProduct=0109
Jul 21 12:20:03 home kernel: [71759.415045] usb 5-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jul 21 12:20:03 home kernel: [71759.415048] usb 5-2: Product: FE240/X795
Jul 21 12:20:03 home kernel: [71759.415050] usb 5-2: Manufacturer: OLYMPUS
Jul 21 12:20:03 home kernel: [71759.415052] usb 5-2: SerialNumber: 000K97507827
Jul 21 12:20:03 home kernel: [71759.418158] scsi58 : usb-storage 5-2:1.0

А дальше тишина, ни чего не происходит. Подозреваю, что оба этих случая (глюк фотоаппарата и плеера) результат одного бага.

Если перезапустит кеды все встает на свои места и начинает работать. А вот перезапуск hal и udev ни чего не дают.

Не понятно, что именно и в каком месте начинает сбоить. Закономерностей ни каких я не нашел. В логах тоже тихо. Не понятно, какие именно действия приводят к появлению бага. Он может появиться через час просто стояния компа без дела, а может и не появится за два часа работы на нем. В связи с этим вопрос, как этот баг выловить? Может есть какие-то средства мониторинга, которые позволят, например, выяснить что перехватывает плеер и коннектит его как mtp устройство, или что мешает фотоаппарату нормально подключаться?

[Skyb]

Ты уверен что топик должен быть в разделе администрирование??

[AndreyMust19]

Jul 21 11:58:26 home hald: mounted /dev/sdb1 on behalf of uid 1000
Jul 21 11:58:28 home hald[1588]: forcibly attempting to lazy unmount /dev/sdb1 as enclosing drive was disconnected
Jul 21 11:58:28 home hald: unmounted /dev/sdb1 from '/media/WALKMAN' on behalf of uid 0
Jul 21 11:58:33 home kernel: [70470.044483] usb 1-4: USB disconnect, address 56

hald размонтирует вашу флешку. Гугл сказал, что это демон автомонтирования флешек. Может он увидел, что к смонтированному устройству давно никто не обращается и поэтому ее размонтирует.
Попробуйте вставить в USB-разъемы с другой стороны системного блока.

[sash-kan]

Karapuzz
kde или железо.
первый вариант проверить несложно.
второй вариант тоже проверяем:
1. наверняка у вас не один usb-контроллер стоит. попробуйте подключаться к другому (будет видно по сменившемуся номеру bus в выводе lsusb).
2. usb-шнурок тоже наверняка есть запасной.

[Karapuzz]

Jul 21 11:58:26 home hald: mounted /dev/sdb1 on behalf of uid 1000
Jul 21 11:58:28 home hald[1588]: forcibly attempting to lazy unmount /dev/sdb1 as enclosing drive was disconnected
Jul 21 11:58:28 home hald: unmounted /dev/sdb1 from '/media/WALKMAN' on behalf of uid 0
Jul 21 11:58:33 home kernel: [70470.044483] usb 1-4: USB disconnect, address 56

hald размонтирует вашу флешку. Гугл сказал, что это демон автомонтирования флешек. Может он увидел, что к смонтированному устройству давно никто не обращается и поэтому ее размонтирует.
Попробуйте вставить в USB-разъемы с другой стороны системного блока.

Да не, немного не так. Обратите внимание, между 11:58:26 home hald: mounted и 11:58:28 home hald: unmounted разница в две секунды. На "давно ни кто не обращался" не похоже. Я наверно не много не тот лог привел. Дело в том, что в настройках KDE стояло авто монтирование этого устройства при подключении. Вот система его и пыталась примонтировать, и похоже что получилось, но тут же размонтировала, (forcibly attempting to lazy unmount /dev/sdb1 as enclosing drive was disconnected), потому как железка подключилась как mtp устройство. Точнее что-то ее подключило, но что именно не понятно.

[Karapuzz]

Karapuzz
kde или железо.
первый вариант проверить несложно.
второй вариант тоже проверяем:
1. наверняка у вас не один usb-контроллер стоит. попробуйте подключаться к другому (будет видно по сменившемуся номеру bus в выводе lsusb).
2. usb-шнурок тоже наверняка есть запасной.

Пробовал в другой usb разъем воткнуть, не помогло. Да вряд ли проблема железная. Железо, оно либо работает либо нет, а не так, что сейчас работает, а через два часа вдруг перестало, и перезапуск KDE не лечит железные проблемы. Кроме того обычные флэшки всегда нормально монтируются. Ясно, что проблема в кедах, но где именно? Я бы уже давно баг репорт написал, но о чем писать? У меня ВНЕЗАПНО перестает монтироваться фотоаппарат?

[Karapuzz]

Сделал большой шаг вперед! Методом научного тыка+интуиция выяснил, что причиной всему служит gvfs, а точней процесс по имени gvfs-gphoto2-volume-monitor. Убил его и все стало хорошо. Кстати, после запуска KDE его нет, он не запущен, и не понятно, какого хрена он запускается потом. Я пол дня щелкал все подряд программы, но так и не смог инициировать запуск этого процесса. Примечательно, что ни один пакет из набора gvfs инструментов, включая саму gvfs ни дают ни каких зависимостей. Вопрос, а он мне нужен? То есть какие могут быть последствия от удаления gvfs? Вроде в KDE есть kio, и зачем в opnsuse 11.3 из коробки ставится gvfs не понятно, но видимо не просто так.