> Date: Fri, 21 Aug 2020 20:41:25 +0200 > From: Klemens Nanni <[email protected]> > Content-Type: text/plain; charset=us-ascii > Content-Disposition: inline > > This happens on clean -CURRENT on an EdgeRouter PRO 8: > > Index: sys/arch/octeon/conf/GENERIC.MP > =================================================================== > RCS file: /cvs/src/sys/arch/octeon/conf/GENERIC.MP,v > retrieving revision 1.1 > diff -u -p -r1.1 GENERIC.MP > --- sys/arch/octeon/conf/GENERIC.MP 1 Jun 2013 17:09:41 -0000 1.1 > +++ sys/arch/octeon/conf/GENERIC.MP 21 Aug 2020 18:08:02 -0000 > @@ -3,6 +3,8 @@ > include "arch/octeon/conf/GENERIC" > > option MULTIPROCESSOR > -#option MP_LOCKDEBUG > +option MP_LOCKDEBUG > +option WITNESS > +option DEBUG > > cpu* at mainbus? > > > Although this message is under MP_LOCKDEBUG, that plus WITNESS is not > enough to trigger it: DEBUG is the relevant option and disabling it > makes the kernel boot, albeit much slower.
These are *debug* options. Not all combinations make sense. And DEBUG in particular almost never makes sense, It enables random debug stuff that varies by platform. Don't use it! One particular problem with MP_LOCKDEBUG is that there is a fixed global pinout count. And for some platforms it simply is too low. Hitting the timeout does not necessarily mean there is a problem! > dmesg and ddb output below. > > > OpenBSD 6.7-current (GENERIC.MP) #1: Fri Aug 21 18:04:20 UTC 2020 > [email protected]:/usr/src/sys/arch/octeon/compile/GENERIC.MP > real mem = 2147483648 (2048MB) > avail mem = 2080833536 (1984MB) > random: good seed from bootblocks > mainbus0 at root: board 20003 rev 0.14 > cpu0 at mainbus0: CN61xx CPU rev 0.1 1000 MHz, Software FP emulation > cpu0: cache L1-I 37KB 37 way D 32KB 32 way, L2 1024KB 8 way > cpu0: L1 set size 1024:1024 > cpu0: L1 line size 128:128 > cpu0: L2 line size 128 > cpu0: cache configuration 0 > cpu0: virtual alias mask 0x0 > cpu0: config register ffffffff8000c48a, status register 00000000000097a0 > cpu1 at mainbus0: CN61xx CPU rev 0.1 1000 MHz, Software FP emulation > cpu1: cache L1-I 37KB 37 way D 32KB 32 way, L2 1024KB 8 way > cpu1: L1 set size 1024:1024 > cpu1: L1 line size 128:128 > cpu1: L2 line size 128 > cpu1: cache configuration 0 > cpu1: virtual alias mask 0x0 > cpu1: config register ffffffff8000c48a, status register 00000000000097a0 > clock0 at mainbus0: int 5 > octcrypto0 at mainbus0 > iobus0 at mainbus0 > octeon_pcibus_match, no PCI host function detected. > simplebus0 at iobus0: "soc" > octciu0 at simplebus0 > octgpio0 at simplebus0: 20 pins, xbit 16 > octsmi0 at simplebus0 > octsmi1 at simplebus0 > octpip0 at simplebus0 > octgmx0 at octpip0 interface 0 > cnmac0 at octgmx0: SGMII, address 24:a4:3c:3c:3b:d0 > ukphy0 at cnmac0 phy 4: Generic IEEE 802.3u media interface, rev. 3: OUI > 0x180361, model 0x0004 > cnmac1 at octgmx0: SGMII, address 24:a4:3c:3c:3b:d1 > ukphy1 at cnmac1 phy 5: Generic IEEE 802.3u media interface, rev. 3: OUI > 0x180361, model 0x0004 > cnmac2 at octgmx0: SGMII, address 24:a4:3c:3c:3b:d2 > brgphy0 at cnmac2 phy 0: BCM5482 10/100/1000baseT PHY, rev. 2 > cnmac3 at octgmx0: SGMII, address 24:a4:3c:3c:3b:d3 > brgphy1 at cnmac3 phy 1: BCM5482 10/100/1000baseT PHY, rev. 2 > octgmx1 at octpip0 interface 1 > cnmac4 at octgmx1: SGMII, address 24:a4:3c:3c:3b:d4 > ukphy2 at cnmac4 phy 0: Generic IEEE 802.3u media interface, rev. 3: OUI > 0x180361, model 0x0004 > cnmac5 at octgmx1: SGMII, address 24:a4:3c:3c:3b:d5 > ukphy3 at cnmac5 phy 1: Generic IEEE 802.3u media interface, rev. 3: OUI > 0x180361, model 0x0004 > cnmac6 at octgmx1: SGMII, address 24:a4:3c:3c:3b:d6 > ukphy4 at cnmac6 phy 2: Generic IEEE 802.3u media interface, rev. 3: OUI > 0x180361, model 0x0004 > cnmac7 at octgmx1: SGMII, address 24:a4:3c:3c:3b:d7 > ukphy5 at cnmac7 phy 3: Generic IEEE 802.3u media interface, rev. 3: OUI > 0x180361, model 0x0004 > "i2c" at simplebus0 not configured > "i2c" at simplebus0 not configured > com0 at simplebus0: ns16550a, 64 byte fifo > com0: console > com1 at simplebus0: ns16550a, 64 byte fifo > com1: probed fifo depth: 0 bytes > "spi" at simplebus0 not configured > octmmc0 at simplebus0 > sdmmc0 at octmmc0: 8-bit, mmc high-speed > "bootbus" at simplebus0 not configured > "dma-engine" at simplebus0 not configured > "dma-engine" at simplebus0 not configured > octuctl0 at simplebus0 > ehci0 at octuctl0 > usb0 at ehci0: USB revision 2.0 > uhub0 at usb0 configuration 1 interface 0 "Octeon EHCI root hub" rev > 2.00/1.00 addr 1 > ohci0 at octuctl0, version 1.0 > usb1 at ohci0: USB revision 1.0 > uhub1 at usb1 configuration 1 interface 0 "Octeon OHCI root hub" rev > 1.00/1.00 addr 1 > octrng0 at iobus0 base 0x1400000000000 irq 0 > octpcie0 at iobus0: 2 ports > octpcie0 port 1: reset timeout > Timecounters tick every 10.000 msec > scsibus0 at sdmmc0: 2 targets, initiator 0 > sd0 at scsibus0 targ 1 lun 0: <Sandisk, SEM04G, 0000> removable > sd0: 3776MB, 512 bytes/sector, 7733248 sectors > dev=0x2 chrdev=0x902 rawdev=0x902 > vscsi0 at root > scsibus1 at vscsi0: 256 targets > softraid0 at root > scsibus2 at softraid0: 256 targets > root on sd0a (c6e0c1054d42413a.a) swap on sd0b dump on sd0b > dev=0x0 chrdev=0x900 rawdev=0x902 > WARNING: CHECK AND RESET THE DATE! > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > init: copying out path `/sbin/init' 11 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > exec_md_map: p 0x9800000001a8b058 fppgva 0x2d06ae4000 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu0 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > mips64_ipi_nop on cpu1 > __mp_lock_spin: 0xffffffff8170f400 lock spun out > db_enter_ddb 0: state 0 pause 0 > Sdtbo_pepnetde ra_tddb 1: s t a t e db1_ enptaeurse+ 01 > 4: jr ra x > db_enter+0x8: nop > ddb{0}> bt > db_enter+0x4 > (c000000000004870,b93f2fff3d1cf1f1,c7d5acfbd4b37a88,81fe909faca48d96) ra > 0xffffffff812563c8 sp 0x980000000f0b7a80, sz 0 > __mp_lock+0x1f0 > (c000000000004870,be252191e519715c,c7d5acfbd4b37a88,81fe909faca48d96) ra > 0xffffffff812cc3f8 sp 0x980000000f0b7a80, sz 64 > dosoftint+0x70 > (c000000000004870,91a8fe504e27195b,c7d5acfbd4b37a88,81fe909faca48d96) ra > 0xffffffff8123e02c sp 0x980000000f0b7ac0, sz 48 > interrupt+0x1d4 > (c000000000004870,a2ba2e2bdb1e7799,c7d5acfbd4b37a88,81fe909faca48d96) ra > 0xffffffff8146a93c sp 0x980000000f0b7af0, sz 80 > k_intr+0xcc > (980000000f0b7b68,a2ba2e2bdb1e7799,c7d5acfbd4b37a88,ffffffff81138d40) ra 0x0 > sp 0x980000000f0b7b40, sz 0 > (KERNEL INTERRUPT) > setsoftintr0+0x10 > (980000000f0b7b68,a2ba2e2bdb1e7799,c7d5acfbd4b37a88,ffffffff81138d40) ra > 0xffffffff811166d0 sp 0x980000000f0b7cc0, sz 0 > octciu_splx+0x140 > (980000000f0b7b68,8d133c76a6230488,c7d5acfbd4b37a88,ffffffff81138d40) ra > 0xffffffff8123e530 sp 0x980000000f0b7cc0, sz 80 > splx+0x48 > (980000000f0b7b68,7e6edce7ef6926b7,c7d5acfbd4b37a88,ffffffff81138d40) ra > 0xffffffff81256d5c sp 0x980000000f0b7d10, sz 16 > mtx_leave+0xfc > (980000000f0b7b68,777347a4fa46e628,c7d5acfbd4b37a88,ffffffff81138d40) ra > 0xffffffff812f808c sp 0x980000000f0b7d20, sz 32 > uvm_pagezero_thread+0xe4 > (980000000f0b7b68,777347a4fa46e628,c7d5acfbd4b37a88,ffffffff81138d40) ra > 0xffffffff8129ba74 sp 0x980000000f0b7d40, sz 112 > proc_trampoline+0x1c > (980000000f0b7b68,777347a4fa46e628,c7d5acfbd4b37a88,ffffffff81138d40) ra 0x0 > sp 0x980000000f0b7db0, sz 0 > User-level: pid 98070 > ddb{0}> show all locks > CPU 1: > exclusive mutex &uvm.fpageqlock r = 0 (0xffffffff817e4238) > Process 19060 (init) thread 0x9800000001a8b608 (430868) > exclusive kernel_lock &kernel_lock r = 1 (0xffffffff8170f488) > ddb{0}> ps > PID TID PPID UID S FLAGS WAIT COMMAND > 19060 430868 1 0 7 0x1005 init > 40588 217437 0 0 3 0x14200 bored smr > 7196 101196 0 0 3 0x40014200 idle1 > *98070 408616 0 0 7 0x14200 zerothread > 62753 304753 0 0 3 0x14200 aiodoned aiodoned > 37625 21886 0 0 3 0x14200 syncer update > 43671 372171 0 0 3 0x14200 cleaner cleaner > 53309 199018 0 0 3 0x14200 reaper reaper > 80147 341017 0 0 3 0x14200 pgdaemon pagedaemon > 93317 318601 0 0 3 0x14200 bored crynlk > 91591 234017 0 0 3 0x14200 bored crypto > 48535 161966 0 0 3 0x14200 usbtsk usbtask > 75715 474993 0 0 3 0x14200 usbatsk usbatsk > 60111 119416 0 0 3 0x14200 mmctsk sdmmc0 > 72819 353144 0 0 3 0x14200 bored softnet > 48915 250876 0 0 3 0x14200 bored systqmp > 37175 429426 0 0 3 0x14200 bored systq > 10098 143061 0 0 3 0x40014200 bored softclock > 42758 21280 0 0 3 0x40014200 idle0 > 1 83579 0 0 3 0x82 wait init > 0 0 -1 0 3 0x10200 scheduler swapper > >
