From owner-freebsd-firewire@FreeBSD.ORG Sat Feb 21 15:52:00 2009 Return-Path: Delivered-To: freebsd-firewire@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id 8237B106564A for ; Sat, 21 Feb 2009 15:52:00 +0000 (UTC) (envelope-from boris.kotzev@gmail.com) Received: from mu-out-0910.google.com (mu-out-0910.google.com [209.85.134.184]) by mx1.freebsd.org (Postfix) with ESMTP id 0069D8FC26 for ; Sat, 21 Feb 2009 15:51:59 +0000 (UTC) (envelope-from boris.kotzev@gmail.com) Received: by mu-out-0910.google.com with SMTP id w1so944556mue.3 for ; Sat, 21 Feb 2009 07:51:59 -0800 (PST) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=gamma; h=domainkey-signature:received:received:from:organization:to:subject :date:user-agent:references:in-reply-to:cc:mime-version:content-type :content-transfer-encoding:content-disposition:message-id; bh=47dXerNJU/VoQ4gMNrDd/Kp3XzhcREUYMPQ4AyuiECA=; b=LG7aEgjuG6nluS+iWtxDBKi94BAkSboENcru0hq6gTspw6KOiTujdJ3iiJdvVjdxcH ntLKqa7v9BjQxiRy6wzRwYyAk0+E957yzefkUQ1WO1Q5BIXcJ0VU6Nm2CgSk0gMDchqr b0vIMp0REy2cqK2hCWnv7FmzJ+fUwmKkENwbI= DomainKey-Signature: a=rsa-sha1; c=nofws; d=gmail.com; s=gamma; h=from:organization:to:subject:date:user-agent:references:in-reply-to :cc:mime-version:content-type:content-transfer-encoding :content-disposition:message-id; b=cQ5MCj4/hRP7AFKPPP5Qhi9qwl+LS2PLPZNMmngsfdR6KeswFqVdDFphq9K8Ei4XTg dt7V5FWQ7oF5Mto2lY+U9QVX2l8hdGrZdJZEJ6NbIkf98tXi92/eLljyoArLVywkQKvf CQj9rdD4Jl6wqdG6MNXZ6l4Tp522lNXJGOHyA= Received: by 10.103.138.16 with SMTP id q16mr2402620mun.114.1235231516993; Sat, 21 Feb 2009 07:51:56 -0800 (PST) Received: from host7.net62.hdsl-213-169.fastbg.net (host7.net62.HDSL-213-169.FastBG.NET [213.169.62.7]) by mx.google.com with ESMTPS id j2sm1909411mue.54.2009.02.21.07.51.55 (version=TLSv1/SSLv3 cipher=RC4-MD5); Sat, 21 Feb 2009 07:51:56 -0800 (PST) From: Boris Kotzev Organization: Home To: Sean Bruno Date: Sat, 21 Feb 2009 17:51:52 +0200 User-Agent: KMail/1.11.0 (FreeBSD/8.0-CURRENT; KDE/4.2.0; amd64; ; ) References: <200902150012.49329.boris.kotzev@gmail.com> <200902191309.41883.boris.kotzev@gmail.com> <1235092864.24031.4.camel@localhost.localdomain> In-Reply-To: <1235092864.24031.4.camel@localhost.localdomain> MIME-Version: 1.0 Content-Type: text/plain; charset="windows-1251" Content-Transfer-Encoding: quoted-printable Content-Disposition: inline Message-Id: <200902211751.53298.boris.kotzev@gmail.com> Cc: freebsd-firewire@freebsd.org Subject: Re: firewire disk stopped working X-BeenThere: freebsd-firewire@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: Firewire support in FreeBSD List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sat, 21 Feb 2009 15:52:01 -0000 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