From owner-freebsd-hackers Sat Sep 15 18:40: 1 2001 Delivered-To: freebsd-hackers@freebsd.org Received: from kuku.excite.com (kuku-rwcmta.excite.com [198.3.99.63]) by hub.freebsd.org (Postfix) with ESMTP id 000D937B40B; Sat, 15 Sep 2001 18:39:24 -0700 (PDT) Received: from slippery ([199.172.153.106]) by kuku.excite.com (InterMail vM.4.01.02.39 201-229-119-122) with ESMTP id <20010916013924.MKDN22472.kuku.excite.com@slippery>; Sat, 15 Sep 2001 18:39:24 -0700 Message-ID: <18954083.1000604364686.JavaMail.imail@slippery> Date: Sat, 15 Sep 2001 18:39:20 -0700 (PDT) From: john_wilson100@excite.com To: Greg Lehey Subject: Re: VINUM PANIC ON -STABLE Cc: freebsd-hackers@freebsd.org Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Transfer-Encoding: 7bit X-Mailer: Excite Inbox X-Sender-Ip: 62.90.91.23 Sender: owner-freebsd-hackers@FreeBSD.ORG Precedence: bulk List-ID: List-Archive: (Web Archive) List-Help: (List Instructions) List-Subscribe: List-Unsubscribe: X-Loop: FreeBSD.ORG Hi Greg, I apologise in advance if this message is too long. On Sun, 16 Sep 2001 09:38:20 +0930, Greg Lehey wrote: > [Format recovered--see http://www.lemis.com/email/email-format.html] > > On Saturday, 15 September 2001 at 12:26:10 -0700, john_wilson100@excite.com wrote: > > > I posted a message to -hackers several days ago, complete > > with a kernel backtrace. > > Well, not quite 42 hours. That's not "several days" in my > book. You also omitted a lot of other information I asked > for. You are right, but I need to make this machine a file server as soon as possible, and I don't know how long I can keep the current broken setup. > > > The panic is 100% reproducible on my machine running the > > latest -stable. > > > > Does anyone care? > > Yes, I care. Good, I'll be happy to help you fix it. > > My message to Greg Lehey was rejected by his mail server, > > Well, there's something that you can fix in the meantime :-) > > > but I am pretty sure he reads both -hackers and -stable. > > Correct. I also have other things to do. When you sent the > backtrace, I was away from home doing some training, and I > didn't have access to my machines. I'll look at it, but it > would be *really* nice if you'd give me the info I ask for. No problem. I didn't realise that you asked for some specific information, but now that you've told me, here goes: 1) What problems are you having? Vinum panics. 2) Which version of FreeBSD are you running? FreeBSD 4.4-RC, built 12 Sep 2001 (supped around the same time) 3) Have you made any changes to the system sources, including Vinum? No. 4) Supply the output of the vinum list command. 4 drives: D data1 State: up Device /dev/ad4s1g Avail: 0/10240 MB (0%) D home1 State: up Device /dev/ad4s1h Avail: 0/38931 MB (0%) D data2 State: up Device /dev/ad6s1e Avail: 0/10240 MB (0%) D home2 State: up Device /dev/ad6s1f Avail: 0/37733 MB (0%) 2 volumes: V data State: up Plexes: 1 Size: 19 GB V home State: up Plexes: 1 Size: 74 GB 2 plexes: P data.p0 S State: up Subdisks: 2 Size: 19 GB P home.p0 S State: up Subdisks: 2 Size: 74 GB 4 subdisks: S data.p0.s0 State: up PO: 0 B Size: 9 GB S data.p0.s1 State: up PO: 279 kB Size: 9 GB S home.p0.s0 State: up PO: 0 B Size: 38 GB S home.p0.s1 State: up PO: 279 kB Size: 36 GB 5) Supply an extract of the Vinum history file. 13 Sep 2001 01:58:14.762508 *** vinum started *** 13 Sep 2001 01:58:14.763601 *** Created devices *** 13 Sep 2001 01:58:14.977566 create -v vinum.conf # The drives drive data1 device /dev/ad4s1g drive data2 device /dev/ad6s1e drive home1 device /dev/ad4s1h drive home2 device /dev/ad6s1f # One striped volume volume data plex org striped 279k sd length 0 drive data1 sd length 0 drive data2 volume home plex org striped 279k sd length 0 drive home1 sd length 0 drive home2 13 Sep 2001 01:58:15.030494 *** Created devices *** 13 Sep 2001 02:01:01.096464 *** vinum started *** 13 Sep 2001 02:01:01.097047 create -v vinum.conf # The drives drive data1 device /dev/ad4s1g drive data2 device /dev/ad6s1e drive home1 device /dev/ad4s1h drive home2 device /dev/ad6s1f # One striped volume volume data plex org striped 279k sd length 0 drive data1 sd length 0 drive data2 volume home plex org striped 279k sd length 0 drive home1 sd length 0 drive home2 13 Sep 2001 02:01:01.099272 *** Created devices *** 13 Sep 2001 02:02:21.094275 *** vinum started *** 13 Sep 2001 01:58:14.763601 *** Created devices *** 13 Sep 2001 02:02:24.464758 stop 13 Sep 2001 02:02:33.130188 *** vinum started *** 13 Sep 2001 02:02:33.130954 create -v vinum.conf # The drives drive data1 device /dev/ad4s1g drive data2 device /dev/ad6s1e drive home1 device /dev/ad4s1h drive home2 device /dev/ad6s1f # One striped volume volume data plex org striped 279k sd length 0 drive data1 sd length 0 drive data2 volume home plex org striped 279k sd length 0 drive home1 sd length 0 drive home2 13 Sep 2001 02:02:33.158054 *** Created devices *** 13 Sep 2001 02:13:47.686198 *** vinum started *** 13 Sep 2001 02:14:59.824765 *** vinum started *** 13 Sep 2001 02:14:59.825620 read ad4 ad6 13 Sep 2001 02:15:10.914699 *** vinum started *** 13 Sep 2001 02:15:10.915271 read ad4s1 ad6s1 13 Sep 2001 02:15:20.100632 *** vinum started *** 13 Sep 2001 02:15:20.101207 read /dev/ad4s1 /dev/ad6s1 13 Sep 2001 02:15:20.196171 *** Created devices *** 13 Sep 2001 02:29:58.399989 *** vinum started *** 13 Sep 2001 02:29:58.415789 info 13 Sep 2001 02:30:01.983586 *** vinum started *** 13 Sep 2001 02:30:01.984155 info 13 Sep 2001 02:30:13.001453 *** vinum started *** 13 Sep 2001 02:30:13.001991 dumpconfig 13 Sep 2001 02:31:24.133859 *** vinum started *** 13 Sep 2001 02:32:11.335262 *** vinum started *** 13 Sep 2001 02:32:11.335860 start 13 Sep 2001 02:32:11.413807 *** Created devices *** 13 Sep 2001 02:52:51.646474 *** vinum started *** 13 Sep 2001 02:52:51.662275 start 13 Sep 2001 02:52:51.754875 *** Created devices *** 13 Sep 2001 03:13:23.407808 *** vinum started *** 13 Sep 2001 03:13:23.424895 stop 13 Sep 2001 03:13:27.716186 *** vinum started *** 13 Sep 2001 03:13:27.716929 start 13 Sep 2001 03:13:27.835339 *** Created devices *** 16 Sep 2001 04:08:53.103875 *** vinum started *** 16 Sep 2001 04:08:53.112648 list 16 Sep 2001 04:09:08.872212 *** vinum started *** 16 Sep 2001 04:09:08.872769 list 6) Supply an extract of the file /var/log/messages. Sep 12 14:06:10 depot login: ROOT LOGIN (root) ON ttyv0 Sep 12 14:07:29 depot login: ROOT LOGIN (root) ON ttyv1 Sep 13 01:58:14 depot /kernel: vinum: loaded Sep 13 01:58:15 depot /kernel: vinum: drive data1 is up Sep 13 01:58:15 depot /kernel: vinum: drive home1 is up Sep 13 01:58:15 depot /kernel: vinum: drive home2 is up Sep 13 01:58:15 depot /kernel: vinum: removing 499 blocks of partial stripe at the end of data.p0 Sep 13 01:58:15 depot /kernel: vinum: data.p0 must have equal sized subdisks Sep 13 01:58:15 depot /kernel: Correcting length of data.p0: was 20970756, is 20970757 Sep 13 01:58:15 depot /kernel: vinum: data.p0.s0 is up Sep 13 01:58:15 depot /kernel: vinum: data.p0.s1 is up Sep 13 01:58:15 depot /kernel: vinum: data.p0 is up Sep 13 01:58:15 depot /kernel: vinum: data is up Sep 13 01:58:15 depot /kernel: vinum: removing 403 blocks of partial stripe at the end of home.p0 Sep 13 01:58:15 depot /kernel: vinum: home.p0 must have equal sized subdisks Sep 13 01:58:15 depot /kernel: Correcting length of home.p0: was 157008924, is 157008925 Sep 13 01:58:15 depot /kernel: vinum: home.p0.s0 is up Sep 13 01:58:15 depot /kernel: vinum: home.p0.s1 is up Sep 13 01:58:15 depot /kernel: vinum: home.p0 is up Sep 13 01:58:15 depot /kernel: vinum: home is up Sep 13 02:01:01 depot /kernel: vinum: removing 1 blocks of partial stripe at the end of data.p0 Sep 13 02:01:01 depot /kernel: vinum: data.p0 must have equal sized subdisks Sep 13 02:01:01 depot /kernel: Correcting length of data.p0: was 20970756, is 20970757 Sep 13 02:01:01 depot /kernel: vinum: removing 1 blocks of partial stripe at the end of home.p0 Sep 13 02:01:01 depot /kernel: vinum: home.p0 must have equal sized subdisks Sep 13 02:01:01 depot /kernel: Correcting length of home.p0: was 157008924, is 157008925 Sep 13 02:01:01 depot /kernel: vinum: removing 1 blocks of partial stripe at the end of data.p0 Sep 13 02:01:01 depot /kernel: vinum: data.p0 must have equal sized subdisks Sep 13 02:01:01 depot /kernel: Correcting length of data.p0: was 20970756, is 20970757 Sep 13 02:01:01 depot /kernel: vinum: removing 1 blocks of partial stripe at the end of home.p0 Sep 13 02:01:01 depot /kernel: vinum: home.p0 must have equal sized subdisks Sep 13 02:01:01 depot /kernel: Correcting length of home.p0: was 157008924, is 157008925 Sep 13 02:01:01 depot /kernel: vinum: removing 249 blocks of partial stripe at the end of data.p1 Sep 13 02:01:01 depot /kernel: vinum: data.p1 must have equal sized subdisks Sep 13 02:01:01 depot /kernel: Correcting length of data.p1: was 0, is 1 Sep 13 02:01:01 depot /kernel: vinum: data.p1 is faulty Sep 13 02:01:01 depot /kernel: vinum: removing 402 blocks of partial stripe at the end of home.p1 Sep 13 02:01:01 depot /kernel: vinum: home.p1 is faulty Sep 13 02:02:25 depot /kernel: vinum: unloaded Sep 13 02:02:33 depot /kernel: vinum: loaded Sep 13 02:02:33 depot /kernel: vinum: drive data1 is up Sep 13 02:02:33 depot /kernel: vinum: drive data2 is up Sep 13 02:02:33 depot /kernel: vinum: drive home1 is up Sep 13 02:02:33 depot /kernel: vinum: drive home2 is up Sep 13 02:02:33 depot /kernel: vinum: removing 998 blocks of partial stripe at the end of data.p0 Sep 13 02:02:33 depot /kernel: vinum: data.p0.s0 is up Sep 13 02:02:33 depot /kernel: vinum: data.p0.s1 is up Sep 13 02:02:33 depot /kernel: vinum: data.p0 is up Sep 13 02:02:33 depot /kernel: vinum: data is up Sep 13 02:02:33 depot /kernel: vinum: removing 403 blocks of partial stripe at the end of home.p0 Sep 13 02:02:33 depot /kernel: vinum: home.p0 must have equal sized subdisks Sep 13 02:02:33 depot /kernel: Correcting length of home.p0: was 157008924, is 157008925 Sep 13 02:02:33 depot /kernel: vinum: home.p0.s0 is up Sep 13 02:02:33 depot /kernel: vinum: home.p0.s1 is up Sep 13 02:02:33 depot /kernel: vinum: home.p0 is up Sep 13 02:02:33 depot /kernel: vinum: home is up Sep 13 02:13:19 depot /kernel: Copyright (c) 1992-2001 The FreeBSD Project. Sep 13 02:13:19 depot /kernel: Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994 Sep 13 02:13:19 depot /kernel: The Regents of the University of California. All rights reserved. Sep 13 02:13:19 depot /kernel: FreeBSD 4.4-RC #0: Wed Sep 12 12:46:00 IDT 2001 Sep 13 02:13:19 depot /kernel: Timecounter "i8254" frequency 1193182 Hz Sep 13 02:13:19 depot /kernel: CPU: Pentium III/Pentium III Xeon/Celeron (602.19-MHz 686-class CPU) Sep 13 02:13:19 depot /kernel: Origin = "GenuineIntel" Id = 0x673 Stepping = 3 Sep 13 02:13:19 depot /kernel: Features=0x383f9ff Sep 13 02:13:19 depot /kernel: real memory = 536854528 (524272K bytes) Sep 13 02:13:19 depot /kernel: avail memory = 518512640 (506360K bytes) Sep 13 02:13:19 depot /kernel: Preloaded elf kernel "kernel" at 0xc02e5000. Sep 13 02:13:19 depot /kernel: Pentium Pro MTRR support enabled Sep 13 02:13:19 depot /kernel: md0: Malloc disk Sep 13 02:13:19 depot /kernel: Using $PIR table, 8 entries at 0xc00f0e60 Sep 13 02:13:19 depot /kernel: npx0: on motherboard Sep 13 02:13:19 depot /kernel: npx0: INT 16 interface Sep 13 02:13:19 depot /kernel: pcib0: on motherboard Sep 13 02:13:19 depot /kernel: pci0: on pcib0 Sep 13 02:13:19 depot /kernel: pcib2: at device 1.0 on pci0 Sep 13 02:13:19 depot /kernel: pci1: on pcib2 Sep 13 02:13:19 depot /kernel: isab0: at device 4.0 on pci0 Sep 13 02:13:19 depot /kernel: isa0: on isab0 Sep 13 02:13:19 depot /kernel: atapci0: port 0xd800-0xd80f at device 4.1 on pci0 Sep 13 02:13:19 depot /kernel: ata0: at 0x1f0 irq 14 on atapci0 Sep 13 02:13:19 depot /kernel: pci0: at 4.2 irq 10 Sep 13 02:13:19 depot /kernel: fxp0: port 0xd000-0xd03f mem 0xe2800000-0xe28fffff,0xe3000000-0xe3000fff irq 10 at device 9.0 on pci0 Sep 13 02:13:19 depot /kernel: fxp0: Ethernet address 00:d0:b7:af:14:da Sep 13 02:13:19 depot /kernel: inphy0: on miibus0 Sep 13 02:13:19 depot /kernel: inphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto Sep 13 02:13:19 depot /kernel: atapci1: port 0xa400-0xa4ff,0xa800-0xa803,0xb000-0xb007,0xb400-0xb403,0xb800-0xb807 irq 11 at device 11.0 on pci0 Sep 13 02:13:19 depot /kernel: ata2: at 0xb800 on atapci1 Sep 13 02:13:19 depot /kernel: ata3: at 0xb000 on atapci1 Sep 13 02:13:19 depot /kernel: pcib1: on motherboard Sep 13 02:13:19 depot /kernel: pci2: on pcib1 Sep 13 02:13:19 depot /kernel: orm0: