See <https://jenkins.FreeBSD.org/job/FreeBSD_HEAD/703/> ------------------------------------------ [...truncated 355770 lines...] [192.168.10.2] out: bin/sh/set-e/functional_test:elif1 -> passed [0.047s] [192.168.10.2] out: bin/sh/set-e/functional_test:elif2 -> passed [0.046s] [192.168.10.2] out: bin/sh/set-e/functional_test:eval1 -> passed [0.047s] [192.168.10.2] out: bin/sh/set-e/functional_test:eval2 -> passed [0.048s] [192.168.10.2] out: bin/sh/set-e/functional_test:for1 -> passed [0.043s] [192.168.10.2] out: bin/sh/set-e/functional_test:func1 -> passed [0.048s] [192.168.10.2] out: bin/sh/set-e/functional_test:func2 -> passed [0.046s] [192.168.10.2] out: bin/sh/set-e/functional_test:if1 -> passed [0.047s] [192.168.10.2] out: bin/sh/set-e/functional_test:if2 -> passed [0.045s] [192.168.10.2] out: bin/sh/set-e/functional_test:if3 -> passed [0.043s] [192.168.10.2] out: bin/sh/set-e/functional_test:not1 -> passed [0.044s] [192.168.10.2] out: bin/sh/set-e/functional_test:not2 -> passed [0.045s] [192.168.10.2] out: bin/sh/set-e/functional_test:or1 -> passed [0.045s] [192.168.10.2] out: bin/sh/set-e/functional_test:or2 -> passed [0.048s] [192.168.10.2] out: bin/sh/set-e/functional_test:or3 -> passed [0.042s] [192.168.10.2] out: bin/sh/set-e/functional_test:pipe1 -> passed [0.416s] [192.168.10.2] out: bin/sh/set-e/functional_test:pipe2 -> passed [0.234s] [192.168.10.2] out: bin/sh/set-e/functional_test:return1 -> passed [0.047s] [192.168.10.2] out: bin/sh/set-e/functional_test:semi1 -> passed [0.042s] [192.168.10.2] out: bin/sh/set-e/functional_test:semi2 -> passed [0.041s] [192.168.10.2] out: bin/sh/set-e/functional_test:subshell1 -> passed [0.046s] [192.168.10.2] out: bin/sh/set-e/functional_test:subshell2 -> passed [0.045s] [192.168.10.2] out: bin/sh/set-e/functional_test:until1 -> passed [0.046s] [192.168.10.2] out: bin/sh/set-e/functional_test:until2 -> passed [0.045s] [192.168.10.2] out: bin/sh/set-e/functional_test:until3 -> passed [0.047s] [192.168.10.2] out: bin/sh/set-e/functional_test:while1 -> passed [0.046s] [192.168.10.2] out: bin/sh/set-e/functional_test:while2 -> passed [0.045s] [192.168.10.2] out: bin/sh/set-e/functional_test:while3 -> passed [0.042s] [192.168.10.2] out: bin/date/format_string_test:A_test -> passed [0.094s] [192.168.10.2] out: bin/date/format_string_test:B_test -> passed [0.049s] [192.168.10.2] out: bin/date/format_string_test:C_test -> passed [0.047s] [192.168.10.2] out: bin/date/format_string_test:D_test -> passed [0.052s] [192.168.10.2] out: bin/date/format_string_test:F_test -> passed [0.052s] [192.168.10.2] out: bin/date/format_string_test:G_test -> passed [0.051s] [192.168.10.2] out: bin/date/format_string_test:H_test -> passed [0.055s] [192.168.10.2] out: bin/date/format_string_test:I_test -> passed [0.054s] [192.168.10.2] out: bin/date/format_string_test:M_test -> passed [0.055s] [192.168.10.2] out: bin/date/format_string_test:R_test -> passed [0.053s] [192.168.10.2] out: bin/date/format_string_test:S_test -> passed [0.050s] [192.168.10.2] out: bin/date/format_string_test:U_test -> passed [0.049s] [192.168.10.2] out: bin/date/format_string_test:V_test -> passed [0.047s] [192.168.10.2] out: bin/date/format_string_test:W_test -> passed [0.052s] [192.168.10.2] out: bin/date/format_string_test:X_test -> passed [0.050s] [192.168.10.2] out: bin/date/format_string_test:Y_test -> passed [0.050s] [192.168.10.2] out: bin/date/format_string_test:Z_test -> passed [0.050s] [192.168.10.2] out: bin/date/format_string_test:a_test -> passed [0.050s] [192.168.10.2] out: bin/date/format_string_test:b_test -> passed [0.050s] [192.168.10.2] out: bin/date/format_string_test:c_test -> passed [0.049s] [192.168.10.2] out: bin/date/format_string_test:d_test -> passed [0.047s] [192.168.10.2] out: bin/date/format_string_test:e_test -> passed [0.050s] [192.168.10.2] out: bin/date/format_string_test:g_test -> passed [0.049s] [192.168.10.2] out: bin/date/format_string_test:h_test -> passed [0.049s] [192.168.10.2] out: bin/date/format_string_test:j_test -> passed [0.052s] [192.168.10.2] out: bin/date/format_string_test:k_test -> passed [0.053s] [192.168.10.2] out: bin/date/format_string_test:l_test -> passed [0.047s] [192.168.10.2] out: bin/date/format_string_test:m_test -> passed [0.051s] [192.168.10.2] out: bin/date/format_string_test:p_test -> passed [0.051s] [192.168.10.2] out: bin/date/format_string_test:percent_test -> passed [0.051s] [192.168.10.2] out: bin/date/format_string_test:plus_test -> passed [0.050s] [192.168.10.2] out: bin/date/format_string_test:r_test -> passed [0.049s] [192.168.10.2] out: bin/date/format_string_test:s_test -> passed [0.051s] [192.168.10.2] out: bin/date/format_string_test:u_test -> passed [0.050s] [192.168.10.2] out: bin/date/format_string_test:v_test -> passed [0.049s] [192.168.10.2] out: bin/date/format_string_test:w_test -> passed [0.050s] [192.168.10.2] out: bin/date/format_string_test:x_test -> passed [0.056s] [192.168.10.2] out: bin/date/format_string_test:y_test -> passed [0.048s] [192.168.10.2] out: bin/date/format_string_test:z_test -> passed [0.068s] [192.168.10.2] out: bin/pax/legacy_test:main -> passed [0.442s] [192.168.10.2] out: bin/pkill/pgrep-F_test:main -> passed [0.382s] [192.168.10.2] out: bin/pkill/pgrep-LF_test:main -> passed [0.704s] [192.168.10.2] out: bin/pkill/pgrep-P_test:main -> passed [0.557s] [192.168.10.2] out: bin/pkill/pgrep-U_test:main -> passed [0.667s] [192.168.10.2] out: bin/pkill/pgrep-_g_test:main -> passed [0.678s] [192.168.10.2] out: bin/pkill/pgrep-_s_test:main -> passed [0.161s] [192.168.10.2] out: bin/pkill/pgrep-g_test:main -> passed [0.665s] [192.168.10.2] out: bin/pkill/pgrep-i_test:main -> passed [0.342s] [192.168.10.2] out: bin/pkill/pgrep-j_test:main -> passed [6.643s] [192.168.10.2] out: bin/pkill/pgrep-l_test:main -> passed [0.342s] [192.168.10.2] out: bin/pkill/pgrep-n_test:main -> passed [0.344s] [192.168.10.2] out: bin/pkill/pgrep-o_test:main -> passed [0.348s] [192.168.10.2] out: bin/pkill/pgrep-q_test:main -> passed [0.346s] [192.168.10.2] out: bin/pkill/pgrep-s_test:main -> passed [0.682s] [192.168.10.2] out: bin/pkill/pgrep-t_test:main -> passed [0.358s] [192.168.10.2] out: bin/pkill/pgrep-v_test:main -> passed [0.376s] [192.168.10.2] out: bin/pkill/pgrep-x_test:main -> passed [0.381s] [192.168.10.2] out: bin/pkill/pkill-F_test:main -> passed [0.402s] [192.168.10.2] out: bin/pkill/pkill-LF_test:main -> passed [0.676s] [192.168.10.2] out: bin/pkill/pkill-P_test:main -> passed [0.341s] [192.168.10.2] out: bin/pkill/pkill-U_test:main -> passed [0.685s] [192.168.10.2] out: bin/pkill/pkill-_g_test:main -> passed [0.674s] [192.168.10.2] out: bin/pkill/pkill-g_test:main -> passed [0.678s] [192.168.10.2] out: bin/pkill/pkill-i_test:main -> passed [0.345s] [192.168.10.2] out: bin/pkill/pkill-j_test:main -> passed [16.469s] [192.168.10.2] out: bin/pkill/pkill-s_test:main -> passed [0.732s] [192.168.10.2] out: bin/pkill/pkill-t_test:main -> passed [0.681s] [192.168.10.2] out: bin/pkill/pkill-x_test:main -> passed [0.686s] [192.168.10.2] out: bin/cat/cat_test:align -> passed [0.062s] [192.168.10.2] out: bin/cat/cat_test:nonexistent -> passed [0.037s] [192.168.10.2] out: bin/cat/cat_test:se_output -> passed [0.043s] [192.168.10.2] out: bin/mv/legacy_test:main -> passed [1.292s] [192.168.10.2] out: bin/test/legacy_test:main -> passed [0.166s] [192.168.10.2] out: bin/dd/dd_test:io -> passed [0.151s] [192.168.10.2] out: bin/dd/dd_test:length -> passed [0.086s] [192.168.10.2] out: bin/dd/dd_test:seek -> passed [0.146s] [192.168.10.2] out: bin/ls/ls_tests:1_flag -> passed [0.397s] [192.168.10.2] out: bin/ls/ls_tests:A_flag -> passed [0.450s] [192.168.10.2] out: bin/ls/ls_tests:A_flag_implied_when_root -> passed [0.390s] [192.168.10.2] out: bin/ls/ls_tests:B_flag -> passed [0.058s] [192.168.10.2] out: bin/ls/ls_tests:C_flag -> passed [0.591s] [192.168.10.2] out: bin/ls/ls_tests:D_flag -> passed [0.063s] [192.168.10.2] out: bin/ls/ls_tests:F_flag -> passed [2.523s] [192.168.10.2] out: bin/ls/ls_tests:H_flag -> passed [0.383s] [192.168.10.2] out: bin/ls/ls_tests:I_flag -> passed [0.373s] [192.168.10.2] out: bin/ls/ls_tests:I_flag_voids_implied_A_flag_when_root -> passed [0.395s] [192.168.10.2] out: bin/ls/ls_tests:L_flag -> passed [0.388s] [192.168.10.2] out: bin/ls/ls_tests:R_flag -> passed [0.392s] [192.168.10.2] out: bin/ls/ls_tests:S_flag -> passed [1.323s] [192.168.10.2] out: bin/ls/ls_tests:T_flag -> passed [0.089s] [192.168.10.2] out: bin/ls/ls_tests:a_flag -> passed [0.532s] [192.168.10.2] out: bin/ls/ls_tests:b_flag -> passed [0.085s] [192.168.10.2] out: bin/ls/ls_tests:d_flag -> passed [0.102s] [192.168.10.2] out: bin/ls/ls_tests:f_flag -> passed [0.719s] [192.168.10.2] out: bin/ls/ls_tests:g_flag -> passed [0.534s] [192.168.10.2] out: bin/ls/ls_tests:h_flag -> passed [0.648s] [192.168.10.2] out: bin/ls/ls_tests:i_flag -> passed [0.790s] [192.168.10.2] out: bin/ls/ls_tests:k_flag -> passed [0.421s] [192.168.10.2] out: bin/ls/ls_tests:l_flag -> passed [0.064s] [192.168.10.2] out: bin/ls/ls_tests:lcomma_flag -> passed [0.423s] [192.168.10.2] out: bin/ls/ls_tests:m_flag -> passed [0.097s] [192.168.10.2] out: bin/ls/ls_tests:n_flag -> passed [0.097s] [192.168.10.2] out: bin/ls/ls_tests:o_flag -> passed [0.107s] [192.168.10.2] out: bin/ls/ls_tests:p_flag -> passed [0.817s] [192.168.10.2] out: bin/ls/ls_tests:q_flag_and_w_flag -> passed [0.088s] [192.168.10.2] out: bin/ls/ls_tests:r_flag -> passed [0.426s] [192.168.10.2] out: bin/ls/ls_tests:s_flag -> passed [0.386s] [192.168.10.2] out: bin/ls/ls_tests:t_flag -> passed [0.157s] [192.168.10.2] out: bin/ls/ls_tests:u_flag -> passed [0.152s] [192.168.10.2] out: bin/ls/ls_tests:x_flag -> passed [0.403s] [192.168.10.2] out: bin/ls/ls_tests:y_flag -> passed [0.379s] [192.168.10.2] out: [192.168.10.2] out: Results file id is usr_tests.20160925-201949-128274 [192.168.10.2] out: Results saved to /root/.kyua/store/results.usr_tests.20160925-201949-128274.db [192.168.10.2] out: [192.168.10.2] out: 5858/5858 passed (0 failed) [192.168.10.2] out: [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 72057] [192.168.10.2] out: kyuatestprompt # lock order reversal: 1st 0xfffffe007b0b8a80 bufwait (bufwait) _at_ /builds/workspace/FreeBSD_HEAD/src/sys/kern/vfs_bio.c:3505 2nd 0xfffff80006d6c800 dirhash (dirhash) _at_ /builds/workspace/FreeBSD_HEAD/src/sys/ufs/ufs/ufs_dirhash.c:281 stack backtrace: #0 0xffffffff80aad990 at witness_debugger+0x70 #1 0xffffffff80aad884 at witness_checkorder+0xe54 #2 0xffffffff80a55bb2 at _sx_xlock+0x72 #3 0xffffffff80d1bc2d at ufsdirhash_add+0x3d #4 0xffffffff80d1e912 at ufs_direnter+0x502 #5 0xffffffff80d27bf0 at ufs_makeinode+0x5a0 #6 0xffffffff80d238e3 at ufs_create+0x33 #7 0xffffffff810272ca at VOP_CREATE_APV+0xda #8 0xffffffff80b20b78 at vn_open_cred+0x2f8 #9 0xffffffff80b19e1c at kern_openat+0x25c #10 0xffffffff80ec5db4 at amd64_syscall+0x314 #11 0xffffffff80ea567b at Xfast_syscall+0xfb Sep 25 20:22:19 kernel: pid 13630 (sh), uid 0, was killed: exceeded maximum CPU limit lock order reversal: 1st 0xfffff800102e7b78 ufs (ufs) _at_ /builds/workspace/FreeBSD_HEAD/src/sys/kern/vfs_mount.c:1247 2nd 0xfffff80006ed85f0 devfs (devfs) _at_ /builds/workspace/FreeBSD_HEAD/src/sys/ufs/ffs/ffs_vfsops.c:1598 stack backtrace: #0 0xffffffff80aad990 at witness_debugger+0x70 #1 0xffffffff80aad884 at witness_checkorder+0xe54 #2 0xffffffff80a25992 at __lockmgr_args+0x4c2 #3 0xffffffff80b001dc at vop_stdlock+0x3c #4 0xffffffff810299e0 at VOP_LOCK1_APV+0xe0 #5 0xffffffff80b2132a at _vn_lock+0x9a #6 0xffffffff80d15102 at ffs_sync+0x2f2 #7 0xffffffff80b22a00 at vfs_write_suspend+0x180 #8 0xffffffff80b22c47 at vfs_write_suspend_umnt+0x47 #9 0xffffffff80d149c4 at ffs_unmount+0x54 #10 0xffffffff80b09d9d at dounmount+0x71d #11 0xffffffff80b095ed at sys_unmount+0x35d #12 0xffffffff80ec5db4 at amd64_syscall+0x314 #13 0xffffffff80ea567b at Xfast_syscall+0xfb GEOM_CONCAT: Device concat.HWO0HE created (id=2434171921). GEOM_CONCAT: Disk md0 attached to concat.HWO0HE. GEOM_CONCAT: Disk md1 attached to concat.HWO0HE. GEOM_CONCAT: Disk md2 attachedStopping cron. Waiting for PIDS: 587. Stopping sshd. Waiting for PIDS: 552. Stopping devd. Waiting for PIDS: 286. Writing entropy file:. Writing early boot entropy file:. . Sep 25 21:53:00 syslogd: exiting on signal 15 Traceback (most recent call last): File "freebsd-ci/scripts/test/run-tests.py", line 207, in <module> main(sys.argv) File "freebsd-ci/scripts/test/run-tests.py", line 79, in main runTest() File "freebsd-ci/scripts/test/run-tests.py", line 187, in runTest child2.expect(pexpect.EOF, timeout=1000) File "/usr/local/lib/python2.7/site-packages/pexpect/__init__.py", line 1451, in expect timeout, searchwindowsize) File "/usr/local/lib/python2.7/site-packages/pexpect/__init__.py", line 1466, in expect_list timeout, searchwindowsize) File "/usr/local/lib/python2.7/site-packages/pexpect/__init__.py", line 1568, in expect_loop raise TIMEOUT(str(err) + '\n' + str(self)) pexpect.TIMEOUT: Timeout exceeded. <pexpect.spawn object at 0x8064de790> version: 3.3 command: /usr/sbin/bhyve args: [u'/usr/sbin/bhyve', u'-c', u'2', u'-m', u'2G', u'-AI', u'-H', u'-P', u'-g', u'0', u'-s', u'0:0,hostbridge', u'-s', u'1:0,lpc', u'-s', u'2:0,virtio-net,tap10,mac=58:9c:fc:00:00:2e', u'-s', u'3:0,ahci-hd,/net/jenkins-10.freebsd.org//builds/workspace/FreeBSD_HEAD/image/src/test.img', u'-l', u'com1,stdio', u'vm_test'] searcher: <pexpect.searcher_re object at 0x8052e1310> buffer (last 100 chars): 'ropy file:.\r\nWriting early boot entropy file:.\r\n.\r\nSep 25 21:53:00 syslogd: exiting on signal 15\r\r\n' before (last 100 chars): 'ropy file:.\r\nWriting early boot entropy file:.\r\n.\r\nSep 25 21:53:00 syslogd: exiting on signal 15\r\r\n' after: <class 'pexpect.TIMEOUT'> match: None match_index: None exitstatus: None flag_eof: False pid: 30968 child_fd: 4 closed: False timeout: 30 delimiter: <class 'pexpect.EOF'> logfile: <open file '<stdout>', mode 'w' at 0x800671150> logfile_read: None logfile_send: None maxread: 2000 ignorecase: False searchwindowsize: None delaybeforesend: 0.05 delayafterclose: 0.1 delayafterterminate: 0.1 [Pipeline] } [Pipeline] // node [Pipeline] node Running on master in /usr/local/jenkins/workspace/FreeBSD_HEAD [Pipeline] { [Pipeline] stepReceived on Sun Sep 25 2016 - 20:10:47 UTC
This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:41:08 UTC