Work at SourceForge, help us to make it a better place! We have an immediate need for a Support Technician in our San Francisco or Denver office.

Close

#438 Firewire Harddrives hangs FreeNAS

v0.69x
open
nobody
Interface (97)
5
2012-10-28
2008-10-21
Z Someman
No

I've installed a Firewire PCI-card (VIA) and FreeNAS seems to recognize it properly:

Oct 22 01:28:27 freenasbox kernel: fwohci0: <VIA Fire="" II="" (VT6306)=""> port 0x2000-0x207f mem 0xec100000-0xec1007ff irq 11 at device 0.0 on pci1
Oct 22 01:28:27 freenasbox kernel: fwohci0: OHCI version 1.0 (ROM=1)
Oct 22 01:28:27 freenasbox kernel: fwohci0: No. of Isochronous channels is 8.
Oct 22 01:28:27 freenasbox kernel: fwohci0: EUI64 ff:ff:ff:ff:ff:ff:00:0d
Oct 22 01:28:27 freenasbox kernel: fwohci0: Phy 1394a available S400, 3 ports.
Oct 22 01:28:27 freenasbox kernel: fwohci0: Link undef, max_rec 65536 bytes.
Oct 22 01:28:27 freenasbox kernel: fwohci0: max_rec 65536 -> 2048
Oct 22 01:28:27 freenasbox kernel: firewire0: <IEEE1394(FireWire) bus=""> on fwohci0
Oct 22 01:28:27 freenasbox kernel: fwip0: <IP over="" FireWire=""> on firewire0
Oct 22 01:28:27 freenasbox kernel: fwip0: Firewire address: ff:ff:ff:ff:ff:ff:00:0d @ 0xfffe00000000, S400, maxrec 2048
Oct 22 01:28:27 freenasbox kernel: sbp0: <SBP-2 SCSI="" over="" FireWire=""> on firewire0
Oct 22 01:28:27 freenasbox kernel: fwohci0: Initiate bus reset
Oct 22 01:28:27 freenasbox kernel: fwohci0: BUS reset
Oct 22 01:28:27 freenasbox kernel: fwohci0: node_id=0xc800ffc0, gen=1, CYCLEMASTER mode
Oct 22 01:28:27 freenasbox kernel: firewire0: 1 nodes, maxhop <= 0, cable IRM = 0 (me)
Oct 22 01:28:27 freenasbox kernel: firewire0: bus manager 0 (me)

But when connecting a LaCie Firewire drive the system hangs partly. Most services hang, and rebooting is impossible. Only hard-reset or power-cycle helps. Tried two different Lacie drives, both in good condition. One even reformatted just for this test. But same problem remains.
While the drive is connected, the syslog reports repeatedly:

"Operation request block (ORB), Error: Time-out error"

Until the drive is disconnected. Reboot is impossible at any time.

Complete system log dump:
Oct 22 01:22:02 kernel: sbp0:0:0 sbp_agent_reset_callback: resp=60
Oct 22 01:22:02 kernel: firewire0: split transaction timeout dst=0xffc0 tl=0x2f state=3
Oct 22 01:22:02 kernel: firewire0: bus manager 0 (me)
Oct 22 01:22:02 kernel: firewire0: 1 nodes, maxhop <= 0, cable IRM = 0 (me)
Oct 22 01:22:02 kernel: fwohci0: node_id=0xc800ffc0, gen=4, CYCLEMASTER mode
Oct 22 01:22:02 kernel: fwohci0: BUS reset
Oct 22 01:22:02 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:02 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:02 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:02 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:02 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:02 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:02 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:02 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:02 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:02 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:02 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:02 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:01 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:01 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:01 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:01 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:01 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:01 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:01 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:01 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:01 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:01 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:01 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:01 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:01 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:01 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:00 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:00 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:00 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:00 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:00 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:00 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:00 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:00 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:00 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:00 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:00 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:00 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:00 kernel: sbp0:0:0 sbp_scsi_status: unknown scsi status 0x0
Oct 22 01:22:00 kernel: sbp0:0:0 Obj: Operation request block (ORB), Error: Time-out error
Oct 22 01:22:00 kernel: da1: 114473MB (234441648 512 byte sectors: 255H 63S/T 14593C)
Oct 22 01:22:00 kernel: da1: 50.000MB/s transfers
Oct 22 01:22:00 kernel: da1: <LaCie Gr="" LaCie="" Rugged="" LUN="" 0102=""> Fixed Simplified Direct Access SCSI-4 device
Oct 22 01:22:00 kernel: da1 at sbp0 bus 0 target 0 lun 0
Oct 22 01:21:58 kernel: firewire0: New S400 device ID:00d04b7712060ce9
Oct 22 01:21:58 kernel: firewire0: bus manager 1 (me)
Oct 22 01:21:58 kernel: firewire0: 2 nodes, maxhop <= 1, cable IRM = 1 (me)
Oct 22 01:21:58 kernel: fwohci0: node_id=0xc800ffc1, gen=3, CYCLEMASTER mode
Oct 22 01:21:58 kernel: fwohci0: BUS reset
Oct 22 01:21:56 kernel: firewire0: bus manager 1 (me)
Oct 22 01:21:56 kernel: firewire0: 2 nodes, maxhop <= 1, cable IRM = 1 (me)
Oct 22 01:21:56 kernel: fwohci0: node_id=0xc800ffc1, gen=2, CYCLEMASTER mode
Oct 22 01:21:56 kernel: fwohci0: phy int
Oct 22 01:21:56 kernel: fwohci0: BUS reset

Oct 22 01:28:27 freenasbox kernel: fwohci0: <VIA Fire="" II="" (VT6306)=""> port 0x2000-0x207f mem 0xec100000-0xec1007ff irq 11 at device 0.0 on pci1
Oct 22 01:28:27 freenasbox kernel: fwohci0: OHCI version 1.0 (ROM=1)
Oct 22 01:28:27 freenasbox kernel: fwohci0: No. of Isochronous channels is 8.
Oct 22 01:28:27 freenasbox kernel: fwohci0: EUI64 ff:ff:ff:ff:ff:ff:00:0d
Oct 22 01:28:27 freenasbox kernel: fwohci0: Phy 1394a available S400, 3 ports.
Oct 22 01:28:27 freenasbox kernel: fwohci0: Link undef, max_rec 65536 bytes.
Oct 22 01:28:27 freenasbox kernel: fwohci0: max_rec 65536 -> 2048
Oct 22 01:28:27 freenasbox kernel: firewire0: <IEEE1394(FireWire) bus=""> on fwohci0
Oct 22 01:28:27 freenasbox kernel: fwip0: <IP over="" FireWire=""> on firewire0
Oct 22 01:28:27 freenasbox kernel: fwip0: Firewire address: ff:ff:ff:ff:ff:ff:00:0d @ 0xfffe00000000, S400, maxrec 2048
Oct 22 01:28:27 freenasbox kernel: sbp0: <SBP-2 SCSI="" over="" FireWire=""> on firewire0
Oct 22 01:28:27 freenasbox kernel: fwohci0: Initiate bus reset
Oct 22 01:28:27 freenasbox kernel: fwohci0: BUS reset
Oct 22 01:28:27 freenasbox kernel: fwohci0: node_id=0xc800ffc0, gen=1, CYCLEMASTER mode
Oct 22 01:28:27 freenasbox kernel: firewire0: 1 nodes, maxhop <= 0, cable IRM = 0 (me)
Oct 22 01:28:27 freenasbox kernel: firewire0: bus manager 0 (me)

Discussion