Date: Tue, 5 Sep 2023 17:26:41 +0000 (GMT) From: jenkins-admin@FreeBSD.org To: 0mp@FreeBSD.org, dev-ci@FreeBSD.org Cc: jenkins-admin@FreeBSD.org Subject: FreeBSD-main-amd64-test - Build #24117 - Still Failing Message-ID: <1224725452.3514.1693934801463@jenkins.ci.freebsd.org> In-Reply-To: <1785138825.3508.1693926793239@jenkins.ci.freebsd.org> References: <1785138825.3508.1693926793239@jenkins.ci.freebsd.org>
next in thread | previous in thread | raw e-mail | index | archive | help
------=_Part_3513_531600169.1693934801172 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable FreeBSD-main-amd64-test - Build #24117 (5b7a776f481891f10820a0b4838d0e0feb6= 0b8ad) - Still Failing Build information: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/24117= / Full change log: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/24117/c= hanges Full build log: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/24117/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) 52d374a067002fc42409b32059ec8b8506a70429 by 0mp: getdirentries.2: Improve readability of dirent members 5b7a776f481891f10820a0b4838d0e0feb60b8ad by 0mp: getdirentries.2: Reference dir(5) The end of the build log: [...truncated 4.32 MB...] 2023-09-05T17:26:29.840068+00:00 - auditd 25123 - - Auditing enabled 2023-09-05T17:26:29.840595+00:00 - auditd 25123 - - New audit file is /var/= audit/20230905172629.not_terminated passed [0.008s] sys/audit/administrative:auditon_default_failure -> 2023-09-05T17:26:29.8= 43824+00:00 - auditd 25123 - - Registered 677 event to class mappings. 2023-09-05T17:26:29.843991+00:00 - auditd 25123 - - Registered non-attribut= able event mask. 2023-09-05T17:26:29.845355+00:00 - auditd 25123 - - Set audit policy in ker= nel. 2023-09-05T17:26:29.846752+00:00 - auditd 25123 - - Set audit trail size in= kernel. 2023-09-05T17:26:29.847542+00:00 - auditd 25123 - - Set audit trail queue i= n kernel. 2023-09-05T17:26:29.848947+00:00 - auditd 25123 - - Set audit trail min fre= e percent in kernel. 2023-09-05T17:26:29.849104+00:00 - auditd 25123 - - audit_control(5) may be= missing 'host:' field 2023-09-05T17:26:29.849247+00:00 - auditd 25123 - - Audit controls init suc= cessful 2023-09-05T17:26:29.858074+00:00 - auditd 25123 - - Got audit initialize tr= igger passed [0.010s] sys/audit/administrative:auditon_default_success -> 2023-09-05T17:26:29.8= 81581+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.009s] sys/audit/administrative:auditon_getcar_failure -> 2023-09-05T17:26:29.90= 3920+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_getclass_failure -> 2023-09-05T17:26:29.= 925244+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_getclass_success -> 2023-09-05T17:26:29.= 948520+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_getcond_failure -> 2023-09-05T17:26:29.9= 71939+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_getcond_success -> 2023-09-05T17:26:29.9= 95554+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.009s] sys/audit/administrative:auditon_getcwd_failure -> 2023-09-05T17:26:30.01= 9108+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.009s] sys/audit/administrative:auditon_getkmask_failure -> 2023-09-05T17:26:30.= 042703+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_getkmask_success -> 2023-09-05T17:26:30.= 065573+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_getpolicy_failure -> 2023-09-05T17:26:30= .088206+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_getpolicy_success -> 2023-09-05T17:26:30= .111183+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_getqctrl_failure -> 2023-09-05T17:26:30.= 133629+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_getqctrl_success -> 2023-09-05T17:26:30.= 154786+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.009s] sys/audit/administrative:auditon_getstat_failure -> 2023-09-05T17:26:30.1= 76037+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_setclass_failure -> 2023-09-05T17:26:30.= 197438+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_setclass_success -> 2023-09-05T17:26:30.= 220327+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_setcond_failure -> 2023-09-05T17:26:30.2= 42905+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_setcond_success -> 2023-09-05T17:26:30.2= 65617+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_setkmask_failure -> 2023-09-05T17:26:30.= 288558+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_setkmask_success -> 2023-09-05T17:26:30.= 311527+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_setpolicy_failure -> 2023-09-05T17:26:30= .334592+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_setpolicy_success -> 2023-09-05T17:26:30= .357234+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_setqctrl_failure -> 2023-09-05T17:26:30.= 380108+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_setqctrl_success -> 2023-09-05T17:26:30.= 402274+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:auditon_setsmask_failure -> 2023-09-05T17:26:30.= 423757+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.009s] sys/audit/administrative:auditon_setstat_failure -> 2023-09-05T17:26:30.4= 44513+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.007s] sys/audit/administrative:auditon_setumask_failure -> 2023-09-05T17:26:30.= 466117+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:clock_settime_failure -> 2023-09-05T17:26:30.489= 211+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:clock_settime_success -> 2023-09-05T17:26:30.512= 009+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.009s] sys/audit/administrative:getaudit_addr_failure -> 2023-09-05T17:26:30.535= 689+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.009s] sys/audit/administrative:getaudit_addr_success -> 2023-09-05T17:26:30.559= 350+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.009s] sys/audit/administrative:getaudit_failure -> 2023-09-05T17:26:30.583287+0= 0:00 - auditd 25123 - - Got audit initialize trigger passed [0.009s] sys/audit/administrative:getaudit_success -> 2023-09-05T17:26:30.605977+0= 0:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:getauid_failure -> 2023-09-05T17:26:30.629124+00= :00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:getauid_success -> 2023-09-05T17:26:30.651555+00= :00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:mount_failure -> 2023-09-05T17:26:30.673650+00:0= 0 - auditd 25123 - - Got audit initialize trigger passed [0.007s] sys/audit/administrative:nfs_getfh_failure -> 2023-09-05T17:26:30.693735+= 00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:nfs_getfh_success -> 2023-09-05T17:26:30.716806+= 00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:nmount_failure -> 2023-09-05T17:26:30.739231+00:= 00 - auditd 25123 - - Got audit initialize trigger passed [0.009s] sys/audit/administrative:ntp_adjtime_failure -> 2023-09-05T17:26:30.76201= 9+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:ntp_adjtime_success -> 2023-09-05T17:26:30.78532= 3+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:quotactl_failure -> 2023-09-05T17:26:30.807972+0= 0:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:reboot_failure -> 2023-09-05T17:26:30.830624+00:= 00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:setaudit_addr_failure -> 2023-09-05T17:26:30.853= 277+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:setaudit_addr_success -> 2023-09-05T17:26:30.876= 203+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.009s] sys/audit/administrative:setaudit_failure -> 2023-09-05T17:26:30.898706+0= 0:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:setaudit_success -> 2023-09-05T17:26:30.921149+0= 0:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:setauid_failure -> 2023-09-05T17:26:30.944355+00= :00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:setauid_success -> 2023-09-05T17:26:30.966062+00= :00 - auditd 25123 - - Got audit initialize trigger passed [0.009s] sys/audit/administrative:settimeofday_failure -> 2023-09-05T17:26:30.9885= 18+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.009s] sys/audit/administrative:settimeofday_success -> 2023-09-05T17:26:31.0107= 72+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.007s] sys/audit/administrative:swapoff_failure -> 2023-09-05T17:26:31.029537+00= :00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/administrative:swapon_failure -> 2023-09-05T17:26:31.047911+00:= 00 - auditd 25123 - - Got audit initialize trigger passed [0.007s] sys/audit/file-attribute-access:access_failure -> 2023-09-05T17:26:31.070= 352+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.007s] sys/audit/file-attribute-access:access_success -> 2023-09-05T17:26:31.091= 223+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.009s] sys/audit/file-attribute-access:eaccess_failure -> 2023-09-05T17:26:31.11= 3593+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.010s] sys/audit/file-attribute-access:eaccess_success -> 2023-09-05T17:26:31.13= 4729+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/file-attribute-access:extattr_get_fd_failure -> 2023-09-05T17:2= 6:31.154048+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.008s] sys/audit/file-attribute-access:extattr_get_fd_success -> 2023-09-05T17:2= 6:31.175844+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.009s] sys/audit/file-attribute-access:extattr_get_file_failure -> 2023-09-05T17= :26:31.199312+00:00 - auditd 25123 - - Got audit initialize trigger passed [0.009s] sys/audit/file-attribute-access:extattr_get_file_success -> *** /usr/src/= contrib/kyua/utils/process/executor.cpp:779: Invariant check failed: PID 25= 246 already in all_exec_handles; not properly cleaned up or reused too fast *** Fatal signal 6 received *** Log file is /.kyua/logs/kyua.20230905-164904.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-09-05T17:26:31.226939+00:00 - auditd 25123 - - 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 ./disable-zfs-tests.sh a ./auto-shutdown a ./disable-dtrace-tests.sh a ./run.sh a ./disable-notyet-tests.sh a ./run-kyua.sh + [ -f /meta/auto-shutdown ] + uname -p + shutdown -p now Shutdown NOW! shutdown: [pid 25250] + 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-09-05T17:26:31.366906+00:00 - shutdown 25250 - - power-down by root:= =20 System shutdown time has arrived=07=07 2023-09-05T17:26:31.368393+00:00 - auditd 25123 - - auditd_wait_for_events:= SIGTERM 2023-09-05T17:26:31.369764+00:00 - auditd 25123 - - Auditing disabled 2023-09-05T17:26:31.371339+00:00 - auditd 25123 - - renamed /var/audit/2023= 0905172629.not_terminated to /var/audit/20230905172629.20230905172631 2023-09-05T17:26:31.373865+00:00 - auditd 25123 - - 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 0 done All buffers synced. lock order reversal: 1st 0xfffff8000ba44e70 devfs (devfs, lockmgr) @ /usr/src/sys/kern/vfs_moun= t.c:2240 2nd 0xfffff8000ba44770 fdescfs (fdescfs, lockmgr) @ /usr/src/sys/kern/vfs_= subr.c:3085 lock order devfs -> fdescfs attempted at: #0 0xffffffff80bc236e at witness_checkorder+0xbbe #1 0xffffffff80b1c001 at lockmgr_lock_flags+0x181 #2 0xffffffff80c5dd03 at _vn_lock+0x53 #3 0xffffffff80c454dd at vget_finish+0x4d #4 0xffffffff82610262 at fdesc_root+0x22 #5 0xffffffff80c4657b at vflush+0x4b #6 0xffffffff82610211 at fdesc_unmount+0x71 #7 0xffffffff80c3b345 at dounmount+0x7b5 #8 0xffffffff80c47cba at vfs_unmountall+0x6a #9 0xffffffff80c1834c at bufshutdown+0x30c #10 0xffffffff80b4f103 at kern_reboot+0x703 #11 0xffffffff80b4e9a9 at sys_reboot+0x3a9 #12 0xffffffff8104d3d8 at amd64_syscall+0x138 #13 0xffffffff8101fb0b at fast_syscall_common+0xf8 Uptime: 37m30s 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-24117' --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 ./disable-zfs-tests.sh x ./auto-shutdown x ./disable-dtrace-tests.sh x ./run.sh x ./disable-notyet-tests.sh x ./run-kyua.sh + rm -f 'test-report.*' + 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/jenkins12575076875619903732.sh + ./freebsd-ci/artifact/post-link.py Post link: {'job_name': 'FreeBSD-main-amd64-test', 'commit': '5b7a776f48189= 1f10820a0b4838d0e0feb60b8ad', 'branch': 'main', 'target': 'amd64', 'target_= arch': 'amd64', 'link_type': 'latest_tested'} "Link created: main/latest_tested/amd64/amd64 -> ../../5b7a776f481891f10820= a0b4838d0e0feb60b8ad/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_3513_531600169.1693934801172--
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?1224725452.3514.1693934801463>