Date: Mon, 21 Aug 2023 23:11:17 +0000 (GMT) From: jenkins-admin@FreeBSD.org To: fuz@FreeBSD.org, emaste@FreeBSD.org, dev-ci@FreeBSD.org Cc: jenkins-admin@FreeBSD.org Subject: FreeBSD-main-amd64-test - Build #24068 - Failure Message-ID: <85483690.2596.1692659477724@jenkins.ci.freebsd.org> In-Reply-To: <689476163.2586.1692653467516@jenkins.ci.freebsd.org> References: <689476163.2586.1692653467516@jenkins.ci.freebsd.org>
next in thread | previous in thread | raw e-mail | index | archive | help
------=_Part_2595_919908581.1692659477292 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable FreeBSD-main-amd64-test - Build #24068 (4258eb5a0d971cf9b1ea5e8e98535e29ef3= 053f1) - 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/c= hanges Full build log: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/24068/co= nsole 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.76184= 5+00:00 - auditd 23835 - - Got audit initialize trigger passed [0.008s] sys/audit/open:open_read_write_trunc_success -> 2023-08-21T23:11:07.78575= 3+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.8500= 78+00:00 - auditd 23835 - - Got audit initialize trigger passed [0.007s] sys/audit/open:open_write_creat_trunc_success -> 2023-08-21T23:11:07.8688= 40+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 - a= uditd 23835 - - Got audit initialize trigger passed [0.007s] sys/audit/open:open_write_success -> 2023-08-21T23:11:07.910145+00:00 - a= uditd 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.030= 746+00:00 - auditd 23835 - - Got audit initialize trigger passed [0.009s] sys/audit/open:openat_read_creat_trunc_success -> 2023-08-21T23:11:08.053= 136+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.160= 244+00:00 - auditd 23835 - - Got audit initialize trigger passed [0.009s] sys/audit/open:openat_read_write_creat_success -> 2023-08-21T23:11:08.182= 135+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.303= 694+00:00 - auditd 23835 - - Got audit initialize trigger passed [0.009s] sys/audit/open:openat_read_write_trunc_success -> 2023-08-21T23:11:08.325= 674+00:00 - auditd 23835 - - Got audit initialize trigger passed [0.008s] sys/audit/open:openat_write_creat_failure -> 2023-08-21T23:11:08.345425+0= 0:00 - auditd 23835 - - Got audit initialize trigger passed [0.008s] sys/audit/open:openat_write_creat_success -> 2023-08-21T23:11:08.368287+0= 0:00 - auditd 23835 - - Got audit initialize trigger passed [0.009s] sys/audit/open:openat_write_creat_trunc_failure -> 2023-08-21T23:11:08.39= 1053+00:00 - auditd 23835 - - Got audit initialize trigger passed [0.008s] sys/audit/open:openat_write_creat_trunc_success -> 2023-08-21T23:11:08.41= 4507+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+0= 0:00 - auditd 23835 - - Got audit initialize trigger passed [0.010s] sys/audit/open:openat_write_trunc_success -> 2023-08-21T23:11:08.511078+0= 0: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.5843= 38+00:00 - auditd 23835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:cap_getmode_success -> 2023-08-21T23:11:08.6055= 50+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:0= 0 - 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:0= 0 - auditd 23835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:kill_success -> 2023-08-21T23:11:08.914651+00:0= 0 - 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+0= 0:00 - auditd 23835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:munlock_success -> 2023-08-21T23:11:09.091174+0= 0:00 - auditd 23835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:procctl_failure -> 2023-08-21T23:11:09.113724+0= 0:00 - auditd 23835 - - Got audit initialize trigger passed [0.010s] sys/audit/process-control:procctl_success -> 2023-08-21T23:11:09.136271+0= 0: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/util= s/process/executor.cpp:779: Invariant check failed: PID 24607 already in al= l_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 w= hat 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 tr= igger pid 790 (kyua), jid 0, uid 0: exited on signal 6 (core dumped) Abort trap (core dumped) + rc=3D134 + 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=3D$rc_startmsgs' + _value=3DYES + debug 'checkyesno: rc_startmsgs is set to YES.' + return 0 + echo . . + _return=3D0 + 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:= =20 System shutdown time has arrived=07=07 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/2023= 0821231101.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...=20 Syncing disks, vnodes remaining... 0 0 done All buffers synced. lock order reversal: 1st 0xfffff8000ba3ccb0 devfs (devfs, lockmgr) @ /usr/src/sys/kern/vfs_moun= t.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=3D0 + echo 'bhyve return code =3D 0' bhyve return code =3D 0 + sudo /usr/sbin/bhyvectl '--vm=3Dtestvm-main-amd64-24068' --destroy + sh -ex freebsd-ci/scripts/test/extract-meta.sh + METAOUTDIR=3Dmeta-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 direc= tory + report=3Dtest-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': '4258eb5a0d971= cf9b1ea5e8e98535e29ef3053f1', 'branch': 'main', 'target': 'amd64', 'target_= arch': 'amd64', 'link_type': 'latest_tested'} "Link created: main/latest_tested/amd64/amd64 -> ../../4258eb5a0d971cf9b1ea= 5e8e98535e29ef3053f1/amd64/amd64\n" Recording test results ERROR: Step =E2=80=98Publish JUnit test result report=E2=80=99 failed: No t= est 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 ------=_Part_2595_919908581.1692659477292--
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?85483690.2596.1692659477724>