Subject: kern/13367: login: panic: lockmgr: using decommissioned lock
To: None <gnats-bugs@gnats.netbsd.org>
From: None <querbach@realtime.bc.ca>
List: netbsd-bugs
Date: 07/03/2001 15:05:11
>Number:         13367
>Category:       kern
>Synopsis:       login: panic: lockmgr: using decommissioned lock
>Confidential:   no
>Severity:       critical
>Priority:       high
>Responsible:    kern-bug-people
>State:          open
>Class:          sw-bug
>Submitter-Id:   net
>Arrival-Date:   Tue Jul 03 15:03:01 PDT 2001
>Closed-Date:
>Last-Modified:
>Originator:     David Querbach
>Release:        NetBSD 1.5
>Organization:
Real-Time Systems Inc.
>Environment:
System: NetBSD qingdao 1.5 NetBSD 1.5 (RAID) #0: Fri Apr 27 21:15:54 PDT 2001 root@qingdao:/usr/src/sys/arch/i386/compile/RAID i386

This is an i386 "GENERIC-$Revision: 1.354.2.8 $" kernel with the only
changes being that

  pseudo-device   raid            4       # RAIDframe disk driver
  options         RAID_AUTOCONFIG         # auto-configuration of RAID components

are un-commented.

>Description:

The system is configured with two RAID-0 partitions:

/etc/raid0.conf:
  # RAID configuration for partion f

  START array
  1 2 0

  START disks
  /dev/wd0f
  /dev/wd1f

  START layout
  32 0 0 0

  START queue
  fifo 100

/etc/raid1.conf:
  # RAID configuration for partion g

  START array
  1 2 0

  START disks
  /dev/wd0g
  /dev/wd1g

  START layout
  32 0 0 0

  START queue
  fifo 100

The system loads the kernel off /dev/wd0a, then mounts /dev/raid0a as root:

/etc/fstab:
  /dev/raid0a     /                       ffs     rw,softdep      1 1
  /dev/raid1a     /usr/home/querbach/rts  ffs     rw,softdep      1 1
  /dev/wd1a       none                    swap    sw              0 0
  /dev/wd1a       none                    dump    dp              0 0
  /kern           /kern                   kernfs  rw              0 0

Here's a log of the console output, including the panic message:

>How-To-Repeat:

Run _lots_ of cvs client activity for about 2-4 days, as well as supporting
an X server running navigator, xterms, etc, and another user telnetting in.

>Fix:

None known.
>Release-Note:
>Audit-Trail:
>Unformatted:
 >> NetBSD/i386 BIOS Boot, Revision 2.7
 >> (fvdl@sushi, Fri Nov 17 02:20:06 MET 2000)
 >> Memory: 634/523184 k
 > boot
 booting wd0a:netbsd
 4280361+386012=0x53dbc8
 [ preserving 465404 bytes of netbsd ELF symbol table ]
 Copyright (c) 1996, 1997, 1998, 1999, 2000
     The NetBSD Foundation, Inc.  All rights reserved.
 Copyright (c) 1982, 1986, 1989, 1991, 1993
     The Regents of the University of California.  All rights reserved.
 
 NetBSD 1.5 (RAID) #0: Fri Apr 27 21:15:54 PDT 2001
     root@qingdao:/usr/src/sys/arch/i386/compile/RAID
 cpu0: AMD K7 (Athlon) (686-class)
 total memory = 511 MB
 avail memory = 468 MB
 using 6573 buffers containing 26292 KB of memory
 BIOS32 rev. 0 found at 0xf0f50
 mainbus0 (root)
 pci0 at mainbus0 bus 0: configuration mode 1
 pci0: i/o space, memory space enabled
 pchb0 at pci0 dev 0 function 0
 pchb0: VIA Technologies product 0x0305 (rev. 0x03)
 ppb0 at pci0 dev 1 function 0: VIA Technologies product 0x8305 (rev. 0x00)
 pci1 at ppb0 bus 1
 pci1: i/o space, memory space enabled
 vga1 at pci1 dev 0 function 0: ATI Technologies product 0x5246 (rev. 0x00)
 wsdisplay0 at vga1
 pcib0 at pci0 dev 4 function 0
 pcib0: VIA Technologies VT82C686A (Apollo KX133) PCI-ISA Bridge (rev. 0x40)
 pciide0 at pci0 dev 4 function 1: VIA Tech VT82C586A IDE Controller (rev. 0x06)
 pciide0: bus-master DMA support present
 pciide0: primary channel configured to compatibility mode
 pciide0: disabling primary channel (no drives)
 pciide0: secondary channel configured to compatibility mode
 pciide0: disabling secondary channel (no drives)
 uhci0 at pci0 dev 4 function 2: VIA Technologies VT83C572 USB Controller (rev. 0x16)
 uhci0: interrupting at irq 9
 usb0 at uhci0: USB revision 1.0
 uhub0 at usb0
 uhub0: VIA Technologie UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
 uhub0: 2 ports with 2 removable, self powered
 uhci1 at pci0 dev 4 function 3: VIA Technologies VT83C572 USB Controller (rev. 0x16)
 uhci1: interrupting at irq 9
 usb1 at uhci1: USB revision 1.0
 uhub1 at usb1
 uhub1: VIA Technologie UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
 uhub1: 2 ports with 2 removable, self powered
 pchb1 at pci0 dev 4 function 4
 pchb1: VIA Technologies VT82C686A SMBus Controller (rev. 0x40)
 ex0 at pci0 dev 9 function 0: 3Com 3c905B-TX 10/100 Ethernet (rev. 0x30)
 ex0: interrupting at irq 9
 ex0: MAC address 00:01:02:ec:43:8d
 exphy0 at ex0 phy 24: 3Com internal media interface
 exphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
 ex1 at pci0 dev 10 function 0: 3Com 3c905B-TX 10/100 Ethernet (rev. 0x30)
 ex1: interrupting at irq 5
 ex1: MAC address 00:01:02:ec:42:e8
 exphy1 at ex1 phy 24: 3Com internal media interface
 exphy1: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
 unknown vendor 0x9710 product 0x9835 (serial communications, interface 0x02, revision 0x01) at pci0 dev 11 function 0 not configured
 pciide1 at pci0 dev 17 function 0: Promise Ultra100/ATA Bus Master IDE Accelerator (rev. 0x02)
 pciide1: bus-master DMA support present
 pciide1: primary channel configured to native-PCI mode
 pciide1: using irq 10 for native-PCI interrupt
 wd0 at pciide1 channel 0 drive 1: <Maxtor 5T030H3>
 wd0: drive supports 16-sector pio transfers, lba addressing
 wd0: 29311 MB, 16383 cyl, 16 head, 63 sec, 512 bytes/sect x 60030432 sectors
 wd0: 32-bit data port
 wd0: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 5
 wd0(pciide1:0:1): using PIO mode 4, Ultra-DMA mode 4 (using DMA data transfers)
 pciide1: secondary channel configured to native-PCI mode
 wd1 at pciide1 channel 1 drive 0: <Maxtor 5T030H3>
 wd1: drive supports 16-sector pio transfers, lba addressing
 wd1: 29311 MB, 16383 cyl, 16 head, 63 sec, 512 bytes/sect x 60030432 sectors
 wd1: 32-bit data port
 wd1: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 5
 wd1(pciide1:1:0): using PIO mode 4, Ultra-DMA mode 4 (using DMA data transfers)
 isa0 at pcib0
 com0 at isa0 port 0x3f8-0x3ff irq 4: ns16550a, working fifo
 com0: console
 com1 at isa0 port 0x2f8-0x2ff irq 3: ns16550a, working fifo
 pckbc0 at isa0 port 0x60-0x64
 pckbd0 at pckbc0 (kbd slot)
 pckbc0: using irq 1 for kbd slot
 wskbd0 at pckbd0
 pms0 at pckbc0 (aux slot)
 pckbc0: using irq 12 for aux slot
 wsmouse0 at pms0 mux 0
 lpt0 at isa0 port 0x378-0x37b irq 7
 pcppi0 at isa0 port 0x61
 midi0 at pcppi0: PC speaker
 sysbeep0 at pcppi0
 isapnp0 at isa0 port 0x279: ISA Plug 'n Play device support
 npx0 at isa0 port 0xf0-0xff: using exception 16
 fdc0 at isa0 port 0x3f0-0x3f7 irq 6 drq 2
 fd0 at fdc0 drive 0: 1.44MB, 80 cyl, 2 head, 18 sec
 isapnp0: no ISA Plug 'n Play devices found
 biomask ef45 netmask ef65 ttymask ffe7
 Kernelized RAIDframe activated
 RAID autoconfigure
 Configuring raid0:
 RAIDFRAME: protectedSectors is 64
 RAIDFRAME: Configure (RAID Level 0): total number of sectors is 37165504 (18147 MB)
 RAIDFRAME(RAID Level 0): Using 6 floating recon bufs with no head sep limit
 boot device: raid0
 root on raid0a dumps on raid0b
 root file system type: ffs
 raid0: Device already configured!
 raidctl: ioctl (RAIDFRAME_CONFIGURE) failed: Invalid argument
 raid1: Component /dev/wd0g being configured at row: 0 col: 0
          Row: 0 Column: 0 Num Rows: 1 Num Columns: 2
          Version: 2 Serial Number: 2 Mod Counter: 259
          Clean: Yes Status: 0
 raid1: Component /dev/wd1g being configured at row: 0 col: 1
          Row: 0 Column: 1 Num Rows: 1 Num Columns: 2
          Version: 2 Serial Number: 2 Mod Counter: 259
          Clean: Yes Status: 0
 RAIDFRAME: Configure (RAID Level 0): total number of sectors is 37165504 (18147 MB)
 RAIDFRAME(RAID Level 0): Using 6 floating recon bufs with no head sep limit
 /dev/raid0d: Parity status: clean
 /dev/raid1d: Parity status: clean
 swapctl: adding /dev/wd1a as swap device at priority 0
 swapctl: setting dump device to /dev/wd1a
 Automatic boot in progress: starting file system checks.
 /dev/rraid0a: file system is clean; not checking
 /dev/rraid1a: file system is clean; not checking
 Setting tty flags.
 Setting sysctl variables:
 Starting network.
 Hostname: qingdao
 NIS domainname: realtime.bc.ca
 add net 127.0.0.0: gateway 127.0.0.1
 Configuring network interfaces: ex0 ex1.
 add net default: gateway 192.168.1.32
 Adding interface aliases:
 add net fe80::: gateway ::1
 add net fec0::: gateway ::1
 add net ::ffff:0.0.0.0: gateway ::1
 add net ::224.0.0.0: gateway ::1
 add net ::127.0.0.0: gateway ::1
 add net ::0.0.0.0: gateway ::1
 add net ::255.0.0.0: gateway ::1
 add net 2002:e000::: gateway ::1
 add net 2002:7f00::: gateway ::1
 add net 2002:0000::: gateway ::1
 add net 2002:ff00::: gateway ::1
 add net ::0.0.0.0: gateway ::1
 IPv6 mode: host
 wsdisplay0: screen 0 added (80x25, vt100 emulation)
 wsdisplay0: screen 1 added (80x25, vt100 emulation)
 wsdisplay0: screen 2 added (80x25, vt100 emulation)
 wsdisplay0: screen 3 added (80x25, vt100 emulation)
 wsdisplay0: screen 4 added (80x25, vt100 emulation)
 wskbd0: connecting to wsdisplay0
 wsmux1: connecting to wsdisplay0
 Building databaspes...
 ckbc: cmd failed
 Starting syslogd.
 Checking for core dump...
 savecore: no core dump
 Starting named.
 Setting date via ntp.
 Starting rpcbind.
 Starting ypbind.
 Mounting all filesystems...
 Clearing /tmp.
 Checking quotas: done.
 Starting mountd.
 Starting nfsd.
 swapctl: setting dump device to /dev/wd1a
 Creating runtime link editor directory cache.
 Starting amd.
 Starting ntpd.
 Updating motd.
 Starting lpd.
 starting local daemons: pcnfsd.
 Starting dhcpd.
 Starting inetd.
 Starting cron.
 Fri Jun 29 16:05:52 PDT 2001
 
 NetBSD/i386 (qingdao) (console)
 
 <<< Runs for about 2-4 days here >>>
 
 login: panic: lockmgr: using decommissioned lock
 Stopped in cvs at       cpu_Debugger+0x4:       leave
 db> t
 cpu_Debugger(e3eca8f8,18002,0,e4528c44,c01efe85) at cpu_Debugger+0x4
 panic(c0458120,10002,c052432c,0,0) at panic+0x64
 lockmgr(e3eca8f8,10002,e3f3559c,e3f35500,c052432c) at lockmgr+0xb9
 layer_lock(e4528ca4) at layer_lock+0x4c
 vclean(e3f35500,8,e4043e4c) at vclean+0x71
 vgonel(e3f35500,e4043e4c) at vgonel+0x3b
 getnewvnode(1,c0fbd000,c0ebd100,e4528d48,e3bb6530) at getnewvnode+0x1b9
 ffs_vget(c0fbd000,39a014,e4528dd8,e4528e48,0) at ffs_vget+0x51
 ufs_lookup(e4528e48,e3f1f9f8,e4528f1c,e4528ef8,0) at ufs_lookup+0xc0a
 lookup(e4528ef8,e4528f88,e4043e4c,c115af80,e3708360) at lookup+0x257
 namei(e4528ef8,e4528f88,e4043e4c,e4528f80,3e8) at namei+0x30f
 sys_access(e4043e4c,e4528f88,e4528f80,0,80cb121) at sys_access+0x58
 syscall() at syscall+0x224
 --- syscall (number 33) ---
 0x481473ff:
 db> ps
  PID             PPID       PGRP        UID S   FLAGS          COMMAND    WAIT
 >2029           24830      24830       1000 7  0x4006              cvs
  1967             354       1967       1000 3  0x4186     screen-3.9.8   pause
  1935            1934       1933       1022 3  0x4086             less   ttyin
  1934            1933       1933       1022 3  0x4086               sh    wait
  1933            1817       1933       1022 3  0x4086              man    wait
  1817            1816       1817       1022 3  0x4086             bash    wait
  1816             307        307          0 3  0x4084          telnetd  select
  29632          29614      29632       1000 3  0x4086             bash   ttyin
  29614            307        307          0 3  0x4084          telnetd  select
  27856          27813      27856       1000 3  0x4086             bash   ttyin
  27813            307        307          0 3  0x4084          telnetd  select
  24831            467      24830       1000 3  0x4086              tee   netio
  24830            467      24830       1000 3  0x4086               sh    wait
  23546            467      23546       1000 4  0x5006              cvs
  510              410        510       1000 3  0x4086            jstar   ttyin
  467              466        467       1000 3  0x4086             bash    wait
  466                1        466       1000 3   0x184     screen-3.9.8  select
  410              409        410       1000 3  0x4086             bash    wait
  409              349        409       1000 3  0x4186            xterm  select
  408              407        408       1000 3  0x4086             bash   ttyin
  407              349        407       1000 3  0x4186            xterm  select
  406              405        406       1000 3  0x4086             bash   ttyin
  405              349        405       1000 3  0x4186            xterm  select
  398              397        397       1000 3    0x86               xt   ttyin
  397              348        397       1000 3  0x4086               xt   ttyin
  385              384        385       1000 3  0x4086             bash   ttyin
  384              349        384       1000 3  0x4186            xterm  select
  354              353        354       1000 3  0x4086             bash    wait
  353              349        353       1000 3  0x4186            xterm  select
  350              342        350       1000 3  0x4086             bash   ttyin
  349              344        349       1000 3  0x4086             bash   ttyin
  348              341        348       1000 3  0x4086             bash    wait
  347              343        347       1000 3  0x4086           telnet  select
  346              338        338       1000 3  0x4084         xconsole  select
  345              338        338       1000 3  0x4084           xclock  select
  344              338        338       1000 3  0x4184            xterm  select
  343              338        338       1000 3  0x4184            xterm  select
  342              338        338       1000 3  0x4184            xterm  select
  341              338        338       1000 3  0x4184            xterm  select
  338              334        338       1000 3  0x4084              twm  select
  335              334        335       1000 3  0x4184          XFree86  select
  334              324        324       1000 3  0x4084            xinit    wait
  325                1        325          0 3  0x4086            getty   ttyin
  324                1        324       1000 3  0x4086               sh    wait
  317                1        317          0 3  0x4086            getty   ttyin
  316                1        316          0 3  0x4086            getty   ttyin
  315                1        315          0 3  0x4086            getty   ttyin
  313                1        313          0 3  0x4086            getty   ttyin
  311                1        311          0 3    0x84             cron nanosle
  307                1        307          0 3    0x84            inetd  select
  290                1        290          0 3    0x84            dhcpd  select
  284                1        284          0 3    0x84       rpc.pcnfsd  select
  279                1        279          0 3    0x84              lpd  select
  263                1        263          0 3    0x84             ntpd   pause
  257                0          0          0 3 0x20284            nfsio  nfsidl
  256                0          0          0 3 0x20284            nfsio  nfsidl
  255                0          0          0 3 0x20284            nfsio  nfsidl
  254                0          0          0 3 0x20284            nfsio  nfsidl
  229                1        229          0 3    0x84              amd  select
  212              207        207          0 3    0x84             nfsd    nfsd
  211              207        207          0 3    0x84             nfsd    nfsd
  210              207        207          0 3    0x84             nfsd    nfsd
  209              207        207          0 3    0x84             nfsd    nfsd
  207                1        207          0 3    0x84             nfsd  select
  194                1        194          0 3    0x84           mountd  select
  166                1        166          0 3    0x84           ypbind  select
  159                1        159          0 3    0x84          rpcbind  select
  151                1        151          0 3    0x84            named  select
  137                1        137          0 3    0x84          syslogd  select
  16                 0          0          0 3 0x20204             raid raidfra
  7                  0          0          0 3 0x20204          ioflush  syncer
  6                  0          0          0 3 0x20204           reaper  reaper
  5                  0          0          0 3 0x20204       pagedaemon daemon_
  4                  0          0          0 3 0x20204             raid raidfra
  3                  0          0          0 3 0x20204             usb1  usbevt
  2                  0          0          0 3 0x20204             usb0  usbevt
  1                  0          1          0 3  0x4084             init    wait
  0                 -1          0          0 3 0x20204          swapper schedul