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

From: <jenkins-admin_at_FreeBSD.org>
Date: Fri, 22 Dec 2023 22:37:29 UTC
FreeBSD-main-amd64-test - Build #24565 (671a00491d7ac9d6663cdc597ff8c13024eda00d) - Failure

Build information: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/24565/
Full change log: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/24565/changes
Full build log: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/24565/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)

3abc72f8716b55fc71e9463388f5a74fb6abf5ce by kib:
vmm_iommu_modify(): split vm_iommu_map()/unmap() into separate functions

671a00491d7ac9d6663cdc597ff8c13024eda00d by kib:
vm_iommu_map()/unmap(): stop transiently wiring already wired pages



The end of the build log:

[...truncated 4.30 MB...]
bin/sh/builtins/functional_test:type2  ->  passed  [0.031s]
bin/sh/builtins/functional_test:type3  ->  passed  [0.034s]
bin/sh/builtins/functional_test:type4  ->  passed  [0.032s]
bin/sh/builtins/functional_test:unalias  ->  passed  [0.034s]
bin/sh/builtins/functional_test:var_assign  ->  passed  [0.094s]
bin/sh/builtins/functional_test:var_assign2  ->  passed  [0.092s]
bin/sh/builtins/functional_test:wait1  ->  passed  [0.035s]
bin/sh/builtins/functional_test:wait10  ->  passed  [0.034s]
bin/sh/builtins/functional_test:wait2  ->  passed  [0.037s]
bin/sh/builtins/functional_test:wait3  ->  passed  [0.040s]
bin/sh/builtins/functional_test:wait4  ->  passed  [0.038s]
bin/sh/builtins/functional_test:wait5  ->  passed  [0.040s]
bin/sh/builtins/functional_test:wait6  ->  passed  [0.035s]
bin/sh/builtins/functional_test:wait7  ->  passed  [0.034s]
bin/sh/builtins/functional_test:wait8  ->  passed  [0.037s]
bin/sh/builtins/functional_test:wait9  ->  passed  [0.034s]
bin/sh/errors/functional_test:assignment_error1  ->  passed  [0.061s]
bin/sh/errors/functional_test:assignment_error2  ->  passed  [0.031s]
bin/sh/errors/functional_test:backquote_error1  ->  passed  [0.034s]
bin/sh/errors/functional_test:backquote_error2  ->  passed  [0.037s]
bin/sh/errors/functional_test:bad_binary1  ->  passed  [0.031s]
bin/sh/errors/functional_test:bad_keyword1  ->  passed  [0.032s]
bin/sh/errors/functional_test:bad_parm_exp1  ->  passed  [0.025s]
bin/sh/errors/functional_test:bad_parm_exp2  ->  passed  [0.031s]
bin/sh/errors/functional_test:bad_parm_exp3  ->  passed  [0.031s]
bin/sh/errors/functional_test:bad_parm_exp4  ->  passed  [0.031s]
bin/sh/errors/functional_test:bad_parm_exp5  ->  passed  [0.031s]
bin/sh/errors/functional_test:bad_parm_exp6  ->  passed  [0.031s]
bin/sh/errors/functional_test:bad_parm_exp7  ->  passed  [0.031s]
bin/sh/errors/functional_test:bad_parm_exp8  ->  passed  [0.031s]
bin/sh/errors/functional_test:option_error  ->  passed  [0.081s]
bin/sh/errors/functional_test:redirection_error  ->  passed  [0.078s]
bin/sh/errors/functional_test:redirection_error2  ->  passed  [0.032s]
bin/sh/errors/functional_test:redirection_error3  ->  passed  [0.079s]
bin/sh/errors/functional_test:redirection_error4  ->  passed  [0.025s]
bin/sh/errors/functional_test:redirection_error5  ->  passed  [0.031s]
bin/sh/errors/functional_test:redirection_error6  ->  passed  [0.034s]
bin/sh/errors/functional_test:redirection_error7  ->  passed  [0.031s]
bin/sh/errors/functional_test:redirection_error8  ->  passed  [0.027s]
bin/sh/errors/functional_test:script_error1  ->  passed  [0.033s]
bin/sh/errors/functional_test:write_error1  ->  passed  [0.031s]
bin/sh/execution/functional_test:bg1  ->  passed  [0.031s]
bin/sh/execution/functional_test:bg10  ->  passed  [0.038s]
bin/sh/execution/functional_test:bg11  ->  passed  [0.038s]
bin/sh/execution/functional_test:bg12  ->  passed  [0.038s]
bin/sh/execution/functional_test:bg13  ->  passed  [0.036s]
bin/sh/execution/functional_test:bg2  ->  passed  [0.032s]
bin/sh/execution/functional_test:bg3  ->  passed  [0.031s]
bin/sh/execution/functional_test:bg4  ->  passed  [0.033s]
bin/sh/execution/functional_test:bg5  ->  passed  [0.031s]
bin/sh/execution/functional_test:bg6  ->  passed  [0.033s]
bin/sh/execution/functional_test:bg7  ->  passed  [0.032s]
bin/sh/execution/functional_test:bg8  ->  passed  [0.038s]
bin/sh/execution/functional_test:bg9  ->  passed  [0.038s]
bin/sh/execution/functional_test:env1  ->  passed  [0.033s]
bin/sh/execution/functional_test:fork1  ->  passed  [0.033s]
bin/sh/execution/functional_test:fork2  ->  passed  [0.141s]
bin/sh/execution/functional_test:fork3  ->  passed  [0.029s]
bin/sh/execution/functional_test:func1  ->  passed  [0.029s]
bin/sh/execution/functional_test:func2  ->  passed  [0.031s]
bin/sh/execution/functional_test:func3  ->  passed  [0.028s]
bin/sh/execution/functional_test:hash1  ->  passed  [0.037s]
bin/sh/execution/functional_test:int_cmd1  ->  passed  [0.034s]
bin/sh/execution/functional_test:killed1  ->  passed  [0.033s]
bin/sh/execution/functional_test:killed2  ->  passed  [0.034s]
bin/sh/execution/functional_test:not1  ->  passed  [0.032s]
bin/sh/execution/functional_test:not2  ->  passed  [0.027s]
bin/sh/execution/functional_test:path1  ->  passed  [0.035s]
bin/sh/execution/functional_test:pipefail1  ->  passed  [0.036s]
bin/sh/execution/functional_test:pipefail2  ->  passed  [0.032s]
bin/sh/execution/functional_test:pipefail3  ->  passed  [0.033s]
bin/sh/execution/functional_test:pipefail4  ->  passed  [0.033s]
bin/sh/execution/functional_test:pipefail5  ->  passed  [0.033s]
bin/sh/execution/functional_test:pipefail6  ->  passed  [0.032s]
bin/sh/execution/functional_test:pipefail7  ->  passed  [0.032s]
bin/sh/execution/functional_test:redir1  ->  passed  [0.034s]
bin/sh/execution/functional_test:redir2  ->  passed  [0.039s]
bin/sh/execution/functional_test:redir3  ->  passed  [0.031s]
bin/sh/execution/functional_test:redir4  ->  passed  [0.026s]
bin/sh/execution/functional_test:redir5  ->  passed  [0.027s]
bin/sh/execution/functional_test:redir6  ->  passed  [0.042s]
bin/sh/execution/functional_test:redir7  ->  passed  [0.036s]
bin/sh/execution/functional_test:set_C1  ->  passed  [0.037s]
bin/sh/execution/functional_test:set_n1  ->  passed  [0.034s]
bin/sh/execution/functional_test:set_n2  ->  passed  [0.033s]
bin/sh/execution/functional_test:set_n3  ->  passed  [0.034s]
bin/sh/execution/functional_test:set_n4  ->  passed  [0.033s]
bin/sh/execution/functional_test:set_x1  ->  passed  [0.033s]
bin/sh/execution/functional_test:set_x2  ->  passed  [0.031s]
bin/sh/execution/functional_test:set_x3  ->  passed  [0.034s]
bin/sh/execution/functional_test:set_x4  ->  passed  [0.033s]
bin/sh/execution/functional_test:shellproc1  ->  passed  [0.041s]
bin/sh/execution/functional_test:shellproc2  ->  passed  [0.031s]
bin/sh/execution/functional_test:shellproc3  ->  passed  [0.031s]
bin/sh/execution/functional_test:shellproc4  ->  passed  [0.034s]
bin/sh/execution/functional_test:shellproc5  ->  passed  [0.032s]
bin/sh/execution/functional_test:shellproc6  ->  passed  [0.033s]
bin/sh/execution/functional_test:shellproc7  ->  passed  [0.032s]
bin/sh/execution/functional_test:subshell1  ->  passed  [0.036s]
bin/sh/execution/functional_test:subshell2  ->  passed  [0.028s]
bin/sh/execution/functional_test:subshell3  ->  passed  [0.027s]
bin/sh/execution/functional_test:subshell4  ->  passed  [0.027s]
bin/sh/execution/functional_test:unknown1  ->  passed  [0.042s]
bin/sh/execution/functional_test:unknown2  ->  passed  [0.032s]
bin/sh/execution/functional_test:var_assign1  ->  passed  [0.033s]
bin/sh/expansion/functional_test:arith1  ->  passed  [0.034s]
bin/sh/expansion/functional_test:arith10  ->  passed  [0.033s]
bin/sh/expansion/functional_test:arith11  ->  passed  [0.034s]
bin/sh/expansion/functional_test:arith12  ->  passed  [0.030s]
bin/sh/expansion/functional_test:arith13  ->  passed  [0.032s]
bin/sh/expansion/functional_test:arith14  ->  passed  [0.036s]
bin/sh/expansion/functional_test:arith15  ->  passed  [0.031s]
bin/sh/expansion/functional_test:arith16  ->  passed  [0.036s]
bin/sh/expansion/functional_test:arith17  ->  passed  [0.026s]
bin/sh/expansion/functional_test:arith2  ->  passed  [0.027s]
bin/sh/expansion/functional_test:arith3  ->  passed  [0.027s]
bin/sh/expansion/functional_test:arith4  ->  passed  [0.027s]
bin/sh/expansion/functional_test:arith5  ->  passed  [0.027s]
bin/sh/expansion/functional_test:arith6  ->  passed  [0.087s]
bin/sh/expansion/functional_test:arith7  ->  passed  [0.029s]
bin/sh/expansion/functional_test:arith8  ->  passed  [0.028s]
bin/sh/expansion/functional_test:arith9  ->  passed  [0.027s]
bin/sh/expansion/functional_test:assign1  ->  passed  [0.027s]
bin/sh/expansion/functional_test:cmdsubst1  ->  passed  [0.035s]
bin/sh/expansion/functional_test:cmdsubst10  ->  passed  [0.035s]
bin/sh/expansion/functional_test:cmdsubst11  ->  passed  [0.030s]
bin/sh/expansion/functional_test:cmdsubst12  ->  passed  [0.029s]
bin/sh/expansion/functional_test:cmdsubst13  ->  passed  [0.029s]
bin/sh/expansion/functional_test:cmdsubst14  ->  passed  [0.032s]
bin/sh/expansion/functional_test:cmdsubst15  ->  passed  [0.033s]
bin/sh/expansion/functional_test:cmdsubst16  ->  passed  [0.033s]
bin/sh/expansion/functional_test:cmdsubst17  ->  passed  [0.031s]
bin/sh/expansion/functional_test:cmdsubst18  ->  passed  [0.033s]
bin/sh/expansion/functional_test:cmdsubst19  ->  passed  [0.032s]
bin/sh/expansion/functional_test:cmdsubst2  ->  *** /usr/src/contrib/kyua/utils/process/executor.cpp:779: Invariant check failed: PID 39832 already in all_exec_handles; not properly cleaned up or reused too fast
*** Fatal signal 6 received
*** Log file is /.kyua/logs/kyua.20231222-215254.log
*** Please report this problem to kyua-discuss@googlegroups.com detailing what you were doing before the crash happened; if possible, include the log file mentioned above
pid 806 (kyua), jid 0, uid 0: exited on signal 6 (core dumped)
Abort trap (core dumped)
+ rc=134
+ set -e
+ [ 134 -ne 0 ]
+ [ 134 -ne 1 ]
+ exit 134
+ tar cvf /dev/vtbd1 -C /meta .
a .
a ./disable-zfs-tests.sh
a ./run.sh
a ./disable-dtrace-tests.sh
a ./auto-shutdown
a ./run-kyua.sh
a ./disable-notyet-tests.sh
+ [ -f /meta/auto-shutdown ]
+ uname -p
+ shutdown -p now
Shutdown NOW!
shutdown: [pid 39871]
+ startmsg .
+ check_startmsgs
+ [ -n yes ]
+ checkyesno rc_startmsgs
+ eval '_value=$rc_startmsgs'
+ _value=YES
+ debug 'checkyesno: rc_startmsgs is set to YES.'
+ return 0
+ echo .
.
+ _return=0
+ umask 0022
+ [ 0 -ne 0 ]
+ return 0
+ _run_rc_postcmd
+ [ -n '' ]
+ return 0
+ return 0
2023-12-22T22:37:21.338249+00:00 - shutdown 39871 - - power-down by root: 

System shutdown time has arrived
2023-12-22T22:37:21.340345+00:00 - auditd 723 - - auditd_wait_for_events: SIGTERM
2023-12-22T22:37:21.341568+00:00 - auditd 723 - - Auditing disabled
2023-12-22T22:37:21.342814+00:00 - auditd 723 - - renamed /var/audit/20231222215254.not_terminated to /var/audit/20231222215254.20231222223721
2023-12-22T22:37:21.345162+00:00 - auditd 723 - - Finished
Waiting (max 60 seconds) for system process `vnlru' to stop... done
Waiting (max 60 seconds) for system process `syncer' to stop... 
Syncing disks, vnodes remaining... 0 0 0 done
All buffers synced.
lock order reversal:
 1st 0xfffff80003af8930 devfs (devfs, lockmgr) @ /usr/src/sys/kern/vfs_mount.c:2253
 2nd 0xfffff80003af8230 fdescfs (fdescfs, lockmgr) @ /usr/src/sys/kern/vfs_subr.c:3294
lock order devfs -> fdescfs attempted at:
#0 0xffffffff80bc4633 at witness_checkorder+0xb63
#1 0xffffffff80b1d581 at lockmgr_lock_flags+0x181
#2 0xffffffff80c60773 at _vn_lock+0x53
#3 0xffffffff80c47d0d at vget_finish+0x4d
#4 0xffffffff82610262 at fdesc_root+0x22
#5 0xffffffff80c48d9b at vflush+0x4b
#6 0xffffffff82610211 at fdesc_unmount+0x71
#7 0xffffffff80c3d8b5 at dounmount+0x7b5
#8 0xffffffff80c4a4da at vfs_unmountall+0x6a
#9 0xffffffff80c1a83f at bufshutdown+0x31f
#10 0xffffffff80b50483 at kern_reboot+0x703
#11 0xffffffff80b4fd29 at sys_reboot+0x3a9
#12 0xffffffff81056453 at amd64_syscall+0x153
#13 0xffffffff8102873b at fast_syscall_common+0xf8
Uptime: 44m30s
acpi0: Powering system off
+ rc=0
+ echo 'bhyve return code = 0'
bhyve return code = 0
+ sudo /usr/sbin/bhyvectl '--vm=testvm-main-amd64-24565' --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-zfs-tests.sh
x ./run.sh
x ./disable-dtrace-tests.sh
x ./auto-shutdown
x ./run-kyua.sh
x ./disable-notyet-tests.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/jenkins1664431971141930266.sh
+ ./freebsd-ci/artifact/post-link.py
Post link: {'job_name': 'FreeBSD-main-amd64-test', 'commit': '671a00491d7ac9d6663cdc597ff8c13024eda00d', 'branch': 'main', 'target': 'amd64', 'target_arch': 'amd64', 'link_type': 'latest_tested'}
"Link created: main/latest_tested/amd64/amd64 -> ../../671a00491d7ac9d6663cdc597ff8c13024eda00d/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