20220101 09:18:42 all: msdos16.sh newfs_msdos: cannot get number of sectors per track: Operation not supported newfs_msdos: cannot get number of heads: Operation not supported /dev/md11: 2095072 sectors in 130942 FAT32 clusters (8192 bytes/cluster) BytesPerSec=512 SecPerClust=16 ResSectors=32 FATs=2 Media=0xf0 SecPerTrack=63 Heads=255 HiddenSecs=0 HugeSectors=2097152 FATsecs=1023 RootCluster=2 FSInfo=1 Backup=2 panic: lock (sx) kernel linker not locked @ kern/kern_linker.c:1044, no vmcore KDB: debugger backends: ddb KDB: current backend: ddb ---<>--- Copyright (c) 1992-2021 The FreeBSD Project. Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994 The Regents of the University of California. All rights reserved. FreeBSD is a registered trademark of The FreeBSD Foundation. FreeBSD 14.0-CURRENT #1 ufs-n252101-39b9e3c44a7: Sat Jan 1 08:49:12 CET 2022 pho@mercat1.netperf.freebsd.org:/var/tmp/deviant3/sys/amd64/compile/PHO amd64 FreeBSD clang version 13.0.0 (git@github.com:llvm/llvm-project.git llvmorg-13.0.0-0-gd7b669b3a303) WARNING: WITNESS option enabled, expect reduced performance. WARNING: DIAGNOSTIC option enabled, expect reduced performance. VT(vga): resolution 640x480 CPU: Intel(R) Xeon(R) CPU E5-1650 v3 @ 3.50GHz (3500.14-MHz K8-class CPU) Origin="GenuineIntel" Id=0x306f2 Family=0x6 Model=0x3f Stepping=2 Features=0xbfebfbff Features2=0x7ffefbff AMD Features=0x2c100800 AMD Features2=0x21 Structured Extended Features=0x37ab XSAVE Features=0x1 VT-x: PAT,HLT,MTF,PAUSE,EPT,UG,VPID,VID,PostIntr TSC: P-state invariant, performance statistics real memory = 34359738368 (32768 MB) avail memory = 33176518656 (31639 MB) Event timer "LAPIC" quality 600 ACPI APIC Table: FreeBSD/SMP: Multiprocessor System Detected: 12 CPUs FreeBSD/SMP: 1 package(s) x 6 core(s) x 2 hardware threads random: registering fast source Intel Secure Key RNG random: fast provider: "Intel Secure Key RNG" random: unblocking device. ioapic0 irqs 0-23 ioapic1 irqs 24-47 Launching APs: 1 5 7 11 6 10 2 8 4 3 9 random: entropy device external interface kbd1 at kbdmux0 vtvga0: smbios0: at iomem 0xf0560-0xf057e smbios0: Version: 2.8, BCD Revision: 2.7 aesni0: acpi0: acpi0: Power Button (fixed) cpu0: on acpi0 atrtc0: port 0x70-0x71,0x74-0x77 irq 8 on acpi0 atrtc0: registered as a time-of-day clock, resolution 1.000000s Event timer "RTC" frequency 32768 Hz quality 0 attimer0: port 0x40-0x43,0x50-0x53 irq 0 on acpi0 Timecounter "i8254" frequency 1193182 Hz quality 0 Event timer "i8254" frequency 1193182 Hz quality 100 hpet0: iomem 0xfed00000-0xfed003ff on acpi0 Timecounter "HPET" frequency 14318180 Hz quality 950 Event timer "HPET" frequency 14318180 Hz quality 350 Event timer "HPET1" frequency 14318180 Hz quality 340 Event timer "HPET2" frequency 14318180 Hz quality 340 Event timer "HPET3" frequency 14318180 Hz quality 340 Event timer "HPET4" frequency 14318180 Hz quality 340 Event timer "HPET5" frequency 14318180 Hz quality 340 Event timer "HPET6" frequency 14318180 Hz quality 340 Event timer "HPET7" frequency 14318180 Hz quality 340 Timecounter "ACPI-fast" frequency 3579545 Hz quality 900 acpi_timer0: <24-bit timer at 3.579545MHz> port 0x408-0x40b on acpi0 pcib0: on acpi0 pci0: on pcib0 pci0: at device 11.1 (no driver attached) pci0: at device 11.2 (no driver attached) pci0: at device 16.1 (no driver attached) pci0: at device 16.6 (no driver attached) pci0: at device 18.1 (no driver attached) acpi_syscontainer0: on acpi0 acpi_syscontainer1: on acpi0 acpi_syscontainer2: on acpi0 acpi_syscontainer3: on acpi0 apei0: on acpi0 pcib1: port 0xcf8-0xcff on acpi0 pci1: on pcib1 pcib2: irq 26 at device 1.0 on pci1 pci2: on pcib2 pcib3: irq 32 at device 2.0 on pci1 pci3: on pcib3 pci3: at device 0.0 (no driver attached) pci3: at device 0.1 (no driver attached) pcib4: irq 40 at device 3.0 on pci1 pci4: on pcib4 pci1: at device 17.0 (no driver attached) ahci0: port 0xf110-0xf117,0xf100-0xf103,0xf0f0-0xf0f7,0xf0e0-0xf0e3,0xf020-0xf03f mem 0xfb438000-0xfb4387ff irq 16 at device 17.4 on pci1 ahci0: AHCI v1.30 with 4 6Gbps ports, Port Multiplier not supported ahcich0: at channel 0 on ahci0 ahcich1: at channel 1 on ahci0 ahcich2: at channel 2 on ahci0 ahcich3: at channel 3 on ahci0 ahciem0: on ahci0 xhci0: mem 0xfb400000-0xfb40ffff irq 19 at device 20.0 on pci1 xhci0: 32 bytes context size, 64-bit DMA usbus0 on xhci0 usbus0: 5.0Gbps Super Speed USB v3.0 pci1: at device 22.0 (no driver attached) pci1: at device 22.1 (no driver attached) ehci0: mem 0xfb434000-0xfb4343ff irq 18 at device 26.0 on pci1 usbus1: EHCI version 1.0 usbus1 on ehci0 usbus1: 480Mbps High Speed USB v2.0 pcib5: irq 16 at device 28.0 on pci1 pci5: on pcib5 pcib6: irq 18 at device 28.2 on pci1 pci6: on pcib6 pcib7: at device 0.0 on pci6 pci7: on pcib7 vgapci0: port 0xe000-0xe07f mem 0xfa000000-0xfaffffff,0xfb000000-0xfb01ffff irq 18 at device 0.0 on pci7 vgapci0: Boot video device pcib8: irq 16 at device 28.4 on pci1 pci8: on pcib8 igb0: port 0xd020-0xd03f mem 0xfb320000-0xfb33ffff,0xfb344000-0xfb347fff irq 16 at device 0.0 on pci8 igb0: EEPROM V1.63-0 eTrack 0x800009fa igb0: Using 1024 TX descriptors and 1024 RX descriptors igb0: queue equality override not set, capping rx_queues at 6 and tx_queues at 6 igb0: Using 6 RX queues 6 TX queues igb0: Using MSI-X interrupts with 7 vectors igb0: Ethernet address: 0c:c4:7a:a8:cd:da igb0: netmap queues/slots: TX 6/1024, RX 6/1024 igb1: port 0xd000-0xd01f mem 0xfb300000-0xfb31ffff,0xfb340000-0xfb343fff irq 17 at device 0.1 on pci8 igb1: EEPROM V1.63-0 eTrack 0x800009fa igb1: Using 1024 TX descriptors and 1024 RX descriptors igb1: queue equality override not set, capping rx_queues at 6 and tx_queues at 6 igb1: Using 6 RX queues 6 TX queues igb1: Using MSI-X interrupts with 7 vectors igb1: Ethernet address: 0c:c4:7a:a8:cd:db igb1: netmap queues/slots: TX 6/1024, RX 6/1024 ehci1: mem 0xfb433000-0xfb4333ff irq 18 at device 29.0 on pci1 usbus2: EHCI version 1.0 usbus2 on ehci1 usbus2: 480Mbps High Speed USB v2.0 isab0: at device 31.0 on pci1 isa0: on isab0 ahci1: port 0xf070-0xf077,0xf060-0xf063,0xf050-0xf057,0xf040-0xf043,0xf000-0xf01f mem 0xfb432000-0xfb4327ff irq 16 at device 31.2 on pci1 ahci1: AHCI v1.30 with 6 6Gbps ports, Port Multiplier not supported ahcich4: at channel 0 on ahci1 ahcich5: at channel 1 on ahci1 ahcich6: at channel 2 on ahci1 ahcich7: at channel 3 on ahci1 ahcich8: at channel 4 on ahci1 ahcich9: at channel 5 on ahci1 ahciem1: on ahci1 acpi_button0: on acpi0 uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0 uart1: <16550 or compatible> port 0x2f8-0x2ff irq 3 on acpi0 uart1: console (115200,n,8,1) orm0: at iomem 0xc0000-0xc7fff,0xcb000-0xcbfff pnpid ORM0000 on isa0 est0: on cpu0 NULL mp in getnewvnode(9), tag crossmp Timecounter "TSC-low" frequency 1749999459 Hz quality 1000 Timecounters tick every 1.000 msec Expensive timeout(9) function: 0xffffffff80507ac0(0xfffffe0109fa4000) 0.004923537 s ses0 at ahciem0 bus 0 scbus4 target 0 lun 0 ses0: SEMB S-E-S 2.00 device ses0: SEMB SES Device ses1 at ahciem1 bus 0 scbus11 target 0 lun 0 ses1: SEMB S-E-S 2.00 device ses1: SEMB SES Device ses0: pass0 in 'Slot 01', SATA Slot: scbus1 target 0 ada0 at ahcich1 bus 0 scbus1 target 0 lun 0 ada0: ACS-2 ATA SATA 3.x device ada0: Serial Number WD-WCC3F3KDDP98 ada0: 600.000MB/s transfers (SATA 3.x, UDMA6, PIO 8192bytes) ada0: Command Queueing enabled ada0: 953869MB (1953525168 512 byte sectors) ada0: quirks=0x1<4K> Expensive timeout(9) function: 0xffffffff80a509c0(0xffffffff81ae9518) 0.007385426 s mlx5_core0: mem 0xf8000000-0xf9ffffff irq 32 at device 0.0 on pci3 mlx5: Mellanox Core driver 3.7.0 (July 2021)ugen1.1: at usbus1 ugen0.1: <0x8086 XHCI root HUB> at usbus0 ugen2.1: at usbus2 uhub0 on usbus1 uhub0: on usbus1 uhub1 on usbus0 uhub1: <0x8086 XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus0 uhub2 on usbus2 uhub2: on usbus2 mlx5_core1: WARN: mlx5_vsc_set_space:124:(pid 0): Space 0x7 is not supported. mlx5_core1: WARN: mlx5_fwdump_prep:99:(pid 0): VSC scan space is not supported mlx5_core1: INFO: init_one:1614:(pid 0): cannot find SR-IOV PCIe cap mlx5_core: INFO: (mlx5_core1): E-Switch: Total vports 1, l2 table size(65536), per vport: max uc(1024) max mc(16384) mlx5_core1: Failed to initialize SR-IOV support, error 2 mce0: Ethernet address: 7c:fe:90:30:ee:38 mce0: link state changed to DOWN mlx5_core0: ERR: mlx5_cmd_check:710:(pid 0): ACCESS_REG(0x805) op_mod(0x1) failed, status bad parameter(0x3), syndrome (0x6c4d48) mlx5_core0: ERR: mlx5_cmd_check:710:(pid 0): ACCESS_REG(0x805) op_mod(0x1) failed, status bad parameter(0x3), syndrome (0x6c4d48) mce1: Ethernet address: 7c:fe:90:30:ee:39 mce1: link state changed to DOWN mlx5_core1: ERR: mlx5_cmd_check:710:(pid 0): ACCESS_REG(0x805) op_mod(0x1) failed, status bad parameter(0x3), syndrome (0x6c4d48) mlx5_core1: ERR: mlx5_cmd_check:710:(pid 0): ACCESS_REG(0x805) op_mod(0x1) failed, status bad parameter(0x3), syndrome (0x6c4d48) Trying to mount root from ufs:/dev/gpt/work [rw]... WARNING: WITNESS option enabled, expect reduced performance. WARNING: DIAGNOSTIC option enabled, expect reduced performance. WARNING: / was not properly dismounted Dual Console: Serial Primary, Video Secondary uhub0: 2 ports with 2 removable, self powered uhub2: 2 ports with 2 removable, self powered uhub1: 21 ports with 21 removable, self powered igb0: link state changed to UP debugnet_any_ifnet_update: Bad dn_init result from igb0 (ifp 0xfffff8000600b800), ignoring. ugen0.2: at usbus0 uhub3 on uhub1 uhub3: on usbus0 Interface IP-Address Broadcast ugen1.2: at usbus1 uhub4 on uhub0 uhub4: on usbus1 ugen2.2: at usbus2 uhub5 on uhub2 uhub5: on usbus2 Setting hostuuid: 00000000-0000-0000-0000-002590c99976. Setting hostid: 0x90b83241. uhub3: 4 ports wuhub4: 6 ports with 6 removable, self powered Starting file system checks: WARNING: / was not properly dismounted uhub5: 8 ports with 8 removable, self powered Mounting local filesystems:. ugen0.3: at usbus0 ukbd0 on uhub3 ukbd0: on usbus0 kbd2 at ukbd0 fsck** /dev/gpt/tmp ** Last Mounted on /tmp ** Phase 1 - Check Blocks and Sizes ** Phase 2 - Check Pathnames ** Phase 3 - Check Connectivity ** Phase 4 - Check Reference Counts ** Phase 5 - Check Cyl groups 405 files, 19266 used, 1246141 free (69 frags, 155759 blocks, 0.0% fragmentation) ***** FILE SYSTEM IS CLEAN ***** Updating CPU Microcode... CPU: Intel(R) Xeon(R) CPU E5-1650 v3 @ 3.50GHz (3500.00-MHz K8-class CPU) Origin="GenuineIntel" Id=0x306f2 Family=0x6 Model=0x3f Stepping=2 Features=0xbfebfbff Features2=0x7ffefbff AMD Features=0x2c100800 AMD Features2=0x21 Structured Extended Features=0x37ab Structured Extended Features3=0x9c000400 XSAVE Features=0x1 VT-x: PAT,HLT,MTF,PAUSE,EPT,UG,VPID,VID,PostIntr TSC: P-state invariant, performance statistics Done. ELF ldconfig path: /lib /usr/lib /usr/lib/compat /usr/local/lib /usr/local/lib/compat/pkg /usr/local/lib/compat /usr/local/lib/compat/pkg /usr/local/lib/e2fsprogs /usr/local/lib/gcc10 /usr/local/lib/gcc8 /usr/local/lib/gcc9 /usr/local/lib/perl5/5.32/mach/CORE 32-bit compatibility ldconfig path: /usr/lib32 /usr/local/lib32/compat Setting hostname: mercat1.netperf.freebsd.org. Setting up harvesting: PURE_RDRAND,[UMA],[FS_ATIME],SWI,INTERRUPT,NET_NG,[NET_ETHER],NET_TUN,MOUSE,KEYBOARD,ATTACH,CACHED Feeding entropy: . Autoloading module: acpi_wmi.ko Autoloading module: ichsmb.ko Autoloading module: ioat.ko ioat0: mem 0xfb42c000-0xfb42ffff irq 31 at device 4.0 on pci1 ioat0: Capabilities: 2f7 ioat1: mem 0xfb428000-0xfb42bfff irq 39 at device 4.1 on pci1 ioat1: Capabilities: 2f7 ioat2: mem 0xfb424000-0xfb427fff irq 31 at device 4.2 on pci1 ioat2: Capabilities: f7 ioat3: mem 0xfb420000-0xfb423fff irq 39 at device 4.3 on pci1 ioat3: Capabilities: f7 ioat4: mem 0xfb41c000-0xfb41ffff irq 31 at device 4.4 on pci1 ioat4: Capabilities: f7 ioat5: mem 0xfb418000-0xfb41bfff irq 39 at device 4.5 on pci1 ioat5: Capabilities: f7 ioat6: mem 0xfb414000-0xfb417fff irq 31 at device 4.6 on pci1 ioat6: Capabilities: f7 ioat7: mem 0xfb410000-0xfb413fff irq 39 at device 4.7 on pci1 ioat7: Capabilities: f7 ichsmb0: port 0x580-0x59f mem 0xfb431000-0xfb4310ff irq 18 at device 31.3 on pci1 smbus0: on ichsmb0 acpi_wmi0: on acpi0 acpi_wmi0: cannot find EC device lo0: link state changed to UP igb0: link state changed to DOWN Starting Network: lo0 igb0 igb1 mce0 mce1. lo0: flags=8049 metric 0 mtu 16384 options=680003 inet6 ::1 prefixlen 128 inet6 fe80::1%lo0 prefixlen 64 scopeid 0x3 inet 127.0.0.1 netmask 0xff000000 groups: lo nd6 options=21 igb0: flags=8863 metric 0 mtu 1500 options=4e507bb ether 0c:c4:7a:a8:cd:da inet6 fe80::ec4:7aff:fea8:cdda%igb0 prefixlen 64 scopeid 0x1 media: Ethernet autoselect status: no carrier nd6 options=21 igb1: flags=8822 metric 0 mtu 1500 options=4e507bb ether 0c:c4:7a:a8:cd:db media: Ethernet autoselect status: no carrier nd6 options=29 mce0: flags=8822 metric 0 mtu 1500 options=7eed07bb ether 7c:fe:90:30:ee:38 media: Ethernet autoselect status: no carrier nd6 options=29 mce1: flags=8822 metric 0 mtu 1500 options=7eed07bb ether 7c:fe:90:30:ee:39 media: Ethernet autoselect status: no carrier nd6 options=29 Starting devd. igb0: link state changed to UP Autoloading module: acpi_wmi.ko Autoloading module: ioat.ko Autoloading module: ioat.ko Autoloading module: ioat.ko Autoloading module: ioat.ko Autoloading module: ioat.ko Autoloading module: ioat.ko Autoloading module: ioat.ko Autoloading module: ioat.ko Starting Network: igb1. igb1: flags=8822 metric 0 mtu 1500 options=4e507bb ether 0c:c4:7a:a8:cd:db media: Ethernet autoselect status: no carrier nd6 options=29 Autoloading module: ichsmb.ko Starting Network: mce0. mce0: flags=8822 metric 0 mtu 1500 options=7eed07bb ether 7c:fe:90:30:ee:38 media: Ethernet autoselect status: no carrier nd6 options=29 Starting Network: mce1. mce1: flags=8822 metric 0 mtu 1500 options=7eed07bb ether 7c:fe:90:30:ee:39 media: Ethernet autoselect status: no carrier nd6 options=29 Starting dhclient. DHCPREQUEST on igb0 to 255.255.255.255 port 67 DHCPACK from 192if_delmulti_locked: detaching ifnet instance 0xfffff8000600b800 .168.5.1 bound to 192.168.5.171 -- renewal in 300 seconds. Autoloading module: uhid.ko Autoloading module: ums.ko Autoloading module: usbhid.ko ums0 on uhub3 ums0: on usbus0 ums0: 3 buttons and [Z] coordinates ID=0 add host 127.0.0.1: gateway lo0 fib 0: route already in table add host ::1: gateway lo0 fib 0: route already in table add net fe80::: gateway ::1 add net ff02::: gateway ::1 add net ::ffff:0.0.0.0: gateway ::1 add net ::0.0.0.0: gateway ::1 Creating and/or trimming log files. Recovering vi editor sessions:. Updating motd:. Clearing /tmp (X related). Starting syslogd. Starting watchdogd. No core dumps found. Setting date via ntp. 1 Jan 08:52:32 ntpdate[2605]: step time server 192.168.5.1 offset -0.254828 sec Starting rpcbind. NFS access cache time=60 Starting statd. Starting lockd. Starting powerd. Security policy loaded: MAC/ntpd (mac_ntpd) Starting ntpd. Mounting late filesystems:. Starting inetd. Configuring vt: blanktime. Performing sanity check on sshd configuration. Starting sshd. Starting cron. sysctl: unknown oid 'debug.fail_point.pmap_allocpte_page_alloc' at line 12 Sat Jan 1 08:52 FreeBSD/amd64 (mercat1.netperf.freebsd.org) (ttyu1) login: Jan 1 08:52:54 mercat1 su[2757]: pho to root on /dev/pts/0 20220101 08:53:21 all (1/1): msdos16.sh 20220101 08:53:53 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 08:53:59 all (1/1): msdos16.sh 20220101 08:54:05 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073745920, length=4096)]error = 5 20220101 08:54:41 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 08:54:51 all (1/1): msdos16.sh 20220101 08:55:09 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 08:55:24 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 08:55:49 all (1/1): msdos16.sh 20220101 08:56:01 all (1/1): msdos16.sh 20220101 08:56:05 all (1/1): msdos16.sh 20220101 08:56:22 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 08:56:46 all (1/1): msdos16.sh 20220101 08:56:50 all (1/1): msdos16.sh 20220101 08:57:08 all (1/1): msdos16.sh 20220101 08:57:34 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 08:57:53 all (1/1): msdos16.sh 20220101 08:58:17 all (1/1): msdos16.sh 20220101 08:58:44 all (1/1): msdos16.sh 20220101 08:58:52 all (1/1): msdos16.sh 20220101 08:59:03 all (1/1): msdos16.sh 20220101 08:59:09 all (1/1): msdos16.sh 20220101 08:59:16 all (1/1): msdos16.sh 20220101 08:59:56 all (1/1): msdos16.sh 20220101 09:00:02 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 09:00:26 all (1/1): msdos16.sh 20220101 09:00:44 all (1/1): msdos16.sh 20220101 09:00:49 all (1/1): msdos16.sh 20220101 09:00:58 all (1/1): msdos16.sh 20220101 09:01:22 all (1/1): msdos16.sh 20220101 09:01:35 all (1/1): msdos16.sh 20220101 09:01:47 all (1/1): msdos16.sh 20220101 09:02:27 all (1/1): msdos16.sh 20220101 09:02:39 all (1/1): msdos16.sh 20220101 09:03:15 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 09:03:19 all (1/1): msdos16.sh g_dev_taste: make_dev_p() failed (gp->name=msdosfs/st%20entry%20*/, error=22) g_dev_taste: make_dev_p() failed (gp->name=msdosfs/st%20entry%20*/, error=22) 20220101 09:03:28 all (1/1): msdos16.sh 20220101 09:04:23 all (1/1): msdos16.sh 20220101 09:04:27 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 09:04:37 all (1/1): msdos16.sh 20220101 09:04:41 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 09:04:45 all (1/1): msdos16.sh 20220101 09:04:55 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 09:05:13 all (1/1): msdos16.sh 20220101 09:05:23 all (1/1): msdos16.sh 20220101 09:05:36 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 09:05:40 all (1/1): msdos16.sh 20220101 09:05:48 all (1/1): msdos16.sh 20220101 09:06:32 all (1/1): msdos16.sh 20220101 09:06:39 all (1/1): msdos16.sh 20220101 09:06:43 all (1/1): msdos16.sh 20220101 09:06:54 all (1/1): msdos16.sh 20220101 09:07:15 all (1/1): msdos16.sh 20220101 09:07:26 all (1/1): msdos16.sh 20220101 09:07:30 all (1/1): msdos16.sh 20220101 09:07:44 all (1/1): msdos16.sh 20220101 09:07:53 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073745920, length=4096)]error = 5 20220101 09:08:58 all (1/1): msdos16.sh 20220101 09:09:02 all (1/1): msdos16.sh 20220101 09:09:09 all (1/1): msdos16.sh 20220101 09:09:19 all (1/1): msdos16.sh 20220101 09:09:28 all (1/1): msdos16.sh 20220101 09:10:00 all (1/1): msdos16.sh 20220101 09:10:15 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 09:10:26 all (1/1): msdos16.sh 20220101 09:10:34 all (1/1): msdos16.sh 20220101 09:10:40 all (1/1): msdos16.sh 20220101 09:11:01 all (1/1): msdos16.sh 20220101 09:11:05 all (1/1): msdos16.sh 20220101 09:11:42 all (1/1): msdos16.sh 20220101 09:11:49 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 09:11:55 all (1/1): msdos16.sh 20220101 09:12:03 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073745920, length=4096)]error = 5 20220101 09:12:23 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 09:12:29 all (1/1): msdos16.sh 20220101 09:12:33 all (1/1): msdos16.sh 20220101 09:12:46 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073745920, length=4096)]error = 5 20220101 09:13:14 all (1/1): msdos16.sh 20220101 09:13:18 all (1/1): msdos16.sh 20220101 09:13:35 all (1/1): msdos16.sh 20220101 09:13:47 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 09:13:55 all (1/1): msdos16.sh 20220101 09:14:20 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 09:14:42 all (1/1): msdos16.sh 20220101 09:14:46 all (1/1): msdos16.sh 20220101 09:14:52 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=4294983680, length=4096)]error = 5 g_vfs_done():md11[READ(offset=4294983680, length=4096)]error = 5 g_vfs_done():md11[READ(offset=4294983680, length=4096)]error = 5 g_vfs_done():md11[READ(offset=4294983680, length=4096)]error = 5 g_vfs_done():md11[READ(offset=4294983680, length=4096)]error = 5 g_vfs_done():md11[READ(offset=4294983680, length=4096)]error = 5 20220101 09:15:16 all (1/1): msdos16.sh 20220101 09:15:20 all (1/1): msdos16.sh 20220101 09:15:30 all (1/1): msdos16.sh 20220101 09:15:52 all (1/1): msdos16.sh 20220101 09:16:00 all (1/1): msdos16.sh 20220101 09:16:10 all (1/1): msdos16.sh 20220101 09:16:30 all (1/1): msdos16.sh 20220101 09:16:35 all (1/1): msdos16.sh 20220101 09:16:45 all (1/1): msdos16.sh 20220101 09:17:12 all (1/1): msdos16.sh 20220101 09:17:54 all (1/1): msdos16.sh 20220101 09:18:11 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 09:18:16 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 g_vfs_done():md11[READ(offset=1073754112, length=4096)]error = 5 20220101 09:18:35 all (1/1): msdos16.sh g_vfs_done():md11[READ(offset=15452777561088, length=8192)]error = 5 g_vfs_done():md11[READ(offset=5656494029824, length=8192)]error = 5 20220101 09:18:42 all (1/1): msdos16.sh Expensive timeout(9) function: 0xffffffff80f67080(0) 24.627478003 s root Last login: Fri Dec 31 21:16:11 on ttyu1 Jan 1 09:53:53 mercat1 login[2746]: ROOT LOGIN (root) ON ttyu1 FreeBSD 14.0-CURRENT (PHO) #1 ufs-n252101-39b9e3c44a7: Sat Jan 1 08:49:12 CET 2022 root@mercat1:~ # ps -lp47832 UID PID PPID C PRI NI VSZ RSS MWCHAN STAT TT TIME COMMAND 0 47832 47831 3 52 0 12828 2088 - R+ 0 35:44.90 mount_msdosfs /dev/md11 /mnt11 procroot@mercat1:~ # procstat -k 47832 PID TID COMM TDNAME KSTACK 47832 100458 mount_msdosfs - __mtx_lock_flags vm_reserv_free_page vm_page_free_prep vm_page_free_toq _kmem_unback kmem_back_domain kmem_malloc_domainset malloc_large mountmsdosfs msdosfs_mount vfs_domount_first vfs_domount vfs_donmount sys_nmount amd64_syscall fast_syscall_common root@mercat1:~ # root@mercat1:~ # KDB: enter: Break to debugger [ thread pid 47832 tid 100458 ] Stopped at kdb_alt_break_internal+0x101: movq $0,0x1280594(%rip) db> run pho db:0:pho> set $lines 20000 db:0:pho> run pho1 db:1:pho1> dump Dumping 1789 out of 32626 MB:lock order reversal: 1st 0xfffff808486212c0 vm reserv (vm reserv, sleep mutex) @ vm/vm_reserv.c:1043 2nd 0xfffff8000424e4d0 CAM device lock (CAM device lock, sleep mutex) @ cam/cam_xpt.c:5445 panic: lock (sx) kernel linker not locked @ kern/kern_linker.c:1044 cpuid = 0 time = 1641027294 KDB: stack backtrace: db_trace_self_wrapper() at db_trace_self_wrapper+0x2b/frame 0xfffffe010dbba430 vpanic() at vpanic+0x17f/frame 0xfffffe010dbba480 panic() at panic+0x43/frame 0xfffffe010dbba4e0 witness_unlock() at witness_unlock+0x144/frame 0xfffffe010dbba520 _sx_sunlock_int() at _sx_sunlock_int+0x59/frame 0xfffffe010dbba550 linker_search_symbol_name_flags() at linker_search_symbol_name_flags+0x90/frame 0xfffffe010dbba580 stack_sbuf_print_flags() at stack_sbuf_print_flags+0x8e/frame 0xfffffe010dbba610 witness_checkorder() at witness_checkorder+0xbf2/frame 0xfffffe010dbba7d0 __mtx_lock_flags() at __mtx_lock_flags+0x94/frame 0xfffffe010dbba820 xpt_done_process() at xpt_done_process+0x3a4/frame 0xfffffe010dbba860 xpt_sim_poll() at xpt_sim_poll+0x10e/frame 0xfffffe010dbba890 xpt_pollwait() at xpt_pollwait+0x35/frame 0xfffffe010dbba8b0 cam_periph_runccb() at cam_periph_runccb+0x141/frame 0xfffffe010dbbaa00 adadump() at adadump+0x217/frame 0xfffffe010dbbab60 _dump_append() at _dump_append+0x158/frame 0xfffffe010dbbabd0 blk_write() at blk_write+0x144/frame 0xfffffe010dbbac10 cpu_minidumpsys() at cpu_minidumpsys+0x964/frame 0xfffffe010dbbacd0 dumpsys_generic() at dumpsys_generic+0x5a/frame 0xfffffe010dbbadb0 doadump() at doadump+0x88/frame 0xfffffe010dbbadd0 db_dump() at db_dump+0x2a/frame 0xfffffe010dbbadf0 db_command() at db_command+0x372/frame 0xfffffe010dbbaec0 db_script_exec() at db_script_exec+0x1b8/frame 0xfffffe010dbbaf70 db_command() at db_command+0x372/frame 0xfffffe010dbbb040 db_script_exec() at db_script_exec+0x1b8/frame 0xfffffe010dbbb0f0 db_command() at db_command+0x372/frame 0xfffffe010dbbb1c0 db_command_loop() at db_command_loop+0x4d/frame 0xfffffe010dbbb1d0 db_trap() at db_trap+0xe6/frame 0xfffffe010dbbb260 kdb_trap() at kdb_trap+0x14b/frame 0xfffffe010dbbb2d0 trap() at trap+0x84a/frame 0xfffffe010dbbb3e0 calltrap() at calltrap+0x8/frame 0xfffffe010dbbb3e0 --- trap 0x3, rip = 0xffffffff80c57af1, rsp = 0xfffffe010dbbb4b0, rbp = 0xfffffe010dbbb4d0 --- kdb_alt_break_internal() at kdb_alt_break_internal+0x101/frame 0xfffffe010dbbb4d0 kdb_alt_break() at kdb_alt_break+0xb/frame 0xfffffe010dbbb4e0 uart_intr_rxready() at uart_intr_rxready+0xf5/frame 0xfffffe010dbbb510 uart_intr() at uart_intr+0x128/frame 0xfffffe010dbbb550 intr_event_handle() at intr_event_handle+0x129/frame 0xfffffe010dbbb5c0 intr_execute_handlers() at intr_execute_handlers+0x66/frame 0xfffffe010dbbb5f0 Xapic_isr1() at Xapic_isr1+0xdc/frame 0xfffffe010dbbb5f0 --- interrupt, rip = 0xffffffff80f98820, rsp = 0xfffffe010dbbb6c0, rbp = 0xfffffe010dbbb700 --- vm_reserv_free_page() at vm_reserv_free_page+0x320/frame 0xfffffe010dbbb700 vm_page_free_prep() at vm_page_free_prep+0x1eb/frame 0xfffffe010dbbb720 vm_page_free_toq() at vm_page_free_toq+0x12/frame 0xfffffe010dbbb750 _kmem_unback() at _kmem_unback+0x114/frame 0xfffffe010dbbb790 kmem_back_domain() at kmem_back_domain+0x209/frame 0xfffffe010dbbb7f0 kmem_malloc_domainset() at kmem_malloc_domainset+0xaf/frame 0xfffffe010dbbb860 malloc_large() at malloc_large+0x2f/frame 0xfffffe010dbbb890 mountmsdosfs() at mountmsdosfs+0xc3e/frame 0xfffffe010dbbb980 msdosfs_mount() at msdosfs_mount+0x7eb/frame 0xfffffe010dbbbac0 vfs_domount_first() at vfs_domount_first+0x21f/frame 0xfffffe010dbbbbf0 vfs_domount() at vfs_domount+0x2cf/frame 0xfffffe010dbbbd20 vfs_donmount() at vfs_donmount+0x878/frame 0xfffffe010dbbbdc0 sys_nmount() at sys_nmount+0x69/frame 0xfffffe010dbbbe00 amd64_syscall() at amd64_syscall+0x145/frame 0xfffffe010dbbbf30 fast_syscall_common() at fast_syscall_common+0xf8/frame 0xfffffe010dbbbf30 --- syscall (378, FreeBSD ELF64, sys_nmount), rip = 0x28c0ab570bca, rsp = 0x7fffffffdf78, rbp = 0x7fffffffe5f0 --- Uptime: 1h2m43s -- Test scenario: misc/msdos16.sh