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

From: <jenkins-admin_at_FreeBSD.org>
Date: Thu, 19 Oct 2023 14:29:42 UTC
FreeBSD-main-amd64-test - Build #24282 (e44751530e04ef8326ff22c0d3f7d880c82d3395) - Failure

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

bb8ecf259f96510b9c2146d846403393543061b7 by dchagin:
vfs cache: Fallback to namei to resolve symlinks with leading / in

e44751530e04ef8326ff22c0d3f7d880c82d3395 by asiciliano:
bsdinstall hostname: Restore bsdconfig API



The end of the build log:

[...truncated 4.27 MB...]
sys/posixshm/posixshm_test:largepage_msync  ->  skipped: failed to allocate 1073741824-byte superpage  [0.008s]
sys/posixshm/posixshm_test:largepage_munmap  ->  skipped: failed to allocate 1073741824-byte superpage  [0.008s]
sys/posixshm/posixshm_test:largepage_pipe  ->  skipped: failed to allocate 1073741824-byte superpage  [0.013s]
sys/posixshm/posixshm_test:largepage_reopen  ->  passed  [0.009s]
sys/posixshm/posixshm_test:mode  ->  passed  [0.007s]
sys/posixshm/posixshm_test:object_resize  ->  pid 96324 (posixshm_test), jid 0, uid 0: exited on signal 10 (no core dump - too large)
passed  [0.007s]
sys/posixshm/posixshm_test:open_after_link  ->  passed  [0.005s]
sys/posixshm/posixshm_test:open_anon  ->  passed  [0.005s]
sys/posixshm/posixshm_test:open_anon_readonly  ->  passed  [0.005s]
sys/posixshm/posixshm_test:open_bad_path_pointer  ->  passed  [0.005s]
sys/posixshm/posixshm_test:open_create_existing_object  ->  passed  [0.005s]
sys/posixshm/posixshm_test:open_extra_flags  ->  passed  [0.006s]
sys/posixshm/posixshm_test:open_invalid_path  ->  passed  [0.005s]
sys/posixshm/posixshm_test:open_nonexisting_object  ->  passed  [0.005s]
sys/posixshm/posixshm_test:open_path_too_long  ->  passed  [0.005s]
sys/posixshm/posixshm_test:open_write_only  ->  passed  [0.006s]
sys/posixshm/posixshm_test:readonly_mmap_write  ->  passed  [0.006s]
sys/posixshm/posixshm_test:remap_object  ->  passed  [0.006s]
sys/posixshm/posixshm_test:rename_bad_flag  ->  passed  [0.005s]
sys/posixshm/posixshm_test:rename_bad_path_pointer  ->  passed  [0.006s]
sys/posixshm/posixshm_test:rename_from_anon  ->  passed  [0.008s]
sys/posixshm/posixshm_test:rename_from_nonexisting  ->  passed  [0.006s]
sys/posixshm/posixshm_test:rename_to_anon  ->  passed  [0.005s]
sys/posixshm/posixshm_test:rename_to_exchange  ->  passed  [0.006s]
sys/posixshm/posixshm_test:rename_to_exchange_nonexisting  ->  passed  [0.006s]
sys/posixshm/posixshm_test:rename_to_noreplace  ->  passed  [0.006s]
sys/posixshm/posixshm_test:rename_to_replace  ->  passed  [0.005s]
sys/posixshm/posixshm_test:rename_to_self  ->  passed  [0.005s]
sys/posixshm/posixshm_test:reopen_object  ->  passed  [0.005s]
sys/posixshm/posixshm_test:shm_functionality_across_fork  ->  passed  [0.009s]
sys/posixshm/posixshm_test:trunc_resets_object  ->  passed  [0.005s]
sys/posixshm/posixshm_test:unlink_bad_path_pointer  ->  passed  [0.005s]
sys/posixshm/posixshm_test:unlink_path_too_long  ->  passed  [0.005s]
sys/ses/nondestructive:getelmdesc  ->  skipped: Required program 'sg_ses' not found in PATH  [0.004s]
sys/ses/nondestructive:getelmdevnames  ->  skipped: Required program 'sg_ses' not found in PATH  [0.004s]
sys/ses/nondestructive:getelmmap  ->  skipped: Required program 'sg_ses' not found in PATH  [0.004s]
sys/ses/nondestructive:getelmstat  ->  skipped: Required program 'sg_ses' not found in PATH  [0.004s]
sys/ses/nondestructive:getencid  ->  skipped: Required program 'sg_ses' not found in PATH  [0.004s]
sys/ses/nondestructive:getencname  ->  skipped: Required program 'sg_inq' not found in PATH  [0.004s]
sys/ses/nondestructive:getencstat  ->  skipped: Required program 'sg_ses' not found in PATH  [0.004s]
sys/ses/nondestructive:getnelm  ->  skipped: Required program 'sg_ses' not found in PATH  [0.004s]
sys/ses/nondestructive:getstring  ->  skipped: Required program 'sg_ses' not found in PATH  [0.004s]
sys/sys/arb_test:arb_test  ->  passed  [0.005s]
sys/sys/bitset_test:bit_foreach  ->  passed  [0.005s]
sys/sys/bitstring_test:bit_clear  ->  passed  [0.009s]
sys/sys/bitstring_test:bit_count  ->  passed  [0.005s]
sys/sys/bitstring_test:bit_ffc  ->  passed  [0.005s]
sys/sys/bitstring_test:bit_ffc_area  ->  passed  [0.005s]
sys/sys/bitstring_test:bit_ffc_area_at_all_or_nothing  ->  passed  [0.060s]
sys/sys/bitstring_test:bit_ffc_at  ->  passed  [0.005s]
sys/sys/bitstring_test:bit_ffs  ->  passed  [0.005s]
sys/sys/bitstring_test:bit_ffs_area  ->  passed  [0.005s]
sys/sys/bitstring_test:bit_ffs_area_at_all_or_nothing  ->  passed  [0.059s]
sys/sys/bitstring_test:bit_ffs_at  ->  passed  [0.007s]
sys/sys/bitstring_test:bit_foreach  ->  passed  [0.007s]
sys/sys/bitstring_test:bit_foreach_at  ->  passed  [0.018s]
sys/sys/bitstring_test:bit_foreach_unset  ->  passed  [0.006s]
sys/sys/bitstring_test:bit_foreach_unset_at  ->  passed  [0.019s]
sys/sys/bitstring_test:bit_nclear  ->  passed  [0.046s]
sys/sys/bitstring_test:bit_nset  ->  passed  [0.047s]
sys/sys/bitstring_test:bit_set  ->  passed  [0.011s]
sys/sys/bitstring_test:bitstr_in_struct  ->  passed  [0.006s]
sys/sys/bitstring_test:bitstr_size  ->  passed  [0.006s]
sys/sys/qmath_test:basic_s16q  ->  passed  [0.005s]
sys/sys/qmath_test:basic_s32q  ->  passed  [0.005s]
sys/sys/qmath_test:basic_s64q  ->  passed  [0.005s]
sys/sys/qmath_test:basic_s8q  ->  passed  [0.005s]
sys/sys/qmath_test:basic_u16q  ->  passed  [0.005s]
sys/sys/qmath_test:basic_u32q  ->  passed  [0.005s]
sys/sys/qmath_test:basic_u64q  ->  passed  [0.005s]
sys/sys/qmath_test:basic_u8q  ->  passed  [0.005s]
sys/sys/qmath_test:circle_u64q  ->  passed  [0.005s]
sys/sys/qmath_test:qaddi_s64q  ->  passed  [0.005s]
sys/sys/qmath_test:qaddq_s64q  ->  passed  [0.005s]
sys/sys/qmath_test:qdivq_s64q  ->  skipped: https://bugs.freebsd.org/240219  [0.005s]
sys/sys/qmath_test:qfraci_s64q  ->  passed  [0.005s]
sys/sys/qmath_test:qmuli_s64q  ->  passed  [0.005s]
sys/sys/qmath_test:qmulq_s64q  ->  passed  [0.005s]
sys/sys/qmath_test:qsubi_s64q  ->  passed  [0.005s]
sys/sys/qmath_test:qsubq_s64q  ->  passed  [0.005s]
sys/sys/rb_test:rb_test  ->  passed  [0.006s]
sys/sys/splay_test:splay_test  ->  passed  [0.006s]
sys/sys/time_test:mstosbt  ->  passed  [0.006s]
sys/sys/time_test:nstosbt  ->  passed  [0.006s]
sys/sys/time_test:sbttoms  ->  passed  [0.005s]
sys/sys/time_test:sbttons  ->  passed  [0.005s]
sys/sys/time_test:sbttous  ->  passed  [0.005s]
sys/sys/time_test:ustosbt  ->  passed  [0.005s]
sys/vfs/lookup_cap_dotdot:capmode__negative  ->  passed  [0.007s]
sys/vfs/lookup_cap_dotdot:lookup_cap_dotdot__advanced  ->  passed  [0.007s]
sys/vfs/lookup_cap_dotdot:lookup_cap_dotdot__basic  ->  passed  [0.007s]
sys/vfs/lookup_cap_dotdot:lookup_cap_dotdot__negative  ->  passed  [0.007s]
sys/vfs/lookup_cap_dotdot:lookup_cap_dotdot__root  ->  passed  [0.005s]
sys/vfs/lookup_cap_dotdot:openat__basic_negative  ->  passed  [0.007s]
sys/vfs/lookup_cap_dotdot:openat__basic_positive  ->  passed  [0.007s]
sys/vfs/trailing_slash:main  ->  passed  [0.028s]
sys/vm/mlock_test:mlock__copy_on_write_anon  ->  passed  [0.006s]
sys/vm/mlock_test:mlock__copy_on_write_vnode  ->  passed  [0.006s]
sys/vm/mlock_test:mlock__superpage_fault  ->  passed  [0.007s]
sys/vm/mlock_test:mlock__truncate_and_resize  ->  passed  [0.006s]
sys/vm/mlock_test:mlock__truncate_and_unlock  ->  passed  [0.005s]
sys/vm/mmap_map_32bit_test:map_32bit_w_aslr  ->  passed  [0.026s]
sys/vm/mmap_map_32bit_test:map_32bit_wo_aslr  ->  passed  [0.027s]
sys/vm/mmap_test:mmap__bad_arguments  ->  passed  [0.005s]
sys/vm/mmap_test:mmap__dev_zero_private  ->  passed  [0.007s]
sys/vm/mmap_test:mmap__dev_zero_shared  ->  passed  [0.005s]
sys/vm/mmap_test:mmap__map_at_zero  ->  passed  [0.006s]
sys/vm/mmap_test:mmap__write_only  ->  passed  [0.006s]
sys/vm/page_fault_signal:page_fault_signal__bus_objerr_1  ->  passed  [0.005s]
sys/vm/page_fault_signal:page_fault_signal__bus_objerr_2  ->  passed  [0.007s]
sys/vm/page_fault_signal:page_fault_signal__segv_accerr_1  ->  passed  [0.006s]
sys/vm/page_fault_signal:page_fault_signal__segv_accerr_2  ->  passed  [0.006s]
sys/vm/page_fault_signal:page_fault_signal__segv_maperr_1  ->  passed  [0.006s]
sys/vm/shared_shadow_inval_test:shared_shadow_inval__eager_cow  ->  passed  [2.394s]
sys/vm/shared_shadow_inval_test:shared_shadow_inval__lazy_cow  ->  passed  [2.365s]
sys/vm/stack/stack_dlopen_exec_test:dlopen_test  ->  passed  [0.007s]
sys/vm/stack/stack_dt_need_exec_test:dt_need_test  ->  passed  [0.006s]
sys/vm/stack/stack_mprotect_exec_test:mprotect_exec_test  ->  passed  [0.005s]
sys/vmm/vmm_cred_jail:vmm_cred_jail_host  ->  skipped: vmm is not loaded  [0.020s]
sys/vmm/vmm_cred_jail:vmm_cred_jail_other  ->  skipped: vmm is not loaded  [0.024s]
sbin/md5/md5_test:bsd_md5_vec1  ->  passed  [0.083s]
sbin/md5/md5_test:bsd_md5_vec2  ->  passed  [0.080s]
sbin/md5/md5_test:bsd_md5_vec3  ->  passed  [0.092s]
sbin/md5/md5_test:bsd_md5_vec4  ->  passed  [0.091s]
sbin/md5/md5_test:bsd_md5_vec5  ->  passed  [0.091s]
sbin/md5/md5_test:bsd_md5_vec6  ->  passed  [0.091s]
sbin/md5/md5_test:bsd_md5_vec7  ->  passed  [0.090s]
sbin/md5/md5_test:bsd_md5_vec8  ->  passed  [0.088s]
sbin/md5/md5_test:bsd_rmd160_vec1  ->  passed  [0.091s]
sbin/md5/md5_test:bsd_rmd160_vec2  ->  passed  [0.091s]
sbin/md5/md5_test:bsd_rmd160_vec3  ->  passed  [0.084s]
sbin/md5/md5_test:bsd_rmd160_vec4  ->  passed  [0.090s]
sbin/md5/md5_test:bsd_rmd160_vec5  ->  *** /usr/src/contrib/kyua/utils/process/executor.cpp:779: Invariant check failed: PID 1870 already in all_exec_handles; not properly cleaned up or reused too fast
*** Fatal signal 6 received
*** Log file is /.kyua/logs/kyua.20231019-135208.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 ./run-kyua.sh
a ./disable-notyet-tests.sh
a ./run.sh
a ./disable-zfs-tests.sh
a ./auto-shutdown
a ./disable-dtrace-tests.sh
+ [ -f /meta/auto-shutdown ]
+ uname -p
+ shutdown -p now
Shutdown NOW!
shutdown: [pid 1924]
+ 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-10-19T14:29:34.617333+00:00 - shutdown 1924 - - power-down by root: 

System shutdown time has arrived
2023-10-19T14:29:34.618886+00:00 - auditd 707 - - auditd_wait_for_events: SIGTERM
2023-10-19T14:29:34.620394+00:00 - auditd 707 - - Auditing disabled
2023-10-19T14:29:34.621808+00:00 - auditd 707 - - renamed /var/audit/20231019135208.not_terminated to /var/audit/20231019135208.20231019142934
2023-10-19T14:29:34.624476+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 done
All buffers synced.
lock order reversal:
 1st 0xfffff80009a0c070 devfs (devfs, lockmgr) @ /usr/src/sys/kern/vfs_mount.c:2255
 2nd 0xfffff80009a15930 fdescfs (fdescfs, lockmgr) @ /usr/src/sys/kern/vfs_subr.c:3309
lock order devfs -> fdescfs attempted at:
#0 0xffffffff80bc288e at witness_checkorder+0xbbe
#1 0xffffffff80b1c551 at lockmgr_lock_flags+0x181
#2 0xffffffff80c5e663 at _vn_lock+0x53
#3 0xffffffff80c45c4d at vget_finish+0x4d
#4 0xffffffff82610262 at fdesc_root+0x22
#5 0xffffffff80c46ceb at vflush+0x4b
#6 0xffffffff82610211 at fdesc_unmount+0x71
#7 0xffffffff80c3b935 at dounmount+0x7b5
#8 0xffffffff80c4842a at vfs_unmountall+0x6a
#9 0xffffffff80c1896c at bufshutdown+0x30c
#10 0xffffffff80b4f2a3 at kern_reboot+0x703
#11 0xffffffff80b4eb49 at sys_reboot+0x3a9
#12 0xffffffff810506a3 at amd64_syscall+0x153
#13 0xffffffff810226cb at fast_syscall_common+0xf8
Uptime: 37m30s
Khelp module "ertt" can't unload until its refcount drops from 1 to 0.
acpi0: Powering system off
+ rc=0
+ echo 'bhyve return code = 0'
bhyve return code = 0
+ sudo /usr/sbin/bhyvectl '--vm=testvm-main-amd64-24282' --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-kyua.sh
x ./disable-notyet-tests.sh
x ./run.sh
x ./disable-zfs-tests.sh
x ./auto-shutdown
x ./disable-dtrace-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/jenkins12735887715174893742.sh
+ ./freebsd-ci/artifact/post-link.py
Post link: {'job_name': 'FreeBSD-main-amd64-test', 'commit': 'e44751530e04ef8326ff22c0d3f7d880c82d3395', 'branch': 'main', 'target': 'amd64', 'target_arch': 'amd64', 'link_type': 'latest_tested'}
"Link created: main/latest_tested/amd64/amd64 -> ../../e44751530e04ef8326ff22c0d3f7d880c82d3395/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