From nobody Sun Apr 07 20:42:01 2024 X-Original-To: bugs@mlmmj.nyi.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2610:1c1:1:606c::19:1]) by mlmmj.nyi.freebsd.org (Postfix) with ESMTP id 4VCPKQ0zhcz5HVYW for ; Sun, 7 Apr 2024 20:42:02 +0000 (UTC) (envelope-from bugzilla-noreply@freebsd.org) Received: from mxrelay.nyi.freebsd.org (mxrelay.nyi.freebsd.org [IPv6:2610:1c1:1:606c::19:3]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) key-exchange X25519 server-signature RSA-PSS (4096 bits) server-digest SHA256 client-signature RSA-PSS (4096 bits) client-digest SHA256) (Client CN "mxrelay.nyi.freebsd.org", Issuer "R3" (verified OK)) by mx1.freebsd.org (Postfix) with ESMTPS id 4VCPKP3yDtz4kvJ for ; Sun, 7 Apr 2024 20:42:01 +0000 (UTC) (envelope-from bugzilla-noreply@freebsd.org) ARC-Seal: i=1; s=dkim; d=freebsd.org; t=1712522521; a=rsa-sha256; cv=none; b=HGvEtR7v+UN02Asd5/ajHmqCPHgrtb7c5QXjJX9FMJDHJjh5G3Mtg4hdcpWpjtkYsezSLF x7sqJoMaqx2jkrQ7CIzQgy3Q6rMP7S266H9mznbOHxBaWDzLebMvE9fsl1S6GPjhx4tQec ixNZNWD6pgsCGN1eCtxmyfK1SZ73CN+vISpOy1RsJQMZPX1v7CcPVKj2hT1b6RvxO4AjPK H/pJEArTcvCRUcdWTpwZ62Xu+i3NIDGaQC1NP2MFCKrDv6EjUgrVauY+EPtv/2ytcTat7Y X5L/MMFCU5Wwwd7+S8akIVQSNIJmPCSwdFjYxq7pEv6ttt565IcJjGsQX3OUTQ== ARC-Authentication-Results: i=1; mx1.freebsd.org; none ARC-Message-Signature: i=1; a=rsa-sha256; c=relaxed/relaxed; d=freebsd.org; s=dkim; t=1712522521; h=from:from:reply-to:subject:subject:date:date:message-id:message-id: to:to:cc:mime-version:mime-version:content-type:content-type: content-transfer-encoding:content-transfer-encoding: in-reply-to:in-reply-to:references:references; bh=6bWLmZEwaf6rXWFzM5zl6TmQQizsFVGtGa9HJOj0Bs8=; b=KzVOgIK8UvbWaODf8mOK88UREmB8BQqf3G1f01KbhOjKJhMwyoc7fKfufd0WTmsqm//xyX 6x4FcXN/02ZXanm+0hXlY1d8Toz9SooFLJQ6yi5UHgMb46BPNPP1ISPgL0VcjRbfzvKs28 LDrmZTttHcLplg2ssRRua81xslH/jnKZ/rHae383fzXmhDwq3ifmuOV7mQhaeYfLqTljLp yEfsnU/+hHeMVGXE63sotv9QXjyyCP3ZsIrun9zeQzId5zsqBF71qZsSZOVMx0ZuEC7Qao TBYPVeJ5hL798hgRyWqf2Um1vzeDY4hXuI0swZiCZutaNEZZ2QjWRquTHxIXKw== Received: from kenobi.freebsd.org (kenobi.freebsd.org [IPv6:2610:1c1:1:606c::50:1d]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) key-exchange X25519 server-signature RSA-PSS (4096 bits) server-digest SHA256) (Client did not present a certificate) by mxrelay.nyi.freebsd.org (Postfix) with ESMTPS id 4VCPKP3Z1jzjVf for ; Sun, 7 Apr 2024 20:42:01 +0000 (UTC) (envelope-from bugzilla-noreply@freebsd.org) Received: from kenobi.freebsd.org ([127.0.1.5]) by kenobi.freebsd.org (8.15.2/8.15.2) with ESMTP id 437Kg1Wv093984 for ; Sun, 7 Apr 2024 20:42:01 GMT (envelope-from bugzilla-noreply@freebsd.org) Received: (from www@localhost) by kenobi.freebsd.org (8.15.2/8.15.2/Submit) id 437Kg1k7093983 for bugs@FreeBSD.org; Sun, 7 Apr 2024 20:42:01 GMT (envelope-from bugzilla-noreply@freebsd.org) X-Authentication-Warning: kenobi.freebsd.org: www set sender to bugzilla-noreply@freebsd.org using -f From: bugzilla-noreply@freebsd.org To: bugs@FreeBSD.org Subject: [Bug 277992] mpr and possible trim issues Date: Sun, 07 Apr 2024 20:42:01 +0000 X-Bugzilla-Reason: AssignedTo X-Bugzilla-Type: changed X-Bugzilla-Watch-Reason: None X-Bugzilla-Product: Base System X-Bugzilla-Component: kern X-Bugzilla-Version: 14.0-STABLE X-Bugzilla-Keywords: X-Bugzilla-Severity: Affects Some People X-Bugzilla-Who: mike@sentex.net X-Bugzilla-Status: New X-Bugzilla-Resolution: X-Bugzilla-Priority: --- X-Bugzilla-Assigned-To: bugs@FreeBSD.org X-Bugzilla-Flags: X-Bugzilla-Changed-Fields: Message-ID: In-Reply-To: References: Content-Type: text/plain; charset="UTF-8" Content-Transfer-Encoding: quoted-printable X-Bugzilla-URL: https://bugs.freebsd.org/bugzilla/ Auto-Submitted: auto-generated List-Id: Bug reports List-Archive: https://lists.freebsd.org/archives/freebsd-bugs List-Help: List-Post: List-Subscribe: List-Unsubscribe: Sender: owner-freebsd-bugs@freebsd.org MIME-Version: 1.0 https://bugs.freebsd.org/bugzilla/show_bug.cgi?id=3D277992 --- Comment #4 from mike@sentex.net --- With max trim set to 1/4 of the default value, I still get random errors. In this case, I set all 4 drives to=20 sysctl -w kern.cam.da.7.delete_max=3D4294901760 kern.cam.da.7.delete_max: 17179607040 -> 4294901760 Apr 7 13:51:57 r-14mfitest kernel: (da3:mpr0:0:49:0): WRITE(10). CDB: = 2a 00 3f ab 99 08 00 00 40 00 length 32768 SMID 844 Command timeout on target 49(0x000d), 60000 set, 60.788719903 elapsed Apr 7 13:51:57 r-14mfitest kernel: mpr0: At enclosure level 0, slot 3, connector name ( ) Apr 7 13:51:57 r-14mfitest kernel: mpr0: Sending abort to target 49 for SM= ID 844 Apr 7 13:51:57 r-14mfitest kernel: (da3:mpr0:0:49:0): WRITE(10). CDB: = 2a 00 3f ab 99 08 00 00 40 00 length 32768 SMID 844 Aborting command 0xfffffe02068b87a0 Apr 7 13:51:57 r-14mfitest kernel: (da3:mpr0:0:49:0): READ(10). CDB: 2= 8 00 0b 07 eb d0 00 00 c8 00 length 102400 SMID 1453 Command timeout on target 49(0x000d), 60000 set, 60.933970425 elapsed Apr 7 13:51:57 r-14mfitest kernel: mpr0: At enclosure level 0, slot 3, connector name ( ) Apr 7 13:51:57 r-14mfitest kernel: (da3:mpr0:0:49:0): WRITE(10). CDB: = 2a 00 40 a5 23 c8 00 00 10 00 length 8192 SMID 443 Command timeout on target 49(0x000d), 60000 set, 60.1019647685 elapsed Apr 7 13:51:57 r-14mfitest kernel: mpr0: At enclosure level 0, slot 3, connector name ( ) Apr 7 13:51:57 r-14mfitest kernel: (da3:mpr0:0:49:0): WRITE(10). CDB: = 2a 00 3f ab 98 e8 00 00 20 00 length 16384 SMID 113 Command timeout on target 49(0x000d), 60000 set, 60.1101031222 elapsed Apr 7 13:51:57 r-14mfitest kernel: mpr0: At enclosure level 0, slot 3, connector name ( ) Apr 7 13:51:57 r-14mfitest kernel: (da3:mpr0:0:49:0): WRITE(10). CDB: = 2a 00 3f ab 97 e8 00 01 00 00 length 131072 SMID 1395 Command timeout on target 49(0x000d), 60000 set, 60.1184073991 elapsed Apr 7 13:51:57 r-14mfitest kernel: mpr0: At enclosure level 0, slot 3, connector name ( ) Apr 7 13:51:57 r-14mfitest kernel: (da3:mpr0:0:49:0): WRITE(10). CDB: = 2a 00 3f ab 96 e8 00 01 00 00 length 131072 SMID 1364 Command timeout on target 49(0x000d), 60000 set, 60.1266419429 elapsed Apr 7 13:51:57 r-14mfitest kernel: mpr0: At enclosure level 0, slot 3, connector name ( ) Apr 7 13:51:57 r-14mfitest kernel: (da3:mpr0:0:49:0): WRITE(10). CDB: = 2a 00 4c 35 79 68 00 00 08 00 length 4096 SMID 1323 Command timeout on target 49(0x000d), 60000 set, 60.1337927805 elapsed Apr 7 13:51:57 r-14mfitest kernel: mpr0: At enclosure level 0, slot 3, connector name ( ) Apr 7 13:51:57 r-14mfitest kernel: (da3:mpr0:0:49:0): WRITE(10). CDB: = 2a 00 4c 35 79 20 00 00 48 00 length 36864 SMID 1506 Command timeout on target 49(0x000d), 60000 set, 60.1422928220 elapsed Apr 7 13:51:57 r-14mfitest kernel: mpr0: At enclosure level 0, slot 3, connector name ( ) Apr 7 13:51:57 r-14mfitest kernel: (da3:mpr0:0:49:0): WRITE(10). CDB: = 2a 00 4c 35 79 10 00 00 08 00 length 4096 SMID 343 Command timeout on target 49(0x000d), 60000 set, 60.1504915373 elapsed Apr 7 13:51:57 r-14mfitest kernel: mpr0: At enclosure level 0, slot 3, connector name ( ) Apr 7 13:51:57 r-14mfitest kernel: (da3:mpr0:0:49:0): WRITE(10). CDB: = 2a 00 40 a5 23 d8 00 00 10 00 length 8192 SMID 830 Command timeout on target 49(0x000d), 60000 set, 60.1586143647 elapsed Apr 7 13:51:57 r-14mfitest kernel: mpr0: At enclosure level 0, slot 3, connector name ( ) Apr 7 13:52:00 r-14mfitest kernel: mpr0: mprsas_prepare_remove: Sending re= set for target ID 49 Apr 7 13:52:00 r-14mfitest kernel: (da3:mpr0:0:49:0): READ(10). CDB: 2= 8 00 0c 05 97 18 00 00 30 00 length 24576 SMID 1035 Command timeout on target 49(0x000d), 60000 set, 60.52537889 elapsed Apr 7 13:52:00 r-14mfitest kernel: mpr0: At enclosure level 0, slot 3, connector name ( ) Apr 7 13:52:00 r-14mfitest kernel: (da3:mpr0:0:49:0): READ(10). CDB: 2= 8 00 0b 07 ee 38 00 00 08 00 length 4096 SMID 615 Command timeout on target 49(0x000d), 60000 set, 60.133221431 elapsed Apr 7 13:52:00 r-14mfitest kernel: mpr0: At enclosure level 0, slot 3, connector name ( ) Apr 7 13:52:00 r-14mfitest kernel: (da3:mpr0:0:49:0): WRITE(10). CDB: = 2a 00 4c 35 79 70 00 01 00 00 length 131072 SMID 1049 Command timeout on target 49(0x000d), 60000 set, 60.214511381 elapsed Apr 7 13:52:00 r-14mfitest kernel: mpr0: At enclosure level 0, slot 3, connector name ( ) Apr 7 13:52:00 r-14mfitest kernel: (pass3:mpr0:0:49:0): INQUIRY. CDB: = 12 00 00 00 24 00 length 36 SMID 1789 Command timeout on target 49(0x000d), 60= 000 set, 60.158235152 elapsed Apr 7 13:52:00 r-14mfitest kernel: mpr0: At enclosure level 0, slot 3, connector name ( ) Apr 7 13:52:01 r-14mfitest kernel: mpr0: Controller reported scsi ioc terminated tgt 49 SMID 1323 loginfo 31140000 departing Apr 7 13:52:01 r-14mfitest kernel: mpr0: Controller reported scsi ioc terminated tgt 49 SMID 830 loginfo 31140000 departing Apr 7 13:52:01 r-14mfitest kernel: (da3:mpr0:0:49:0): Invalidating pack Apr 7 13:52:01 r-14mfitest kernel: mpr0: Controller reported scsi ioc terminated tgt 49 SMID 1453 loginfo 31140000 departing Apr 7 13:52:01 r-14mfitest kernel: mpr0: Controller reported scsi ioc terminated tgt 49 SMID 443 loginfo 31140000 departing Apr 7 13:52:01 r-14mfitest kernel: mpr0: Controller reported scsi ioc terminated tgt 49 SMID 1364 loginfo 31140000 departing Apr 7 13:52:01 r-14mfitest kernel: mpr0: Controller reported scsi ioc terminated tgt 49 SMID 343 loginfo 31140000 departing Apr 7 13:52:01 r-14mfitest kernel: mpr0: Controller reported scsi ioc terminated tgt 49 SMID 113 loginfo 31140000 departing Apr 7 13:52:01 r-14mfitest kernel: mpr0: Controller reported scsi ioc terminated tgt 49 SMID 1395 loginfo 31140000 departing Apr 7 13:52:01 r-14mfitest kernel: mpr0: Controller reported scsi ioc terminated tgt 49 SMID 1506 loginfo 31140000 departing Apr 7 13:52:01 r-14mfitest kernel: mpr0: Controller reported scsi ioc terminated tgt 49 SMID 1789 loginfo 31140000 departing Apr 7 13:52:01 r-14mfitest kernel: mpr0: Controller reported scsi ioc terminated tgt 49 SMID 1035 loginfo 31140000 departing Apr 7 13:52:01 r-14mfitest kernel: mpr0: Controller reported scsi ioc terminated tgt 49 SMID 615 loginfo 31140000 departing Apr 7 13:52:01 r-14mfitest kernel: mpr0: Controller reported scsi ioc terminated tgt 49 SMID 1049 loginfo 31140000 departing Apr 7 13:52:01 r-14mfitest kernel: mpr0: No pending commands: starting remove_device for target 49 handle 0x000d Apr 7 13:52:01 r-14mfitest kernel: mpr0: clearing target 49 handle 0x000d Apr 7 13:52:01 r-14mfitest kernel: mpr0: At enclosure level 0, slot 3, connector name ( ) Apr 7 13:52:01 r-14mfitest kernel: mpr0: Finished abort recovery for targe= t 49 Apr 7 13:52:01 r-14mfitest kernel: (da3:mpr0:0:49:0): WRITE(10). CDB: 2a 0= 0 3f ab 99 08 00 00 40 00=20 Apr 7 13:52:01 r-14mfitest kernel: (da3:mpr0:0:49:0): CAM status: Command timeout Apr 7 13:52:01 r-14mfitest kernel: (da3:mpr0:0:49:0): Retrying command, 3 = more tries remain Apr 7 13:52:01 r-14mfitest kernel: da3 at mpr0 bus 0 scbus0 target 49 lun 0 Apr 7 13:52:01 r-14mfitest kernel: da3: s/n 240406800922 detached Apr 7 13:52:03 r-14mfitest kernel: (da3:mpr0:0:49:0): Periph destroyed If I do a power cycle of the box, the disk comes back and resilvers just fi= ne.=20 No smart errors. When I move these disks off the MFI controller and onto t= he same server's onboard Sata controllers, I am not able to provoke these erro= rs running the test for a good 12-14hrs. Put them back on the mfi controller,= and if I do a trim -f of the disks and start clean, I seem to be able to get a = good 4 cycles out before the errors. Hence I thought it had something to do with trim. I cant seem to disable trim via that sysctl=20 sysctl -w kern.cam.da.4.delete_method=3DNONE kern.cam.da.4.delete_method: ATA_TRIM -> ATA_TRIM I can however do=20 sysctl -w kern.cam.da.4.delete_method=3DDISABLE kern.cam.da.4.delete_method: ATA_TRIM -> DISABLE it seems to work a little better with it as DISABLED. Post crash of the disk and reboot, I managed to get 4 iterations error free. However, it really sl= ows down on the last loop. It normally takes about 20min to run, but by the 4th time its an hour. Apr 7 14:12:45 r-14mfitest LOOP[4704]: starting Apr 7 14:40:14 r-14mfitest LOOP[16194]: ending Apr 7 14:41:45 r-14mfitest LOOP[16835]: starting Apr 7 15:05:24 r-14mfitest LOOP[26742]: ending Apr 7 15:06:55 r-14mfitest LOOP[27383]: starting Apr 7 15:31:42 r-14mfitest LOOP[37775]: ending Apr 7 15:33:12 r-14mfitest LOOP[38413]: starting Apr 7 16:25:19 r-14mfitest LOOP[60263]: ending I am going to let it keep going to see if it will error out just at a later time. --=20 You are receiving this mail because: You are the assignee for the bug.=