Skip site navigation (1)Skip section navigation (2)
Date:      Tue, 15 Aug 2023 09:06:19 +0000 (GMT)
From:      jenkins-admin@FreeBSD.org
To:        manu@FreeBSD.org, dev-ci@FreeBSD.org
Cc:        jenkins-admin@FreeBSD.org
Subject:   FreeBSD-main-amd64-test - Build #24024 - Failure
Message-ID:  <1664335859.2026.1692090379578@jenkins.ci.freebsd.org>
In-Reply-To: <326852452.2014.1692069880981@jenkins.ci.freebsd.org>
References:  <326852452.2014.1692069880981@jenkins.ci.freebsd.org>

next in thread | previous in thread | raw e-mail | index | archive | help
------=_Part_2025_398352835.1692090379309
Content-Type: text/plain; charset=UTF-8
Content-Transfer-Encoding: quoted-printable

FreeBSD-main-amd64-test - Build #24024 (81b41b2ef5bfd571123919e93d37624d53d=
4f589) - Failure

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

81b41b2ef5bfd571123919e93d37624d53d4f589 by manu:
ofw_firmware: Return BUS_PROBE_GENERIC instead of 0



The end of the build log:

[...truncated 4.31 MB...]
usr.bin/renice/renice_test:renice_delim  ->  passed  [0.107s]
usr.bin/renice/renice_test:renice_incr_noarg  ->  passed  [0.022s]
usr.bin/renice/renice_test:renice_rel_pgid  ->  passed  [0.051s]
usr.bin/renice/renice_test:renice_rel_pid  ->  passed  [0.038s]
usr.bin/renice/renice_test:renice_rel_user  ->  passed  [0.046s]
usr.bin/tftp/tftp_test:tftp_get_big  ->  passed  [4.721s]
usr.bin/tftp/tftp_test:tftp_get_host  ->  passed  [0.055s]
usr.bin/tftp/tftp_test:tftp_get_ipv4  ->  passed  [0.048s]
usr.bin/tftp/tftp_test:tftp_get_ipv6  ->  passed  [0.049s]
usr.bin/tftp/tftp_test:tftp_get_more  ->  passed  [0.296s]
usr.bin/tftp/tftp_test:tftp_get_multi_host  ->  passed  [0.066s]
usr.bin/tftp/tftp_test:tftp_get_one  ->  passed  [0.048s]
usr.bin/tftp/tftp_test:tftp_get_two  ->  passed  [0.057s]
usr.bin/tftp/tftp_test:tftp_put_big  ->  passed  [4.549s]
usr.bin/tftp/tftp_test:tftp_put_host  ->  passed  [0.051s]
usr.bin/tftp/tftp_test:tftp_put_ipv4  ->  passed  [0.059s]
usr.bin/tftp/tftp_test:tftp_put_ipv6  ->  passed  [0.061s]
usr.bin/tftp/tftp_test:tftp_put_more  ->  passed  [0.299s]
usr.bin/tftp/tftp_test:tftp_put_multi_host  ->  passed  [0.085s]
usr.bin/tftp/tftp_test:tftp_put_one  ->  passed  [0.051s]
usr.bin/tftp/tftp_test:tftp_put_two  ->  passed  [0.053s]
usr.bin/tftp/tftp_test:tftp_url_host  ->  passed  [0.057s]
usr.bin/tftp/tftp_test:tftp_url_ipv4  ->  passed  [0.059s]
usr.bin/tftp/tftp_test:tftp_url_ipv6  ->  expected_failure: tftp does not s=
upport bracketed IPv6 literals in URLs: atf-check failed; see the output of=
 the test for details  [0.047s]
usr.sbin/makefs/makefs_zfs_tests:autoexpand  ->  passed  [0.488s]
usr.sbin/makefs/makefs_zfs_tests:basic  ->  passed  [0.302s]
usr.sbin/makefs/makefs_zfs_tests:dataset_removal  ->  passed  [0.205s]
usr.sbin/makefs/makefs_zfs_tests:empty_dir  ->  passed  [0.168s]
usr.sbin/makefs/makefs_zfs_tests:empty_fs  ->  passed  [0.169s]
usr.sbin/makefs/makefs_zfs_tests:file_sizes  ->  passed  [0.247s]
usr.sbin/makefs/makefs_zfs_tests:hard_links  ->  passed  [0.268s]
usr.sbin/makefs/makefs_zfs_tests:indirect_dnode_array  ->  passed  [0.597s]
usr.sbin/makefs/makefs_zfs_tests:long_file_name  ->  passed  [0.397s]
usr.sbin/makefs/makefs_zfs_tests:multi_dataset_1  ->  passed  [0.238s]
usr.sbin/makefs/makefs_zfs_tests:multi_dataset_2  ->  passed  [0.174s]
usr.sbin/makefs/makefs_zfs_tests:multi_dataset_3  ->  passed  [0.190s]
usr.sbin/makefs/makefs_zfs_tests:multi_dataset_4  ->  passed  [0.252s]
usr.sbin/makefs/makefs_zfs_tests:multi_staging_1  ->  passed  [0.159s]
usr.sbin/makefs/makefs_zfs_tests:multi_staging_2  ->  passed  [0.180s]
usr.sbin/makefs/makefs_zfs_tests:reproducible  ->  passed  [2.100s]
usr.sbin/makefs/makefs_zfs_tests:root_props  ->  passed  [0.403s]
usr.sbin/makefs/makefs_zfs_tests:snapshot  ->  passed  [0.165s]
usr.sbin/makefs/makefs_zfs_tests:soft_links  ->  passed  [0.177s]
usr.sbin/jail/jail_basic_test:basic  ->  passed  [0.063s]
usr.sbin/jail/jail_basic_test:commands  ->  passed  [0.053s]
usr.sbin/jail/jail_basic_test:nested  ->  passed  [0.057s]
sbin/ping/ping_test:inject_opts  ->  tun0: link state changed to UP
tun0: link state changed to DOWN
failed: atf-check failed; see the output of the test for details  [0.976s]
sbin/ping/ping_test:inject_pip  ->  tun0: link state changed to UP
tun0: link state changed to DOWN
failed: atf-check failed; see the output of the test for details  [2.006s]
sbin/ping/ping_test:inject_reply  ->  tun0: link state changed to UP
tun0: link state changed to DOWN
failed: atf-check failed; see the output of the test for details  [0.964s]
sbin/ping/ping_test:ping6_46  ->  passed  [0.026s]
sbin/ping/ping_test:ping6_c1_s8_t1  ->  passed  [0.033s]
sbin/ping/ping_test:ping6_c1t4  ->  passed  [0.029s]
sbin/ping/ping_test:ping_46  ->  passed  [0.028s]
sbin/ping/ping_test:ping_6_c1_s8_t1  ->  passed  [0.037s]
sbin/ping/ping_test:ping_c1_s56_t1  ->  passed  [0.033s]
sbin/ping/ping_test:ping_c1_s56_t1_S127  ->  passed  [0.040s]
sbin/ping/ping_test:ping_c1_s8_t1_S1  ->  passed  [0.041s]
sbin/ping/ping_test:ping_c1t6  ->  passed  [0.026s]
sbin/dhclient/pcp:normal  ->  skipped: ISC dhcp server (isc-dhcp44-server) =
not installed  [0.021s]
sbin/dhclient/pcp:pcp  ->  skipped: ISC dhcp server (isc-dhcp44-server) not=
 installed  [0.024s]
sys/acl/00:main  ->  passed  [0.485s]
sys/acl/01:main  ->  passed  [0.840s]
sys/acl/02:main  ->  passed  [0.712s]
sys/acl/03:main  ->  passed  [0.635s]
sys/acl/04:main  ->  passed  [0.204s]
sys/aio/lio_test:lio_listio_eagain_kevent  ->  passed  [0.008s]
sys/aio/lio_test:lio_listio_empty_nowait  ->  passed  [0.005s]
sys/aio/lio_test:lio_listio_empty_nowait_kevent  ->  expected_failure: Bug =
251515 - lio_listio(2) never sends kevent if nent=3D=3D0  [10.003s]
sys/aio/lio_test:lio_listio_empty_nowait_signal  ->  passed  [0.006s]
sys/aio/lio_test:lio_listio_empty_nowait_thread  ->  passed  [0.006s]
sys/aio/lio_test:lio_listio_empty_wait  ->  passed  [0.005s]
sys/aio/lio_test:lio_listio_invalid_opcode  ->  passed  [0.005s]
sys/aio/lio_test:lio_listio_opcodes  ->  passed  [0.006s]
sys/audit/administrative:acct_failure  ->  2023-08-15T09:06:09.837959+00:00=
 - auditd 707 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/administrative:acct_success  ->  Accounting enabled
2023-08-15T09:06:09.857429+00:00 - auditd 707 - - Got audit initialize trig=
ger
Accounting disabled
passed  [0.008s]
sys/audit/administrative:adjtime_failure  ->  2023-08-15T09:06:09.897904+00=
:00 - auditd 707 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/administrative:adjtime_success  ->  2023-08-15T09:06:09.917299+00=
:00 - auditd 707 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/administrative:auditctl_failure  ->  2023-08-15T09:06:09.935705+0=
0:00 - auditd 707 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/administrative:auditctl_success  ->  2023-08-15T09:06:09.955065+0=
0:00 - auditd 707 - - Got audit initialize trigger
2023-08-15T09:06:09.986216+00:00 - auditd 707 - - Got close and die trigger
2023-08-15T09:06:09.986650+00:00 - auditd 707 - - Auditing disabled
2023-08-15T09:06:09.988224+00:00 - auditd 707 - - renamed /var/audit/202308=
15082734.not_terminated to /var/audit/20230815082734.20230815090609
2023-08-15T09:06:09.988863+00:00 - auditd 707 - - Finished
2023-08-15T09:06:09.989669+00:00 - auditd 707 - - auditd exiting.
2023-08-15T09:06:11.016319+00:00 - auditd 24634 - - starting...
2023-08-15T09:06:11.017345+00:00 - auditd 24635 - - Auditing disabled
2023-08-15T09:06:11.018087+00:00 - auditd 24635 - - Configured trail files =
distribution.
2023-08-15T09:06:11.019040+00:00 - auditd 24635 - - Auditing enabled
2023-08-15T09:06:11.019500+00:00 - auditd 24635 - - New audit file is /var/=
audit/20230815090611.not_terminated
passed  [0.008s]
sys/audit/administrative:auditon_default_failure  ->  2023-08-15T09:06:11.0=
23627+00:00 - auditd 24635 - - Registered 677 event to class mappings.
2023-08-15T09:06:11.024481+00:00 - auditd 24635 - - Registered non-attribut=
able event mask.
2023-08-15T09:06:11.025254+00:00 - auditd 24635 - - Set audit policy in ker=
nel.
2023-08-15T09:06:11.025966+00:00 - auditd 24635 - - Set audit trail size in=
 kernel.
2023-08-15T09:06:11.026315+00:00 - auditd 24635 - - Set audit trail queue i=
n kernel.
2023-08-15T09:06:11.026904+00:00 - auditd 24635 - - Set audit trail min fre=
e percent in kernel.
2023-08-15T09:06:11.028305+00:00 - auditd 24635 - - audit_control(5) may be=
 missing 'host:' field
2023-08-15T09:06:11.028440+00:00 - auditd 24635 - - Audit controls init suc=
cessful
2023-08-15T09:06:11.034423+00:00 - auditd 24635 - - Got audit initialize tr=
igger
passed  [0.010s]
sys/audit/administrative:auditon_default_success  ->  2023-08-15T09:06:11.0=
54158+00:00 - auditd 24635 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/administrative:auditon_getcar_failure  ->  2023-08-15T09:06:11.07=
2699+00:00 - auditd 24635 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/administrative:auditon_getclass_failure  ->  2023-08-15T09:06:11.=
091633+00:00 - auditd 24635 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/administrative:auditon_getclass_success  ->  2023-08-15T09:06:11.=
110410+00:00 - auditd 24635 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/administrative:auditon_getcond_failure  ->  2023-08-15T09:06:11.1=
29122+00:00 - auditd 24635 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/administrative:auditon_getcond_success  ->  2023-08-15T09:06:11.1=
48105+00:00 - auditd 24635 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/administrative:auditon_getcwd_failure  ->  2023-08-15T09:06:11.16=
6992+00:00 - auditd 24635 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/administrative:auditon_getkmask_failure  ->  2023-08-15T09:06:11.=
185489+00:00 - auditd 24635 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/administrative:auditon_getkmask_success  ->  2023-08-15T09:06:11.=
203292+00:00 - auditd 24635 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/administrative:auditon_getpolicy_failure  ->  2023-08-15T09:06:11=
.221602+00:00 - auditd 24635 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/administrative:auditon_getpolicy_success  ->  2023-08-15T09:06:11=
.239520+00:00 - auditd 24635 - - Got audit initialize trigger
*** /usr/src/contrib/kyua/utils/process/executor.cpp:822: Invariant check f=
ailed: PID 24659 already in all_exec_handles; not properly cleaned up or re=
used too fast
*** Fatal signal 6 received
*** Log file is /.kyua/logs/kyua.20230815-082734.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
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-notyet-tests.sh
a ./disable-zfs-tests.sh
a ./run-kyua.sh
a ./disable-dtrace-tests.sh
a ./run.sh
a ./auto-shutdown
+ [ -f /meta/auto-shutdown ]
+ uname -p
+ shutdown -p now
Shutdown NOW!
shutdown: [pid 24663]
+ 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-15T09:06:11.354957+00:00 - shutdown 24663 - - power-down by root:=
=20

System shutdown time has arrived=07=07
2023-08-15T09:06:11.356940+00:00 - auditd 24635 - - auditd_wait_for_events:=
 SIGTERM
2023-08-15T09:06:11.358254+00:00 - auditd 24635 - - Auditing disabled
2023-08-15T09:06:11.359676+00:00 - auditd 24635 - - renamed /var/audit/2023=
0815090611.not_terminated to /var/audit/20230815090611.20230815090611
2023-08-15T09:06:11.362074+00:00 - auditd 24635 - - 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 0xfffff8000ba07070 devfs (devfs, lockmgr) @ /usr/src/sys/kern/vfs_moun=
t.c:2242
 2nd 0xfffff8000ba11930 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 0xffffffff8101c7bb at fast_syscall_common+0xf8
Uptime: 38m40s
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-24024' --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-notyet-tests.sh
x ./disable-zfs-tests.sh
x ./run-kyua.sh
x ./disable-dtrace-tests.sh
x ./run.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/jenkins370600671041596750.sh
+ ./freebsd-ci/artifact/post-link.py
Post link: {'job_name': 'FreeBSD-main-amd64-test', 'commit': '81b41b2ef5bfd=
571123919e93d37624d53d4f589', 'branch': 'main', 'target': 'amd64', 'target_=
arch': 'amd64', 'link_type': 'latest_tested'}
"Link created: main/latest_tested/amd64/amd64 -> ../../81b41b2ef5bfd5711239=
19e93d37624d53d4f589/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_2025_398352835.1692090379309--



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?1664335859.2026.1692090379578>