Skip site navigation (1)Skip section navigation (2)
Date:      Sat, 21 Feb 2009 17:51:52 +0200
From:      Boris Kotzev <boris.kotzev@gmail.com>
To:        Sean Bruno <sean.bruno@dsl-only.net>
Cc:        freebsd-firewire@freebsd.org
Subject:   Re: firewire disk stopped working
Message-ID:  <200902211751.53298.boris.kotzev@gmail.com>
In-Reply-To: <1235092864.24031.4.camel@localhost.localdomain>
References:  <200902150012.49329.boris.kotzev@gmail.com> <200902191309.41883.boris.kotzev@gmail.com> <1235092864.24031.4.camel@localhost.localdomain>

next in thread | previous in thread | raw e-mail | index | archive | help
On =EF=E5=F2=FA=EA 20 =F4=E5=E2=F0=F3=E0=F0=E8 2009 03:21:04 you wrote:
> > > > > Can you update to -current, and set:
> > > > > debug.sbp_debug =3D 1
> > > > > debug.firewire_debug =3D 1
> > > > >
> > > > > Then repost your dmesg.
> > > > >
> > > > > Sean
> > > >
> > > > Hi,
> > > >
> > > > The dmesg output after plugging the cable are as follows (full
> > > > verbose dmesg can be found at
> > > > http://host7.net62.hdsl-213-169.fastbg.net/dmesg.debug.txt ):
> > >
> > > I went over everything and came up with the following patch.  Let me
> > > apply this to -current and let me know if it makes any difference.
> > >
> > > Sean
> >
> > Hi,
> >
> > No, unfortunately the patch did not help. The new dmesg output after
> > plugging the cable follows below. (After unplugging the cable the system
> > prints this on the screen:
> > http://host7.net62.hdsl-213-169.fastbg.net/messages.jpg
> > and then freezes completely.)
>
> Ok, this output looks like something else I was pursuing.  Thanks for
> testing.
>
> Here's another patch for to test with.  Let me know what the results
> are!
>
> There was a couple of variable not getting initialized causing some
> serious grief in the speed negotiation.  Effectively causing the driver
> to try and negotiate with itself.  Very icky.
>
> Sean

The last patch produces the following dmesg:

fwohci0: fwohci_intr_core: BUS reset
sbp_post_busreset
fwohci0: fwohci_intr_core: node_id=3D0x00000001, SelfID Count=3D3, CYCLEMAS=
TER mode
node:0 link:1 gap:63 spd:2 con:0 pwr:4 p0:1 p1:2 p2:0 i:0 m:0
node:1 link:1 gap:5 spd:2 con:1 pwr:0 p0:3 p1:1 p2:1 i:1 m:0
firewire0: 2 nodes, maxhop <=3D 1 cable IRM irm(1)  (me)=20
fwohci0: fwohci_set_bus_manager: 1->1 (loop=3D0)
firewire0: bus manager 1=20
firewire0: fw_phy_config: root_node=3D1 gap_count=3D5
fwohci0: start AT DMA status=3D11
firewire0: fw_bus_probe:iterate and invalidate all nodes
firewire0: fw_explore:node(0) fc->max_node(1) found
firewire0: fw_explore:found myself node(1) fc->nodeid(1) fc->max_node(1)
firewire0: fw_explore_node: node(0) BUS INFO BLOCK:
irmc(0) cmc(0) isc(0) bmc(0) pmc(0) cyc_clk_acc(255) max_rec(5) max_rom(1) =
generation(1) link_spd(2)
firewire0: New S400 device ID:0020370004a03421
firewire0: fw_explore: node 2, err =3D 0
bus_explore done
sbp_post_explore (sbp_cold=3D1)
sbp0: sbp_post_explore:: EUI:0020370004a03421  attached, state=3D2
target 0 lun 0 found
sbp0: sbp_show_sdev_info: sbp0:0:0: ordered:1 type:0 EUI:0020370004a03421 n=
ode:0 speed:2 maxrec:8
sbp0: sbp_show_sdev_info: sbp0:0:0 'Seagate' 'ATA Device 00' '000102'
sbp_login: sec =3D 0 usec =3D 716699 ticks =3D 716
sbp0: sbp_mgm_orb:sbp0:0:0 LOGIN
sbp: alloc 1 xfer
fwohci0: fwohci_start: maxdesc 3
sbp0: sbp_recv1:sbp0:0:0 login: len 16, ID 0, cmd 0000fffff0100000, recon_h=
old 0
sbp0: sbp_busy_timeout:sbp0:0:0
sbp0: sbp_agent_reset:sbp0:0:0
sbp0: sbp_do_attach:sbp0:0:0
sbp0: sbp_cam_scan_target:sbp0:0:0
fwohci0: fwohci_intr_core: BUS reset
fwohci0: fwohci_intr_core: node_id=3D0x00000001, SelfID Count=3D4, CYCLEMAS=
TER mode
sbp_post_busreset
node:0 link:1 gap:5 spd:2 con:0 pwr:4 p0:1 p1:2 p2:0 i:1 m:0
node:1 link:1 gap:5 spd:2 con:1 pwr:0 p0:3 p1:1 p2:1 i:0 m:0
firewire0: 2 nodes, maxhop <=3D 1 cable IRM irm(1)  (me)=20
fwohci0: fwohci_set_bus_manager: 1->1 (loop=3D0)
firewire0: bus manager 1=20
firewire0: fw_phy_config: root_node=3D1 gap_count=3D5
fwohci0: start AT DMA status=3D12
firewire0: fw_bus_probe:iterate and invalidate all nodes
firewire0: fw_bus_probe:Invalidate Dev ID: 0020370004a03421
firewire0: fw_explore:node(0) fc->max_node(1) found
firewire0: fw_explore:found myself node(1) fc->nodeid(1) fc->max_node(1)
firewire0: fw_explore_node: node(0) BUS INFO BLOCK:
irmc(0) cmc(0) isc(0) bmc(0) pmc(0) cyc_clk_acc(255) max_rec(5) max_rom(1) =
generation(1) link_spd(2)
fwohci0: node0: crom unchanged
firewire0: fw_explore: node 2, err =3D 0
bus_explore done
sbp_post_explore (sbp_cold=3D0)
sbp0: sbp_post_explore:: EUI:0020370004a03421  attached, state=3D2
target 0 lun 0 found
sbp0: sbp_show_sdev_info: sbp0:0:0: ordered:1 type:0 EUI:0020370004a03421 n=
ode:0 speed:2 maxrec:8
sbp0: sbp_show_sdev_info: sbp0:0:0 'Seagate' 'ATA Device 00' '000102'
sbp0: sbp_abort_ocb:sbp0:0:0 0x5df1178
sbp0: (probe0:sbp_mgm_orb:sbp0:0:0 RECONNECTsbp0:0:
0:0): Bus Reset issued
(probe0:sbp0:0:0:0): Retrying Command
sbp0: sbp_recv1:sbp0:0:0 reconnect: len 16, ID 0, cmd 0000fffff0100000
sbp0: sbp_agent_reset:sbp0:0:0
sbp0: sbp_recv1:sbp0:0:0 ORB status src:1 resp:1 dead:1 len:3 stat:4e orb:0=
05df1568
sbp0: sbp0:0:0
Obj: Data buffer, Error: Type error
sbp0:0:0 XPT_SCSI_IO: cmd: 12 01 00 00 ff 00 00 00 00 00, flags: 0x40, 6b c=
md/255b data/18b sense
sbp0:0:0: SCSI status 0 sfmt 0 valid 0 key 0 code 0 qlfr 0 len 3
sbp0: sbp_scsi_status:sbp0:0:0 unknown scsi status 0x0
sbp0: (probe0:sbp_agent_reset:sbp0:0:0sbp0:0:
0:0): Request Requeued
(probe0:sbp0:0:0:0): Retrying Command
sbp0: sbp_recv1:sbp0:0:0 ORB status src:1 resp:1 dead:1 len:3 stat:4e orb:0=
05df16b8
sbp0: sbp0:0:0
Obj: Data buffer, Error: Type error
sbp0:0:0 XPT_SCSI_IO: cmd: 12 01 00 00 ff 00 00 00 00 00, flags: 0x40, 6b c=
md/255b data/18b sense
sbp0:0:0: SCSI status 0 sfmt 0 valid 0 key 0 code 0 qlfr 0 len 3
sbp0: sbp_scsi_status:sbp0:0:0 unknown scsi status 0x0
sbp0: (probe0:sbp_agent_reset:sbp0:0:0sbp0:0:
0:0): Request Requeued
(probe0:sbp0:0:0:0): Retrying Command
sbp0: sbp_recv1:sbp0:0:0 ORB status src:1 resp:1 dead:1 len:3 stat:4e orb:0=
05df1808
sbp0: sbp0:0:0
Obj: Data buffer, Error: Type error
sbp0:0:0 XPT_SCSI_IO: cmd: 12 01 00 00 ff 00 00 00 00 00, flags: 0x40, 6b c=
md/255b data/18b sense
sbp0:0:0: SCSI status 0 sfmt 0 valid 0 key 0 code 0 qlfr 0 len 3
sbp0: sbp_scsi_status:sbp0:0:0 unknown scsi status 0x0
sbp0: (probe0:sbp_agent_reset:sbp0:0:0sbp0:0:
0:0): Request Requeued
(probe0:sbp0:0:0:0): Retrying Command
sbp0: sbp_recv1:sbp0:0:0 ORB status src:1 resp:1 dead:1 len:3 stat:4e orb:0=
05df1958
sbp0: sbp0:0:0
Obj: Data buffer, Error: Type error
sbp0:0:0 XPT_SCSI_IO: cmd: 12 01 00 00 ff 00 00 00 00 00, flags: 0x40, 6b c=
md/255b data/18b sense
sbp0:0:0: SCSI status 0 sfmt 0 valid 0 key 0 code 0 qlfr 0 len 3
sbp0: sbp_scsi_status:sbp0:0:0 unknown scsi status 0x0
sbp0: (probe0:sbp_agent_reset:sbp0:0:0sbp0:0:
0:0): Request Requeued
(probe0:sbp0:0:0:0): Retrying Command
sbp0: sbp_recv1:sbp0:0:0 ORB status src:1 resp:1 dead:1 len:3 stat:4e orb:0=
05df1aa8
sbp0: sbp0:0:0

and the last 9 lines keep repeating over and over again.

Best regards,

Boris Kotzev






Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?200902211751.53298.boris.kotzev>