From nobody Sat Nov 18 11:26:31 2023 X-Original-To: dev-ci@mlmmj.nyi.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2610:1c1:1:606c::19:1]) by mlmmj.nyi.freebsd.org (Postfix) with ESMTP id 4SXWgX0V3Rz50yMM for ; Sat, 18 Nov 2023 11:26:32 +0000 (UTC) (envelope-from jenkins-admin@FreeBSD.org) Received: from mxrelay.nyi.freebsd.org (mxrelay.nyi.freebsd.org [IPv6:2610:1c1:1:606c::19:3]) (using TLSv1.3 with cipher TLS_AES_256_GCM_SHA384 (256/256 bits) key-exchange X25519 server-signature RSA-PSS (4096 bits) server-digest SHA256 client-signature RSA-PSS (4096 bits) client-digest SHA256) (Client CN "mxrelay.nyi.freebsd.org", Issuer "R3" (verified OK)) by mx1.freebsd.org (Postfix) with ESMTPS id 4SXWgW3yXMz4Nv9; Sat, 18 Nov 2023 11:26:31 +0000 (UTC) (envelope-from jenkins-admin@FreeBSD.org) ARC-Message-Signature: i=1; a=rsa-sha256; c=relaxed/relaxed; d=freebsd.org; s=dkim; t=1700306791; h=from:from:reply-to:subject:subject:date:date:message-id:message-id: to:to:cc:cc:mime-version:mime-version:content-type:content-type: list-id; bh=7q5Y47Bgw50M1hNWKMAw0ztjt19Y1k+5aD510RSsPdU=; b=nohLzAVG5smeew3VUwcpv/YyrFsfeAb1jXoOjrfXIn8AygCwNM3s7Tg/9EEkpXAxuka1wL Jgvy8fY/ebqeyQd3zpFxiD7qDePFDTvgidC63V96nyu5ICw8adcEfkXJT3VUiVZES7uhn2 slrneKvyARxwGpK5YZbU/0zzZ4H4GuBO//ozUqtgCyjZWgvxSnkE1eRu0vl5vuwyXUz9e9 Q21xJd79i2rgOOtY52RMg+QWknW6VsS0/oJUEPNSYiRUchRwmHToS0jlVLHHB3hTKgTagA o5kj6HWyB827/zxFWz+fNdrLoZxqUTb2lParOloFkODgblt26ocj0Iif9rphKQ== ARC-Authentication-Results: i=1; mx1.freebsd.org; none ARC-Seal: i=1; s=dkim; d=freebsd.org; t=1700306791; a=rsa-sha256; cv=none; b=urJRRUpCuCY/ZkIu1VI+OaAu5eAUcjlThnElQpt2yWk4P9ohZrk5uddUCneK7YKagKZ6O3 oI7bFvY+sVehGhHH036SM88V6/b2LTywmyRlWZUvTe/axfFNVnQYgVNXZBTcmYBq4fqAKq Ony7GjPgWR8UIlmtEzcV/2MuZgdSpWSM3FMyXUAWuqOG4CigPjVVliWTOEUySp0THijkwS 4NUB9lBtNHT/Lzlz2V/BxVvS1Z5acKZ4/30X21TyAGX1c8xqvGKB4lG8y4W2MeWIPbgW+J zk5Sk5Fk2FXVoZ8R/EUduOfH7UZe+k382t+KkPbILz36fBc96113s8lXgGJ8eQ== Received: from jenkins.ci.freebsd.org (jenkins.ci.freebsd.org [IPv6:2610:1c1:1:607c::16:16]) by mxrelay.nyi.freebsd.org (Postfix) with ESMTP id 4SXWgW2rypz9Gj; Sat, 18 Nov 2023 11:26:31 +0000 (UTC) (envelope-from jenkins-admin@FreeBSD.org) Date: Sat, 18 Nov 2023 11:26:31 +0000 (GMT) From: jenkins-admin@FreeBSD.org To: gbe@FreeBSD.org, dev-ci@FreeBSD.org Cc: jenkins-admin@FreeBSD.org Message-ID: <1512166918.8624.1700306791405@jenkins.ci.freebsd.org> Subject: FreeBSD-main-amd64-test - Build #24406 - Failure List-Id: Continuous Integration Build and Test Results List-Archive: https://lists.freebsd.org/archives/dev-ci List-Help: List-Post: List-Subscribe: List-Unsubscribe: Sender: owner-dev-ci@freebsd.org X-BeenThere: dev-ci@freebsd.org MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_8623_1560552878.1700306791033" X-Jenkins-Job: FreeBSD-main-amd64-test X-Jenkins-Result: FAILURE List-ID: FreeBSD CI Build Notifications Precedence: bulk ------=_Part_8623_1560552878.1700306791033 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable FreeBSD-main-amd64-test - Build #24406 (fa9f74220146233b7224da7c94870540dc3= 9ae68) - Failure Build information: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/24406= / Full change log: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/24406/c= hanges Full build log: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/24406/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) fa9f74220146233b7224da7c94870540dc39ae68 by gbe: getpeerid.3: Clarify the 's' argument The end of the build log: [...truncated 4.38 MB...] passed [0.008s] sys/audit/open:openat_read_write_trunc_failure -> 2023-11-18T11:26:21.313= 468+00:00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/open:openat_read_write_trunc_success -> 2023-11-18T11:26:21.335= 741+00:00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/open:openat_write_creat_failure -> 2023-11-18T11:26:21.355162+0= 0:00 - auditd 38835 - - Got audit initialize trigger passed [0.007s] sys/audit/open:openat_write_creat_success -> 2023-11-18T11:26:21.377604+0= 0:00 - auditd 38835 - - Got audit initialize trigger passed [0.010s] sys/audit/open:openat_write_creat_trunc_failure -> 2023-11-18T11:26:21.40= 0205+00:00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/open:openat_write_creat_trunc_success -> 2023-11-18T11:26:21.42= 3068+00:00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/open:openat_write_failure -> 2023-11-18T11:26:21.447753+00:00 -= auditd 38835 - - Got audit initialize trigger passed [0.010s] sys/audit/open:openat_write_success -> 2023-11-18T11:26:21.471298+00:00 -= auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/open:openat_write_trunc_failure -> 2023-11-18T11:26:21.495164+0= 0:00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/open:openat_write_trunc_success -> 2023-11-18T11:26:21.518143+0= 0:00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:_exit_success -> 2023-11-18T11:26:21.538144+00:= 00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:cap_enter_success -> 2023-11-18T11:26:21.558545= +00:00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:cap_getmode_failure -> 2023-11-18T11:26:21.5789= 83+00:00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:cap_getmode_success -> 2023-11-18T11:26:21.6044= 12+00:00 - auditd 38835 - - Got audit initialize trigger passed [0.010s] sys/audit/process-control:chdir_failure -> 2023-11-18T11:26:21.625255+00:= 00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:chdir_success -> 2023-11-18T11:26:21.648898+00:= 00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:chroot_failure -> 2023-11-18T11:26:21.668231+00= :00 - auditd 38835 - - Got audit initialize trigger passed [0.007s] sys/audit/process-control:chroot_success -> 2023-11-18T11:26:21.687202+00= :00 - auditd 38835 - - Got audit initialize trigger passed [0.007s] sys/audit/process-control:fchdir_failure -> 2023-11-18T11:26:21.707019+00= :00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:fchdir_success -> 2023-11-18T11:26:21.728646+00= :00 - auditd 38835 - - Got audit initialize trigger passed [0.010s] sys/audit/process-control:fork_success -> 2023-11-18T11:26:21.752208+00:0= 0 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:getresgid_failure -> 2023-11-18T11:26:21.772454= +00:00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:getresgid_success -> 2023-11-18T11:26:21.796088= +00:00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:getresuid_failure -> 2023-11-18T11:26:21.818329= +00:00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:getresuid_success -> 2023-11-18T11:26:21.842330= +00:00 - auditd 38835 - - Got audit initialize trigger passed [0.010s] sys/audit/process-control:kill_failure -> 2023-11-18T11:26:21.866090+00:0= 0 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:kill_success -> 2023-11-18T11:26:21.887861+00:0= 0 - auditd 38835 - - Got audit initialize trigger passed [0.007s] sys/audit/process-control:ktrace_failure -> 2023-11-18T11:26:21.908363+00= :00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:ktrace_success -> 2023-11-18T11:26:21.931479+00= :00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:minherit_failure -> 2023-11-18T11:26:21.951764+= 00:00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:minherit_success -> 2023-11-18T11:26:21.972651+= 00:00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:mlock_failure -> 2023-11-18T11:26:21.997062+00:= 00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:mlock_success -> 2023-11-18T11:26:22.019531+00:= 00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:munlock_failure -> 2023-11-18T11:26:22.042260+0= 0:00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:munlock_success -> 2023-11-18T11:26:22.067029+0= 0:00 - auditd 38835 - - Got audit initialize trigger passed [0.010s] sys/audit/process-control:procctl_failure -> 2023-11-18T11:26:22.091655+0= 0:00 - auditd 38835 - - Got audit initialize trigger passed [0.010s] sys/audit/process-control:procctl_success -> 2023-11-18T11:26:22.112943+0= 0:00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:profil_failure -> 2023-11-18T11:26:22.136363+00= :00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:profil_success -> 2023-11-18T11:26:22.156502+00= :00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:ptrace_failure -> 2023-11-18T11:26:22.181017+00= :00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:ptrace_success -> 2023-11-18T11:26:22.202955+00= :00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:rfork_failure -> 2023-11-18T11:26:22.222938+00:= 00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:rfork_success -> 2023-11-18T11:26:22.246656+00:= 00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:rtprio_failure -> 2023-11-18T11:26:22.270247+00= :00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:rtprio_success -> 2023-11-18T11:26:22.293915+00= :00 - auditd 38835 - - Got audit initialize trigger passed [0.010s] sys/audit/process-control:setegid_success -> 2023-11-18T11:26:22.316731+0= 0:00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:seteuid_success -> 2023-11-18T11:26:22.340048+0= 0:00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:setgid_success -> 2023-11-18T11:26:22.360549+00= :00 - auditd 38835 - - Got audit initialize trigger passed [0.007s] sys/audit/process-control:setgroups_failure -> 2023-11-18T11:26:22.379985= +00:00 - auditd 38835 - - Got audit initialize trigger passed [0.007s] sys/audit/process-control:setgroups_success -> 2023-11-18T11:26:22.398522= +00:00 - auditd 38835 - - Got audit initialize trigger passed [0.007s] sys/audit/process-control:setlogin_failure -> 2023-11-18T11:26:22.418278+= 00:00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:setlogin_success -> 2023-11-18T11:26:22.438089+= 00:00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:setpgrp_failure -> 2023-11-18T11:26:22.457201+0= 0:00 - auditd 38835 - - Got audit initialize trigger passed [0.007s] sys/audit/process-control:setpgrp_success -> 2023-11-18T11:26:22.476897+0= 0:00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:setpriority_failure -> 2023-11-18T11:26:22.4966= 37+00:00 - auditd 38835 - - Got audit initialize trigger passed [0.007s] sys/audit/process-control:setpriority_success -> 2023-11-18T11:26:22.5152= 92+00:00 - auditd 38835 - - Got audit initialize trigger passed [0.007s] sys/audit/process-control:setregid_success -> 2023-11-18T11:26:22.534240+= 00:00 - auditd 38835 - - Got audit initialize trigger passed [0.007s] sys/audit/process-control:setresgid_success -> 2023-11-18T11:26:22.556461= +00:00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:setresuid_success -> 2023-11-18T11:26:22.581722= +00:00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:setreuid_success -> 2023-11-18T11:26:22.601949+= 00:00 - auditd 38835 - - Got audit initialize trigger passed [0.007s] sys/audit/process-control:setrlimit_failure -> 2023-11-18T11:26:22.621480= +00:00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:setrlimit_success -> 2023-11-18T11:26:22.640396= +00:00 - auditd 38835 - - Got audit initialize trigger passed [0.007s] sys/audit/process-control:setsid_failure -> 2023-11-18T11:26:22.662819+00= :00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:setsid_success -> 2023-11-18T11:26:22.687093+00= :00 - auditd 38835 - - Got audit initialize trigger passed [0.011s] sys/audit/process-control:setuid_success -> 2023-11-18T11:26:22.712764+00= :00 - auditd 38835 - - Got audit initialize trigger passed [0.009s] sys/audit/process-control:umask_success -> 2023-11-18T11:26:22.732700+00:= 00 - auditd 38835 - - Got audit initialize trigger passed [0.008s] sys/audit/process-control:wait4_failure -> 2023-11-18T11:26:22.753411+00:= 00 - auditd 38835 - - Got audit initialize trigger *** /usr/src/contrib/kyua/utils/process/executor.cpp:822: Invariant check f= ailed: PID 39659 already in all_exec_handles; not properly cleaned up or re= used too fast *** Fatal signal 6 received *** Log file is /.kyua/logs/kyua.20231118-104100.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 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 ./auto-shutdown a ./disable-dtrace-tests.sh a ./disable-zfs-tests.sh a ./run-kyua.sh a ./disable-notyet-tests.sh a ./run.sh + [ -f /meta/auto-shutdown ] + uname -p + shutdown -p now Shutdown NOW! shutdown: [pid 39663] + 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-18T11:26:22.874697+00:00 - shutdown 39663 - - power-down by root:= =20 System shutdown time has arrived=07=07 2023-11-18T11:26:22.876468+00:00 - auditd 38835 - - auditd_wait_for_events:= SIGTERM 2023-11-18T11:26:22.877746+00:00 - auditd 38835 - - Auditing disabled 2023-11-18T11:26:22.879037+00:00 - auditd 38835 - - renamed /var/audit/2023= 1118112614.not_terminated to /var/audit/20231118112614.20231118112622 2023-11-18T11:26:22.881367+00:00 - auditd 38835 - - 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 done All buffers synced. lock order reversal: 1st 0xfffff80009a00e70 devfs (devfs, lockmgr) @ /usr/src/sys/kern/vfs_moun= t.c:2254 2nd 0xfffff80009a00770 fdescfs (fdescfs, lockmgr) @ /usr/src/sys/kern/vfs_= subr.c:3296 lock order devfs -> fdescfs attempted at: #0 0xffffffff80bc34de at witness_checkorder+0xbbe #1 0xffffffff80b1ce51 at lockmgr_lock_flags+0x181 #2 0xffffffff80c5f2a3 at _vn_lock+0x53 #3 0xffffffff80c4688d at vget_finish+0x4d #4 0xffffffff82610262 at fdesc_root+0x22 #5 0xffffffff80c4792b at vflush+0x4b #6 0xffffffff82610211 at fdesc_unmount+0x71 #7 0xffffffff80c3c575 at dounmount+0x7b5 #8 0xffffffff80c4906a at vfs_unmountall+0x6a #9 0xffffffff80c195bc at bufshutdown+0x30c #10 0xffffffff80b4fce3 at kern_reboot+0x713 #11 0xffffffff80b4f579 at sys_reboot+0x3a9 #12 0xffffffff810546b3 at amd64_syscall+0x153 #13 0xffffffff8102647b at fast_syscall_common+0xf8 Uptime: 45m28s 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-24406' --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 ./auto-shutdown x ./disable-dtrace-tests.sh x ./disable-zfs-tests.sh x ./run-kyua.sh x ./disable-notyet-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 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/jenkins18379721099838295781.sh + ./freebsd-ci/artifact/post-link.py Post link: {'job_name': 'FreeBSD-main-amd64-test', 'commit': 'fa9f742201462= 33b7224da7c94870540dc39ae68', 'branch': 'main', 'target': 'amd64', 'target_= arch': 'amd64', 'link_type': 'latest_tested'} "Link created: main/latest_tested/amd64/amd64 -> ../../fa9f74220146233b7224= da7c94870540dc39ae68/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_8623_1560552878.1700306791033--