FreeBSD-main-amd64-test - Build #24217 - Failure
Date: Fri, 06 Oct 2023 22:37:32 UTC
FreeBSD-main-amd64-test - Build #24217 (86885b18689889e9b9142fd31d8c67f21334ba32) - Failure
Build information: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/24217/
Full change log: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/24217/changes
Full build log: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/24217/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)
7786911ffd1f7c6eec0334a06bf9ec1bc14540d3 by manu:
dwc: Rewrite part of the descriptors setup functions
7854d0f6584d259fd7f13595e3d3a4f157db9339 by manu:
dwc: Get the mii clock after calling IF_DWC_INIT
cf037a17c7c81e8a2e73c89eb90877f2625749d7 by manu:
dwc: rockchip: Properly calculate mii clock based on pclk_mac
38cbdae33b7c3f772845c06f52b86c0ddeab6a17 by manu:
dwc: Rewrite barrier part
03205a8cd57feb95752142d899d026ff8f45f3e6 by manu:
i2c: rtc: Add module for hym8563 driver
86885b18689889e9b9142fd31d8c67f21334ba32 by asomers:
Fix intermittency in the sys.fs.fusefs.mknod.main test
The end of the build log:
[...truncated 4.36 MB...]
passed [0.007s]
sys/audit/file-close:revoke_success -> 2023-10-06T22:37:22.472954+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/file-create:link_failure -> 2023-10-06T22:37:22.493249+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.007s]
sys/audit/file-create:link_success -> 2023-10-06T22:37:22.511990+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.007s]
sys/audit/file-create:linkat_failure -> 2023-10-06T22:37:22.533860+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.011s]
sys/audit/file-create:linkat_success -> 2023-10-06T22:37:22.557477+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.009s]
sys/audit/file-create:mkdir_failure -> 2023-10-06T22:37:22.578271+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/file-create:mkdir_success -> 2023-10-06T22:37:22.598249+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/file-create:mkdirat_failure -> 2023-10-06T22:37:22.618750+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.009s]
sys/audit/file-create:mkdirat_success -> 2023-10-06T22:37:22.643951+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.010s]
sys/audit/file-create:mkfifo_failure -> 2023-10-06T22:37:22.669198+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.009s]
sys/audit/file-create:mkfifo_success -> 2023-10-06T22:37:22.689538+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/file-create:mkfifoat_failure -> 2023-10-06T22:37:22.709665+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/file-create:mkfifoat_success -> 2023-10-06T22:37:22.732784+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.009s]
sys/audit/file-create:mknod_failure -> 2023-10-06T22:37:22.756737+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.010s]
sys/audit/file-create:mknod_success -> 2023-10-06T22:37:22.781699+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.010s]
sys/audit/file-create:mknodat_failure -> 2023-10-06T22:37:22.804351+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.009s]
sys/audit/file-create:mknodat_success -> 2023-10-06T22:37:22.824392+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/file-create:rename_failure -> 2023-10-06T22:37:22.843837+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.007s]
sys/audit/file-create:rename_success -> 2023-10-06T22:37:22.862462+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.007s]
sys/audit/file-create:renameat_failure -> 2023-10-06T22:37:22.881260+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.007s]
sys/audit/file-create:renameat_success -> 2023-10-06T22:37:22.899691+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/file-create:symlink_failure -> 2023-10-06T22:37:22.918998+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.007s]
sys/audit/file-create:symlink_success -> 2023-10-06T22:37:22.937884+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.007s]
sys/audit/file-create:symlinkat_failure -> 2023-10-06T22:37:22.957198+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/file-create:symlinkat_success -> 2023-10-06T22:37:22.976339+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/file-delete:rename_failure -> 2023-10-06T22:37:22.999265+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.009s]
sys/audit/file-delete:rename_success -> 2023-10-06T22:37:23.018342+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/file-delete:renameat_failure -> 2023-10-06T22:37:23.038223+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/file-delete:renameat_success -> 2023-10-06T22:37:23.056470+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.007s]
sys/audit/file-delete:rmdir_failure -> 2023-10-06T22:37:23.075640+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.007s]
sys/audit/file-delete:rmdir_success -> 2023-10-06T22:37:23.099213+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.009s]
sys/audit/file-delete:unlink_failure -> 2023-10-06T22:37:23.119068+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.007s]
sys/audit/file-delete:unlink_success -> 2023-10-06T22:37:23.137770+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.007s]
sys/audit/file-delete:unlinkat_failure -> 2023-10-06T22:37:23.159255+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.010s]
sys/audit/file-delete:unlinkat_success -> 2023-10-06T22:37:23.182157+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.009s]
sys/audit/file-read:readlink_failure -> 2023-10-06T22:37:23.206073+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/file-read:readlink_success -> 2023-10-06T22:37:23.225193+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/file-read:readlinkat_failure -> 2023-10-06T22:37:23.249405+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.010s]
sys/audit/file-read:readlinkat_success -> 2023-10-06T22:37:23.273686+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.009s]
sys/audit/file-write:ftruncate_failure -> 2023-10-06T22:37:23.298475+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.011s]
sys/audit/file-write:ftruncate_success -> 2023-10-06T22:37:23.319952+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.007s]
sys/audit/file-write:truncate_failure -> 2023-10-06T22:37:23.341635+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/file-write:truncate_success -> 2023-10-06T22:37:23.364685+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/inter-process:msgctl_illegal_command -> 2023-10-06T22:37:23.385221+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/inter-process:msgctl_rmid_failure -> 2023-10-06T22:37:23.409361+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/inter-process:msgctl_rmid_success -> 2023-10-06T22:37:23.434675+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.010s]
sys/audit/inter-process:msgctl_set_failure -> 2023-10-06T22:37:23.458668+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.009s]
sys/audit/inter-process:msgctl_set_success -> 2023-10-06T22:37:23.478993+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/inter-process:msgctl_stat_failure -> 2023-10-06T22:37:23.499025+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/inter-process:msgctl_stat_success -> 2023-10-06T22:37:23.519895+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/inter-process:msgget_failure -> 2023-10-06T22:37:23.541293+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/inter-process:msgget_success -> 2023-10-06T22:37:23.566234+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.009s]
sys/audit/inter-process:msgrcv_failure -> 2023-10-06T22:37:23.590978+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.009s]
sys/audit/inter-process:msgrcv_success -> 2023-10-06T22:37:23.612071+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/inter-process:msgsnd_failure -> 2023-10-06T22:37:23.632426+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.007s]
sys/audit/inter-process:msgsnd_success -> 2023-10-06T22:37:23.654806+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.010s]
sys/audit/inter-process:pipe_failure -> 2023-10-06T22:37:23.678709+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.009s]
sys/audit/inter-process:pipe_success -> 2023-10-06T22:37:23.699538+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/inter-process:posix_openpt_failure -> 2023-10-06T22:37:23.720176+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.009s]
sys/audit/inter-process:posix_openpt_success -> 2023-10-06T22:37:23.741218+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/inter-process:semctl_getall_failure -> 2023-10-06T22:37:23.762156+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/inter-process:semctl_getall_success -> 2023-10-06T22:37:23.782177+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/inter-process:semctl_getncnt_failure -> 2023-10-06T22:37:23.803113+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/inter-process:semctl_getncnt_success -> 2023-10-06T22:37:23.824400+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/inter-process:semctl_getpid_failure -> 2023-10-06T22:37:23.845937+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/inter-process:semctl_getpid_success -> 2023-10-06T22:37:23.866485+00:00 - auditd 36956 - - Got audit initialize trigger
passed [0.008s]
sys/audit/inter-process:semctl_getval_failure -> 2023-10-06T22:37:23.889110+00:00 - auditd 36956 - - Got audit initialize trigger
*** /usr/src/contrib/kyua/utils/process/executor.cpp:822: Invariant check failed: PID 37408 already in all_exec_handles; not properly cleaned up or reused too fast
*** Fatal signal 6 received
*** Log file is /.kyua/logs/kyua.20231006-215129.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 ./auto-shutdown
a ./disable-notyet-tests.sh
a ./disable-zfs-tests.sh
a ./disable-dtrace-tests.sh
a ./run.sh
+ [ -f /meta/auto-shutdown ]
+ uname -p
+ shutdown -p now
Shutdown NOW!
shutdown: [pid 37412]
+ 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-06T22:37:24.013490+00:00 - shutdown 37412 - - power-down by root:
System shutdown time has arrived
2023-10-06T22:37:24.015596+00:00 - auditd 36956 - - auditd_wait_for_events: SIGTERM
2023-10-06T22:37:24.016968+00:00 - auditd 36956 - - Auditing disabled
2023-10-06T22:37:24.018380+00:00 - auditd 36956 - - renamed /var/audit/20231006223718.not_terminated to /var/audit/20231006223718.20231006223724
2023-10-06T22:37:24.020725+00:00 - auditd 36956 - - 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 0xfffff8000ba07e70 devfs (devfs, lockmgr) @ /usr/src/sys/kern/vfs_mount.c:2240
2nd 0xfffff8000ba07770 fdescfs (fdescfs, lockmgr) @ /usr/src/sys/kern/vfs_subr.c:3138
lock order devfs -> fdescfs attempted at:
#0 0xffffffff80bc271e at witness_checkorder+0xbbe
#1 0xffffffff80b1c451 at lockmgr_lock_flags+0x181
#2 0xffffffff80c5e0a3 at _vn_lock+0x53
#3 0xffffffff80c4573d at vget_finish+0x4d
#4 0xffffffff82610262 at fdesc_root+0x22
#5 0xffffffff80c467db at vflush+0x4b
#6 0xffffffff82610211 at fdesc_unmount+0x71
#7 0xffffffff80c3b4c5 at dounmount+0x7b5
#8 0xffffffff80c47f1a at vfs_unmountall+0x6a
#9 0xffffffff80c1852c at bufshutdown+0x30c
#10 0xffffffff80b4f1a3 at kern_reboot+0x703
#11 0xffffffff80b4ea49 at sys_reboot+0x3a9
#12 0xffffffff8104f693 at amd64_syscall+0x153
#13 0xffffffff810212db at fast_syscall_common+0xf8
Uptime: 45m58s
acpi0: Powering system off
+ rc=0
+ echo 'bhyve return code = 0'
bhyve return code = 0
+ sudo /usr/sbin/bhyvectl '--vm=testvm-main-amd64-24217' --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 ./auto-shutdown
x ./disable-notyet-tests.sh
x ./disable-zfs-tests.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/jenkins16937649608222819301.sh
+ ./freebsd-ci/artifact/post-link.py
Post link: {'job_name': 'FreeBSD-main-amd64-test', 'commit': '86885b18689889e9b9142fd31d8c67f21334ba32', 'branch': 'main', 'target': 'amd64', 'target_arch': 'amd64', 'link_type': 'latest_tested'}
"Link created: main/latest_tested/amd64/amd64 -> ../../86885b18689889e9b9142fd31d8c67f21334ba32/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