2006-02-19 19:38:04

by Subodh Shrivastava

[permalink] [raw]
Subject: ieee1394 failed to work after S3 resume.

Hi,

Suspend to Ram works fine here with 2.6.16-rc3 kernel except ieee1394
fails to resume properly.

2.6.16-rc3 #4 PREEMPT Sun Feb 19 18:13:07 GMT 2006 i686 Intel(R)
Pentium(R) M processor 1300MHz GNU/Linux
.config is available on request.

Feb 19 18:17:22 [kernel] ieee1394: sbp2: Logged into SBP-2 device
Feb 19 18:17:22 [kernel] ieee1394: Node 0-00:1023: Max speed [S400] -
Max payload [2048]
Feb 19 18:17:22 [kernel] Vendor: PLEXTOR Model: DVDR PX-750A Rev: 1.02
Feb 19 18:17:22 [kernel] Type: CD-ROM
ANSI SCSI revision: 00
Feb 19 18:17:22 [kernel] sr0: scsi3-mmc drive: 40x/40x writer dvd-ram
cd/rw xa/form2 cdda tray
Feb 19 18:17:22 [kernel] sr 0:0:0:0: Attached scsi CD-ROM sr0
Feb 19 18:17:22 [kernel] sr 0:0:0:0: Attached scsi generic sg0 type 5
Feb 19 18:17:22 [kernel] Adding 499928k swap on /dev/hda9.
Priority:-1 extents:1 across:499928k
Feb 19 18:17:22 [kernel] EXT3 FS on hda8, internal journal
Feb 19 18:17:22 [kernel] ieee80211_crypt: registered algorithm 'WEP'
Feb 19 18:17:22 [kernel] ieee80211_crypt: registered algorithm 'CCMP'
Feb 19 18:17:22 [kernel] ipw2100: Intel(R) PRO/Wireless 2100 Network
Driver, 1.1.3
Feb 19 18:17:22 [kernel] ipw2100: Copyright(c) 2003-2005 Intel Corporation
Feb 19 18:17:22 [kernel] ACPI: PCI Interrupt Link [LNKE] enabled at IRQ 11
Feb 19 18:17:22 [kernel] PCI: setting IRQ 11 as level-triggered
Feb 19 18:17:22 [kernel] ACPI: PCI Interrupt 0000:02:04.0[A] -> Link
[LNKE] -> GSI 11 (level, low) -> IRQ 11
Feb 19 18:17:22 [kernel] ipw2100: Detected Intel PRO/Wireless 2100
Network Connection
Feb 19 18:17:22 [kernel] kjournald starting. Commit interval 5 seconds
Feb 19 18:17:22 [kernel] EXT3 FS on hda6, internal journal
Feb 19 18:17:22 [kernel] EXT3-fs: mounted filesystem with ordered data mode.
Feb 19 18:17:22 [kernel] kjournald starting. Commit interval 5 seconds
Feb 19 18:17:22 [kernel] EXT3 FS on hda7, internal journal
Feb 19 18:17:22 [kernel] EXT3-fs: mounted filesystem with ordered data mode.
Feb 19 18:17:22 [kernel] ACPI: PCI Interrupt 0000:00:1f.5[B] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:17:22 [kernel] PCI: Setting latency timer of device 0000:00:1f.5 to 64
Feb 19 18:17:22 [kernel] intel8x0_measure_ac97_clock: measured 55491 usecs
Feb 19 18:17:22 [kernel] intel8x0: clocking to 48000
Feb 19 18:18:03 [kernel] ACPI: PCI interrupt for device 0000:00:1f.5 disabled
Feb 19 18:18:11 [kernel] Stopping tasks:
===============================================================|
Feb 19 18:18:11 [kernel] ACPI: PCI interrupt for device 0000:02:04.0 disabled
Feb 19 18:18:11 [kernel] ACPI: PCI interrupt for device 0000:00:1d.7 disabled
Feb 19 18:18:11 [kernel] ACPI: PCI interrupt for device 0000:00:1d.2 disabled
Feb 19 18:18:11 [kernel] ACPI: PCI interrupt for device 0000:00:1d.1 disabled
Feb 19 18:18:11 [kernel] ACPI: PCI interrupt for device 0000:00:1d.0 disabled
Feb 19 18:18:11 [kernel] Intel machine check architecture supported.
Feb 19 18:18:11 [kernel] Intel machine check reporting enabled on CPU#0.
Feb 19 18:18:11 [kernel] Back to C!
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:00:1d.0[A] -> Link
[LNKA] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] PCI: Setting latency timer of device 0000:00:1d.0 to 64
Feb 19 18:18:11 [kernel] usb usb2: root hub lost power or was reset
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:00:1d.1[B] -> Link
[LNKD] -> GSI 5 (level, low) -> IRQ 5
Feb 19 18:18:11 [kernel] PCI: Setting latency timer of device 0000:00:1d.1 to 64
Feb 19 18:18:11 [kernel] usb usb3: root hub lost power or was reset
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:00:1d.2[C] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] PCI: Setting latency timer of device 0000:00:1d.2 to 64
Feb 19 18:18:11 [kernel] usb usb4: root hub lost power or was reset
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:00:1d.7[D] -> Link
[LNKH] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] PCI: Setting latency timer of device 0000:00:1d.7 to 64
Feb 19 18:18:11 [kernel] PCI: Setting latency timer of device 0000:00:1e.0 to 64
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:00:1f.1[A] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:01:00.0[A] -> Link
[LNKA] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] PCI: Enabling device 0000:02:04.0 (0000 -> 0002)
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:02:04.0[A] -> Link
[LNKE] -> GSI 11 (level, low) -> IRQ 11
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:02:06.0[A] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:02:06.1[A] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] PCI: Enabling device 0000:02:07.0 (0000 -> 0002)
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:02:07.0[A] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] pnp: Failed to activate device 00:06.
Feb 19 18:18:11 [kernel] pnp: Failed to activate device 00:07.
Feb 19 18:18:11 [kernel] Restarting tasks...<6>usb 3-1: USB
disconnect, address 2
Feb 19 18:18:11 [kernel] done
Feb 19 18:18:11 [kernel] usb 3-1: new low speed USB device using
uhci_hcd and address 3
Feb 19 18:18:11 [kernel] usb 3-1: configuration #1 chosen from 1 choice
Feb 19 18:18:11 [kernel] input: Logitech USB Receiver as /class/input/input4
Feb 19 18:18:11 [kernel] input: USB HID v1.10 Keyboard [Logitech USB
Receiver] on usb-0000:00:1d.1-1
Feb 19 18:18:11 [kernel] input: Logitech USB Receiver as /class/input/input5
Feb 19 18:18:11 [kernel] input: USB HID v1.10 Mouse [Logitech USB
Receiver] on usb-0000:00:1d.1-1
Feb 19 18:18:12 [kernel] PCI: Setting latency timer of device 0000:00:1f.5 to 64
Feb 19 18:18:13 [kernel] intel8x0_measure_ac97_clock: measured 55410 usecs
Feb 19 18:18:13 [kernel] intel8x0: clocking to 48000
Feb 19 18:18:31 [kernel] agpgart: Found an AGP 2.0 compliant device at
0000:00:00.0.
Feb 19 18:18:31 [kernel] agpgart: Putting AGP V2 device at
0000:00:00.0 into 4x mode
Feb 19 18:18:31 [kernel] agpgart: Putting AGP V2 device at
0000:01:00.0 into 4x mode
Feb 19 18:18:31 [kernel] [drm] Loading R200 Microcode
Feb 19 18:18:41 [kernel] ieee1394: sbp2: aborting sbp2 command
Feb 19 18:18:41 [kernel] sr 0:0:0:0:
Feb 19 18:18:41 [kernel] command: cdb[0]=0x0: 00 00 00 00 00 00
Feb 19 18:18:51 [kernel] ieee1394: sbp2: aborting sbp2 command
Feb 19 18:18:51 [kernel] sr 0:0:0:0:
Feb 19 18:18:51 [kernel] command: cdb[0]=0x0: 00 00 00 00 00 00
Feb 19 18:18:51 [kernel] ieee1394: sbp2: reset requested
Feb 19 18:18:51 [kernel] ieee1394: sbp2: Generating sbp2 fetch agent reset
Feb 19 18:19:01 [kernel] ieee1394: sbp2: aborting sbp2 command
Feb 19 18:19:01 [kernel] sr 0:0:0:0:
Feb 19 18:19:01 [kernel] command: cdb[0]=0x0: 00 00 00 00 00 00
Feb 19 18:19:01 [kernel] sr 0:0:0:0: scsi: Device offlined - not ready
after error recovery
Feb 19 18:19:01 [kernel] sr 0:0:0:0: rejecting I/O to offline device
Feb 19 18:19:41 [kernel] Stopping tasks:
====================================================|
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:02:04.0 disabled
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:00:1f.5 disabled
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:00:1d.7 disabled
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:00:1d.2 disabled
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:00:1d.1 disabled
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:00:1d.0 disabled
Feb 19 18:19:41 [kernel] Intel machine check architecture supported.
Feb 19 18:19:41 [kernel] Intel machine check reporting enabled on CPU#0.
Feb 19 18:19:41 [kernel] Back to C!
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1d.0[A] -> Link
[LNKA] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1d.0 to 64
Feb 19 18:19:41 [kernel] usb usb2: root hub lost power or was reset
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1d.1[B] -> Link
[LNKD] -> GSI 5 (level, low) -> IRQ 5
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1d.1 to 64
Feb 19 18:19:41 [kernel] usb usb3: root hub lost power or was reset
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1d.2[C] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1d.2 to 64
Feb 19 18:19:41 [kernel] usb usb4: root hub lost power or was reset
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1d.7[D] -> Link
[LNKH] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1d.7 to 64
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1e.0 to 64
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1f.1[A] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1f.5[B] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1f.5 to 64
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:01:00.0[A] -> Link
[LNKA] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Enabling device 0000:02:04.0 (0000 -> 0002)
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:02:04.0[A] -> Link
[LNKE] -> GSI 11 (level, low) -> IRQ 11
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:02:06.0[A] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:02:06.1[A] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Enabling device 0000:02:07.0 (0000 -> 0002)
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:02:07.0[A] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] pnp: Failed to activate device 00:06.
Feb 19 18:19:41 [kernel] pnp: Failed to activate device 00:07.
Feb 19 18:19:41 [kernel] Restarting tasks...<6>usb 3-1: USB
disconnect, address 3
Feb 19 18:19:41 [kernel] usb 3-1: new low speed USB device using
uhci_hcd and address 4
Feb 19 18:19:41 [kernel] done
Feb 19 18:19:41 [kernel] usb 3-1: configuration #1 chosen from 1 choice
Feb 19 18:19:41 [kernel] input: Logitech USB Receiver as /class/input/input6
Feb 19 18:19:41 [kernel] input: USB HID v1.10 Keyboard [Logitech USB
Receiver] on usb-0000:00:1d.1-1
Feb 19 18:19:41 [kernel] input: Logitech USB Receiver as /class/input/input7
Feb 19 18:19:41 [kernel] input: USB HID v1.10 Mouse [Logitech USB
Receiver] on usb-0000:00:1d.1-1
Feb 19 18:19:42 [kernel] agpgart: Found an AGP 2.0 compliant device at
0000:00:00.0.
Feb 19 18:19:42 [kernel] agpgart: Putting AGP V2 device at
0000:00:00.0 into 4x mode

Feb 19 18:17:22 [kernel] ieee1394: sbp2: Logged into SBP-2 device
Feb 19 18:17:22 [kernel] ieee1394: Node 0-00:1023: Max speed [S400] -
Max payload [2048]
Feb 19 18:17:22 [kernel] Vendor: PLEXTOR Model: DVDR PX-750A Rev: 1.02
Feb 19 18:17:22 [kernel] Type: CD-ROM
ANSI SCSI revision: 00
Feb 19 18:17:22 [kernel] sr0: scsi3-mmc drive: 40x/40x writer dvd-ram
cd/rw xa/form2 cdda tray
Feb 19 18:17:22 [kernel] sr 0:0:0:0: Attached scsi CD-ROM sr0
Feb 19 18:17:22 [kernel] sr 0:0:0:0: Attached scsi generic sg0 type 5
Feb 19 18:17:22 [kernel] Adding 499928k swap on /dev/hda9.
Priority:-1 extents:1 across:499928k
Feb 19 18:17:22 [kernel] EXT3 FS on hda8, internal journal
Feb 19 18:17:22 [kernel] ieee80211_crypt: registered algorithm 'WEP'
Feb 19 18:17:22 [kernel] ieee80211_crypt: registered algorithm 'CCMP'
Feb 19 18:17:22 [kernel] ipw2100: Intel(R) PRO/Wireless 2100 Network
Driver, 1.1.3
Feb 19 18:17:22 [kernel] ipw2100: Copyright(c) 2003-2005 Intel Corporation
Feb 19 18:17:22 [kernel] ACPI: PCI Interrupt Link [LNKE] enabled at IRQ 11
Feb 19 18:17:22 [kernel] PCI: setting IRQ 11 as level-triggered
Feb 19 18:17:22 [kernel] ACPI: PCI Interrupt 0000:02:04.0[A] -> Link
[LNKE] -> GSI 11 (level, low) -> IRQ 11
Feb 19 18:17:22 [kernel] ipw2100: Detected Intel PRO/Wireless 2100
Network Connection
Feb 19 18:17:22 [kernel] kjournald starting. Commit interval 5 seconds
Feb 19 18:17:22 [kernel] EXT3 FS on hda6, internal journal
Feb 19 18:17:22 [kernel] EXT3-fs: mounted filesystem with ordered data mode.
Feb 19 18:17:22 [kernel] kjournald starting. Commit interval 5 seconds
Feb 19 18:17:22 [kernel] EXT3 FS on hda7, internal journal
Feb 19 18:17:22 [kernel] EXT3-fs: mounted filesystem with ordered data mode.
Feb 19 18:17:22 [kernel] ACPI: PCI Interrupt 0000:00:1f.5[B] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:17:22 [kernel] PCI: Setting latency timer of device 0000:00:1f.5 to 64
Feb 19 18:17:22 [kernel] intel8x0_measure_ac97_clock: measured 55491 usecs
Feb 19 18:17:22 [kernel] intel8x0: clocking to 48000
Feb 19 18:18:03 [kernel] ACPI: PCI interrupt for device 0000:00:1f.5 disabled
Feb 19 18:18:11 [kernel] Stopping tasks:
===============================================================|
Feb 19 18:18:11 [kernel] ACPI: PCI interrupt for device 0000:02:04.0 disabled
Feb 19 18:18:11 [kernel] ACPI: PCI interrupt for device 0000:00:1d.7 disabled
Feb 19 18:18:11 [kernel] ACPI: PCI interrupt for device 0000:00:1d.2 disabled
Feb 19 18:18:11 [kernel] ACPI: PCI interrupt for device 0000:00:1d.1 disabled
Feb 19 18:18:11 [kernel] ACPI: PCI interrupt for device 0000:00:1d.0 disabled
Feb 19 18:18:11 [kernel] Intel machine check architecture supported.
Feb 19 18:18:11 [kernel] Intel machine check reporting enabled on CPU#0.
Feb 19 18:18:11 [kernel] Back to C!
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:00:1d.0[A] -> Link
[LNKA] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] PCI: Setting latency timer of device 0000:00:1d.0 to 64
Feb 19 18:18:11 [kernel] usb usb2: root hub lost power or was reset
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:00:1d.1[B] -> Link
[LNKD] -> GSI 5 (level, low) -> IRQ 5
Feb 19 18:18:11 [kernel] PCI: Setting latency timer of device 0000:00:1d.1 to 64
Feb 19 18:18:11 [kernel] usb usb3: root hub lost power or was reset
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:00:1d.2[C] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] PCI: Setting latency timer of device 0000:00:1d.2 to 64
Feb 19 18:18:11 [kernel] usb usb4: root hub lost power or was reset
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:00:1d.7[D] -> Link
[LNKH] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] PCI: Setting latency timer of device 0000:00:1d.7 to 64
Feb 19 18:18:11 [kernel] PCI: Setting latency timer of device 0000:00:1e.0 to 64
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:00:1f.1[A] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:01:00.0[A] -> Link
[LNKA] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] PCI: Enabling device 0000:02:04.0 (0000 -> 0002)
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:02:04.0[A] -> Link
[LNKE] -> GSI 11 (level, low) -> IRQ 11
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:02:06.0[A] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:02:06.1[A] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] PCI: Enabling device 0000:02:07.0 (0000 -> 0002)
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:02:07.0[A] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] pnp: Failed to activate device 00:06.
Feb 19 18:18:11 [kernel] pnp: Failed to activate device 00:07.
Feb 19 18:18:11 [kernel] Restarting tasks...<6>usb 3-1: USB
disconnect, address 2
Feb 19 18:18:11 [kernel] done
Feb 19 18:18:11 [kernel] usb 3-1: new low speed USB device using
uhci_hcd and address 3
Feb 19 18:18:11 [kernel] usb 3-1: configuration #1 chosen from 1 choice
Feb 19 18:18:11 [kernel] input: Logitech USB Receiver as /class/input/input4
Feb 19 18:18:11 [kernel] input: USB HID v1.10 Keyboard [Logitech USB
Receiver] on usb-0000:00:1d.1-1
Feb 19 18:18:11 [kernel] input: Logitech USB Receiver as /class/input/input5
Feb 19 18:18:11 [kernel] input: USB HID v1.10 Mouse [Logitech USB
Receiver] on usb-0000:00:1d.1-1
Feb 19 18:18:12 [kernel] PCI: Setting latency timer of device 0000:00:1f.5 to 64
Feb 19 18:18:13 [kernel] intel8x0_measure_ac97_clock: measured 55410 usecs
Feb 19 18:18:13 [kernel] intel8x0: clocking to 48000
Feb 19 18:18:31 [kernel] agpgart: Found an AGP 2.0 compliant device at
0000:00:00.0.
Feb 19 18:18:31 [kernel] agpgart: Putting AGP V2 device at
0000:00:00.0 into 4x mode
Feb 19 18:18:31 [kernel] agpgart: Putting AGP V2 device at
0000:01:00.0 into 4x mode
Feb 19 18:18:31 [kernel] [drm] Loading R200 Microcode
Feb 19 18:18:41 [kernel] ieee1394: sbp2: aborting sbp2 command
Feb 19 18:18:41 [kernel] sr 0:0:0:0:
Feb 19 18:18:41 [kernel] command: cdb[0]=0x0: 00 00 00 00 00 00
Feb 19 18:18:51 [kernel] ieee1394: sbp2: aborting sbp2 command
Feb 19 18:18:51 [kernel] sr 0:0:0:0:
Feb 19 18:18:51 [kernel] command: cdb[0]=0x0: 00 00 00 00 00 00
Feb 19 18:18:51 [kernel] ieee1394: sbp2: reset requested
Feb 19 18:18:51 [kernel] ieee1394: sbp2: Generating sbp2 fetch agent reset
Feb 19 18:19:01 [kernel] ieee1394: sbp2: aborting sbp2 command
Feb 19 18:19:01 [kernel] sr 0:0:0:0:
Feb 19 18:19:01 [kernel] command: cdb[0]=0x0: 00 00 00 00 00 00
Feb 19 18:19:01 [kernel] sr 0:0:0:0: scsi: Device offlined - not ready
after error recovery
Feb 19 18:19:01 [kernel] sr 0:0:0:0: rejecting I/O to offline device
Feb 19 18:19:41 [kernel] Stopping tasks:
====================================================|
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:02:04.0 disabled
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:00:1f.5 disabled
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:00:1d.7 disabled
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:00:1d.2 disabled
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:00:1d.1 disabled
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:00:1d.0 disabled
Feb 19 18:19:41 [kernel] Intel machine check architecture supported.
Feb 19 18:19:41 [kernel] Intel machine check reporting enabled on CPU#0.
Feb 19 18:19:41 [kernel] Back to C!
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1d.0[A] -> Link
[LNKA] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1d.0 to 64
Feb 19 18:19:41 [kernel] usb usb2: root hub lost power or was reset
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1d.1[B] -> Link
[LNKD] -> GSI 5 (level, low) -> IRQ 5
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1d.1 to 64
Feb 19 18:19:41 [kernel] usb usb3: root hub lost power or was reset
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1d.2[C] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1d.2 to 64
Feb 19 18:19:41 [kernel] usb usb4: root hub lost power or was reset
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1d.7[D] -> Link
[LNKH] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1d.7 to 64
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1e.0 to 64
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1f.1[A] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1f.5[B] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1f.5 to 64
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:01:00.0[A] -> Link
[LNKA] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Enabling device 0000:02:04.0 (0000 -> 0002)
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:02:04.0[A] -> Link
[LNKE] -> GSI 11 (level, low) -> IRQ 11
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:02:06.0[A] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:02:06.1[A] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Enabling device 0000:02:07.0 (0000 -> 0002)
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:02:07.0[A] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] pnp: Failed to activate device 00:06.
Feb 19 18:19:41 [kernel] pnp: Failed to activate device 00:07.
Feb 19 18:19:41 [kernel] Restarting tasks...<6>usb 3-1: USB
disconnect, address 3
Feb 19 18:19:41 [kernel] usb 3-1: new low speed USB device using
uhci_hcd and address 4
Feb 19 18:19:41 [kernel] done
Feb 19 18:19:41 [kernel] usb 3-1: configuration #1 chosen from 1 choice
Feb 19 18:19:41 [kernel] input: Logitech USB Receiver as /class/input/input6
Feb 19 18:19:41 [kernel] input: USB HID v1.10 Keyboard [Logitech USB
Receiver] on usb-0000:00:1d.1-1
Feb 19 18:19:41 [kernel] input: Logitech USB Receiver as /class/input/input7
Feb 19 18:19:41 [kernel] input: USB HID v1.10 Mouse [Logitech USB
Receiver] on usb-0000:00:1d.1-1
Feb 19 18:19:42 [kernel] agpgart: Found an AGP 2.0 compliant device at
0000:00:00.0.
Feb 19 18:19:42 [kernel] agpgart: Putting AGP V2 device at
0000:00:00.0 into 4x mode



Feb 19 18:17:22 [kernel] ieee1394: sbp2: Logged into SBP-2 device
Feb 19 18:17:22 [kernel] ieee1394: Node 0-00:1023: Max speed [S400] -
Max payload [2048]
Feb 19 18:17:22 [kernel] Vendor: PLEXTOR Model: DVDR PX-750A Rev: 1.02
Feb 19 18:17:22 [kernel] Type: CD-ROM
ANSI SCSI revision: 00
Feb 19 18:17:22 [kernel] sr0: scsi3-mmc drive: 40x/40x writer dvd-ram
cd/rw xa/form2 cdda tray
Feb 19 18:17:22 [kernel] sr 0:0:0:0: Attached scsi CD-ROM sr0
Feb 19 18:17:22 [kernel] sr 0:0:0:0: Attached scsi generic sg0 type 5
Feb 19 18:17:22 [kernel] Adding 499928k swap on /dev/hda9.
Priority:-1 extents:1 across:499928k
Feb 19 18:17:22 [kernel] EXT3 FS on hda8, internal journal
Feb 19 18:17:22 [kernel] ieee80211_crypt: registered algorithm 'WEP'
Feb 19 18:17:22 [kernel] ieee80211_crypt: registered algorithm 'CCMP'
Feb 19 18:17:22 [kernel] ipw2100: Intel(R) PRO/Wireless 2100 Network
Driver, 1.1.3
Feb 19 18:17:22 [kernel] ipw2100: Copyright(c) 2003-2005 Intel Corporation
Feb 19 18:17:22 [kernel] ACPI: PCI Interrupt Link [LNKE] enabled at IRQ 11
Feb 19 18:17:22 [kernel] PCI: setting IRQ 11 as level-triggered
Feb 19 18:17:22 [kernel] ACPI: PCI Interrupt 0000:02:04.0[A] -> Link
[LNKE] -> GSI 11 (level, low) -> IRQ 11
Feb 19 18:17:22 [kernel] ipw2100: Detected Intel PRO/Wireless 2100
Network Connection
Feb 19 18:17:22 [kernel] kjournald starting. Commit interval 5 seconds
Feb 19 18:17:22 [kernel] EXT3 FS on hda6, internal journal
Feb 19 18:17:22 [kernel] EXT3-fs: mounted filesystem with ordered data mode.
Feb 19 18:17:22 [kernel] kjournald starting. Commit interval 5 seconds
Feb 19 18:17:22 [kernel] EXT3 FS on hda7, internal journal
Feb 19 18:17:22 [kernel] EXT3-fs: mounted filesystem with ordered data mode.
Feb 19 18:17:22 [kernel] ACPI: PCI Interrupt 0000:00:1f.5[B] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:17:22 [kernel] PCI: Setting latency timer of device 0000:00:1f.5 to 64
Feb 19 18:17:22 [kernel] intel8x0_measure_ac97_clock: measured 55491 usecs
Feb 19 18:17:22 [kernel] intel8x0: clocking to 48000
Feb 19 18:18:03 [kernel] ACPI: PCI interrupt for device 0000:00:1f.5 disabled
Feb 19 18:18:11 [kernel] Stopping tasks:
===============================================================|
Feb 19 18:18:11 [kernel] ACPI: PCI interrupt for device 0000:02:04.0 disabled
Feb 19 18:18:11 [kernel] ACPI: PCI interrupt for device 0000:00:1d.7 disabled
Feb 19 18:18:11 [kernel] ACPI: PCI interrupt for device 0000:00:1d.2 disabled
Feb 19 18:18:11 [kernel] ACPI: PCI interrupt for device 0000:00:1d.1 disabled
Feb 19 18:18:11 [kernel] ACPI: PCI interrupt for device 0000:00:1d.0 disabled
Feb 19 18:18:11 [kernel] Intel machine check architecture supported.
Feb 19 18:18:11 [kernel] Intel machine check reporting enabled on CPU#0.
Feb 19 18:18:11 [kernel] Back to C!
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:00:1d.0[A] -> Link
[LNKA] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] PCI: Setting latency timer of device 0000:00:1d.0 to 64
Feb 19 18:18:11 [kernel] usb usb2: root hub lost power or was reset
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:00:1d.1[B] -> Link
[LNKD] -> GSI 5 (level, low) -> IRQ 5
Feb 19 18:18:11 [kernel] PCI: Setting latency timer of device 0000:00:1d.1 to 64
Feb 19 18:18:11 [kernel] usb usb3: root hub lost power or was reset
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:00:1d.2[C] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] PCI: Setting latency timer of device 0000:00:1d.2 to 64
Feb 19 18:18:11 [kernel] usb usb4: root hub lost power or was reset
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:00:1d.7[D] -> Link
[LNKH] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] PCI: Setting latency timer of device 0000:00:1d.7 to 64
Feb 19 18:18:11 [kernel] PCI: Setting latency timer of device 0000:00:1e.0 to 64
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:00:1f.1[A] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:01:00.0[A] -> Link
[LNKA] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] PCI: Enabling device 0000:02:04.0 (0000 -> 0002)
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:02:04.0[A] -> Link
[LNKE] -> GSI 11 (level, low) -> IRQ 11
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:02:06.0[A] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:02:06.1[A] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] PCI: Enabling device 0000:02:07.0 (0000 -> 0002)
Feb 19 18:18:11 [kernel] ACPI: PCI Interrupt 0000:02:07.0[A] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:18:11 [kernel] pnp: Failed to activate device 00:06.
Feb 19 18:18:11 [kernel] pnp: Failed to activate device 00:07.
Feb 19 18:18:11 [kernel] Restarting tasks...<6>usb 3-1: USB
disconnect, address 2
Feb 19 18:18:11 [kernel] done
Feb 19 18:18:11 [kernel] usb 3-1: new low speed USB device using
uhci_hcd and address 3
Feb 19 18:18:11 [kernel] usb 3-1: configuration #1 chosen from 1 choice
Feb 19 18:18:11 [kernel] input: Logitech USB Receiver as /class/input/input4
Feb 19 18:18:11 [kernel] input: USB HID v1.10 Keyboard [Logitech USB
Receiver] on usb-0000:00:1d.1-1
Feb 19 18:18:11 [kernel] input: Logitech USB Receiver as /class/input/input5
Feb 19 18:18:11 [kernel] input: USB HID v1.10 Mouse [Logitech USB
Receiver] on usb-0000:00:1d.1-1
Feb 19 18:18:12 [kernel] PCI: Setting latency timer of device 0000:00:1f.5 to 64
Feb 19 18:18:13 [kernel] intel8x0_measure_ac97_clock: measured 55410 usecs
Feb 19 18:18:13 [kernel] intel8x0: clocking to 48000
Feb 19 18:18:31 [kernel] agpgart: Found an AGP 2.0 compliant device at
0000:00:00.0.
Feb 19 18:18:31 [kernel] agpgart: Putting AGP V2 device at
0000:00:00.0 into 4x mode
Feb 19 18:18:31 [kernel] agpgart: Putting AGP V2 device at
0000:01:00.0 into 4x mode
Feb 19 18:18:31 [kernel] [drm] Loading R200 Microcode
Feb 19 18:18:41 [kernel] ieee1394: sbp2: aborting sbp2 command
Feb 19 18:18:41 [kernel] sr 0:0:0:0:
Feb 19 18:18:41 [kernel] command: cdb[0]=0x0: 00 00 00 00 00 00
Feb 19 18:18:51 [kernel] ieee1394: sbp2: aborting sbp2 command
Feb 19 18:18:51 [kernel] sr 0:0:0:0:
Feb 19 18:18:51 [kernel] command: cdb[0]=0x0: 00 00 00 00 00 00
Feb 19 18:18:51 [kernel] ieee1394: sbp2: reset requested
Feb 19 18:18:51 [kernel] ieee1394: sbp2: Generating sbp2 fetch agent reset
Feb 19 18:19:01 [kernel] ieee1394: sbp2: aborting sbp2 command
Feb 19 18:19:01 [kernel] sr 0:0:0:0:
Feb 19 18:19:01 [kernel] command: cdb[0]=0x0: 00 00 00 00 00 00
Feb 19 18:19:01 [kernel] sr 0:0:0:0: scsi: Device offlined - not ready
after error recovery
Feb 19 18:19:01 [kernel] sr 0:0:0:0: rejecting I/O to offline device
Feb 19 18:19:41 [kernel] Stopping tasks:
====================================================|
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:02:04.0 disabled
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:00:1f.5 disabled
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:00:1d.7 disabled
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:00:1d.2 disabled
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:00:1d.1 disabled
Feb 19 18:19:41 [kernel] ACPI: PCI interrupt for device 0000:00:1d.0 disabled
Feb 19 18:19:41 [kernel] Intel machine check architecture supported.
Feb 19 18:19:41 [kernel] Intel machine check reporting enabled on CPU#0.
Feb 19 18:19:41 [kernel] Back to C!
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1d.0[A] -> Link
[LNKA] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1d.0 to 64
Feb 19 18:19:41 [kernel] usb usb2: root hub lost power or was reset
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1d.1[B] -> Link
[LNKD] -> GSI 5 (level, low) -> IRQ 5
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1d.1 to 64
Feb 19 18:19:41 [kernel] usb usb3: root hub lost power or was reset
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1d.2[C] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1d.2 to 64
Feb 19 18:19:41 [kernel] usb usb4: root hub lost power or was reset
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1d.7[D] -> Link
[LNKH] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1d.7 to 64
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1e.0 to 64
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1f.1[A] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:00:1f.5[B] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Setting latency timer of device 0000:00:1f.5 to 64
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:01:00.0[A] -> Link
[LNKA] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Enabling device 0000:02:04.0 (0000 -> 0002)
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:02:04.0[A] -> Link
[LNKE] -> GSI 11 (level, low) -> IRQ 11
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:02:06.0[A] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:02:06.1[A] -> Link
[LNKB] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] PCI: Enabling device 0000:02:07.0 (0000 -> 0002)
Feb 19 18:19:41 [kernel] ACPI: PCI Interrupt 0000:02:07.0[A] -> Link
[LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 19 18:19:41 [kernel] pnp: Failed to activate device 00:06.
Feb 19 18:19:41 [kernel] pnp: Failed to activate device 00:07.
Feb 19 18:19:41 [kernel] Restarting tasks...<6>usb 3-1: USB
disconnect, address 3
Feb 19 18:19:41 [kernel] usb 3-1: new low speed USB device using
uhci_hcd and address 4
Feb 19 18:19:41 [kernel] done
Feb 19 18:19:41 [kernel] usb 3-1: configuration #1 chosen from 1 choice
Feb 19 18:19:41 [kernel] input: Logitech USB Receiver as /class/input/input6
Feb 19 18:19:41 [kernel] input: USB HID v1.10 Keyboard [Logitech USB
Receiver] on usb-0000:00:1d.1-1
Feb 19 18:19:41 [kernel] input: Logitech USB Receiver as /class/input/input7
Feb 19 18:19:41 [kernel] input: USB HID v1.10 Mouse [Logitech USB
Receiver] on usb-0000:00:1d.1-1
Feb 19 18:19:42 [kernel] agpgart: Found an AGP 2.0 compliant device at
0000:00:00.0.
Feb 19 18:19:42 [kernel] agpgart: Putting AGP V2 device at
0000:00:00.0 into 4x mode

--
Subodh


2006-02-20 15:59:09

by Stefan Richter

[permalink] [raw]
Subject: Re: ieee1394 failed to work after S3 resume.

Subodh Shrivastava wrote:
> Suspend to Ram works fine here with 2.6.16-rc3 kernel except ieee1394
> fails to resume properly.
...
[SCSI command timeout in sbp2 after resume; no nodemgr updates after resume]
...

AFAICT the reason is that the host adapter drivers (1394 low-level
drivers) ohci1394 and pcilynx lack proper .suspend and .resume hooks.
Such functionality seems only be present for Powermacs with Uninorth
chipset.

OHCI 1.1 table A-11 says (surprise!) that host adapters loose PCI
configuration and 1394 configuration when going into D3 state. We don't
backup and restore it yet. I could perhaps look into it in late spring
or summer.
--
Stefan Richter
-=====-=-==- --=- =-=--
http://arcgraph.de/sr/

2006-02-20 16:59:23

by Matthias Bläsing

[permalink] [raw]
Subject: Re: ieee1394 failed to work after S3 resume.

Am Montag, den 20.02.2006, 16:58 +0100 schrieb Stefan Richter:
> Subodh Shrivastava wrote:
> > Suspend to Ram works fine here with 2.6.16-rc3 kernel except ieee1394
> > fails to resume properly.
> ...
> [SCSI command timeout in sbp2 after resume; no nodemgr updates after resume]
> ...
>
> [
> I could perhaps look into it in late spring or summer.


A workaround till then is the usage of a script (I use hibernate and
have blacklisted ohci1394), that unloads ohci1394 prior to suspending
and reloading the module after resume.

HTH

Matthias

--
Matthias Bl?sing (GPG-Schl?sselkennung: A71B4BD5)
ICQ: 84617206 AIM: linuxfun81 MSN: [email protected]


Attachments:
signature.asc (189.00 B)
Dies ist ein digital signierter Nachrichtenteil

2006-02-20 21:19:33

by Stefan Richter

[permalink] [raw]
Subject: Re: ieee1394 failed to work after S3 resume.

Matthias Bl?sing wrote:
>>Subodh Shrivastava wrote:
>>>Suspend to Ram works fine here with 2.6.16-rc3 kernel except ieee1394
>>>fails to resume properly.
...
> A workaround till then is the usage of a script (I use hibernate and
> have blacklisted ohci1394), that unloads ohci1394 prior to suspending
> and reloading the module after resume.

Note, -mm contains a patch which lets sbp2 raise ohci1394's use count
while it is logged in to a target.
http://www.kernel.org/git/?p=linux/kernel/git/scjody/ieee1394.git;a=commitdiff;h=d415a9a9685578058800f2677bfeb8090fc212a1

If you run -mm or once this patch is merged, you have to adapt your
script to either unload sbp2 before ohci1394, or you have to detach sbp2
via sysfs before attempting to unload ohci1394:
# echo 1 > /sys/bus/ieee1394/drivers/sbp2/${guid}-${ud}/ignore_driver
(for each device)
The corresponding sequence after reloading ohci1394 would be
# echo 0 > /sys/bus/ieee1394/devices/${guid}-${ud}/ignore_driver
(for each device)
# echo 1 > /sys/bus/ieee1394/rescan
(once)
--
Stefan Richter
-=====-=-==- --=- =-=--
http://arcgraph.de/sr/