firewire disk problem: fast writing, but very slow reading

Hi,

Recently I bought an 160gb IDE disk and a week later a firewire case for
that disk. After putting those two together I was really amazed that
the average READ-rate to the disk was around 14 MB/s, but the average
READ-rate was only around 30KB/s. This first test was using my new iBook
with firewire.

So I tried on an pci-firewire card with VIA-chipset on another box,
exactly the same problem.

I exchanged the firewire-case because I thought it might be damaged and
got an exchange one a few days later, but the problem remains as
described, very fast writing, nearly no reading.

Another model of a firewire-disk-case worked with full speed (which I borrowed
from a friend).

Might that be a bug in the current firewire-implementation?

information about my system (the iBook, actually):

Distribution: Debian unstable, latest
Kernel: tested with 2.4.20 and 2.4.21, since 2.6.0-test* doesn't
        compiled under PPC
libraw1394 version: 0.10.0-1
Driver: OHCI

I compiled the driver with debug-support and got the following logs:

modprobe ohci1394:
Sep 12 14:16:34 [kernel] ohci1394: $Rev: 1010 $ Ben Collins <bcollins@debian.org>
Sep 12 14:16:34 [kernel] _0: Starting transmit DMA ctx=0
Sep 12 14:16:34 [kernel] ieee1394: Initiating ConfigROM request for node 0-00:1023
Sep 12 14:16:34 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:16:34 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:16:34 [kernel] ohci1394_0: Packet sent to node 63 tcode=0x0 tLabel=0x00 ack=0x11 spd=0 data=0x1F0000C0 ctx=0

connecting the firewire-case:
Sep 12 14:17:49 [kernel] ohci1394_0: IntEvent: 00020010
Sep 12 14:17:49 [kernel] ohci1394_0: IntEvent: 00010000
Sep 12 14:17:49 [kernel] ieee1394: Initiating ConfigROM request for node 0-00:1023
Sep 12 14:17:50 [kernel] ohci1394_0: IntEvent: 00020010
Sep 12 14:17:50 [kernel] ohci1394_0: IntEvent: 00010000
Sep 12 14:17:51 [kernel] ieee1394: ConfigROM quadlet transaction error for node 0-01:1023
Sep 12 14:17:51 [kernel] ieee1394: Initiating ConfigROM request for node 0-00:1023
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=1, tcode=0x4, speed=2
Sep 12 14:17:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:17:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008012
Sep 12 14:17:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x4 tLabel=0x01 ack=0x12 spd=0 data=0x00000000 ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Got RSPkt interrupt status=0x00008451
Sep 12 14:17:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:17:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x6 length=20 ctx=0 tlabel=25
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=3, tcode=0x4, speed=2
Sep 12 14:17:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:17:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008012
Sep 12 14:17:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x4 tLabel=0x03 ack=0x12 spd=0 data=0x00000000 ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=6, tcode=0x4, speed=2
Sep 12 14:17:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:17:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008012
Sep 12 14:17:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x4 tLabel=0x06 ack=0x12 spd=0 data=0x00000000 ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Got RSPkt interrupt status=0x00008451
Sep 12 14:17:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:17:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x6 length=20 ctx=0 tlabel=26
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=8, tcode=0x4, speed=2
Sep 12 14:17:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:17:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008012
Sep 12 14:17:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x4 tLabel=0x08 ack=0x12 spd=0 data=0x00000000 ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Got RSPkt interrupt status=0x00008451
Sep 12 14:17:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:17:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x6 length=20 ctx=0 tlabel=26
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=10, tcode=0x4, speed=2
Sep 12 14:17:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:17:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008012
Sep 12 14:17:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x4 tLabel=0x0A ack=0x12 spd=0 data=0x00000000 ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Got RSPkt interrupt status=0x00008451
Sep 12 14:17:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:17:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x6 length=20 ctx=0 tlabel=26
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=12, tcode=0x4, speed=2
Sep 12 14:17:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:17:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008012
Sep 12 14:17:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x4 tLabel=0x0C ack=0x12 spd=0 data=0x00000000 ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Got RSPkt interrupt status=0x00008451
Sep 12 14:17:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:17:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x6 length=20 ctx=0 tlabel=26
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=14, tcode=0x4, speed=2
Sep 12 14:17:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:17:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008012
Sep 12 14:17:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x4 tLabel=0x0E ack=0x12 spd=0 data=0x00000000 ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=16, tcode=0x4, speed=2
Sep 12 14:17:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:17:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008012
Sep 12 14:17:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x4 tLabel=0x10 ack=0x12 spd=0 data=0x00000000 ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=18, tcode=0x4, speed=2
Sep 12 14:17:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:17:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008012
Sep 12 14:17:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x4 tLabel=0x12 ack=0x12 spd=0 data=0x00000000 ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=20, tcode=0x4, speed=2
Sep 12 14:17:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:17:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008012
Sep 12 14:17:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x4 tLabel=0x14 ack=0x12 spd=0 data=0x00000000 ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=22, tcode=0x4, speed=2
Sep 12 14:17:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:17:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008012
Sep 12 14:17:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x4 tLabel=0x16 ack=0x12 spd=0 data=0x00000000 ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=24, tcode=0x4, speed=2
Sep 12 14:17:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:17:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008012
Sep 12 14:17:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x4 tLabel=0x18 ack=0x12 spd=0 data=0x00000000 ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000020
Sep 12 14:17:51 [kernel] ohci1394_0: Got RSPkt interrupt status=0x00008451
Sep 12 14:17:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:17:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x6 length=20 ctx=0 tlabel=26
Sep 12 14:17:51 [kernel] ohci1394_0: Inserting packet for node 0-00:0000, tlabel=0, tcode=0x0, speed=0
Sep 12 14:17:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:17:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:17:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0xE tLabel=0x00 ack=0x11 spd=0 data=0x00000000 ctx=0
Sep 12 14:17:51 [default.hotplug] arguments (ieee1394) env (OLDPWD=/ PATH=/bin:/sbin:/usr/sbin:/usr/bin ACTION=add SPECIFIER_ID=00609e PWD=/etc/hotplug VENDOR_ID=000000 VERSION=010483 HOME=/ SHLVL=2 DEBUG=kernel GUID=0001a35000022370 _=/usr/bin/env)
Sep 12 14:17:51 [default.hotplug] invoke /etc/hotplug/ieee1394.agent ()
Sep 12 14:17:51 [ieee1394.agent] Setup sbp2 for IEEE1394 product 0x000000/0x00609e/0x010483
Sep 12 14:17:51 [kernel] sbp2: $Rev: 1010 $ Ben Collins <bcollins@debian.org>
Sep 12 14:17:51 [kernel] scsi1 : SCSI emulation for IEEE-1394 SBP-2 Devices
Sep 12 14:17:51 [kernel] blk: queue c0dda614, I/O limit 4095Mb (mask 0xffffffff)
Sep 12 14:17:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=25, tcode=0x1, speed=2
Sep 12 14:17:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:17:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:17:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=25 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:17:51 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00008451
Sep 12 14:17:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:17:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=28
Sep 12 14:17:51 [kernel] ieee1394: sbp2: Logged into SBP-2 device
Sep 12 14:17:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=26, tcode=0x0, speed=2
Sep 12 14:17:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:17:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:17:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x1A ack=0x11 spd=0 data=0x0F000000 ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=27, tcode=0x0, speed=2
Sep 12 14:17:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:17:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:17:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:17:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x1B ack=0x11 spd=0 data=0x0F000000 ctx=0
Sep 12 14:17:51 [kernel] ieee1394: sbp2: Node 0-00:1023: Max speed [S400] - Max payload [2048]

rescanning the scsi-bus:
Sep 12 14:19:47 [kernel] scsi singledevice 0 0 1 0
Sep 12 14:19:47 [kernel] scsi singledevice 0 0 2 0
Sep 12 14:19:47 [kernel] scsi singledevice 0 0 3 0
Sep 12 14:19:47 [kernel] scsi singledevice 0 0 4 0
Sep 12 14:19:47 [kernel] scsi singledevice 0 0 5 0
Sep 12 14:19:47 [kernel] scsi singledevice 0 0 6 0
Sep 12 14:19:47 [kernel] scsi singledevice 0 0 7 0
Sep 12 14:19:47 [kernel] scsi singledevice 1 0 0 0
Sep 12 14:19:47 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=28, tcode=0x1, speed=2
Sep 12 14:19:47 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:19:47 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:19:47 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:19:47 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=28 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:19:47 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:19:47 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:19:47 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00008451
Sep 12 14:19:47 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:19:47 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=61
Sep 12 14:19:47 [kernel] Vendor: SAMSUNG Model: SV1604N Rev: 0.01
Sep 12 14:19:47 [kernel] Type: Direct-Access ANSI SCSI revision: 02
Sep 12 14:19:47 [kernel] blk: queue e0ac6214, I/O limit 4095Mb (mask 0xffffffff)
Sep 12 14:19:47 [kernel] Attached scsi disk sda at scsi1, channel 0, id 0, lun 0
Sep 12 14:19:47 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=29, tcode=0x1, speed=2
Sep 12 14:19:47 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:19:47 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:19:47 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:19:47 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=29 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:19:47 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:19:47 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00008451
Sep 12 14:19:47 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:19:47 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=61
Sep 12 14:19:47 [kernel] sda1
Sep 12 14:19:47 [kernel] scsi singledevice 1 0 1 0
Sep 12 14:19:47 [kernel] scsi singledevice 1 0 2 0
Sep 12 14:19:47 [kernel] scsi singledevice 1 0 3 0
Sep 12 14:19:47 [kernel] scsi singledevice 1 0 4 0
Sep 12 14:19:47 [kernel] scsi singledevice 1 0 5 0
Sep 12 14:19:48 [kernel] scsi singledevice 1 0 6 0
Sep 12 14:19:48 [kernel] scsi singledevice 1 0 7 0

copying a file to the disk:
Sep 12 14:23:31 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:31 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:31 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:23:31 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=53, tcode=0x0, speed=2
Sep 12 14:23:31 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:31 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:23:31 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x35 ack=0x11 spd=0 data=0x20B0AB20 ctx=0
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:31 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:31 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:23:31 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=54, tcode=0x0, speed=2
Sep 12 14:23:31 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:31 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:23:31 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x36 ack=0x11 spd=0 data=0x20C0AB20 ctx=0
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:31 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:31 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:23:31 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=55, tcode=0x0, speed=2
Sep 12 14:23:31 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:31 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:23:31 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x37 ack=0x11 spd=0 data=0x20D0AB20 ctx=0
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:31 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:31 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:23:31 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=56, tcode=0x0, speed=2
Sep 12 14:23:31 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:31 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:23:31 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x38 ack=0x11 spd=0 data=0x20E0AB20 ctx=0
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:31 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:31 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:23:31 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=57, tcode=0x0, speed=2
Sep 12 14:23:31 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:31 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:23:31 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x39 ack=0x11 spd=0 data=0x20F0AB20 ctx=0
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:31 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:31 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:23:31 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=58, tcode=0x0, speed=2
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:31 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:23:31 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x3A ack=0x11 spd=0 data=0x20805F20 ctx=0
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:31 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:31 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:23:31 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=63, tcode=0x0, speed=2
Sep 12 14:23:31 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:31 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:23:31 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x3F ack=0x11 spd=0 data=0x20D05F20 ctx=0
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:31 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:31 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:31 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:31 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:31 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:31 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:31 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:31 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:31 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:31 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:31 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:31 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:31 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:23:31 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=0, tcode=0x1, speed=2
Sep 12 14:23:31 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:23:31 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:31 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:23:31 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=0 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=1, tcode=0x1, speed=2
Sep 12 14:23:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:23:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=1 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=2, tcode=0x0, speed=2
Sep 12 14:23:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:23:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x02 ack=0x11 spd=0 data=0x20006920 ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=3, tcode=0x0, speed=2
Sep 12 14:23:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=4, tcode=0x0, speed=2
Sep 12 14:23:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008411
Sep 12 14:23:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x03 ack=0x11 spd=0 data=0x20106920 ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=5, tcode=0x0, speed=2
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=6, tcode=0x0, speed=2
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00009411
Sep 12 14:23:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x04 ack=0x11 spd=0 data=0x20206920 ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=7, tcode=0x0, speed=2
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00009411
Sep 12 14:23:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x05 ack=0x11 spd=0 data=0x20306920 ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=8, tcode=0x0, speed=2
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008411
Sep 12 14:23:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x06 ack=0x11 spd=0 data=0x20505C20 ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008411
Sep 12 14:23:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x07 ack=0x11 spd=0 data=0x20406920 ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:23:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x08 ack=0x11 spd=0 data=0x20506920 ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=23
Sep 12 14:23:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=9, tcode=0x0, speed=2
Sep 12 14:23:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:23:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x09 ack=0x11 spd=0 data=0x20606920 ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=23
Sep 12 14:23:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=10, tcode=0x0, speed=2
Sep 12 14:23:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:23:51 [kernel] ohc<6>ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=23
Sep 12 14:23:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=21, tcode=0x0, speed=2
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:23:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x15 ack=0x11 spd=0 data=0x20306A20 ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:23:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:23:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=23
Sep 12 14:23:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=26, tcode=0x0, speed=2
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:23:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:23:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x1A ack=0x11 spd=0 data=0x20D05320 ctx=0
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:56 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:56 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:56 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:56 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:56 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:56 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:56 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:56 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:56 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:56 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:23:56 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:24:06 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:24:06 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:24:06 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:24:06 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:24:06 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:24:06 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:24:06 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:24:06 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:24:06 [kernel] ohci1394_0: IntEvent: 00000010

trying to read that file from the disk:
Sep 12 14:26:46 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:46 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:49 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:26:51 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:26:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:26:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=55
Sep 12 14:27:16 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=52, tcode=0x0, speed=2
Sep 12 14:27:16 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:27:16 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=53, tcode=0x0, speed=2
Sep 12 14:27:16 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:27:16 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:27:16 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:27:16 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x34 ack=0x3 spd=0 data=0x2060E320 ctx=0
Sep 12 14:27:16 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x35 ack=0x11 spd=0 data=0x2070E320 ctx=0
Sep 12 14:27:16 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=54, tcode=0x0, speed=2
Sep 12 14:27:16 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:27:16 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:27:16 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:27:16 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x36 ack=0x11 spd=0 data=0x2080E320 ctx=0
Sep 12 14:27:16 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:16 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:27:16 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:27:16 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=63
Sep 12 14:27:16 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:16 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:27:16 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:27:16 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=63
Sep 12 14:27:16 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:16 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:27:16 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:27:16 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=63
Sep 12 14:27:19 [kernel] ieee1394: sbp2: aborting sbp2 command
Sep 12 14:27:19 [kernel] Read (10) 00 07 c7 f4 9f 00 00 f8 00
Sep 12 14:27:19 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=55, tcode=0x0, speed=2
Sep 12 14:27:19 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:27:19 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=56, tcode=0x1, speed=2
Sep 12 14:27:19 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:27:19 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:27:19 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:27:19 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x37 ack=0x11 spd=0 data=0x0F000000 ctx=0
Sep 12 14:27:19 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=56 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:27:19 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:19 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:27:19 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:27:19 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=29
Sep 12 14:27:19 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:19 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:27:19 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:27:19 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=29
Sep 12 14:27:19 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=57, tcode=0x1, speed=2
Sep 12 14:27:19 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:27:19 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:27:19 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:27:19 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=57 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:27:19 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:19 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:27:19 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:27:19 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=29
Sep 12 14:27:19 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=58, tcode=0x1, speed=2
Sep 12 14:27:19 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:27:19 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:27:19 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:27:19 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=58 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:27:19 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=59, tcode=0x0, speed=2
Sep 12 14:27:19 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:27:19 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:27:19 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:27:19 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x3B ack=0x11 spd=0 data=0x20D0E320 ctx=0
Sep 12 14:27:19 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:19 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:27:19 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:27:19 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=30
Sep 12 14:27:19 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:19 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:27:19 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:27:19 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=30
Sep 12 14:27:19 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=60, tcode=0x1, speed=2
Sep 12 14:27:19 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:27:19 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=61, tcode=0x0, speed=2
Sep 12 14:27:19 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:27:19 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:27:19 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:27:19 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=60 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:27:19 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x3D ack=0x11 spd=0 data=0x20F0E320 ctx=0
Sep 12 14:27:19 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:19 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:51 [kernel] ieee1394: sbp2: aborting sbp2 command
Sep 12 14:27:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:27:56 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ieee1394: sbp2: aborting sbp2 command
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:26 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:51 [kernel] ieee1394: sbp2: aborting sbp2 command
Sep 12 14:28:51 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:28:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:28:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:28:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:51 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:28:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:28:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:28:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=12, tcode=0x1, speed=2
Sep 12 14:28:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:28:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:28:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:28:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=12 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:28:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:51 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:28:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:28:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:28:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=13, tcode=0x1, speed=2
Sep 12 14:28:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:28:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=14, tcode=0x0, speed=2
Sep 12 14:28:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:28:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:28:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:28:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=13 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:28:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x0E ack=0x11 spd=0 data=0x20C0E120 ctx=0
Sep 12 14:28:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:28:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:28:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:51 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:28:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:28:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:28:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:51 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:28:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:28:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:28:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=15, tcode=0x1, speed=2
Sep 12 14:28:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:28:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=16, tcode=0x0, speed=2
Sep 12 14:28:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:28:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:28:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:28:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=15 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:28:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x10 ack=0x11 spd=0 data=0x20E0E120 ctx=0
Sep 12 14:28:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:51 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:28:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:28:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:28:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:51 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:28:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:28:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:28:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=17, tcode=0x1, speed=2
Sep 12 14:28:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:28:51 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=18, tcode=0x0, speed=2
Sep 12 14:28:51 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:28:51 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:28:51 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:28:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=17 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:28:51 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x12 ack=0x11 spd=0 data=0x2000C820 ctx=0
Sep 12 14:28:51 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:51 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:28:51 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:28:51 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=58
Sep 12 14:28:56 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=19, tcode=0x0, speed=2
Sep 12 14:28:56 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:28:56 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:28:56 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:28:56 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x13 ack=0x11 spd=0 data=0x2010C820 ctx=0
Sep 12 14:28:56 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:56 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:28:56 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:28:56 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=30
Sep 12 14:28:56 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=20, tcode=0x0, speed=2
Sep 12 14:28:56 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:28:56 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:28:56 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:28:56 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x14 ack=0x11 spd=0 data=0x2020C820 ctx=0
Sep 12 14:28:56 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:28:56 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:28:56 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:28:56 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=30
Sep 12 14:29:15 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=21, tcode=0x0, speed=2
Sep 12 14:29:15 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:29:15 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:29:15 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:29:15 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x15 ack=0x11 spd=0 data=0x2030C820 ctx=0
Sep 12 14:29:15 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:29:16 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:29:16 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:29:16 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=62
Sep 12 14:29:21 [kernel] ieee1394: sbp2: aborting sbp2 command
Sep 12 14:29:21 [kernel] Read (10) 00 07 c9 a6 b7 00 00 f8 00
Sep 12 14:29:21 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=22, tcode=0x0, speed=2
Sep 12 14:29:21 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:29:21 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:29:21 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:29:21 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x0 tLabel=0x16 ack=0x11 spd=0 data=0x0F000000 ctx=0
Sep 12 14:29:21 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=23, tcode=0x1, speed=2
Sep 12 14:29:21 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:29:21 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:29:21 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:29:21 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=23 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:29:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:29:21 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:29:21 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:29:21 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=42
Sep 12 14:29:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:29:21 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:29:21 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:29:21 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=42
Sep 12 14:29:21 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=24, tcode=0x1, speed=2
Sep 12 14:29:21 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:29:21 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:29:21 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:29:21 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=24 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:29:21 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:29:21 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:29:21 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:29:21 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=42
Sep 12 14:29:26 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:29:26 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=25, tcode=0x1, speed=2
Sep 12 14:29:26 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:29:26 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:29:26 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:29:26 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=25 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:29:26 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:29:26 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:29:26 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:29:26 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=14
Sep 12 14:29:26 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=26, tcode=0x1, speed=2
Sep 12 14:29:26 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:29:26 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:29:26 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:29:26 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=26 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:29:46 [kernel] ohci1394_0: Inserting packet for node 0-00:1023, tlabel=27, tcode=0x1, speed=2
Sep 12 14:29:46 [kernel] ohci1394_0: Waking transmit DMA ctx=0
Sep 12 14:29:46 [kernel] ohci1394_0: IntEvent: 00000001
Sep 12 14:29:46 [kernel] ohci1394_0: Got reqTxComplete interrupt status=0x00008011
Sep 12 14:29:46 [kernel] ohci1394_0: Packet sent to node 0 tcode=0x1 tLabel=27 ack=0x11 spd=0 dataLength=8 ctx=0
Sep 12 14:29:46 [kernel] ohci1394_0: IntEvent: 00000010
Sep 12 14:29:46 [kernel] ohci1394_0: Got RQPkt interrupt status=0x00009451
Sep 12 14:29:46 [kernel] ohci1394_0: Single packet rcv'd
Sep 12 14:29:46 [kernel] ohci1394_0: Packet received from node 0 ack=0x11 spd=2 tcode=0x1 length=28 ctx=0 tlabel=46

lspci:
00:0b.0 Host bridge: Apple Computer Inc. UniNorth/Pangea AGP
00:10.0 VGA compatible controller: ATI Technologies Inc Radeon Mobility M7 LW [Radeon Mobility 7500]
10:0b.0 Host bridge: Apple Computer Inc. UniNorth/Pangea PCI
10:17.0 Class ff00: Apple Computer Inc. KeyLargo/Pangea Mac I/O
10:18.0 USB Controller: Apple Computer Inc. KeyLargo/Pangea USB
10:19.0 USB Controller: Apple Computer Inc. KeyLargo/Pangea USB
20:0b.0 Host bridge: Apple Computer Inc. UniNorth/Pangea Internal PCI
20:0e.0 FireWire (IEEE 1394): Apple Computer Inc. UniNorth/Pangea FireWire
20:0f.0 Ethernet controller: Apple Computer Inc. UniNorth/Pangea GMAC (Sun GEM)

/proc/bus/ieee1394/devices:
Node[0-01:1023] GUID[000a95fffea1175e]:
  Vendor ID: `Linux OHCI-1394' [0x000000]
  Capabilities: 0x0083c0
  Bus Options:
    IRMC(1) CMC(1) ISC(1) BMC(0) PMC(0) GEN(0)
    LSPD(2) MAX_REC(2048) CYC_CLK_ACC(0)
  Host Node Status:
    Host Driver : ohci1394
    Nodes connected : 2
    Nodes active : 2
    SelfIDs received: 2
    Irm ID : [0-01:1023]
    BusMgr ID : [0-63:1023]
    In Bus Reset : no
    Root : yes
    Cycle Master : yes
    IRM : yes
    Bus Manager : no
Node[0-00:1023] GUID[0001a35000022370]:
  Vendor ID: `337 COMBO' [0x0001a3]
  Capabilities: 0x0083c0
  Bus Options:
    IRMC(0) CMC(0) ISC(0) BMC(0) PMC(0) GEN(0)
    LSPD(2) MAX_REC(64) CYC_CLK_ACC(255)
  Unit Directory 0:
    Vendor/Model ID: 337 COMBO [0001a3] / Unknown [008034]
    Software Specifier ID: 00609e
    Software Version: 010483
    Driver: SBP2 Driver
    Length (in quads): 5

I hope that this is enough information to debug my problem, please let
me know if I can test anything else...

Thanks,
- Alexander

-- 
"Good luck... The only good thing about being compromised is that it
makes you more paranoid about being on the net."
 - Jamie Lawrence on debian-security

-------------------------------------------------------
This sf.net email is sponsored by:ThinkGeek
Welcome to geek heaven.
http://thinkgeek.com/sf
_______________________________________________
mailing list Linux1394-user@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/linux1394-user