FreeBSD_HEAD-tests - Build #1228 - Still Unstable

From: <jenkins-admin_at_FreeBSD.org>
Date: Tue, 28 Jul 2015 23:08:38 +0000 (GMT)
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) _at_ /builds/FreeBSD_HEAD/sys/kern/vfs_bio.c:3121
 2nd 0xfffff800077a7c00 dirhash (dirhash) _at_ /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) _at_ /builds/FreeBSD_HEAD/sys/kern/vfs_mount.c:1224
 2nd 0xfffff800072c8240 devfs (devfs) _at_ /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)
Received on Tue Jul 28 2015 - 21:08:39 UTC

This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:40:58 UTC