Skip site navigation (1)Skip section navigation (2)
Date:      Fri, 24 Nov 2023 05:08:15 +0000 (GMT)
From:      jenkins-admin@FreeBSD.org
To:        delphij@FreeBSD.org, jhb@FreeBSD.org, mhorne@FreeBSD.org, dev-ci@FreeBSD.org
Cc:        jenkins-admin@FreeBSD.org
Subject:   FreeBSD-main-amd64-test - Build #24435 - Failure
Message-ID:  <1012870885.9030.1700802496446@jenkins.ci.freebsd.org>

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

FreeBSD-main-amd64-test - Build #24435 (c4dacfa7f4b82f23ec0924d9db772860b20=
66f9b) - Failure

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

3578000a6f505148c5e0ecf5be9d644ed07a81bc by jhb:
ehci_ps3: Remove unused struct definition.

ad34121518bb7e1a38d4a6b1ccf9ca6fe298dd0a by mhorne:
Revert "pst: improve shutdown_post_sync handler"

88d2b69c719a20da9eb53f1c58adf6ecb005f8ce by delphij:
re(4): Add support for 8168FP HW rev

7aa3bf6952b2c7d1b02d3a67e7f51b96fcf52b50 by jhb:
etc/mtree: Remove entry for /usr/tests/usr.sbin/mixer

c4dacfa7f4b82f23ec0924d9db772860b2066f9b by mhorne:
pst: improve shutdown_post_sync handler



The end of the build log:

[...truncated 4.35 MB...]
passed  [0.007s]
sys/audit/file-attribute-modify:fchownat_failure  ->  2023-11-24T05:08:05.8=
99897+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-attribute-modify:fchownat_success  ->  2023-11-24T05:08:05.9=
19267+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-attribute-modify:fcntl_failure  ->  2023-11-24T05:08:05.9405=
10+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-attribute-modify:fcntl_success  ->  2023-11-24T05:08:05.9589=
90+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-attribute-modify:flock_failure  ->  2023-11-24T05:08:05.9773=
00+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-attribute-modify:flock_success  ->  2023-11-24T05:08:05.9953=
12+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-attribute-modify:fsync_failure  ->  2023-11-24T05:08:06.0138=
24+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-attribute-modify:fsync_success  ->  2023-11-24T05:08:06.0331=
29+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-attribute-modify:futimes_failure  ->  2023-11-24T05:08:06.05=
2662+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-attribute-modify:futimes_success  ->  2023-11-24T05:08:06.07=
7661+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/file-attribute-modify:futimesat_failure  ->  2023-11-24T05:08:06.=
101900+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/file-attribute-modify:futimesat_success  ->  2023-11-24T05:08:06.=
126421+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/file-attribute-modify:lchflags_failure  ->  2023-11-24T05:08:06.1=
50462+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/file-attribute-modify:lchflags_success  ->  2023-11-24T05:08:06.1=
72037+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/file-attribute-modify:lchmod_failure  ->  2023-11-24T05:08:06.196=
939+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/file-attribute-modify:lchmod_success  ->  2023-11-24T05:08:06.220=
267+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/file-attribute-modify:lchown_failure  ->  2023-11-24T05:08:06.244=
281+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-attribute-modify:lchown_success  ->  2023-11-24T05:08:06.264=
274+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-attribute-modify:lutimes_failure  ->  2023-11-24T05:08:06.28=
3351+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-attribute-modify:lutimes_success  ->  2023-11-24T05:08:06.30=
3049+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-attribute-modify:mprotect_failure  ->  2023-11-24T05:08:06.3=
22215+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-attribute-modify:mprotect_success  ->  2023-11-24T05:08:06.3=
41739+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-attribute-modify:undelete_failure  ->  2023-11-24T05:08:06.3=
61947+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-attribute-modify:utimes_failure  ->  2023-11-24T05:08:06.384=
500+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-attribute-modify:utimes_success  ->  2023-11-24T05:08:06.403=
416+00:00 - auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-close:close_failure  ->  2023-11-24T05:08:06.422049+00:00 - =
auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-close:close_success  ->  2023-11-24T05:08:06.439968+00:00 - =
auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-close:closefrom_success  ->  2023-11-24T05:08:06.459065+00:0=
0 - auditd 38852 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/file-close:munmap_failure  ->  2023-11-24T05:08:06.478343+00:00 -=
 auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-close:munmap_success  ->  2023-11-24T05:08:06.495974+00:00 -=
 auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-close:revoke_failure  ->  2023-11-24T05:08:06.516452+00:00 -=
 auditd 38852 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/file-close:revoke_success  ->  2023-11-24T05:08:06.537888+00:00 -=
 auditd 38852 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/file-create:link_failure  ->  2023-11-24T05:08:06.562719+00:00 - =
auditd 38852 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/file-create:link_success  ->  2023-11-24T05:08:06.585969+00:00 - =
auditd 38852 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/file-create:linkat_failure  ->  2023-11-24T05:08:06.606663+00:00 =
- auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-create:linkat_success  ->  2023-11-24T05:08:06.627806+00:00 =
- auditd 38852 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/file-create:mkdir_failure  ->  2023-11-24T05:08:06.650326+00:00 -=
 auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-create:mkdir_success  ->  2023-11-24T05:08:06.671293+00:00 -=
 auditd 38852 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/file-create:mkdirat_failure  ->  2023-11-24T05:08:06.695322+00:00=
 - auditd 38852 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/file-create:mkdirat_success  ->  2023-11-24T05:08:06.719031+00:00=
 - auditd 38852 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/file-create:mkfifo_failure  ->  2023-11-24T05:08:06.741713+00:00 =
- auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-create:mkfifo_success  ->  2023-11-24T05:08:06.760319+00:00 =
- auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-create:mkfifoat_failure  ->  2023-11-24T05:08:06.781542+00:0=
0 - auditd 38852 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/file-create:mkfifoat_success  ->  2023-11-24T05:08:06.803650+00:0=
0 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-create:mknod_failure  ->  2023-11-24T05:08:06.823168+00:00 -=
 auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-create:mknod_success  ->  2023-11-24T05:08:06.842520+00:00 -=
 auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-create:mknodat_failure  ->  2023-11-24T05:08:06.862989+00:00=
 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-create:mknodat_success  ->  2023-11-24T05:08:06.883486+00:00=
 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-create:rename_failure  ->  2023-11-24T05:08:06.904030+00:00 =
- auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:rename_success  ->  2023-11-24T05:08:06.928028+00:00 =
- auditd 38852 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/file-create:renameat_failure  ->  2023-11-24T05:08:06.948007+00:0=
0 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-create:renameat_success  ->  2023-11-24T05:08:06.967826+00:0=
0 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-create:symlink_failure  ->  2023-11-24T05:08:06.988072+00:00=
 - auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:symlink_success  ->  2023-11-24T05:08:07.007078+00:00=
 - auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-create:symlinkat_failure  ->  2023-11-24T05:08:07.028440+00:=
00 - auditd 38852 - - Got audit initialize trigger
passed  [0.009s]
sys/audit/file-create:symlinkat_success  ->  2023-11-24T05:08:07.050212+00:=
00 - auditd 38852 - - Got audit initialize trigger
passed  [0.010s]
sys/audit/file-delete:rename_failure  ->  2023-11-24T05:08:07.074056+00:00 =
- auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-delete:rename_success  ->  2023-11-24T05:08:07.092997+00:00 =
- auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-delete:renameat_failure  ->  2023-11-24T05:08:07.111994+00:0=
0 - auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-delete:renameat_success  ->  2023-11-24T05:08:07.130386+00:0=
0 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-delete:rmdir_failure  ->  2023-11-24T05:08:07.149710+00:00 -=
 auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-delete:rmdir_success  ->  2023-11-24T05:08:07.167511+00:00 -=
 auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-delete:unlink_failure  ->  2023-11-24T05:08:07.185664+00:00 =
- auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-delete:unlink_success  ->  2023-11-24T05:08:07.203261+00:00 =
- auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-delete:unlinkat_failure  ->  2023-11-24T05:08:07.220539+00:0=
0 - auditd 38852 - - Got audit initialize trigger
passed  [0.007s]
sys/audit/file-delete:unlinkat_success  ->  2023-11-24T05:08:07.239103+00:0=
0 - auditd 38852 - - Got audit initialize trigger
passed  [0.008s]
sys/audit/file-read:readlink_failure  ->  *** /usr/src/contrib/kyua/utils/p=
rocess/executor.cpp:779: Invariant check failed: PID 39241 already in all_e=
xec_handles; not properly cleaned up or reused too fast
*** Fatal signal 6 received
*** Log file is /.kyua/logs/kyua.20231124-042317.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-11-24T05:08:07.266367+00:00 - auditd 38852 - - Got audit initialize tr=
igger
pid 766 (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-kyua.sh
a ./disable-dtrace-tests.sh
a ./disable-zfs-tests.sh
a ./run.sh
a ./auto-shutdown
a ./disable-notyet-tests.sh
+ [ -f /meta/auto-shutdown ]
+ uname -p
+ shutdown -p now
Shutdown NOW!
shutdown: [pid 39245]
+ 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-11-24T05:08:07.360273+00:00 - shutdown 39245 - - power-down by root:=
=20

System shutdown time has arrived=07=07
2023-11-24T05:08:07.362238+00:00 - auditd 38852 - - auditd_wait_for_events:=
 SIGTERM
2023-11-24T05:08:07.363439+00:00 - auditd 38852 - - Auditing disabled
2023-11-24T05:08:07.364606+00:00 - auditd 38852 - - renamed /var/audit/2023=
1124050803.not_terminated to /var/audit/20231124050803.20231124050807
2023-11-24T05:08:07.367306+00:00 - auditd 38852 - - 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 0xfffff80003ae5cb0 devfs (devfs, lockmgr) @ /usr/src/sys/kern/vfs_moun=
t.c:2254
 2nd 0xfffff80003ae55b0 fdescfs (fdescfs, lockmgr) @ /usr/src/sys/kern/vfs_=
subr.c:3296
lock order devfs -> fdescfs attempted at:
#0 0xffffffff80bc326e at witness_checkorder+0xbbe
#1 0xffffffff80b1cb31 at lockmgr_lock_flags+0x181
#2 0xffffffff80c5f173 at _vn_lock+0x53
#3 0xffffffff80c4673d at vget_finish+0x4d
#4 0xffffffff82610262 at fdesc_root+0x22
#5 0xffffffff80c477db at vflush+0x4b
#6 0xffffffff82610211 at fdesc_unmount+0x71
#7 0xffffffff80c3c2e5 at dounmount+0x7b5
#8 0xffffffff80c48f1a at vfs_unmountall+0x6a
#9 0xffffffff80c1932c at bufshutdown+0x30c
#10 0xffffffff80b4f9e3 at kern_reboot+0x703
#11 0xffffffff80b4f289 at sys_reboot+0x3a9
#12 0xffffffff810546a3 at amd64_syscall+0x153
#13 0xffffffff8102621b at fast_syscall_common+0xf8
Uptime: 44m53s
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-24435' --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-kyua.sh
x ./disable-dtrace-tests.sh
x ./disable-zfs-tests.sh
x ./run.sh
x ./auto-shutdown
x ./disable-notyet-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 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/jenkins15993398843852160108.sh
+ ./freebsd-ci/artifact/post-link.py
Post link: {'job_name': 'FreeBSD-main-amd64-test', 'commit': 'c4dacfa7f4b82=
f23ec0924d9db772860b2066f9b', 'branch': 'main', 'target': 'amd64', 'target_=
arch': 'amd64', 'link_type': 'latest_tested'}
"Link created: main/latest_tested/amd64/amd64 -> ../../c4dacfa7f4b82f23ec09=
24d9db772860b2066f9b/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_9029_1381607186.1700802495978--



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