From owner-freebsd-firewire@FreeBSD.ORG Sun Apr 10 20:00:37 2005 Return-Path: Delivered-To: freebsd-firewire@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id 16C4D16A4CE for ; Sun, 10 Apr 2005 20:00:37 +0000 (GMT) Received: from cgp.pu.ru (vlan3.cgp.pu.ru [195.70.196.209]) by mx1.FreeBSD.org (Postfix) with ESMTP id DBC9443D60 for ; Sun, 10 Apr 2005 20:00:35 +0000 (GMT) (envelope-from batuto@mail.ru) Received: from wg.pu.ru ([193.124.85.219] verified) by cgp.pu.ru (CommuniGate Pro SMTP 4.2.8) with ESMTP id 63271344 for freebsd-firewire@freebsd.org; Mon, 11 Apr 2005 00:00:33 +0400 Received: (from uucp@localhost) by wg.pu.ru (8.9.1a/8.9.1) with UUCP id UAA28660 for freebsd-firewire@freebsd.org; Sun, 10 Apr 2005 20:00:33 GMT Received: from localhost (localhost [127.0.0.1]) by yb3791.spb.edu (Postfix) with ESMTP id 8AF1E4051 for ; Sun, 10 Apr 2005 23:58:36 +0400 (MSD) From: "Yan V. Batuto" To: freebsd-firewire@freebsd.org User-Agent: KMail/1.8 References: <200504061910.44800.batuto@mail.ru> <873bu2d92z.wl@tora.nunu.org> <200504081249.55477.batuto@mail.ru> In-Reply-To: <200504081249.55477.batuto@mail.ru> MIME-Version: 1.0 Content-Disposition: inline Date: Sun, 10 Apr 2005 23:58:35 +0400 Content-Type: text/plain; charset="iso-8859-1" Content-Transfer-Encoding: 7bit Message-Id: <200504102358.36152.batuto@mail.ru> Content-Transfer-Encoding: 7bit Subject: Re: sbp failure X-BeenThere: freebsd-firewire@freebsd.org X-Mailman-Version: 2.1.1 Precedence: list List-Id: Firewire support in FreeBSD List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sun, 10 Apr 2005 20:00:37 -0000 It looks like failure occurs in sbp_cam_scan_target... Connecting/disconnecting drive when debug.sbp_debug=1: ----------------------------------------- Best regards! Yan V. Batuto ----------------------------------------- ## Connecting drive Apr 10 15:41:55 yb3791 kernel: fwohci0: BUS reset Apr 10 15:41:55 yb3791 kernel: sbp_post_busreset Apr 10 15:41:55 yb3791 kernel: fwohci0: node_id=0xc800ffc1, gen=2, CYCLEMASTER mode Apr 10 15:41:55 yb3791 kernel: firewire0: 2 nodes, maxhop <= 1, cable IRM = 1 (me) Apr 10 15:41:55 yb3791 kernel: firewire0: bus manager 1 (me) Apr 10 15:41:56 yb3791 kernel: fwohci0: txd err=14 ack busy_X Apr 10 15:41:56 yb3791 kernel: fw_asybusy Apr 10 15:41:57 yb3791 kernel: fwohci0: txd err=14 ack busy_X Apr 10 15:41:57 yb3791 kernel: fw_asybusy Apr 10 15:41:58 yb3791 kernel: fwohci0: txd err=14 ack busy_X Apr 10 15:41:58 yb3791 kernel: fw_asybusy Apr 10 15:41:59 yb3791 kernel: fwohci0: BUS reset Apr 10 15:41:59 yb3791 kernel: sbp_post_busreset Apr 10 15:41:59 yb3791 kernel: fwohci0: txd err= 3 miss Ack err Apr 10 15:41:59 yb3791 kernel: firewire0: bus_explore node=0 addr=0x400 resp=22 retry=4 Apr 10 15:41:59 yb3791 kernel: fwohci0: node_id=0xc800ffc1, gen=3, CYCLEMASTER mode Apr 10 15:41:59 yb3791 kernel: firewire0: 2 nodes, maxhop <= 1, cable IRM = 1 (me) Apr 10 15:41:59 yb3791 kernel: firewire0: bus manager 1 (me) Apr 10 15:41:59 yb3791 kernel: firewire0: New S400 device ID:0000000e00000000 Apr 10 15:41:59 yb3791 kernel: sbp_post_explore (sbp_cold=1) Apr 10 15:41:59 yb3791 kernel: sbp_post_explore: EUI:0000000e00000000 attached Apr 10 15:41:59 yb3791 kernel: target 0 lun 0 found Apr 10 15:41:59 yb3791 kernel: sbp0:0:0 ordered:1 type:14 EUI:0000000e00000000 node:0 speed:2 maxrec:0 new! Apr 10 15:41:59 yb3791 kernel: sbp0:0:0 'TS0GHDC2' '' '100102' Apr 10 15:41:59 yb3791 kernel: sbp_login: sec = 0 usec = 676757 ticks = 67 Apr 10 15:42:00 yb3791 kernel: sbp0:0:0 LOGIN Apr 10 15:42:00 yb3791 kernel: sbp: alloc 1 xfer Apr 10 15:42:00 yb3791 kernel: sbp0:0:0 mgm orb: 0c3fc01c Apr 10 15:42:00 yb3791 kernel: sbp0:0:0 login: len 16, ID 0, cmd 0000fffff0010020, recon_hold 0 Apr 10 15:42:00 yb3791 kernel: sbp0:0:0 sbp_busy_timeout Apr 10 15:42:00 yb3791 kernel: sbp0:0:0 sbp_agent_reset Apr 10 15:42:00 yb3791 kernel: sbp0:0:0 sbp_do_attach Apr 10 15:42:00 yb3791 kernel: sbp0:0:0 sbp_cam_scan_target Apr 10 15:43:00 yb3791 kernel: sbp0:0:0 request timeout(cmd orb:0x0c3fc28c) ... agent reset Apr 10 15:43:00 yb3791 kernel: sbp0:0:0 sbp_abort_ocb 0xc3fc28c Apr 10 15:43:00 yb3791 kernel: sbp0:0:0 sbp_agent_reset Apr 10 15:44:00 yb3791 kernel: sbp0:0:0 request timeout(cmd orb:0x0c3fc3c4) ... sbp0:0:0 sbp_abort_ocb 0xc3fc3c4 Apr 10 15:44:00 yb3791 kernel: target reset Apr 10 15:44:00 yb3791 kernel: sbp0:0:0 TARGET RESET Apr 10 15:44:00 yb3791 kernel: sbp0:0:0 mgm orb: 0c3fc4fc Apr 10 15:44:00 yb3791 kernel: sbp0:0:0 sbp_busy_timeout Apr 10 15:44:00 yb3791 kernel: sbp0:0:0 sbp_agent_reset Apr 10 15:45:00 yb3791 kernel: sbp0:0:0 request timeout(cmd orb:0x0c3fc634) ... sbp0:0:0 sbp_abort_ocb 0xc3fc634 Apr 10 15:45:00 yb3791 kernel: reset start Apr 10 15:45:00 yb3791 kernel: sbp0:0:0 sbp_reset_start Apr 10 15:45:00 yb3791 kernel: sbp_login: sec = -181 usec = 507859 ticks = 0 Apr 10 15:45:00 yb3791 kernel: sbp0:0:0 LOGIN Apr 10 15:45:00 yb3791 kernel: sbp: alloc 2 xfer Apr 10 15:45:00 yb3791 kernel: sbp0:0:0 mgm orb: 0c3fc76c Apr 10 15:45:00 yb3791 kernel: sbp0:0:0 login failed Apr 10 15:45:01 yb3791 kernel: firewire0: split transaction timeout dst=0xffc0 tl=0xc state=10 Apr 10 15:45:01 yb3791 kernel: sbp0:0:0 sbp_reset_start failed: resp=60 ## Disconnecting drive Apr 10 15:45:51 yb3791 kernel: fwohci0: BUS reset Apr 10 15:45:51 yb3791 kernel: sbp_post_busreset Apr 10 15:45:51 yb3791 kernel: fwohci0: node_id=0xc800ffc0, gen=4, CYCLEMASTER mode Apr 10 15:45:51 yb3791 kernel: firewire0: 1 nodes, maxhop <= 0, cable IRM = 0 (me) Apr 10 15:45:51 yb3791 kernel: firewire0: bus manager 0 (me) Apr 10 15:45:52 yb3791 kernel: sbp_post_explore (sbp_cold=0) Apr 10 15:45:52 yb3791 kernel: sbp_post_explore: EUI:0000000e00000000 not attached, state=3. Apr 10 15:45:52 yb3791 kernel: target 0 lun 0 found