FreeBSD-main-amd64-test - Build #20383 - Failure

From: <jenkins-admin_at_FreeBSD.org>
Date: Fri, 14 Jan 2022 12:51:17 UTC
FreeBSD-main-amd64-test - Build #20383 (5f5e32f1b3945087a687c5962071d3f46e34e1ff) - Failure

Build information: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/20383/
Full change log: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/20383/changes
Full build log: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/20383/console

Status explanation:
"Failure" - the build is suspected being broken by the following changes
"Still Failing" - the build has not been fixed by the following changes and
                  this is a notification to note that these changes have
                  not been fully tested by the CI system

Change summaries:
(Those commits are likely but not certainly responsible)

5f5e32f1b3945087a687c5962071d3f46e34e1ff by kp:
pf: protect the rpool from races



The end of the build log:

[...truncated 3.86 KB...]
+ cd freebsd-ci
+ git rev-parse HEAD
0399f78c9281595d1167532834d0192948824292
+ cd ..
[FreeBSD-main-amd64-test] $ /bin/sh -xe /tmp/jenkins8033821244762262561.sh
+ sh -ex freebsd-ci/jobs/FreeBSD-main-amd64-test/build.sh
+ export 'TARGET=amd64'
+ export 'TARGET_ARCH=amd64'
+ export 'USE_TEST_SUBR=
disable-dtrace-tests.sh
disable-zfs-tests.sh
disable-notyet-tests.sh
run-kyua.sh
'
+ sh -x freebsd-ci/scripts/test/run-tests.sh
+ SSL_CA_CERT_FILE=/usr/local/share/certs/ca-root-nss.crt
+ [ -z 5f5e32f1b3945087a687c5962071d3f46e34e1ff ]
+ KERNCONF=GENERIC
+ ARTIFACT_SERVER=artifact.ci.freebsd.org
+ ARTIFACT_SUBDIR=snapshot/main/5f5e32f1b3945087a687c5962071d3f46e34e1ff/amd64/amd64
+ [ GENERIC '=' GENERIC ]
+ IMG_NAME=disk-test.img
+ JOB_DIR=freebsd-ci/jobs/FreeBSD-main-amd64-test
+ dirname freebsd-ci/scripts/test/run-tests.sh
+ TEST_BASE=freebsd-ci/scripts/test
+ TIMEOUT_MS=7200000
+ TIMEOUT=7200
+ TIMEOUT_EXPECT=7140
+ TIMEOUT_VM=7020
+ : 2
+ : 8192m
+ EXTRA_DISK_NUM=5
+ BHYVE_EXTRA_DISK_PARAM=''
+ METADIR=meta
+ METAOUTDIR=meta-out
+ fetch https://artifact.ci.freebsd.org/snapshot/main/5f5e32f1b3945087a687c5962071d3f46e34e1ff/amd64/amd64/disk-test.img.zst
disk-test.img.zst                                     1284 MB   68 MBps    19s
+ zstd --rm -fd disk-test.img.zst
disk-test.img.zst    : 694 MB...     disk-test.img.zst    : 1066 MB...     disk-test.img.zst    : 1158 MB...     disk-test.img.zst    : 1259 MB...     disk-test.img.zst    : 1359 MB...     disk-test.img.zst    : 1478 MB...     disk-test.img.zst    : 1585 MB...     disk-test.img.zst    : 1670 MB...     disk-test.img.zst    : 1839 MB...     disk-test.img.zst    : 1930 MB...     disk-test.img.zst    : 2027 MB...     disk-test.img.zst    : 2120 MB...     disk-test.img.zst    : 2220 MB...     disk-test.img.zst    : 2337 MB...     disk-test.img.zst    : 2439 MB...     disk-test.img.zst    : 2525 MB...     disk-test.img.zst    : 2612 MB...     disk-test.img.zst    : 2697 MB...     disk-test.img.zst    : 2831 MB...     disk-test.img.zst    : 2917 MB...     disk-test.img.zst    : 2991 MB...     disk-test.img.zst    : 3076 MB...     disk-test.img.zst    : 3155 MB...     disk-test.img.zst    : 3241 MB...     disk-test.img.zst    : 3337 MB...     disk-test.img.zst    : 3431 MB...     disk-test.img.zst    : 3530 MB...     disk-test.img.zst    : 3623 MB...     disk-test.img.zst    : 3715 MB...     disk-test.img.zst    : 3798 MB...     disk-test.img.zst    : 3835 MB...     disk-test.img.zst    : 3907 MB...     disk-test.img.zst    : 3975 MB...     disk-test.img.zst    : 4049 MB...     disk-test.img.zst    : 4135 MB...     disk-test.img.zst    : 4249 MB...     disk-test.img.zst    : 4329 MB...     disk-test.img.zst    : 4425 MB...     disk-test.img.zst    : 4510 MB...     disk-test.img.zst    : 4585 MB...     disk-test.img.zst    : 4662 MB...     disk-test.img.zst    : 4741 MB...     disk-test.img.zst    : 4825 MB...     disk-test.img.zst    : 4908 MB...     disk-test.img.zst    : 4999 MB...     disk-test.img.zst    : 5108 MB...     disk-test.img.zst    : 5199 MB...     disk-test.img.zst    : 5293 MB...     disk-test.img.zst    : 5995 MB...     disk-test.img.zst    : 6697 MB...     disk-test.img.zst    : 7401 MB...     disk-test.img.zst    : 7521 MB...     disk-test.img.zst    : 7602 MB...     disk-test.img.zst    : 8101 MB...     disk-test.img.zst    : 8807 MB...                                                                                    disk-test.img.zst   : 9663745024 bytes 
+ truncate -s 128m disk-cam
+ BHYVE_EXTRA_DISK_PARAM=' -s 4:0,ahci-hd,disk-cam'
+ jot 5
+ truncate -s 128m disk1
+ BHYVE_EXTRA_DISK_PARAM=' -s 4:0,ahci-hd,disk-cam -s 5:0,virtio-blk,disk1'
+ truncate -s 128m disk2
+ BHYVE_EXTRA_DISK_PARAM=' -s 4:0,ahci-hd,disk-cam -s 5:0,virtio-blk,disk1 -s 6:0,virtio-blk,disk2'
+ truncate -s 128m disk3
+ BHYVE_EXTRA_DISK_PARAM=' -s 4:0,ahci-hd,disk-cam -s 5:0,virtio-blk,disk1 -s 6:0,virtio-blk,disk2 -s 7:0,virtio-blk,disk3'
+ truncate -s 128m disk4
+ BHYVE_EXTRA_DISK_PARAM=' -s 4:0,ahci-hd,disk-cam -s 5:0,virtio-blk,disk1 -s 6:0,virtio-blk,disk2 -s 7:0,virtio-blk,disk3 -s 8:0,virtio-blk,disk4'
+ truncate -s 128m disk5
+ BHYVE_EXTRA_DISK_PARAM=' -s 4:0,ahci-hd,disk-cam -s 5:0,virtio-blk,disk1 -s 6:0,virtio-blk,disk2 -s 7:0,virtio-blk,disk3 -s 8:0,virtio-blk,disk4 -s 9:0,virtio-blk,disk5'
+ rm -fr meta
+ mkdir meta
+ cp -R freebsd-ci/jobs/FreeBSD-main-amd64-test/meta/ meta/
+ cp freebsd-ci/scripts/test/subr/disable-dtrace-tests.sh meta/
+ cp freebsd-ci/scripts/test/subr/disable-zfs-tests.sh meta/
+ cp freebsd-ci/scripts/test/subr/disable-notyet-tests.sh meta/
+ cp freebsd-ci/scripts/test/subr/run-kyua.sh meta/
+ touch meta/auto-shutdown
+ sh -ex freebsd-ci/scripts/test/create-meta.sh
+ rm -f meta.tar
+ truncate -s 256M meta.tar
+ tar rvf meta.tar -C meta .
a .
a ./disable-dtrace-tests.sh
a ./disable-zfs-tests.sh
a ./auto-shutdown
a ./run-kyua.sh
a ./disable-notyet-tests.sh
a ./run.sh
+ echo main
+ sed -e 's,.*-,,'
+ FBSD_BRANCH_SHORT=main
+ TEST_VM_NAME=testvm-main-amd64-20383
+ [ '' '=' 1 ]
+ sudo /usr/sbin/bhyvectl '--vm=testvm-main-amd64-20383' --destroy
VM:testvm-main-amd64-20383 is not created.
+ true
+ sudo /usr/sbin/bhyveload -c stdio -m 8192m -d disk-test.img testvm-main-amd64-20383
Consoles: userboot  

FreeBSD/amd64 User boot lua, Revision 1.2
(Sun Jun 13 07:09:00 UTC 2021 root@build-13.freebsd.org)
|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-Loading /boot/defaults/loader.conf
\|/-\|/Loading /boot/defaults/loader.conf
Loading /boot/device.hints
-\|/Loading /boot/loader.conf
-\|/-Loading /boot/loader.conf.local
\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/c-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|  ```                        ` s` `.....---.......--.```   -/ +o   .--`         /y:`      +.  yo`:.            :o      `+-   y/               -/`   -o/  .-                  ::/sy+:.  /                     `--  / `:                          :` `:                          :`  /                          /  .-                        -.   --                      -.    `:`                  `:`      .--             `--.         .---.....----.  ______               ____   _____ _____   |  ____|             |  _ \ / ____|  __ \  | |___ _ __ ___  ___ | |_) | (___ | |  | | |  ___| '__/ _ \/ _ \|  _ < \___ \| |  | | | |   | | |  __/  __/| |_) |____) | |__| | | |   | | |    |    ||     |      |      | |_|   |_|  \___|\___||____/|_____/|_____/ ++++----------------------------------------------------------------------------------|||||||||||||||||||||||| Welcome to FreeBSD 1. Boot Multi user [Enter]2. Boot Single user3. Escape to loader prompt4. Reboot5. Cons: SerialOptions:/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-6. Kernel: default/kernel (1 of 1)7. Boot OptionsAutoboot in 1 seconds. [Space] to pause 
Autoboot in 0 seconds. [Space] to pause 
Loading kernel...
\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-/boot/kernel/kernel text=0x186958 \|/-\|/-\|/-text=0xe14e48 \|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\text=0x6a7f04 |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|data=0x140 data=0x1c6e10+0x4381f0 /-\|/-\|/-\|/-\syms=[0x8+0x194898|/-\|/-\|/-\+0x8+0x1b4ae8|/-\|/-\|/-\|/]
Loading configured modules...
-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/boot/kernel/mac_bsdextended.ko /size 0x6cb0 at 0x234a000
-\|/-\|/-\/boot/entropy size=0x1000
|/-\|/-\|/-\can't find '/etc/hostid'
|/-\+ set +e
+ expect -c $'set timeout 7140; \t\tspawn sudo /usr/bin/timeout -k 60 7020 /usr/sbin/bhyve \t\t-c 2 -m 8192m -A -H -P \t\t-s 0:0,hostbridge \t\t-s 1:0,lpc \t\t-s 2:0,virtio-blk,disk-test.img \t\t-s 3:0,virtio-blk,meta.tar \t\t -s 4:0,ahci-hd,disk-cam -s 5:0,virtio-blk,disk1 -s 6:0,virtio-blk,disk2 -s 7:0,virtio-blk,disk3 -s 8:0,virtio-blk,disk4 -s 9:0,virtio-blk,disk5 \t\t-l com1,stdio \t\ttestvm-main-amd64-20383; \t\texpect { eof }'
spawn sudo /usr/bin/timeout -k 60 7020 /usr/sbin/bhyve -c 2 -m 8192m -A -H -P -s 0:0,hostbridge -s 1:0,lpc -s 2:0,virtio-blk,disk-test.img -s 3:0,virtio-blk,meta.tar -s 4:0,ahci-hd,disk-cam -s 5:0,virtio-blk,disk1 -s 6:0,virtio-blk,disk2 -s 7:0,virtio-blk,disk3 -s 8:0,virtio-blk,disk4 -s 9:0,virtio-blk,disk5 -l com1,stdio testvm-main-amd64-20383
GDB: no debug ports present
KDB: debugger backends: ddb
KDB: current backend: ddb
---<<BOOT>>---
Copyright (c) 1992-2022 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 #0 5f5e32f: Fri Jan 14 10:40:54 UTC 2022
    root@FreeBSD-main-amd64-build.jail.ci.FreeBSD.org:/usr/obj/usr/src/amd64.amd64/sys/GENERIC 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.
VT: init without driver.
CPU: Intel(R) Xeon(R) Silver 4216 CPU @ 2.10GHz (2100.00-MHz K8-class CPU)
  Origin="GenuineIntel"  Id=0x50657  Family=0x6  Model=0x55  Stepping=7
  Features=0x9f83fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR,SSE,SSE2,SS,HTT,PBE>
  Features2=0xfede7a17<SSE3,PCLMULQDQ,DTES64,DS_CPL,SSSE3,SDBG,FMA,CX16,xTPR,PCID,DCA,SSE4.1,SSE4.2,MOVBE,POPCNT,AESNI,XSAVE,OSXSAVE,AVX,F16C,RDRAND,HV>
  AMD Features=0x2c100800<SYSCALL,NX,Page1GB,RDTSCP,LM>
  AMD Features2=0x121<LAHF,ABM,Prefetch>
  Structured Extended Features=0x10050f39<FSGSBASE,BMI1,HLE,AVX2,BMI2,ERMS,INVPCID,RTM,AVX512F,RDSEED,AVX512CD>
  Structured Extended Features3=0x400<MD_CLEAR>
  XSAVE Features=0x1<XSAVEOPT>
  TSC: P-state invariant
Hypervisor: Origin = "bhyve bhyve "
real memory  = 9663676416 (9216 MB)
avail memory = 8260579328 (7877 MB)
Event timer "LAPIC" quality 600
ACPI APIC Table: <BHYVE  BVMADT  >
FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs
FreeBSD/SMP: 2 package(s) x 1 core(s)
random: registering fast source Intel Secure Key RNG
random: fast provider: "Intel Secure Key RNG"
random: unblocking device.
Security policy loaded: TrustedBSD MAC/BSD Extended (mac_bsdextended)
ioapic0 <Version 1.1> irqs 0-31
Launching APs: 1
random: entropy device external interface
kbd1 at kbdmux0
smbios0: <System Management BIOS> at iomem 0xf1000-0xf101e
smbios0: Version: 2.6, BCD Revision: 2.6
aesni0: <AES-CBC,AES-CCM,AES-GCM,AES-ICM,AES-XTS>
acpi0: <BHYVE BVXSDT>
acpi0: Power Button (fixed)
atrtc0: <AT realtime clock> port 0x70-0x71 irq 8 on acpi0
atrtc0: registered as a time-of-day clock, resolution 1.000000s
Event timer "RTC" frequency 32768 Hz quality 0
attimer0: <AT timer> port 0x40-0x43 irq 0 on acpi0
Timecounter "i8254" frequency 1193182 Hz quality 0
Event timer "i8254" frequency 1193182 Hz quality 100
hpet0: <High Precision Event Timer> iomem 0xfed00000-0xfed003ff on acpi0
Timecounter "HPET" frequency 16777216 Hz quality 950
Event timer "HPET" frequency 16777216 Hz quality 550
Event timer "HPET1" frequency 16777216 Hz quality 450
Event timer "HPET2" frequency 16777216 Hz quality 450
Event timer "HPET3" frequency 16777216 Hz quality 450
Event timer "HPET4" frequency 16777216 Hz quality 450
Event timer "HPET5" frequency 16777216 Hz quality 450
Event timer "HPET6" frequency 16777216 Hz quality 450
Timecounter "ACPI-fast" frequency 3579545 Hz quality 900
acpi_timer0: <32-bit timer at 3.579545MHz> port 0x408-0x40b on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pcib0: could not evaluate _ADR - AE_NOT_FOUND
pci0: <ACPI PCI bus> on pcib0
isab0: <PCI-ISA bridge> at device 1.0 on pci0
isa0: <ISA bus> on isab0
virtio_pci0: <VirtIO PCI (legacy) Block adapter> port 0x2000-0x207f mem 0xc0000000-0xc0001fff irq 16 at device 2.0 on pci0
vtblk0: <VirtIO Block Adapter> on virtio_pci0
vtblk0: 9216MB (18874502 512 byte sectors)
virtio_pci1: <VirtIO PCI (legacy) Block adapter> port 0x2080-0x20ff mem 0xc0002000-0xc0003fff irq 17 at device 3.0 on pci0
vtblk1: <VirtIO Block Adapter> on virtio_pci1
vtblk1: 256MB (524288 512 byte sectors)
ahci0: <Intel ICH8 AHCI SATA controller> mem 0xc0004000-0xc00043ff irq 18 at device 4.0 on pci0
ahci0: AHCI v1.30 with 6 6Gbps ports, Port Multiplier not supported
ahcich0: <AHCI channel> at channel 0 on ahci0
virtio_pci2: <VirtIO PCI (legacy) Block adapter> port 0x2100-0x217f mem 0xc0006000-0xc0007fff irq 19 at device 5.0 on pci0
vtblk2: <VirtIO Block Adapter> on virtio_pci2
vtblk2: 128MB (262144 512 byte sectors)
virtio_pci3: <VirtIO PCI (legacy) Block adapter> port 0x2180-0x21ff mem 0xc0008000-0xc0009fff irq 20 at device 6.0 on pci0
vtblk3: <VirtIO Block Adapter> on virtio_pci3
vtblk3: 128MB (262144 512 byte sectors)
virtio_pci4: <VirtIO PCI (legacy) Block adapter> port 0x2200-0x227f mem 0xc000a000-0xc000bfff irq 21 at device 7.0 on pci0
vtblk4: <VirtIO Block Adapter> on virtio_pci4
vtblk4: 128MB (262144 512 byte sectors)
virtio_pci5: <VirtIO PCI (legacy) Block adapter> port 0x2280-0x22ff mem 0xc000c000-0xc000dfff irq 22 at device 8.0 on pci0
vtblk5: <VirtIO Block Adapter> on virtio_pci5
vtblk5: 128MB (262144 512 byte sectors)
virtio_pci6: <VirtIO PCI (legacy) Block adapter> port 0x2300-0x237f mem 0xc000e000-0xc000ffff irq 23 at device 9.0 on pci0
vtblk6: <VirtIO Block Adapter> on virtio_pci6
vtblk6: 128MB (262144 512 byte sectors)
vmgenc0: <VM Generation Counter> on acpi0
atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
driver bug: Unable to set devclass (class: atkbdc devname: (unknown))
Unhandled ps2 mouse command 0xe1
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: [GIANT-LOCKED]
WARNING: Device "psm" is Giant locked and may be deleted before FreeBSD 14.0.
psm0: model Generic PS/2 mouse, device ID 0
uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
uart0: console (9600,n,8,1)
uart1: <16550 or compatible> port 0x2f8-0x2ff irq 3 on acpi0
uart2: <16550 or compatible> port 0x3e8-0x3ef irq 4 on acpi0
uart3: <16550 or compatible> port 0x2e8-0x2ef irq 3 on acpi0
vga0: <Generic ISA VGA> at port 0x3b0-0x3bb iomem 0xb0000-0xb7fff pnpid PNP0900 on isa0
Timecounter "TSC" frequency 2100054307 Hz quality 1000
+ rc=0
+ echo 'bhyve return code = 0'
bhyve return code = 0
+ sudo /usr/sbin/bhyvectl '--vm=testvm-main-amd64-20383' --destroy
+ sh -ex freebsd-ci/scripts/test/extract-meta.sh
+ METAOUTDIR=meta-out
+ rm -fr meta-out
+ mkdir meta-out
+ tar xvf meta.tar -C meta-out
x ./
x ./disable-dtrace-tests.sh
x ./disable-zfs-tests.sh
x ./auto-shutdown
x ./run-kyua.sh
x ./disable-notyet-tests.sh
x ./run.sh
+ rm -f test-report.txt test-report.xml
+ mv 'meta-out/test-report.*' .
mv: rename meta-out/test-report.* to ./test-report.*: No such file or directory
+ report=test-report.xml
+ [ -e freebsd-ci/jobs/FreeBSD-main-amd64-test/xfail-list -a -e test-report.xml ]
+ rm -f disk-cam
+ jot 5
+ rm -f disk1
+ rm -f disk2
+ rm -f disk3
+ rm -f disk4
+ rm -f disk5
+ rm -f disk-test.img
[PostBuildScript] - [INFO] Executing post build scripts.
[FreeBSD-main-amd64-test] $ /bin/sh -xe /tmp/jenkins3934904263018032473.sh
+ ./freebsd-ci/artifact/post-link.py
Post link: {'job_name': 'FreeBSD-main-amd64-test', 'commit': '5f5e32f1b3945087a687c5962071d3f46e34e1ff', 'branch': 'main', 'target': 'amd64', 'target_arch': 'amd64', 'link_type': 'latest_tested'}
"Link created: main/latest_tested/amd64/amd64 -> ../../5f5e32f1b3945087a687c5962071d3f46e34e1ff/amd64/amd64\n"
Recording test results
ERROR: Step ‘Publish JUnit test result report’ failed: No test report files were found. Configuration error?
Checking for post-build
Performing post-build step
Checking if email needs to be generated
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Sending mail from default account using System Admin e-mail address