From owner-freebsd-current@freebsd.org Tue Jul 28 23:08:39 2015 Return-Path: Delivered-To: freebsd-current@mailman.ysv.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:1900:2254:206a::19:1]) by mailman.ysv.freebsd.org (Postfix) with ESMTP id F038C9AD872 for ; Tue, 28 Jul 2015 23:08:38 +0000 (UTC) (envelope-from jenkins-admin@FreeBSD.org) Received: from jenkins-9.freebsd.org (jenkins-9.freebsd.org [8.8.178.209]) by mx1.freebsd.org (Postfix) with ESMTP id D4414CDF; Tue, 28 Jul 2015 23:08:38 +0000 (UTC) (envelope-from jenkins-admin@FreeBSD.org) Received: from jenkins-9.freebsd.org (localhost [127.0.0.1]) by jenkins-9.freebsd.org (Postfix) with ESMTP id BBFEB1EC; Tue, 28 Jul 2015 23:08:38 +0000 (UTC) Date: Tue, 28 Jul 2015 23:08:38 +0000 (GMT) From: jenkins-admin@FreeBSD.org To: jenkins-admin@FreeBSD.org, freebsd-current@FreeBSD.org Message-ID: <185934114.11.1438124918342.JavaMail.jenkins@jenkins-9.freebsd.org> In-Reply-To: <798972436.7.1438101761539.JavaMail.jenkins@jenkins-9.freebsd.org> References: <798972436.7.1438101761539.JavaMail.jenkins@jenkins-9.freebsd.org> Subject: FreeBSD_HEAD-tests - Build #1228 - Still Unstable MIME-Version: 1.0 X-Jenkins-Job: FreeBSD_HEAD-tests X-Jenkins-Result: UNSTABLE Precedence: bulk Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: 7bit X-Content-Filtered-By: Mailman/MimeDel 2.1.20 X-BeenThere: freebsd-current@freebsd.org X-Mailman-Version: 2.1.20 List-Id: Discussions about the use of FreeBSD-current List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Tue, 28 Jul 2015 23:08:39 -0000 FreeBSD_HEAD-tests - Build #1228 - Still Unstable: Build information: https://jenkins.FreeBSD.org/job/FreeBSD_HEAD-tests/1228/ Full change log: https://jenkins.FreeBSD.org/job/FreeBSD_HEAD-tests/1228/changes Full build log: https://jenkins.FreeBSD.org/job/FreeBSD_HEAD-tests/1228/console Change summaries: No changes The end of the build log: [...truncated 4416 lines...] [192.168.10.2] out: bin/sh/set-e/functional_test:func2 -> passed [0.107s] [192.168.10.2] out: bin/sh/set-e/functional_test:if1 -> passed [0.072s] [192.168.10.2] out: bin/sh/set-e/functional_test:if2 -> passed [0.068s] [192.168.10.2] out: bin/sh/set-e/functional_test:if3 -> passed [0.064s] [192.168.10.2] out: bin/sh/set-e/functional_test:not1 -> passed [0.069s] [192.168.10.2] out: bin/sh/set-e/functional_test:not2 -> passed [0.070s] [192.168.10.2] out: bin/sh/set-e/functional_test:or1 -> passed [0.072s] [192.168.10.2] out: bin/sh/set-e/functional_test:or2 -> passed [0.069s] [192.168.10.2] out: bin/sh/set-e/functional_test:or3 -> passed [0.107s] [192.168.10.2] out: bin/sh/set-e/functional_test:pipe1 -> passed [0.090s] [192.168.10.2] out: bin/sh/set-e/functional_test:pipe2 -> passed [0.096s] [192.168.10.2] out: bin/sh/set-e/functional_test:return1 -> passed [0.071s] [192.168.10.2] out: bin/sh/set-e/functional_test:semi1 -> passed [0.091s] [192.168.10.2] out: bin/sh/set-e/functional_test:semi2 -> passed [0.091s] [192.168.10.2] out: bin/sh/set-e/functional_test:subshell1 -> passed [0.070s] [192.168.10.2] out: bin/sh/set-e/functional_test:subshell2 -> passed [0.072s] [192.168.10.2] out: bin/sh/set-e/functional_test:until1 -> passed [0.070s] [192.168.10.2] out: bin/sh/set-e/functional_test:until2 -> passed [0.070s] [192.168.10.2] out: bin/sh/set-e/functional_test:until3 -> passed [0.098s] [192.168.10.2] out: bin/sh/set-e/functional_test:while1 -> passed [0.076s] [192.168.10.2] out: bin/sh/set-e/functional_test:while2 -> passed [0.073s] [192.168.10.2] out: bin/sh/set-e/functional_test:while3 -> passed [0.099s] [192.168.10.2] out: bin/date/format_string_test:A_test -> passed [0.141s] [192.168.10.2] out: bin/date/format_string_test:B_test -> passed [0.231s] [192.168.10.2] out: bin/date/format_string_test:C_test -> passed [0.194s] [192.168.10.2] out: bin/date/format_string_test:D_test -> passed [0.118s] [192.168.10.2] out: bin/date/format_string_test:F_test -> passed [0.090s] [192.168.10.2] out: bin/date/format_string_test:G_test -> passed [0.113s] [192.168.10.2] out: bin/date/format_string_test:H_test -> passed [0.326s] [192.168.10.2] out: bin/date/format_string_test:I_test -> passed [0.560s] [192.168.10.2] out: bin/date/format_string_test:M_test -> passed [0.150s] [192.168.10.2] out: bin/date/format_string_test:R_test -> passed [0.277s] [192.168.10.2] out: bin/date/format_string_test:S_test -> passed [0.146s] [192.168.10.2] out: bin/date/format_string_test:U_test -> passed [0.116s] [192.168.10.2] out: bin/date/format_string_test:V_test -> passed [0.101s] [192.168.10.2] out: bin/date/format_string_test:W_test -> passed [0.118s] [192.168.10.2] out: bin/date/format_string_test:X_test -> passed [0.119s] [192.168.10.2] out: bin/date/format_string_test:Y_test -> passed [0.129s] [192.168.10.2] out: bin/date/format_string_test:Z_test -> passed [0.109s] [192.168.10.2] out: bin/date/format_string_test:a_test -> passed [0.125s] [192.168.10.2] out: bin/date/format_string_test:b_test -> passed [0.093s] [192.168.10.2] out: bin/date/format_string_test:c_test -> passed [0.134s] [192.168.10.2] out: bin/date/format_string_test:d_test -> passed [0.107s] [192.168.10.2] out: bin/date/format_string_test:e_test -> passed [0.083s] [192.168.10.2] out: bin/date/format_string_test:g_test -> passed [0.094s] [192.168.10.2] out: bin/date/format_string_test:h_test -> passed [0.115s] [192.168.10.2] out: bin/date/format_string_test:j_test -> passed [0.103s] [192.168.10.2] out: bin/date/format_string_test:k_test -> passed [0.080s] [192.168.10.2] out: bin/date/format_string_test:l_test -> passed [0.082s] [192.168.10.2] out: bin/date/format_string_test:m_test -> passed [0.196s] [192.168.10.2] out: bin/date/format_string_test:p_test -> passed [0.095s] [192.168.10.2] out: bin/date/format_string_test:percent_test -> passed [0.103s] [192.168.10.2] out: bin/date/format_string_test:plus_test -> passed [0.160s] [192.168.10.2] out: bin/date/format_string_test:r_test -> passed [0.109s] [192.168.10.2] out: bin/date/format_string_test:s_test -> passed [0.136s] [192.168.10.2] out: bin/date/format_string_test:u_test -> passed [0.099s] [192.168.10.2] out: bin/date/format_string_test:v_test -> passed [0.102s] [192.168.10.2] out: bin/date/format_string_test:w_test -> passed [0.085s] [192.168.10.2] out: bin/date/format_string_test:x_test -> passed [0.093s] [192.168.10.2] out: bin/date/format_string_test:y_test -> passed [0.107s] [192.168.10.2] out: bin/date/format_string_test:z_test -> passed [0.091s] [192.168.10.2] out: bin/pkill/pgrep-F_test:main -> passed [0.423s] [192.168.10.2] out: bin/pkill/pgrep-LF_test:main -> passed [0.922s] [192.168.10.2] out: bin/pkill/pgrep-P_test:main -> passed [0.515s] [192.168.10.2] out: bin/pkill/pgrep-U_test:main -> passed [0.730s] [192.168.10.2] out: bin/pkill/pgrep-_g_test:main -> passed [0.709s] [192.168.10.2] out: bin/pkill/pgrep-_s_test:main -> passed [0.062s] [192.168.10.2] out: bin/pkill/pgrep-g_test:main -> passed [0.830s] [192.168.10.2] out: bin/pkill/pgrep-i_test:main -> passed [0.407s] [192.168.10.2] out: bin/pkill/pgrep-j_test:main -> passed [6.254s] [192.168.10.2] out: bin/pkill/pgrep-l_test:main -> passed [0.579s] [192.168.10.2] out: bin/pkill/pgrep-n_test:main -> passed [0.421s] [192.168.10.2] out: bin/pkill/pgrep-o_test:main -> passed [0.359s] [192.168.10.2] out: bin/pkill/pgrep-q_test:main -> passed [0.405s] [192.168.10.2] out: bin/pkill/pgrep-s_test:main -> passed [0.708s] [192.168.10.2] out: bin/pkill/pgrep-t_test:main -> passed [0.401s] [192.168.10.2] out: bin/pkill/pgrep-v_test:main -> passed [0.399s] [192.168.10.2] out: bin/pkill/pgrep-x_test:main -> passed [0.386s] [192.168.10.2] out: bin/pkill/pkill-F_test:main -> passed [0.362s] [192.168.10.2] out: bin/pkill/pkill-LF_test:main -> passed [0.691s] [192.168.10.2] out: bin/pkill/pkill-P_test:main -> passed [0.433s] [192.168.10.2] out: bin/pkill/pkill-U_test:main -> passed [0.769s] [192.168.10.2] out: bin/pkill/pkill-_g_test:main -> passed [0.698s] [192.168.10.2] out: bin/pkill/pkill-g_test:main -> passed [0.720s] [192.168.10.2] out: bin/pkill/pkill-i_test:main -> passed [0.368s] [192.168.10.2] out: bin/pkill/pkill-j_test:main -> passed [8.451s] [192.168.10.2] out: bin/pkill/pkill-s_test:main -> passed [0.708s] [192.168.10.2] out: bin/pkill/pkill-t_test:main -> passed [0.711s] [192.168.10.2] out: bin/pkill/pkill-x_test:main -> passed [0.702s] [192.168.10.2] out: bin/expr/expr_test:arithmetic_ops -> expected_failure: the following testcases fail with syntax errors on FreeBSD: Expected 0, got expr: syntax error from expression: .java_wrapper : / [0.081s] [192.168.10.2] out: bin/expr/expr_test:basic_functional -> passed [0.089s] [192.168.10.2] out: bin/expr/expr_test:basic_math -> passed [0.114s] [192.168.10.2] out: bin/expr/expr_test:colon_vs_math -> passed [0.056s] [192.168.10.2] out: bin/expr/expr_test:compare_ops -> passed [0.108s] [192.168.10.2] out: bin/expr/expr_test:compare_ops_precedence -> passed [0.102s] [192.168.10.2] out: bin/expr/expr_test:gtkmm -> passed [0.086s] [192.168.10.2] out: bin/expr/expr_test:lang -> passed [0.094s] [192.168.10.2] out: bin/expr/expr_test:math_precedence -> passed [0.056s] [192.168.10.2] out: bin/expr/expr_test:multiply -> passed [0.076s] [192.168.10.2] out: bin/expr/expr_test:negative -> passed [0.080s] [192.168.10.2] out: bin/expr/expr_test:overflow -> passed [0.146s] [192.168.10.2] out: bin/expr/expr_test:precedence -> passed [0.052s] [192.168.10.2] out: bin/expr/expr_test:regex -> passed [0.097s] [192.168.10.2] out: bin/test/legacy_test:main -> passed [0.363s] [192.168.10.2] out: libexec/rtld-elf/ld_library_pathfds:bad_library_directories -> passed [0.130s] [192.168.10.2] out: libexec/rtld-elf/ld_library_pathfds:first_library_directory -> passed [0.085s] [192.168.10.2] out: libexec/rtld-elf/ld_library_pathfds:last_library_directory -> passed [0.061s] [192.168.10.2] out: libexec/rtld-elf/ld_library_pathfds:middle_library_directory -> passed [0.031s] [192.168.10.2] out: libexec/rtld-elf/ld_library_pathfds:missing_library -> passed [0.027s] [192.168.10.2] out: libexec/rtld-elf/ld_library_pathfds:single_library_directory -> passed [0.030s] [192.168.10.2] out: libexec/rtld-elf/ld_library_pathfds:wrong_library_directories -> passed [0.038s] [192.168.10.2] out: libexec/atf/atf-sh/atf_check_test:equal -> passed [0.288s] [192.168.10.2] out: libexec/atf/atf-sh/atf_check_test:experr_mismatch -> passed [0.173s] [192.168.10.2] out: libexec/atf/atf-sh/atf_check_test:expout_mismatch -> passed [0.265s] [192.168.10.2] out: libexec/atf/atf-sh/atf_check_test:flush_stdout_on_death -> passed [1.142s] [192.168.10.2] out: libexec/atf/atf-sh/atf_check_test:info_ok -> passed [0.170s] [192.168.10.2] out: libexec/atf/atf-sh/atf_check_test:null_stderr -> passed [0.139s] [192.168.10.2] out: libexec/atf/atf-sh/atf_check_test:null_stdout -> passed [0.202s] [192.168.10.2] out: libexec/atf/atf-sh/config_test:get -> passed [0.189s] [192.168.10.2] out: libexec/atf/atf-sh/config_test:has -> passed [0.227s] [192.168.10.2] out: libexec/atf/atf-sh/integration_test:arguments -> passed [0.153s] [192.168.10.2] out: libexec/atf/atf-sh/integration_test:custom_shell__command_line -> passed [0.102s] [192.168.10.2] out: libexec/atf/atf-sh/integration_test:custom_shell__shebang -> passed [0.105s] [192.168.10.2] out: libexec/atf/atf-sh/integration_test:missing_script -> passed [0.070s] [192.168.10.2] out: libexec/atf/atf-sh/integration_test:no_args -> passed [0.081s] [192.168.10.2] out: libexec/atf/atf-sh/integration_test:set_e -> passed [0.122s] [192.168.10.2] out: libexec/atf/atf-sh/normalize_test:main -> passed [0.113s] [192.168.10.2] out: libexec/atf/atf-sh/tc_test:default_status -> passed [0.204s] [192.168.10.2] out: libexec/atf/atf-sh/tc_test:missing_body -> passed [0.086s] [192.168.10.2] out: libexec/atf/atf-sh/tp_test:srcdir -> passed [0.569s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:eflag_empty -> passed [0.133s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:eflag_file -> passed [0.316s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:eflag_ignore -> passed [0.280s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:eflag_inline -> passed [0.746s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:eflag_match -> passed [0.338s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:eflag_multiple -> passed [0.308s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:eflag_negated -> passed [0.224s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:eflag_save -> passed [0.242s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:invalid_umask -> passed [0.137s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:oflag_empty -> passed [0.189s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:oflag_file -> passed [0.259s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:oflag_ignore -> passed [0.169s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:oflag_inline -> passed [1.311s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:oflag_match -> passed [0.431s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:oflag_multiple -> passed [0.356s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:oflag_negated -> passed [0.183s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:oflag_save -> passed [0.154s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:sflag_eq_ne -> passed [0.290s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:sflag_exit -> passed [0.228s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:sflag_ignore -> passed [0.153s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:sflag_signal -> passed [0.196s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:stdin -> passed [0.082s] [192.168.10.2] out: libexec/atf/atf-check/atf-check_test:xflag -> passed [0.101s] [192.168.10.2] out: [192.168.10.2] out: Results file id is usr_tests.20150728-214137-422996 [192.168.10.2] out: Results saved to /root/.kyua/store/results.usr_tests.20150728-214137-422996.db [192.168.10.2] out: [192.168.10.2] out: 4336/4338 passed (2 failed) [192.168.10.2] out: Warning: run() received nonzero return code 1 while executing 'kyua test'! [192.168.10.2] run: kyua report --verbose --results-filter passed,skipped,xfail,broken,failed --output test-report.txt [192.168.10.2] run: kyua report-junit --output=test-report.xml [192.168.10.2] run: shutdown -p now [192.168.10.2] out: Shutdown NOW! [192.168.10.2] out: shutdown: [pid 67957] [192.168.10.2] out: xffffff00 broadcast 192.168.10.255 kyuatestprompt # lock order reversal: 1st 0xfffffe007b223fc0 bufwait (bufwait) @ /builds/FreeBSD_HEAD/sys/kern/vfs_bio.c:3121 2nd 0xfffff800077a7c00 dirhash (dirhash) @ /builds/FreeBSD_HEAD/sys/ufs/ufs/ufs_dirhash.c:281 KDB: stack backtrace: db_trace_self_wrapper() at db_trace_self_wrapper+0x2b/frame 0xfffffe0096f9c400 witness_checkorder() at witness_checkorder+0xe7a/frame 0xfffffe0096f9c480 _sx_xlock() at _sx_xlock+0x75/frame 0xfffffe0096f9c4c0 ufsdirhash_add() at ufsdirhash_add+0x3d/frame 0xfffffe0096f9c510 ufs_direnter() at ufs_direnter+0x5da/frame 0xfffffe0096f9c5e0 ufs_makeinode() at ufs_makeinode+0x5d3/frame 0xfffffe0096f9c7a0 ufs_create() at ufs_create+0x2d/frame 0xfffffe0096f9c7c0 VOP_CREATE_APV() at VOP_CREATE_APV+0xf1/frame 0xfffffe0096f9c7f0 vn_open_cred() at vn_open_cred+0x30e/frame 0xfffffe0096f9c960 kern_openat() at kern_openat+0x235/frame 0xfffffe0096f9cae0 amd64_syscall() at amd64_syscall+0x282/frame 0xfffffe0096f9cbf0 Xfast_syscall() at Xfast_syscall+0xfb/frame 0xfffffe0096f9cbf0 --- syscall (499, FreeBSD ELF64, sys_openat), rip = 0x8008f2f7a, rsp = 0x7fffffffbb68, rbp = 0x7fffffffbc40 --- ahcich0: Timeout on slot 6 port 0 ahcich0: is 00000000 cs 00000000 ss 00000040 rs 00000040 tfd 50 serr 00000000 cmd 1000c617 (ada0:ahcich0:0:0:0): WRITE_FPDMA_QUEUED. ACB: 61 08 f0 c2 27 40 00 00 00 00 00 00 (ada0:ahcich0:0:0:0): CAM status: Command timeout (ada0:ahcich0:0:0:0): Retrying command ahcich0: Timeout on slot 10 port 0 ahcich0: is 00000000 cs 00000000 ss 00000400 rs 00000400 tfd 50 serr 00000000 cmd 1000ca17 (ada0:ahcich0:0:0:0): WRITE_FPDMA_QUEUED. ACB: 61 08 b0 c2 27 40 00 00 00 00 00 00 (ada0:ahcich0:0:0:0): CAM status: Command timeout (ada0:ahcich0:0:0:0): Retrying command ahcich0: Timeout on slot 17 port 0 ahcich0: is 00000000 cs 00000000 ss 00060000 rs 00060000 tfd 50 serr 00000000 cmd 1000d217 (ada0:ahcich0:0:0:0): WRITE_FPDMA_QUEUED. ACB: 61 08 f0 c2 27 40 00 00 Stopping cron. Waiting for PIDS: 577. Stopping sshd. Waiting for PIDS: 542. Stopping casperd. Waiting for PIDS: 443. Stopping devd. Waiting for PIDS: 275. Writing entropy file:. Writing early boot entropy file:. . Terminated Jul 28 23:08:20 syslogd: exiting on signal 15 Waiting (max 60 seconds) for system process `vnlru' to stop...done Waiting (max 60 seconds) for system process `bufdaemon' to stop...done Waiting (max 60 seconds) for system process `syncer' to stop... Syncing disks, vnodes remaining...0 0 done All buffers synced. lock order reversal: 1st 0xfffff800072c77c8 ufs (ufs) @ /builds/FreeBSD_HEAD/sys/kern/vfs_mount.c:1224 2nd 0xfffff800072c8240 devfs (devfs) @ /builds/FreeBSD_HEAD/sys/kern/vfs_subr.c:2219 KDB: stack backtrace: db_trace_self_wrapper() at db_trace_self_wrapper+0x2b/frame 0xfffffe007b995650 witness_checkorder() at witness_checkorder+0xe7a/frame 0xfffffe007b9956d0 __lockmgr_args() at __lockmgr_args+0xa5c/frame 0xfffffe007b995800 vop_stdlock() at vop_stdlock+0x3c/frame 0xfffffe007b995820 VOP_LOCK1_APV() at VOP_LOCK1_APV+0xfc/frame 0xfffffe007b995850 _vn_lock() at _vn_lock+0x9a/frame 0xfffffe007b9958c0 vget() at vget+0x7e/frame 0xfffffe007b995910 devfs_allocv() at devfs_allocv+0xfd/frame 0xfffffe007b995960 devfs_root() at devfs_root+0x43/frame 0xfffffe007b995990 dounmount() at dounmount+0x44a/frame 0xfffffe007b995a10 vfs_unmountall() at vfs_unmountall+0x69/frame 0xfffffe007b995a40 kern_reboot() at kern_reboot+0x4f6/frame 0xfffffe007b995ac0 sys_reboot() at sys_reboot+0x58/frame 0xfffffe007b995ae0 amd64_syscall() at amd64_syscall+0x282/frame 0xfffffe007b995bf0 Xfast_syscall() at Xfast_syscall+0xfb/frame 0xfffffe007b995bf0 --- syscall (55, FreeBSD ELF64, sys_reboot), rip = 0x41205a, rsp = 0x7fffffffe718, rbp = 0x7fffffffe810 --- Uptime: 1h27m2s acpi0: Powering system off + sudo python /vm/freebsd-ci/scripts/test/extract-test-logs.py -f /vm/freebsd-ci/scripts/test/config/config.json mdconfig -a -u 99 -t vnode -f /net/jenkins-10.freebsd.org/builds/Build-UFS-image/image/FreeBSD_HEAD/test.img umount /tmp/tmpBMVNre mdconfig -d -u 99 Recording test results Build step 'Publish JUnit test result report' changed build result to UNSTABLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures)