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

From: <jenkins-admin_at_FreeBSD.org>
Date: Tue, 26 Sep 2023 17:46:13 UTC
FreeBSD-main-amd64-test - Build #24172 (e6dc6a27230f836a131118326731b7c5fd417723) - Failure

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

5b732ae8332b2bad256016569c0ead6d918bdf79 by emaste:
kernel: remove unused HWPMC_MIPS_BACKTRACE option

e6dc6a27230f836a131118326731b7c5fd417723 by mjg:
sys/conf/newvers.sh: whack sccs tag



The end of the build log:

[...truncated 4.07 MB...]
lib/libc/sys/clock_nanosleep_test:clock_nanosleep_remain  ->  passed  [0.004s]
lib/libc/sys/connect_test:connect_foreign_family  ->  passed  [0.004s]
lib/libc/sys/connect_test:connect_low_port  ->  passed  [0.004s]
lib/libc/sys/cpuset_test:badparent  ->  passed  [0.004s]
lib/libc/sys/cpuset_test:deadlk  ->  skipped: Test requires 3 or more cores.  [0.004s]
lib/libc/sys/cpuset_test:jail_attach_disjoint  ->  passed  [0.207s]
lib/libc/sys/cpuset_test:jail_attach_newbase  ->  passed  [0.005s]
lib/libc/sys/cpuset_test:jail_attach_newbase_plain  ->  passed  [0.005s]
lib/libc/sys/cpuset_test:jail_attach_plain  ->  passed  [0.005s]
lib/libc/sys/cpuset_test:jail_attach_prevbase  ->  passed  [0.005s]
lib/libc/sys/cpuset_test:newset  ->  passed  [0.004s]
lib/libc/sys/cpuset_test:transient  ->  passed  [0.004s]
lib/libc/sys/dup_test:dup2_basic  ->  passed  [0.004s]
lib/libc/sys/dup_test:dup2_err  ->  passed  [0.004s]
lib/libc/sys/dup_test:dup2_max  ->  passed  [0.004s]
lib/libc/sys/dup_test:dup2_mode  ->  passed  [0.008s]
lib/libc/sys/dup_test:dup3_err  ->  passed  [0.004s]
lib/libc/sys/dup_test:dup3_max  ->  passed  [0.006s]
lib/libc/sys/dup_test:dup3_mode  ->  passed  [0.007s]
lib/libc/sys/dup_test:dup_err  ->  passed  [0.004s]
lib/libc/sys/dup_test:dup_max  ->  passed  [0.004s]
lib/libc/sys/dup_test:dup_mode  ->  passed  [0.007s]
lib/libc/sys/fsync_test:fsync_err  ->  passed  [0.004s]
lib/libc/sys/fsync_test:fsync_sync  ->  passed  [0.007s]
lib/libc/sys/getcontext_test:getcontext_err  ->  passed  [0.004s]
lib/libc/sys/getcontext_test:setcontext_err  ->  passed  [0.003s]
lib/libc/sys/getcontext_test:setcontext_link  ->  passed  [0.004s]
lib/libc/sys/getgroups_test:getgroups_err  ->  passed  [0.004s]
lib/libc/sys/getgroups_test:getgroups_getgid  ->  passed  [0.004s]
lib/libc/sys/getgroups_test:getgroups_setgid  ->  passed  [0.004s]
lib/libc/sys/getgroups_test:getgroups_zero  ->  passed  [0.004s]
lib/libc/sys/getitimer_test:getitimer_empty  ->  passed  [0.004s]
lib/libc/sys/getitimer_test:getitimer_err  ->  passed  [0.004s]
lib/libc/sys/getitimer_test:setitimer_basic  ->  passed  [0.005s]
lib/libc/sys/getitimer_test:setitimer_err  ->  passed  [0.004s]
lib/libc/sys/getitimer_test:setitimer_old  ->  passed  [0.003s]
lib/libc/sys/getlogin_test:getlogin_r_err  ->  passed  [0.004s]
lib/libc/sys/getlogin_test:getlogin_same  ->  passed  [0.004s]
lib/libc/sys/getlogin_test:setlogin_basic  ->  passed  [0.004s]
lib/libc/sys/getlogin_test:setlogin_err  ->  passed  [0.004s]
lib/libc/sys/getlogin_test:setlogin_perm  ->  passed  [0.004s]
lib/libc/sys/getpid_test:getpid_process  ->  passed  [0.007s]
lib/libc/sys/getpid_test:getpid_thread  ->  passed  [0.004s]
lib/libc/sys/getrusage_test:getrusage_err  ->  passed  [0.003s]
lib/libc/sys/getrusage_test:getrusage_sig  ->  passed  [0.004s]
lib/libc/sys/getrusage_test:getrusage_utime_back  ->  passed  [0.876s]
lib/libc/sys/getrusage_test:getrusage_utime_zero  ->  skipped: this testcase passes/fails sporadically on FreeBSD/i386 @ r273153 (at least)  [0.005s]
lib/libc/sys/getsid_test:getsid_current  ->  passed  [0.004s]
lib/libc/sys/getsid_test:getsid_err  ->  passed  [0.004s]
lib/libc/sys/getsid_test:getsid_process  ->  passed  [0.004s]
lib/libc/sys/getsockname_test:getsockname_unix  ->  passed  [0.004s]
lib/libc/sys/gettimeofday_test:gettimeofday_err  ->  passed  [0.004s]
lib/libc/sys/gettimeofday_test:gettimeofday_mono  ->  passed  [0.004s]
lib/libc/sys/issetugid_test:issetugid_egid  ->  passed  [0.005s]
lib/libc/sys/issetugid_test:issetugid_euid  ->  passed  [0.005s]
lib/libc/sys/issetugid_test:issetugid_rgid  ->  passed  [0.005s]
lib/libc/sys/issetugid_test:issetugid_ruid  ->  passed  [0.005s]
lib/libc/sys/kevent_test:kevent_zerotimer  ->  passed  [0.005s]
lib/libc/sys/kevent_test:kqueue_desc_passing  ->  skipped: PR kern/46523  [0.004s]
lib/libc/sys/kevent_test:kqueue_unsupported_fd  ->  skipped: no /nonexistent available for testing  [0.004s]
lib/libc/sys/kill_test:kill_basic  ->  passed  [0.005s]
lib/libc/sys/kill_test:kill_err  ->  passed  [0.005s]
lib/libc/sys/kill_test:kill_perm  ->  passed  [1.023s]
lib/libc/sys/kill_test:kill_pgrp_neg  ->  passed  [0.005s]
lib/libc/sys/kill_test:kill_pgrp_zero  ->  passed  [0.005s]
lib/libc/sys/link_test:link_count  ->  passed  [0.005s]
lib/libc/sys/link_test:link_err  ->  passed  [0.004s]
lib/libc/sys/link_test:link_perm  ->  passed  [0.004s]
lib/libc/sys/link_test:link_stat  ->  passed  [0.004s]
lib/libc/sys/listen_test:listen_err  ->  passed  [0.004s]
lib/libc/sys/listen_test:listen_low_port  ->  passed  [0.005s]
lib/libc/sys/mincore_test:mincore_err  ->  passed  [0.004s]
lib/libc/sys/mincore_test:mincore_resid  ->  passed  [0.011s]
lib/libc/sys/mincore_test:mincore_shmseg  ->  passed  [0.004s]
lib/libc/sys/mkdir_test:mkdir_err  ->  passed  [0.005s]
lib/libc/sys/mkdir_test:mkdir_mode  ->  passed  [1.040s]
lib/libc/sys/mkdir_test:mkdir_perm  ->  passed  [0.004s]
lib/libc/sys/mkdir_test:mkdir_trail  ->  passed  [0.005s]
lib/libc/sys/mkfifo_test:mkfifo_block  ->  passed  [1.023s]
lib/libc/sys/mkfifo_test:mkfifo_err  ->  passed  [0.004s]
lib/libc/sys/mkfifo_test:mkfifo_nonblock  ->  passed  [1.017s]
lib/libc/sys/mkfifo_test:mkfifo_perm  ->  passed  [0.005s]
lib/libc/sys/mkfifo_test:mkfifo_stat  ->  passed  [0.004s]
lib/libc/sys/mknod_test:mknod_err  ->  passed  [0.004s]
lib/libc/sys/mknod_test:mknod_exist  ->  passed  [0.004s]
lib/libc/sys/mknod_test:mknod_perm  ->  passed  [0.004s]
lib/libc/sys/mknod_test:mknod_stat  ->  expected_failure: mknod does not allow S_IFREG: /usr/src/contrib/netbsd-tests/lib/libc/sys/t_mknod.c:179: mknod(path, S_IFREG, 0) == 0 not met  [0.004s]
lib/libc/sys/mlock_test:mlock_clip  ->  passed  [0.004s]
lib/libc/sys/mlock_test:mlock_err  ->  passed  [0.004s]
lib/libc/sys/mlock_test:mlock_limits  ->  passed  [0.004s]
lib/libc/sys/mlock_test:mlock_mmap  ->  passed  [0.004s]
lib/libc/sys/mlock_test:mlock_nested  ->  passed  [0.004s]
lib/libc/sys/mlock_test:mlock_unaligned  ->  passed  [0.004s]
lib/libc/sys/mlock_test:munlock_unlocked  ->  passed  [0.004s]
lib/libc/sys/mmap_test:mmap_err  ->  passed  [0.004s]
lib/libc/sys/mmap_test:mmap_loan  ->  passed  [0.004s]
lib/libc/sys/mmap_test:mmap_prot_1  ->  passed  [0.004s]
lib/libc/sys/mmap_test:mmap_prot_2  ->  passed  [0.004s]
lib/libc/sys/mmap_test:mmap_prot_3  ->  passed  [0.004s]
lib/libc/sys/mmap_test:mmap_truncate  ->  passed  [0.005s]
lib/libc/sys/mmap_test:mmap_truncate_signal  ->  passed  [0.005s]
lib/libc/sys/mmap_test:mmap_va0  ->  passed  [0.004s]
lib/libc/sys/mprotect_test:mprotect_access  ->  passed  [0.004s]
lib/libc/sys/mprotect_test:mprotect_err  ->  passed  [0.004s]
lib/libc/sys/mprotect_test:mprotect_pax  ->  passed  [0.004s]
lib/libc/sys/mprotect_test:mprotect_write  ->  passed  [0.004s]
lib/libc/sys/msync_test:msync_async  ->  passed  [0.004s]
lib/libc/sys/msync_test:msync_err  ->  passed  [0.004s]
lib/libc/sys/msync_test:msync_invalidate  ->  passed  [0.004s]
lib/libc/sys/msync_test:msync_sync  ->  passed  [0.004s]
lib/libc/sys/nanosleep_test:nanosleep_basic  ->  passed  [0.004s]
lib/libc/sys/nanosleep_test:nanosleep_eintr  ->  passed  [5.012s]
lib/libc/sys/nanosleep_test:nanosleep_err  ->  passed  [0.005s]
lib/libc/sys/nanosleep_test:nanosleep_sig  ->  passed  [1.081s]
lib/libc/sys/pipe2_test:pipe2_basic  ->  passed  [0.004s]
lib/libc/sys/pipe2_test:pipe2_cloexec  ->  passed  [0.004s]
lib/libc/sys/pipe2_test:pipe2_consume  ->  passed  [0.004s]
lib/libc/sys/pipe2_test:pipe2_einval  ->  passed  [0.004s]
lib/libc/sys/pipe2_test:pipe2_nonblock  ->  passed  [0.004s]
lib/libc/sys/pipe_test:pipe_restart  ->  passed  [2.014s]
lib/libc/sys/poll_test:poll_3way  ->  passed  [10.046s]
lib/libc/sys/poll_test:poll_basic  ->  passed  [0.006s]
lib/libc/sys/poll_test:poll_err  ->  passed  [0.004s]
lib/libc/sys/posix_fallocate_test:ebadf  ->  passed  [0.004s]
lib/libc/sys/queue_test:slist_test  ->  passed  [0.004s]
lib/libc/sys/queue_test:stailq_test  ->  passed  [0.004s]
lib/libc/sys/revoke_test:revoke_basic  ->  skipped: revoke(2) is only implemented for devfs(5).  [0.004s]
lib/libc/sys/revoke_test:revoke_err  ->  skipped: revoke(2) is only implemented for devfs(5).  [0.004s]
lib/libc/sys/revoke_test:revoke_perm  ->  skipped: revoke(2) is only implemented for devfs(5).  [0.004s]
lib/libc/sys/select_test:pselect_sigmask  ->  passed  [1.015s]
lib/libc/sys/select_test:pselect_timeout  ->  passed  [0.005s]
lib/libc/sys/sendfile_test:fd_negative_bad_fd_v4  ->  passed  [0.004s]
lib/libc/sys/sendfile_test:fd_negative_bad_fd_v6  ->  passed  [0.004s]
lib/libc/sys/sendfile_test:fd_positive_file_v4  ->  passed  [0.005s]
lib/libc/sys/sendfile_test:fd_positive_file_v6  ->  *** /usr/src/contrib/kyua/utils/process/executor.cpp:779: Invariant check failed: PID 60331 already in all_exec_handles; not properly cleaned up or reused too fast
*** Fatal signal 6 received
*** Log file is /.kyua/logs/kyua.20230926-171115.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 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 ./disable-zfs-tests.sh
a ./auto-shutdown
a ./disable-notyet-tests.sh
a ./run-kyua.sh
a ./disable-dtrace-tests.sh
a ./run.sh
+ [ -f /meta/auto-shutdown ]
+ uname -p
+ shutdown -p now
Shutdown NOW!
shutdown: [pid 60336]
+ 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-09-26T17:46:05.312118+00:00 - shutdown 60336 - - power-down by root: 

System shutdown time has arrived
2023-09-26T17:46:05.313971+00:00 - auditd 707 - - auditd_wait_for_events: SIGTERM
2023-09-26T17:46:05.315494+00:00 - auditd 707 - - Auditing disabled
2023-09-26T17:46:05.316845+00:00 - auditd 707 - - renamed /var/audit/20230926171115.not_terminated to /var/audit/20230926171115.20230926174605
2023-09-26T17:46:05.318910+00:00 - auditd 707 - - 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 0xfffff8000ba36af0 devfs (devfs, lockmgr) @ /usr/src/sys/kern/vfs_mount.c:2240
 2nd 0xfffff8000ba363f0 fdescfs (fdescfs, lockmgr) @ /usr/src/sys/kern/vfs_subr.c:3138
lock order devfs -> fdescfs attempted at:
#0 0xffffffff80bc29be at witness_checkorder+0xbbe
#1 0xffffffff80b1c381 at lockmgr_lock_flags+0x181
#2 0xffffffff80c5e483 at _vn_lock+0x53
#3 0xffffffff80c45b2d at vget_finish+0x4d
#4 0xffffffff82610262 at fdesc_root+0x22
#5 0xffffffff80c46bcb at vflush+0x4b
#6 0xffffffff82610211 at fdesc_unmount+0x71
#7 0xffffffff80c3b8b5 at dounmount+0x7b5
#8 0xffffffff80c4830a at vfs_unmountall+0x6a
#9 0xffffffff80c1891c at bufshutdown+0x30c
#10 0xffffffff80b4f483 at kern_reboot+0x703
#11 0xffffffff80b4ed29 at sys_reboot+0x3a9
#12 0xffffffff8104f67f at amd64_syscall+0x14f
#13 0xffffffff81020e2b at fast_syscall_common+0xf8
Uptime: 34m53s
acpi0: Powering system off
+ rc=0
+ echo 'bhyve return code = 0'
bhyve return code = 0
+ sudo /usr/sbin/bhyvectl '--vm=testvm-main-amd64-24172' --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 ./auto-shutdown
x ./disable-notyet-tests.sh
x ./run-kyua.sh
x ./disable-dtrace-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/jenkins2329120927978814728.sh
+ ./freebsd-ci/artifact/post-link.py
Post link: {'job_name': 'FreeBSD-main-amd64-test', 'commit': 'e6dc6a27230f836a131118326731b7c5fd417723', 'branch': 'main', 'target': 'amd64', 'target_arch': 'amd64', 'link_type': 'latest_tested'}
"Link created: main/latest_tested/amd64/amd64 -> ../../e6dc6a27230f836a131118326731b7c5fd417723/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