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

From: <jenkins-admin_at_FreeBSD.org>
Date: Mon, 21 Aug 2023 23:11:17 UTC
FreeBSD-main-amd64-test - Build #24068 (4258eb5a0d971cf9b1ea5e8e98535e29ef3053f1) - Failure

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

9fbea870286d53d906ffaf6b15ace8e40019a880 by fuz:
lib/libc/amd64/string/stpcpy.S: add baseline implementation

245fee96c0a55ae04e432131a7f0aa34fe19ebce by fuz:
share/man/man7/simd.7: document SIMD-enhanced stpcpy()

8803f01e932275cd405690526bb8dba031a02ffe by fuz:
lib/libc/amd64/string/memcmp.S: add baseline implementation

b404e030070686bfb3101c314f3e17168dbd16ca by fuz:
share/man/man7/simd.7: document new amd64 memcmp/bcmp baseline routines

4258eb5a0d971cf9b1ea5e8e98535e29ef3053f1 by emaste:
x86: handle domains with no CPUs usable for intr delivery



The end of the build log:

[...truncated 4.36 MB...]
passed  [0.008s]
sys/audit/open:open_read_write_trunc_failure  ->  2023-08-21T23:11:07.761845+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/open:open_read_write_trunc_success  ->  2023-08-21T23:11:07.785753+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/open:open_write_creat_failure  ->  2023-08-21T23:11:07.808063+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/open:open_write_creat_success  ->  2023-08-21T23:11:07.830625+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/open:open_write_creat_trunc_failure  ->  2023-08-21T23:11:07.850078+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/open:open_write_creat_trunc_success  ->  2023-08-21T23:11:07.868840+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/open:open_write_failure  ->  2023-08-21T23:11:07.888332+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/open:open_write_success  ->  2023-08-21T23:11:07.910145+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/open:open_write_trunc_failure  ->  2023-08-21T23:11:07.934717+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/open:open_write_trunc_success  ->  2023-08-21T23:11:07.959346+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/open:openat_read_creat_failure  ->  2023-08-21T23:11:07.983529+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/open:openat_read_creat_success  ->  2023-08-21T23:11:08.008214+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/open:openat_read_creat_trunc_failure  ->  2023-08-21T23:11:08.030746+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/open:openat_read_creat_trunc_success  ->  2023-08-21T23:11:08.053136+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/open:openat_read_failure  ->  2023-08-21T23:11:08.073419+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/open:openat_read_success  ->  2023-08-21T23:11:08.093482+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/open:openat_read_trunc_failure  ->  2023-08-21T23:11:08.114585+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/open:openat_read_trunc_success  ->  2023-08-21T23:11:08.135132+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/open:openat_read_write_creat_failure  ->  2023-08-21T23:11:08.160244+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/open:openat_read_write_creat_success  ->  2023-08-21T23:11:08.182135+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/open:openat_read_write_creat_trunc_failure  ->  2023-08-21T23:11:08.205688+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/open:openat_read_write_creat_trunc_success  ->  2023-08-21T23:11:08.231236+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/open:openat_read_write_failure  ->  2023-08-21T23:11:08.255163+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/open:openat_read_write_success  ->  2023-08-21T23:11:08.279579+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/open:openat_read_write_trunc_failure  ->  2023-08-21T23:11:08.303694+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/open:openat_read_write_trunc_success  ->  2023-08-21T23:11:08.325674+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/open:openat_write_creat_failure  ->  2023-08-21T23:11:08.345425+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/open:openat_write_creat_success  ->  2023-08-21T23:11:08.368287+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/open:openat_write_creat_trunc_failure  ->  2023-08-21T23:11:08.391053+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/open:openat_write_creat_trunc_success  ->  2023-08-21T23:11:08.414507+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/open:openat_write_failure  ->  2023-08-21T23:11:08.439530+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/open:openat_write_success  ->  2023-08-21T23:11:08.461367+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/open:openat_write_trunc_failure  ->  2023-08-21T23:11:08.486231+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/open:openat_write_trunc_success  ->  2023-08-21T23:11:08.511078+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:_exit_success  ->  2023-08-21T23:11:08.533827+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:cap_enter_success  ->  2023-08-21T23:11:08.559329+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/process-control:cap_getmode_failure  ->  2023-08-21T23:11:08.584338+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:cap_getmode_success  ->  2023-08-21T23:11:08.605550+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:chdir_failure  ->  2023-08-21T23:11:08.630827+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:chdir_success  ->  2023-08-21T23:11:08.655478+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:chroot_failure  ->  2023-08-21T23:11:08.681128+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:chroot_success  ->  2023-08-21T23:11:08.705062+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:fchdir_failure  ->  2023-08-21T23:11:08.729361+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:fchdir_success  ->  2023-08-21T23:11:08.752613+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:fork_success  ->  2023-08-21T23:11:08.776578+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:getresgid_failure  ->  2023-08-21T23:11:08.800478+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:getresgid_success  ->  2023-08-21T23:11:08.824637+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/process-control:getresuid_failure  ->  2023-08-21T23:11:08.849810+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/process-control:getresuid_success  ->  2023-08-21T23:11:08.871359+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/process-control:kill_failure  ->  2023-08-21T23:11:08.892824+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/process-control:kill_success  ->  2023-08-21T23:11:08.914651+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:ktrace_failure  ->  2023-08-21T23:11:08.938300+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:ktrace_success  ->  2023-08-21T23:11:08.961829+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:minherit_failure  ->  2023-08-21T23:11:08.986223+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:minherit_success  ->  2023-08-21T23:11:09.007933+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/process-control:mlock_failure  ->  2023-08-21T23:11:09.030960+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:mlock_success  ->  2023-08-21T23:11:09.050881+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/process-control:munlock_failure  ->  2023-08-21T23:11:09.070913+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/process-control:munlock_success  ->  2023-08-21T23:11:09.091174+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/process-control:procctl_failure  ->  2023-08-21T23:11:09.113724+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/process-control:procctl_success  ->  2023-08-21T23:11:09.136271+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:profil_failure  ->  2023-08-21T23:11:09.161402+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:profil_success  ->  2023-08-21T23:11:09.185166+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:ptrace_failure  ->  2023-08-21T23:11:09.209789+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/process-control:ptrace_success  ->  2023-08-21T23:11:09.233505+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/process-control:rfork_failure  ->  2023-08-21T23:11:09.258381+00:00 - auditd 23835 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/process-control:rfork_success  ->  *** /usr/src/contrib/kyua/utils/process/executor.cpp:779: Invariant check failed: PID 24607 already in all_exec_handles; not properly cleaned up or reused too fast
*** Fatal signal 6 received
*** Log file is /.kyua/logs/kyua.20230821-223328.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
2023-08-21T23:11:09.285231+00:00 - auditd 23835 - - Got audit initialize trigger
pid 790 (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 ./run.sh
a ./run-kyua.sh
a ./disable-dtrace-tests.sh
a ./disable-zfs-tests.sh
a ./disable-notyet-tests.sh
a ./auto-shutdown
+ [ -f /meta/auto-shutdown ]
+ uname -p
+ shutdown -p now
Shutdown NOW!
shutdown: [pid 24612]
+ 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-08-21T23:11:09.387105+00:00 - shutdown 24612 - - power-down by root: 

System shutdown time has arrived
2023-08-21T23:11:09.389425+00:00 - auditd 23835 - - auditd_wait_for_events: SIGTERM
2023-08-21T23:11:09.390724+00:00 - auditd 23835 - - Auditing disabled
2023-08-21T23:11:09.392139+00:00 - auditd 23835 - - renamed /var/audit/20230821231101.not_terminated to /var/audit/20230821231101.20230821231109
2023-08-21T23:11:09.394483+00:00 - auditd 23835 - - 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 done
All buffers synced.
lock order reversal:
 1st 0xfffff8000ba3ccb0 devfs (devfs, lockmgr) @ /usr/src/sys/kern/vfs_mount.c:2240
 2nd 0xfffff8000ba3c5b0 fdescfs (fdescfs, lockmgr) @ /usr/src/sys/kern/vfs_subr.c:3060
lock order devfs -> fdescfs attempted at:
#0 0xffffffff80bc046e at witness_checkorder+0xbbe
#1 0xffffffff80b1a7b1 at lockmgr_lock_flags+0x181
#2 0xffffffff80c5a883 at _vn_lock+0x53
#3 0xffffffff80c4212d at vget_finish+0x4d
#4 0xffffffff82610262 at fdesc_root+0x22
#5 0xffffffff80c431cb at vflush+0x4b
#6 0xffffffff82610211 at fdesc_unmount+0x71
#7 0xffffffff80c38095 at dounmount+0x7b5
#8 0xffffffff80c4490a at vfs_unmountall+0x6a
#9 0xffffffff80c150bc at bufshutdown+0x30c
#10 0xffffffff80b4d203 at kern_reboot+0x703
#11 0xffffffff80b4caa9 at sys_reboot+0x3a9
#12 0xffffffff8104a398 at amd64_syscall+0x138
#13 0xffffffff8101c89b at fast_syscall_common+0xf8
Uptime: 37m44s
acpi0: Powering system off
+ rc=0
+ echo 'bhyve return code = 0'
bhyve return code = 0
+ sudo /usr/sbin/bhyvectl '--vm=testvm-main-amd64-24068' --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 ./run.sh
x ./run-kyua.sh
x ./disable-dtrace-tests.sh
x ./disable-zfs-tests.sh
x ./disable-notyet-tests.sh
x ./auto-shutdown
+ 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/jenkins17883532925155889545.sh
+ ./freebsd-ci/artifact/post-link.py
Post link: {'job_name': 'FreeBSD-main-amd64-test', 'commit': '4258eb5a0d971cf9b1ea5e8e98535e29ef3053f1', 'branch': 'main', 'target': 'amd64', 'target_arch': 'amd64', 'link_type': 'latest_tested'}
"Link created: main/latest_tested/amd64/amd64 -> ../../4258eb5a0d971cf9b1ea5e8e98535e29ef3053f1/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