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

Reply: jenkins-admin_a_FreeBSD.org: "FreeBSD-main-amd64-test - Build #24034 - Still unstable"
In reply to: jenkins-admin_a_FreeBSD.org: "FreeBSD-main-amd64-test - Build #24032 - Still Unstable"
Go to: [ bottom of page ] [ top of archives ] [ this month ]
From: <jenkins-admin_at_FreeBSD.org>
Date: Wed, 16 Aug 2023 09:34:52 UTC
FreeBSD-main-amd64-test - Build #24033 (750a49b606f173fdc2a53461882260fcf6a407f1) - Failure

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

fa12e8fe5145722728bac4e394edb11b2a86c675 by imp:
fw_stub: Don't add $FreeBSD$ to generated file

750a49b606f173fdc2a53461882260fcf6a407f1 by imp:
vnode_if: Don't add $FreeBSD$ to the output



The end of the build log:

[...truncated 4.34 MB...]
passed  [0.006s]
sys/audit/file-create:link_failure  ->  2023-08-16T09:34:43.140024+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:link_success  ->  2023-08-16T09:34:43.158896+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-create:linkat_failure  ->  2023-08-16T09:34:43.180651+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/file-create:linkat_success  ->  2023-08-16T09:34:43.199653+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:mkdir_failure  ->  2023-08-16T09:34:43.221088+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/file-create:mkdir_success  ->  2023-08-16T09:34:43.240344+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:mkdirat_failure  ->  2023-08-16T09:34:43.258428+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:mkdirat_success  ->  2023-08-16T09:34:43.276017+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:mkfifo_failure  ->  2023-08-16T09:34:43.294192+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:mkfifo_success  ->  2023-08-16T09:34:43.311170+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:mkfifoat_failure  ->  2023-08-16T09:34:43.328050+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:mkfifoat_success  ->  2023-08-16T09:34:43.344762+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:mknod_failure  ->  2023-08-16T09:34:43.361996+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:mknod_success  ->  2023-08-16T09:34:43.379550+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:mknodat_failure  ->  2023-08-16T09:34:43.396758+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:mknodat_success  ->  2023-08-16T09:34:43.413556+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:rename_failure  ->  2023-08-16T09:34:43.430511+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/file-create:rename_success  ->  2023-08-16T09:34:43.446038+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/file-create:renameat_failure  ->  2023-08-16T09:34:43.462661+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/file-create:renameat_success  ->  2023-08-16T09:34:43.478923+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:symlink_failure  ->  2023-08-16T09:34:43.495741+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/file-create:symlink_success  ->  2023-08-16T09:34:43.511528+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/file-create:symlinkat_failure  ->  2023-08-16T09:34:43.527615+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:symlinkat_success  ->  2023-08-16T09:34:43.544268+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-delete:rename_failure  ->  2023-08-16T09:34:43.561338+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/file-delete:rename_success  ->  2023-08-16T09:34:43.576752+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/file-delete:renameat_failure  ->  2023-08-16T09:34:43.592443+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/file-delete:renameat_success  ->  2023-08-16T09:34:43.608695+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/file-delete:rmdir_failure  ->  2023-08-16T09:34:43.625163+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.005s]
sys/audit/file-delete:rmdir_success  ->  2023-08-16T09:34:43.640560+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/file-delete:unlink_failure  ->  2023-08-16T09:34:43.656203+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.005s]
sys/audit/file-delete:unlink_success  ->  2023-08-16T09:34:43.673874+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-delete:unlinkat_failure  ->  2023-08-16T09:34:43.691757+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/file-delete:unlinkat_success  ->  2023-08-16T09:34:43.709028+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-read:readlink_failure  ->  2023-08-16T09:34:43.728064+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/file-read:readlink_success  ->  2023-08-16T09:34:43.743269+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.005s]
sys/audit/file-read:readlinkat_failure  ->  2023-08-16T09:34:43.758919+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/file-read:readlinkat_success  ->  2023-08-16T09:34:43.775455+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/file-write:ftruncate_failure  ->  2023-08-16T09:34:43.792058+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.005s]
sys/audit/file-write:ftruncate_success  ->  2023-08-16T09:34:43.807946+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/file-write:truncate_failure  ->  2023-08-16T09:34:43.823220+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.005s]
sys/audit/file-write:truncate_success  ->  2023-08-16T09:34:43.838108+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.005s]
sys/audit/inter-process:msgctl_illegal_command  ->  2023-08-16T09:34:43.854283+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/inter-process:msgctl_rmid_failure  ->  2023-08-16T09:34:43.871262+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/inter-process:msgctl_rmid_success  ->  2023-08-16T09:34:43.888278+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/inter-process:msgctl_set_failure  ->  2023-08-16T09:34:43.905100+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/inter-process:msgctl_set_success  ->  2023-08-16T09:34:43.922381+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/inter-process:msgctl_stat_failure  ->  2023-08-16T09:34:43.939329+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/inter-process:msgctl_stat_success  ->  2023-08-16T09:34:43.956287+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/inter-process:msgget_failure  ->  2023-08-16T09:34:43.973226+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/inter-process:msgget_success  ->  2023-08-16T09:34:43.990064+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/inter-process:msgrcv_failure  ->  2023-08-16T09:34:44.006801+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/inter-process:msgrcv_success  ->  2023-08-16T09:34:44.024217+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/inter-process:msgsnd_failure  ->  2023-08-16T09:34:44.041262+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/inter-process:msgsnd_success  ->  2023-08-16T09:34:44.057901+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/inter-process:pipe_failure  ->  2023-08-16T09:34:44.075148+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/inter-process:pipe_success  ->  2023-08-16T09:34:44.094555+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/inter-process:posix_openpt_failure  ->  2023-08-16T09:34:44.116541+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/inter-process:posix_openpt_success  ->  2023-08-16T09:34:44.135422+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/inter-process:semctl_getall_failure  ->  2023-08-16T09:34:44.153405+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.006s]
sys/audit/inter-process:semctl_getall_success  ->  2023-08-16T09:34:44.171910+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/inter-process:semctl_getncnt_failure  ->  2023-08-16T09:34:44.192681+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/inter-process:semctl_getncnt_success  ->  2023-08-16T09:34:44.214062+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/inter-process:semctl_getpid_failure  ->  2023-08-16T09:34:44.233436+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/inter-process:semctl_getpid_success  ->  2023-08-16T09:34:44.254622+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/inter-process:semctl_getval_failure  ->  2023-08-16T09:34:44.276304+00:00 - auditd 24609 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/inter-process:semctl_getval_success  ->  2023-08-16T09:34:44.296309+00:00 - auditd 24609 - - Got audit initialize trigger
*** /usr/src/contrib/kyua/utils/process/executor.cpp:822: Invariant check failed: PID 25063 already in all_exec_handles; not properly cleaned up or reused too fast
*** Fatal signal 6 received
*** Log file is /.kyua/logs/kyua.20230816-085701.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.sh
a ./disable-notyet-tests.sh
a ./disable-zfs-tests.sh
a ./auto-shutdown
a ./run-kyua.sh
a ./disable-dtrace-tests.sh
+ [ -f /meta/auto-shutdown ]
+ uname -p
+ shutdown -p now
Shutdown NOW!
shutdown: [pid 25067]
+ 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-16T09:34:44.417653+00:00 - shutdown 25067 - - power-down by root: 

System shutdown time has arrived
2023-08-16T09:34:44.419343+00:00 - auditd 24609 - - auditd_wait_for_events: SIGTERM
2023-08-16T09:34:44.420704+00:00 - auditd 24609 - - Auditing disabled
2023-08-16T09:34:44.422181+00:00 - auditd 24609 - - renamed /var/audit/20230816093439.not_terminated to /var/audit/20230816093439.20230816093444
2023-08-16T09:34:44.424844+00:00 - auditd 24609 - - 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 0xfffff8000b9fd770 devfs (devfs, lockmgr) @ /usr/src/sys/kern/vfs_mount.c:2242
 2nd 0xfffff8000b9fd070 fdescfs (fdescfs, lockmgr) @ /usr/src/sys/kern/vfs_subr.c:3057
lock order devfs -> fdescfs attempted at:
#0 0xffffffff80bc063e at witness_checkorder+0xbbe
#1 0xffffffff80b1a861 at lockmgr_lock_flags+0x181
#2 0xffffffff80c5aa43 at _vn_lock+0x53
#3 0xffffffff80c422ad at vget_finish+0x4d
#4 0xffffffff82610262 at fdesc_root+0x22
#5 0xffffffff80c4334b at vflush+0x4b
#6 0xffffffff82610211 at fdesc_unmount+0x71
#7 0xffffffff80c38265 at dounmount+0x7b5
#8 0xffffffff80c44a8a at vfs_unmountall+0x6a
#9 0xffffffff80c1528c at bufshutdown+0x30c
#10 0xffffffff80b4d383 at kern_reboot+0x703
#11 0xffffffff80b4cc29 at sys_reboot+0x3a9
#12 0xffffffff8104a398 at amd64_syscall+0x138
#13 0xffffffff8101c7cb at fast_syscall_common+0xf8
Uptime: 37m46s
acpi0: Powering system off
+ rc=0
+ echo 'bhyve return code = 0'
bhyve return code = 0
+ sudo /usr/sbin/bhyvectl '--vm=testvm-main-amd64-24033' --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 ./disable-notyet-tests.sh
x ./disable-zfs-tests.sh
x ./auto-shutdown
x ./run-kyua.sh
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/jenkins8362075193498892095.sh
+ ./freebsd-ci/artifact/post-link.py
Post link: {'job_name': 'FreeBSD-main-amd64-test', 'commit': '750a49b606f173fdc2a53461882260fcf6a407f1', 'branch': 'main', 'target': 'amd64', 'target_arch': 'amd64', 'link_type': 'latest_tested'}
"Link created: main/latest_tested/amd64/amd64 -> ../../750a49b606f173fdc2a53461882260fcf6a407f1/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