From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S261458AbTIRSVW (ORCPT ); Thu, 18 Sep 2003 14:21:22 -0400 Received: (majordomo@vger.kernel.org) by vger.kernel.org id S261470AbTIRSVW (ORCPT ); Thu, 18 Sep 2003 14:21:22 -0400 Received: from [4.5.103.38] ([4.5.103.38]:45741 "EHLO gallant.omgwallhack.org") by vger.kernel.org with ESMTP id S261458AbTIRSVS (ORCPT ); Thu, 18 Sep 2003 14:21:18 -0400 Subject: Problem with ohci1394/sbp2 From: Julian Blake Kongslie To: bcollins@debian.org Cc: linux-kernel , linux1394-devel@lists.sourceforge.net Content-Type: text/plain Message-Id: <1063909274.1253.12.camel@festa.omgwallhack.org> Mime-Version: 1.0 X-Mailer: Ximian Evolution 1.4.4 Date: Thu, 18 Sep 2003 11:21:14 -0700 Content-Transfer-Encoding: 7bit Sender: linux-kernel-owner@vger.kernel.org X-Mailing-List: linux-kernel@vger.kernel.org I'm on 2.6.0-test5, and have been having this problem since at least -test3. Whenever I plug in my external drive enclosure, I get this: Sep 18 11:00:14 festa kernel: ieee1394: Node changed: 0-00:1023 -> 0-01:1023 Sep 18 11:00:14 festa kernel: SCSI subsystem initialized Sep 18 11:00:14 festa kernel: sbp2: $Rev: 1034 $ Ben Collins Sep 18 11:00:14 festa kernel: scsi0 : SCSI emulation for IEEE-1394 SBP-2 Devices Sep 18 11:00:14 festa ntpd[1009]: kernel time discipline status change 41 Sep 18 11:00:15 festa ieee1394.agent[1231]: ... can't load module sbp2 Sep 18 11:00:15 festa ieee1394.agent[1231]: missing kernel or user mode driver sbp2 Sep 18 11:00:15 festa kernel: ieee1394: sbp2: Logged into SBP-2 device Sep 18 11:00:15 festa kernel: arch/i386/kernel/semaphore.c:84: spin_is_locked on uninitialized spinlock de1d98b8. Sep 18 11:00:15 festa kernel: Unable to handle kernel paging request at virtual address 6b6b6b6b Sep 18 11:00:15 festa kernel: printing eip: Sep 18 11:00:15 festa kernel: c02d2d79 Sep 18 11:00:15 festa kernel: *pde = 00000000 Sep 18 11:00:15 festa kernel: Oops: 0000 [#1] Sep 18 11:00:15 festa kernel: CPU: 0 Sep 18 11:00:15 festa kernel: EIP: 0060:[] Not tainted Sep 18 11:00:15 festa kernel: EFLAGS: 00010097 Sep 18 11:00:15 festa kernel: EIP is at vsnprintf+0x319/0x450 Sep 18 11:00:16 festa kernel: eax: 6b6b6b6b ebx: 0000000a ecx: 6b6b6b6b edx: fffffffe Sep 18 11:00:16 festa kernel: esi: c05db56b edi: 00000000 ebp: de0b7d74 esp: de0b7d3c Sep 18 11:00:16 festa kernel: ds: 007b es: 007b ss: 0068 Sep 18 11:00:16 festa kernel: Process modprobe (pid: 1241, threadinfo=de0b6000 task=de0caf90) Sep 18 11:00:16 festa kernel: Stack: c05db55d c05db93f 00000054 00000000 0000000a ffffffff 00000001 00000002 Sep 18 11:00:16 festa kernel: ffffffff ffffffff c05db93f c05db540 00000046 0000019b de0b7dc4 c012caa0 Sep 18 11:00:16 festa kernel: c05db540 00000400 c04bdbb2 de0b7ddc 00000086 ddf046ac de1d9880 00000004 Sep 18 11:00:16 festa kernel: Call Trace: Sep 18 11:00:16 festa kernel: [] printk+0x170/0x3f0 Sep 18 11:00:16 festa kernel: [] dma_trm_flush+0x12c/0x180 [ohci1394] Sep 18 11:00:16 festa kernel: [] __down+0x1e1/0x350 Sep 18 11:00:16 festa kernel: [] default_wake_function+0x0/0x30 Sep 18 11:00:16 festa kernel: [] hpsb_send_packet+0xa2/0x1b0 [ieee1394] Sep 18 11:00:16 festa kernel: [] __down_failed+0xb/0x14 Sep 18 11:00:16 festa kernel: [] .text.lock.sbp2+0x5/0x35 [sbp2] Sep 18 11:00:16 festa kernel: [] sbp2_start_device+0x210/0x3f0 [sbp2] Sep 18 11:00:16 festa kernel: [] sbp2_start_ud+0x115/0x160 [sbp2] Sep 18 11:00:16 festa kernel: [] sbp2_probe+0x38/0x50 [sbp2] Sep 18 11:00:16 festa kernel: [] bus_match+0x3d/0x70 Sep 18 11:00:16 festa kernel: [] driver_attach+0x70/0xb0 Sep 18 11:00:16 festa kernel: [] bus_add_driver+0xa5/0xc0 Sep 18 11:00:16 festa kernel: [] driver_register+0x88/0x90 Sep 18 11:00:16 festa kernel: [] hpsb_register_protocol+0x17/0x30 [ieee1394] Sep 18 11:00:16 festa kernel: [] sbp2_module_init+0x7f/0xab [sbp2] Sep 18 11:00:16 festa kernel: [] sys_init_module+0x1e7/0x3c0 Sep 18 11:00:16 festa kernel: [] syscall_call+0x7/0xb Sep 18 11:00:16 festa kernel: Sep 18 11:00:16 festa kernel: Code: 80 38 00 74 07 40 4a 83 fa ff 75 f4 29 c8 83 e7 10 89 c3 75 Sep 18 11:00:16 festa kernel: <6>note: modprobe[1241] exited with preempt_count 2 Sep 18 11:00:27 festa kernel: Slab corruption: start=de1d9880, expend=de1d98f7, problemat=de1d98c0 Sep 18 11:00:27 festa kernel: Last user: [](free_hpsb_packet+0x2c/0x40 [ieee1394]) Sep 18 11:00:27 festa kernel: Data: ****************************************************************6A ******************************************************A5 Sep 18 11:00:27 festa kernel: Next: 71 F0 2C .AC 61 AB EA 71 F0 2C ..................... Sep 18 11:00:27 festa kernel: slab error in check_poison_obj(): cache `hpsb_packet': object was modified after freeing Sep 18 11:00:27 festa kernel: Call Trace: Sep 18 11:00:27 festa kernel: [] check_poison_obj+0x17c/0x1d0 Sep 18 11:00:27 festa kernel: [] slab_destroy+0x1b0/0x1c0 Sep 18 11:00:27 festa kernel: [] reap_timer_fnc+0x211/0x4b0 Sep 18 11:00:27 festa kernel: [] reap_timer_fnc+0x0/0x4b0 Sep 18 11:00:27 festa kernel: [] run_timer_softirq+0x196/0x460 Sep 18 11:00:27 festa kernel: [] timer_interrupt+0x8f/0x270 Sep 18 11:00:27 festa kernel: [] e100intr+0x26a/0x330 Sep 18 11:00:27 festa kernel: [] do_softirq+0xab/0xb0 Sep 18 11:00:27 festa kernel: [] do_IRQ+0x255/0x3b0 Sep 18 11:00:27 festa kernel: [] common_interrupt+0x18/0x20 Sep 18 11:00:27 festa kernel: [] probe_irq_off+0x8b/0x1c0 Sep 18 11:00:27 festa kernel: [] apm_bios_call_simple+0x72/0xd0 Sep 18 11:00:27 festa kernel: [] apm_do_idle+0x25/0x80 Sep 18 11:00:27 festa kernel: [] apm_cpu_idle+0xa2/0x150 Sep 18 11:00:27 festa kernel: [] _stext+0x0/0x100 Sep 18 11:00:27 festa kernel: [] cpu_idle+0x34/0x40 Sep 18 11:00:27 festa kernel: [] start_kernel+0x1fa/0x280 Sep 18 11:00:27 festa kernel: [] unknown_bootoption+0x0/0x110 Sep 18 11:00:27 festa kernel: Sep 18 11:00:54 festa kernel: Debug: sleeping function called from invalid context at kernel/sched.c:1516 Sep 18 11:00:54 festa kernel: Call Trace: Sep 18 11:00:54 festa kernel: [] __might_sleep+0x61/0x80 Sep 18 11:00:54 festa kernel: [] wait_for_completion+0x20/0x320 Sep 18 11:00:54 festa kernel: [] kill_proc_info+0x40/0x60 Sep 18 11:00:54 festa kernel: [] nodemgr_remove_host+0x57/0xa0 [ieee1394] Sep 18 11:00:54 festa kernel: [] highlevel_remove_host+0x8c/0xb0 [ieee1394] Sep 18 11:00:54 festa kernel: [] ohci1394_pci_remove+0x45/0x340 [ohci1394] Sep 18 11:00:54 festa kernel: [] pci_device_remove+0x3b/0x40 Sep 18 11:00:54 festa kernel: [] device_release_driver+0x66/0x70 Sep 18 11:00:54 festa kernel: [] driver_detach+0x2b/0x40 Sep 18 11:00:54 festa kernel: [] bus_remove_driver+0x58/0x90 Sep 18 11:00:54 festa kernel: [] driver_unregister+0x1a/0x45 Sep 18 11:00:54 festa kernel: [] pci_unregister_driver+0x17/0x30 Sep 18 11:00:54 festa kernel: [] ohci1394_cleanup+0x12/0x16 [ohci1394] Sep 18 11:00:54 festa kernel: [] sys_delete_module+0x129/0x1b0 Sep 18 11:00:54 festa kernel: [] sys_munmap+0x58/0x80 Sep 18 11:00:54 festa kernel: [] syscall_call+0x7/0xb Sep 18 11:00:54 festa kernel: Sep 18 11:00:54 festa kernel: bad: scheduling while atomic! Sep 18 11:00:54 festa kernel: Call Trace: Sep 18 11:00:54 festa kernel: [] schedule+0x729/0x730 Sep 18 11:00:54 festa kernel: [] dump_stack+0x1e/0x30 Sep 18 11:00:54 festa kernel: [] __might_sleep+0x61/0x80 Sep 18 11:00:54 festa kernel: [] wait_for_completion+0x140/0x320 Sep 18 11:00:54 festa kernel: [] default_wake_function+0x0/0x30 Sep 18 11:00:54 festa kernel: [] default_wake_function+0x0/0x30 Sep 18 11:00:54 festa kernel: [] kill_proc_info+0x40/0x60 Sep 18 11:00:54 festa kernel: [] nodemgr_remove_host+0x57/0xa0 [ieee1394] Sep 18 11:00:54 festa kernel: [] highlevel_remove_host+0x8c/0xb0 [ieee1394] Sep 18 11:00:54 festa kernel: [] ohci1394_pci_remove+0x45/0x340 [ohci1394] Sep 18 11:00:54 festa kernel: [] pci_device_remove+0x3b/0x40 Sep 18 11:00:54 festa kernel: [] device_release_driver+0x66/0x70 Sep 18 11:00:54 festa kernel: [] driver_detach+0x2b/0x40 Sep 18 11:00:54 festa kernel: [] bus_remove_driver+0x58/0x90 Sep 18 11:00:54 festa kernel: [] driver_unregister+0x1a/0x45 Sep 18 11:00:54 festa kernel: [] pci_unregister_driver+0x17/0x30 Sep 18 11:00:54 festa kernel: [] ohci1394_cleanup+0x12/0x16 [ohci1394] Sep 18 11:00:54 festa kernel: [] sys_delete_module+0x129/0x1b0 Sep 18 11:00:54 festa kernel: [] sys_munmap+0x58/0x80 Sep 18 11:00:54 festa kernel: [] syscall_call+0x7/0xb Sep 18 11:00:54 festa kernel: Sep 18 11:01:06 festa kernel: Debug: sleeping function called from invalid context at include/asm/uaccess.h:473 Sep 18 11:01:06 festa kernel: Call Trace: Sep 18 11:01:06 festa kernel: [] __might_sleep+0x61/0x80 Sep 18 11:01:06 festa kernel: [] save_v86_state+0x69/0x210 Sep 18 11:01:06 festa kernel: [] filemap_nopage+0x28b/0x310 Sep 18 11:01:06 festa kernel: [] handle_vm86_fault+0xb7/0xa10 Sep 18 11:01:06 festa kernel: [] do_no_page+0x2ae/0x610 Sep 18 11:01:06 festa kernel: [] do_general_protection+0x0/0xa0 Sep 18 11:01:06 festa kernel: [] error_code+0x2d/0x38 Sep 18 11:01:06 festa kernel: [] syscall_call+0x7/0xb I cannot access the drive - it is not shown in /proc/scsi/scsi, there are no devices for it in /dev/scsi. I am running without ACPI, without APIC, with APM, on a single-processor Intel Pentium 3 machine. This drive works perfectly in 2.4.22, and in Windows. I can provide any information you might need, and I'm willing to test patches or wild guesses. Please CC me with replies.