From nobody Fri Oct 06 22:37:32 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 4S2Nbc6Bdkz4wqG3 for ; Fri, 6 Oct 2023 22:37: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 4S2Nbc4b80z4KS0; Fri, 6 Oct 2023 22:37:32 +0000 (UTC) (envelope-from jenkins-admin@FreeBSD.org) ARC-Seal: i=1; s=dkim; d=freebsd.org; t=1696631852; a=rsa-sha256; cv=none; b=SAJ6x+qzbL2Hgi293fX2uu+v809cdMMjloTC1ItR+4qHSAmawS/ZHB895EtMrTDOtpdJC4 /IVM8GYdRwSL3EhpGEJjt9ybmM1FvjU80xfZmecvPIEJvGx97Sy5WqFe062ZigZA0k1+y6 wk+0Kv2/lOg9qNpR36B4bIgRdR9OkUQNKOEmA5pJDyRvzZ9kwqhU241K1UfCdTySXuPH9L iYp9bec3O2VLe/3q6K93Es7AcjsNIWtO9LxzehBnHDONRXUWLxOboDdrc81kUpO2iOFHtd 3FwyPWYGqhZ3GVBYmgsfuM6ENB3QyZ1DwBkEDH2+ilop5rmiOKVeC9lHRS7z5Q== ARC-Authentication-Results: i=1; mx1.freebsd.org; none ARC-Message-Signature: i=1; a=rsa-sha256; c=relaxed/relaxed; d=freebsd.org; s=dkim; t=1696631852; 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: in-reply-to:in-reply-to:references:references:list-id; bh=CKxnYP+X9tubtTGVUhi80sShejQe9q1cISmIOTxB3+w=; b=uA7t6yqJZi+LVDDn/PTdQJUVjVibXx/sb0JypgU1Q/dsLX7jy6HJdnK0crQMu9C8XsvUea sBOaNBCk8r2uuGSKOxE6hcUfpT0lCcA6eBxB5pSyIHpkzvrLvwU90Y0WRAjMHaljjWD6Mh qvDLIlKGCTdnN1yGfy7UfrGRR+4CsfNiVznB20Q16s6otbuS2hC3fX5QbrJ6z/E9iepvnq Os8CvaukEz8y0xssWJ+PPnU7X9U7hfqtGaFxoF4BSLqW662TLeV+7b/3CYr02PLPUb8XM8 yYBUcI6gGT617aJZa5zabdvHc+yk0cBZmSjuYx/GtxzGphH5mEu/3fuxxzhlbQ== 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 4S2Nbc3W7Bz15Rv; Fri, 6 Oct 2023 22:37:32 +0000 (UTC) (envelope-from jenkins-admin@FreeBSD.org) Date: Fri, 6 Oct 2023 22:37:32 +0000 (GMT) From: jenkins-admin@FreeBSD.org To: asomers@FreeBSD.org, manu@FreeBSD.org, dev-ci@FreeBSD.org Cc: jenkins-admin@FreeBSD.org Message-ID: <1429668995.5638.1696631852498@jenkins.ci.freebsd.org> In-Reply-To: <210360197.5632.1696621872282@jenkins.ci.freebsd.org> References: <210360197.5632.1696621872282@jenkins.ci.freebsd.org> Subject: FreeBSD-main-amd64-test - Build #24217 - 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_5637_880970101.1696631852098" X-Jenkins-Job: FreeBSD-main-amd64-test X-Jenkins-Result: FAILURE List-ID: FreeBSD CI Build Notifications Precedence: bulk ------=_Part_5637_880970101.1696631852098 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable FreeBSD-main-amd64-test - Build #24217 (86885b18689889e9b9142fd31d8c67f2133= 4ba32) - Failure Build information: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/24217= / Full change log: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/24217/c= hanges Full build log: https://ci.FreeBSD.org/job/FreeBSD-main-amd64-test/24217/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) 7786911ffd1f7c6eec0334a06bf9ec1bc14540d3 by manu: dwc: Rewrite part of the descriptors setup functions 7854d0f6584d259fd7f13595e3d3a4f157db9339 by manu: dwc: Get the mii clock after calling IF_DWC_INIT cf037a17c7c81e8a2e73c89eb90877f2625749d7 by manu: dwc: rockchip: Properly calculate mii clock based on pclk_mac 38cbdae33b7c3f772845c06f52b86c0ddeab6a17 by manu: dwc: Rewrite barrier part 03205a8cd57feb95752142d899d026ff8f45f3e6 by manu: i2c: rtc: Add module for hym8563 driver 86885b18689889e9b9142fd31d8c67f21334ba32 by asomers: Fix intermittency in the sys.fs.fusefs.mknod.main test The end of the build log: [...truncated 4.36 MB...] passed [0.007s] sys/audit/file-close:revoke_success -> 2023-10-06T22:37:22.472954+00:00 -= auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/file-create:link_failure -> 2023-10-06T22:37:22.493249+00:00 - = auditd 36956 - - Got audit initialize trigger passed [0.007s] sys/audit/file-create:link_success -> 2023-10-06T22:37:22.511990+00:00 - = auditd 36956 - - Got audit initialize trigger passed [0.007s] sys/audit/file-create:linkat_failure -> 2023-10-06T22:37:22.533860+00:00 = - auditd 36956 - - Got audit initialize trigger passed [0.011s] sys/audit/file-create:linkat_success -> 2023-10-06T22:37:22.557477+00:00 = - auditd 36956 - - Got audit initialize trigger passed [0.009s] sys/audit/file-create:mkdir_failure -> 2023-10-06T22:37:22.578271+00:00 -= auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/file-create:mkdir_success -> 2023-10-06T22:37:22.598249+00:00 -= auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/file-create:mkdirat_failure -> 2023-10-06T22:37:22.618750+00:00= - auditd 36956 - - Got audit initialize trigger passed [0.009s] sys/audit/file-create:mkdirat_success -> 2023-10-06T22:37:22.643951+00:00= - auditd 36956 - - Got audit initialize trigger passed [0.010s] sys/audit/file-create:mkfifo_failure -> 2023-10-06T22:37:22.669198+00:00 = - auditd 36956 - - Got audit initialize trigger passed [0.009s] sys/audit/file-create:mkfifo_success -> 2023-10-06T22:37:22.689538+00:00 = - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/file-create:mkfifoat_failure -> 2023-10-06T22:37:22.709665+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/file-create:mkfifoat_success -> 2023-10-06T22:37:22.732784+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.009s] sys/audit/file-create:mknod_failure -> 2023-10-06T22:37:22.756737+00:00 -= auditd 36956 - - Got audit initialize trigger passed [0.010s] sys/audit/file-create:mknod_success -> 2023-10-06T22:37:22.781699+00:00 -= auditd 36956 - - Got audit initialize trigger passed [0.010s] sys/audit/file-create:mknodat_failure -> 2023-10-06T22:37:22.804351+00:00= - auditd 36956 - - Got audit initialize trigger passed [0.009s] sys/audit/file-create:mknodat_success -> 2023-10-06T22:37:22.824392+00:00= - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/file-create:rename_failure -> 2023-10-06T22:37:22.843837+00:00 = - auditd 36956 - - Got audit initialize trigger passed [0.007s] sys/audit/file-create:rename_success -> 2023-10-06T22:37:22.862462+00:00 = - auditd 36956 - - Got audit initialize trigger passed [0.007s] sys/audit/file-create:renameat_failure -> 2023-10-06T22:37:22.881260+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.007s] sys/audit/file-create:renameat_success -> 2023-10-06T22:37:22.899691+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/file-create:symlink_failure -> 2023-10-06T22:37:22.918998+00:00= - auditd 36956 - - Got audit initialize trigger passed [0.007s] sys/audit/file-create:symlink_success -> 2023-10-06T22:37:22.937884+00:00= - auditd 36956 - - Got audit initialize trigger passed [0.007s] sys/audit/file-create:symlinkat_failure -> 2023-10-06T22:37:22.957198+00:= 00 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/file-create:symlinkat_success -> 2023-10-06T22:37:22.976339+00:= 00 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/file-delete:rename_failure -> 2023-10-06T22:37:22.999265+00:00 = - auditd 36956 - - Got audit initialize trigger passed [0.009s] sys/audit/file-delete:rename_success -> 2023-10-06T22:37:23.018342+00:00 = - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/file-delete:renameat_failure -> 2023-10-06T22:37:23.038223+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/file-delete:renameat_success -> 2023-10-06T22:37:23.056470+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.007s] sys/audit/file-delete:rmdir_failure -> 2023-10-06T22:37:23.075640+00:00 -= auditd 36956 - - Got audit initialize trigger passed [0.007s] sys/audit/file-delete:rmdir_success -> 2023-10-06T22:37:23.099213+00:00 -= auditd 36956 - - Got audit initialize trigger passed [0.009s] sys/audit/file-delete:unlink_failure -> 2023-10-06T22:37:23.119068+00:00 = - auditd 36956 - - Got audit initialize trigger passed [0.007s] sys/audit/file-delete:unlink_success -> 2023-10-06T22:37:23.137770+00:00 = - auditd 36956 - - Got audit initialize trigger passed [0.007s] sys/audit/file-delete:unlinkat_failure -> 2023-10-06T22:37:23.159255+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.010s] sys/audit/file-delete:unlinkat_success -> 2023-10-06T22:37:23.182157+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.009s] sys/audit/file-read:readlink_failure -> 2023-10-06T22:37:23.206073+00:00 = - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/file-read:readlink_success -> 2023-10-06T22:37:23.225193+00:00 = - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/file-read:readlinkat_failure -> 2023-10-06T22:37:23.249405+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.010s] sys/audit/file-read:readlinkat_success -> 2023-10-06T22:37:23.273686+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.009s] sys/audit/file-write:ftruncate_failure -> 2023-10-06T22:37:23.298475+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.011s] sys/audit/file-write:ftruncate_success -> 2023-10-06T22:37:23.319952+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.007s] sys/audit/file-write:truncate_failure -> 2023-10-06T22:37:23.341635+00:00= - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/file-write:truncate_success -> 2023-10-06T22:37:23.364685+00:00= - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/inter-process:msgctl_illegal_command -> 2023-10-06T22:37:23.385= 221+00:00 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/inter-process:msgctl_rmid_failure -> 2023-10-06T22:37:23.409361= +00:00 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/inter-process:msgctl_rmid_success -> 2023-10-06T22:37:23.434675= +00:00 - auditd 36956 - - Got audit initialize trigger passed [0.010s] sys/audit/inter-process:msgctl_set_failure -> 2023-10-06T22:37:23.458668+= 00:00 - auditd 36956 - - Got audit initialize trigger passed [0.009s] sys/audit/inter-process:msgctl_set_success -> 2023-10-06T22:37:23.478993+= 00:00 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/inter-process:msgctl_stat_failure -> 2023-10-06T22:37:23.499025= +00:00 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/inter-process:msgctl_stat_success -> 2023-10-06T22:37:23.519895= +00:00 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/inter-process:msgget_failure -> 2023-10-06T22:37:23.541293+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/inter-process:msgget_success -> 2023-10-06T22:37:23.566234+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.009s] sys/audit/inter-process:msgrcv_failure -> 2023-10-06T22:37:23.590978+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.009s] sys/audit/inter-process:msgrcv_success -> 2023-10-06T22:37:23.612071+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/inter-process:msgsnd_failure -> 2023-10-06T22:37:23.632426+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.007s] sys/audit/inter-process:msgsnd_success -> 2023-10-06T22:37:23.654806+00:0= 0 - auditd 36956 - - Got audit initialize trigger passed [0.010s] sys/audit/inter-process:pipe_failure -> 2023-10-06T22:37:23.678709+00:00 = - auditd 36956 - - Got audit initialize trigger passed [0.009s] sys/audit/inter-process:pipe_success -> 2023-10-06T22:37:23.699538+00:00 = - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/inter-process:posix_openpt_failure -> 2023-10-06T22:37:23.72017= 6+00:00 - auditd 36956 - - Got audit initialize trigger passed [0.009s] sys/audit/inter-process:posix_openpt_success -> 2023-10-06T22:37:23.74121= 8+00:00 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/inter-process:semctl_getall_failure -> 2023-10-06T22:37:23.7621= 56+00:00 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/inter-process:semctl_getall_success -> 2023-10-06T22:37:23.7821= 77+00:00 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/inter-process:semctl_getncnt_failure -> 2023-10-06T22:37:23.803= 113+00:00 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/inter-process:semctl_getncnt_success -> 2023-10-06T22:37:23.824= 400+00:00 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/inter-process:semctl_getpid_failure -> 2023-10-06T22:37:23.8459= 37+00:00 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/inter-process:semctl_getpid_success -> 2023-10-06T22:37:23.8664= 85+00:00 - auditd 36956 - - Got audit initialize trigger passed [0.008s] sys/audit/inter-process:semctl_getval_failure -> 2023-10-06T22:37:23.8891= 10+00:00 - auditd 36956 - - Got audit initialize trigger *** /usr/src/contrib/kyua/utils/process/executor.cpp:822: Invariant check f= ailed: PID 37408 already in all_exec_handles; not properly cleaned up or re= used too fast *** Fatal signal 6 received *** Log file is /.kyua/logs/kyua.20231006-215129.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 ./run-kyua.sh a ./auto-shutdown a ./disable-notyet-tests.sh a ./disable-zfs-tests.sh a ./disable-dtrace-tests.sh a ./run.sh + [ -f /meta/auto-shutdown ] + uname -p + shutdown -p now Shutdown NOW! shutdown: [pid 37412] + 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-10-06T22:37:24.013490+00:00 - shutdown 37412 - - power-down by root:= =20 System shutdown time has arrived=07=07 2023-10-06T22:37:24.015596+00:00 - auditd 36956 - - auditd_wait_for_events:= SIGTERM 2023-10-06T22:37:24.016968+00:00 - auditd 36956 - - Auditing disabled 2023-10-06T22:37:24.018380+00:00 - auditd 36956 - - renamed /var/audit/2023= 1006223718.not_terminated to /var/audit/20231006223718.20231006223724 2023-10-06T22:37:24.020725+00:00 - auditd 36956 - - 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 0xfffff8000ba07e70 devfs (devfs, lockmgr) @ /usr/src/sys/kern/vfs_moun= t.c:2240 2nd 0xfffff8000ba07770 fdescfs (fdescfs, lockmgr) @ /usr/src/sys/kern/vfs_= subr.c:3138 lock order devfs -> fdescfs attempted at: #0 0xffffffff80bc271e at witness_checkorder+0xbbe #1 0xffffffff80b1c451 at lockmgr_lock_flags+0x181 #2 0xffffffff80c5e0a3 at _vn_lock+0x53 #3 0xffffffff80c4573d at vget_finish+0x4d #4 0xffffffff82610262 at fdesc_root+0x22 #5 0xffffffff80c467db at vflush+0x4b #6 0xffffffff82610211 at fdesc_unmount+0x71 #7 0xffffffff80c3b4c5 at dounmount+0x7b5 #8 0xffffffff80c47f1a at vfs_unmountall+0x6a #9 0xffffffff80c1852c at bufshutdown+0x30c #10 0xffffffff80b4f1a3 at kern_reboot+0x703 #11 0xffffffff80b4ea49 at sys_reboot+0x3a9 #12 0xffffffff8104f693 at amd64_syscall+0x153 #13 0xffffffff810212db at fast_syscall_common+0xf8 Uptime: 45m58s 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-24217' --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 ./auto-shutdown x ./disable-notyet-tests.sh x ./disable-zfs-tests.sh x ./disable-dtrace-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/jenkins16937649608222819301.sh + ./freebsd-ci/artifact/post-link.py Post link: {'job_name': 'FreeBSD-main-amd64-test', 'commit': '86885b1868988= 9e9b9142fd31d8c67f21334ba32', 'branch': 'main', 'target': 'amd64', 'target_= arch': 'amd64', 'link_type': 'latest_tested'} "Link created: main/latest_tested/amd64/amd64 -> ../../86885b18689889e9b914= 2fd31d8c67f21334ba32/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_5637_880970101.1696631852098--