Build failed in Jenkins: FreeBSD_HEAD-tests2 #1035

Fri May 15 05:04:28 UTC 2015

See <https://jenkins.freebsd.org/job/FreeBSD_HEAD-tests2/1035/>

------------------------------------------
[...truncated 2446 lines...]
lib/libnv/dnv_tests:dnvlist_take_string__present  ->  passed  [0.017s]
lib/libnv/nv_tests:nvlist_add_binary__single_insert  ->  passed  [0.019s]
lib/libnv/nv_tests:nvlist_add_bool__single_insert  ->  passed  [0.018s]
lib/libnv/nv_tests:nvlist_add_null__single_insert  ->  passed  [0.018s]
lib/libnv/nv_tests:nvlist_add_number__single_insert  ->  passed  [0.017s]
lib/libnv/nv_tests:nvlist_add_nvlist__child_with_error  ->  passed  [0.017s]
lib/libnv/nv_tests:nvlist_add_nvlist__single_insert  ->  passed  [0.162s]
lib/libnv/nv_tests:nvlist_add_string__single_insert  ->  passed  [0.018s]
lib/libnv/nv_tests:nvlist_clone__empty_nvlist  ->  passed  [0.018s]
lib/libnv/nv_tests:nvlist_clone__error_nvlist  ->  passed  [0.019s]
lib/libnv/nv_tests:nvlist_clone__nested_nvlist  ->  passed  [0.184s]
lib/libnv/nv_tests:nvlist_clone__nonempty_nvlist  ->  passed  [0.017s]
lib/libnv/nv_tests:nvlist_create__is_empty  ->  passed  [0.018s]
lib/libnv/nv_tests:nvlist_free__single_binary  ->  passed  [0.018s]
lib/libnv/nv_tests:nvlist_free__single_bool  ->  passed  [0.079s]
lib/libnv/nv_tests:nvlist_free__single_null  ->  passed  [0.020s]
lib/libnv/nv_tests:nvlist_free__single_number  ->  passed  [0.018s]
lib/libnv/nv_tests:nvlist_free__single_nvlist  ->  passed  [0.045s]
lib/libnv/nv_tests:nvlist_free__single_string  ->  passed  [0.017s]
lib/libnv/nv_tests:nvlist_free_binary__single_binary  ->  passed  [0.018s]
lib/libnv/nv_tests:nvlist_free_bool__single_bool  ->  passed  [0.021s]
lib/libnv/nv_tests:nvlist_free_null__single_null  ->  passed  [0.021s]
lib/libnv/nv_tests:nvlist_free_number__single_number  ->  passed  [0.020s]
lib/libnv/nv_tests:nvlist_free_nvlist__single_nvlist  ->  passed  [0.025s]
lib/libnv/nv_tests:nvlist_free_string__single_string  ->  passed  [0.026s]
lib/libnv/nv_tests:nvlist_move_binary__single_insert  ->  passed  [0.018s]
lib/libnv/nv_tests:nvlist_move_nvlist__child_with_error  ->  passed  [0.020s]
lib/libnv/nv_tests:nvlist_move_nvlist__null_child  ->  passed  [0.051s]
lib/libnv/nv_tests:nvlist_move_nvlist__single_insert  ->  passed  [0.189s]
lib/libnv/nv_tests:nvlist_move_string__single_insert  ->  passed  [0.018s]
lib/libnv/nv_tests:nvlist_pack__empty_nvlist  ->  passed  [0.019s]
lib/libnv/nv_tests:nvlist_pack__error_nvlist  ->  passed  [0.019s]
lib/libnv/nv_tests:nvlist_pack__multiple_values  ->  passed  [0.041s]
lib/libnv/nv_tests:nvlist_take_binary__other_keys_unchanged  ->  passed  [0.019s]
lib/libnv/nv_tests:nvlist_take_binary__single_remove  ->  passed  [0.061s]
lib/libnv/nv_tests:nvlist_take_bool__other_keys_unchanged  ->  passed  [0.018s]
lib/libnv/nv_tests:nvlist_take_bool__single_remove  ->  passed  [0.018s]
lib/libnv/nv_tests:nvlist_take_number__other_keys_unchanged  ->  passed  [0.018s]
lib/libnv/nv_tests:nvlist_take_number__single_remove  ->  passed  [0.017s]
lib/libnv/nv_tests:nvlist_take_nvlist__other_keys_unchanged  ->  passed  [0.017s]
lib/libnv/nv_tests:nvlist_take_nvlist__single_remove  ->  passed  [0.020s]
lib/libnv/nv_tests:nvlist_take_string__other_keys_unchanged  ->  passed  [0.018s]
lib/libnv/nv_tests:nvlist_take_string__single_remove  ->  passed  [0.017s]
lib/libnv/nv_tests:nvlist_unpack__duplicate_key  ->  passed  [0.021s]
lib/libnv/nv_tests:nvlist_unpack__flags_nvlist  ->  passed  [0.018s]
lib/libnv/nvlist_add_test:main  ->  passed  [0.021s]
lib/libnv/nvlist_exists_test:main  ->  passed  [0.025s]
lib/libnv/nvlist_free_test:main  ->  passed  [0.107s]
lib/libnv/nvlist_get_test:main  ->  passed  [0.023s]
lib/libnv/nvlist_move_test:main  ->  passed  [0.024s]
lib/libnv/nvlist_send_recv_test:main  ->  passed  [0.059s]
lib/libpam/t_openpam_ctype:main  ->  passed  [0.016s]
lib/libpam/t_openpam_readlinev:main  ->  passed  [0.090s]
lib/libpam/t_openpam_readword:main  ->  May 15 04:50:35  t_openpam_readword: in openpam_readword(): unexpected end of file
passed  [0.124s]
lib/libthr/barrier_test:barrier  ->  passed  [10.257s]
lib/libthr/cond_test:bogus_timedwaits  ->  passed  [0.017s]
lib/libthr/cond_test:broadcast  ->  passed  [2.798s]
lib/libthr/cond_test:cond_timedwait_race  ->  passed  [4.698s]
lib/libthr/cond_test:destroy_after_cancel  ->  passed  [0.018s]
lib/libthr/cond_test:signal_before_unlock  ->  passed  [2.227s]
lib/libthr/cond_test:signal_before_unlock_static_init  ->  passed  [2.115s]
lib/libthr/cond_test:signal_delay_wait  ->  passed  [2.027s]
lib/libthr/cond_test:signal_wait_race  ->  passed  [5.953s]
lib/libthr/condwait_test:cond_wait_mono  ->  passed  [2.120s]
lib/libthr/condwait_test:cond_wait_real  ->  passed  [2.287s]
lib/libthr/detach_test:pthread_detach  ->  May 15 04:50:35  last message repeated 2 times
passed  [4.083s]
lib/libthr/equal_test:pthread_equal  ->  passed  [0.165s]
lib/libthr/fork_test:fork  ->  passed  [5.082s]
lib/libthr/fpu_test:fpu  ->  passed  [0.022s]
lib/libthr/join_test:pthread_join  ->  passed  [0.034s]
lib/libthr/kill_test:simple  ->  passed  [0.037s]
lib/libthr/mutex_test:mutex1  ->  passed  [4.097s]
lib/libthr/mutex_test:mutex2  ->  passed  [2.067s]
lib/libthr/mutex_test:mutex3  ->  passed  [2.386s]
lib/libthr/mutex_test:mutex4  ->  passed  [4.039s]
lib/libthr/once_test:once1  ->  passed  [0.018s]
lib/libthr/once_test:once2  ->  passed  [0.074s]
lib/libthr/once_test:once3  ->  passed  [0.149s]
lib/libthr/preempt_test:preempt1  ->  passed  [1.098s]
lib/libthr/rwlock_test:rwlock1  ->  passed  [2.147s]
lib/libthr/sem_test:before_start_no_threads  ->  passed  [8.825s]
lib/libthr/sem_test:before_start_one_thread  ->  passed  [3.300s]
lib/libthr/sem_test:named  ->  passed  [0.019s]
lib/libthr/sem_test:unnamed  ->  passed  [0.244s]
lib/libthr/sigmask_test:before_threads  ->  passed  [0.016s]
lib/libthr/sigmask_test:incorrect_mask_bug  ->  passed  [3.071s]
lib/libthr/sigmask_test:respected_while_running  ->  passed  [1.082s]
lib/libthr/sigmask_test:upcalls_not_started  ->  passed  [0.019s]
lib/libthr/sigsuspend_test:sigsuspend  ->  passed  [1.088s]
lib/libthr/siglongjmp_test:siglongjmp1  ->  passed  [0.019s]
lib/libthr/sleep_test:sleep1  ->  passed  [1.084s]
lib/libthr/swapcontext_test:swapcontext1  ->  passed  [0.197s]
lib/libthr/atexit_test:atexit  ->  passed  [5.322s]
lib/libthr/cancel_test:register_while_disabled  ->  passed  [2.429s]
lib/libthr/exit_test:main_thread  ->  passed  [0.144s]
lib/libthr/resolv_test:resolv  ->  passed  [2.280s]
lib/libthr/dlopen/dlopen_test:dlopen  ->  passed  [0.021s]
lib/libthr/dlopen/dlopen_test:dlopen_mutex  ->  passed  [0.073s]
lib/libthr/dlopen/dlopen_test:dlopen_mutex_libc  ->  passed  [0.043s]
lib/libthr/dlopen/dlopen_test:dlopen_mutex_libpthread  ->  passed  [0.018s]
lib/libthr/dlopen/main_pthread_create_test:main_pthread_create_dso  ->  passed  [0.018s]
lib/libthr/dlopen/main_pthread_create_test:main_pthread_create_main  ->  passed  [0.071s]
usr.sbin/newsyslog/legacy_test:main  ->  passed  [60.448s]
usr.sbin/pw/pw_etcdir:etcdir_must_exist  ->  passed  [0.876s]
usr.sbin/pw/pw_lock:user_locking  ->  passed  [0.784s]
usr.sbin/pw/pw_groupdel:group_do_not_delete_wheel_if_group_unknown  ->  passed  [0.264s]
usr.sbin/pw/pw_groupmod:do_not_duplicate_group_on_gid_change  ->  passed  [0.115s]
usr.sbin/pw/pw_groupmod:groupmod_bug_193704  ->  passed  [0.115s]
usr.sbin/pw/pw_groupmod:groupmod_invalid_user  ->  passed  [0.174s]
usr.sbin/pw/pw_groupmod:groupmod_user  ->  passed  [0.496s]
usr.sbin/pw/pw_groupmod:usermod_bug_185666  ->  passed  [0.272s]
usr.sbin/pw/pw_useradd:user_add  ->  passed  [0.119s]
usr.sbin/pw/pw_useradd:user_add_account_expiration_date_month  ->  passed  [0.155s]
usr.sbin/pw/pw_useradd:user_add_account_expiration_date_numeric  ->  passed  [0.115s]
usr.sbin/pw/pw_useradd:user_add_account_expiration_date_relative  ->  passed  [0.115s]
usr.sbin/pw/pw_useradd:user_add_account_expiration_epoch  ->  passed  [0.261s]
usr.sbin/pw/pw_useradd:user_add_comments  ->  passed  [0.230s]
usr.sbin/pw/pw_useradd:user_add_comments_invalid  ->  passed  [0.108s]
usr.sbin/pw/pw_useradd:user_add_comments_invalid_noupdate  ->  passed  [0.174s]
usr.sbin/pw/pw_useradd:user_add_comments_noupdate  ->  passed  [0.103s]
usr.sbin/pw/pw_useradd:user_add_homedir  ->  passed  [0.137s]
usr.sbin/pw/pw_useradd:user_add_noupdate  ->  passed  [0.996s]
usr.sbin/pw/pw_useradd:user_add_password_expiration_date_month  ->  passed  [0.203s]
usr.sbin/pw/pw_useradd:user_add_password_expiration_date_numeric  ->  passed  [0.203s]
usr.sbin/pw/pw_useradd:user_add_password_expiration_date_relative  ->  passed  [0.204s]
usr.sbin/pw/pw_useradd:user_add_password_expiration_epoch  ->  passed  [0.204s]
usr.sbin/pw/pw_userdel:rmuser_seperate_group  ->  passed  [0.313s]
usr.sbin/pw/pw_userdel:user_do_not_try_to_delete_root_if_user_unknown  ->  passed  [0.077s]
usr.sbin/pw/pw_usermod:user_mod  ->  passed  [0.203s]
usr.sbin/pw/pw_usermod:user_mod_comments  ->  passed  [0.144s]
usr.sbin/pw/pw_usermod:user_mod_comments_invalid  ->  passed  [0.167s]
usr.sbin/pw/pw_usermod:user_mod_comments_invalid_noupdate  ->  passed  [0.157s]
usr.sbin/pw/pw_usermod:user_mod_comments_noupdate  ->  passed  [0.332s]
usr.sbin/pw/pw_usermod:user_mod_name  ->  passed  [0.336s]
usr.sbin/pw/pw_usermod:user_mod_name_noupdate  ->  passed  [0.128s]
usr.sbin/pw/pw_usermod:user_mod_noupdate  ->  passed  [0.201s]
usr.sbin/pw/pw_usernext:usernext  ->  ahcich0: Timeout on slot 24 port 0
ahcich0: is 00000000 cs 00000000 ss 01000000 rs 01000000 tfd 50 serr 00000000 cmd 1000d817
(ada0:ahcich0:0:0:0): WRITE_FPDMA_QUEUED. ACB: 61 80 c0 98 21 40 00 00 00 00 00 00
(ada0:ahcich0:0:0:0): CAM status: Command timeout
(ada0:ahcich0:0:0:0): Retrying command
broken: Test case body timed out  [309.064s]
usr.sbin/pw/pw_usernext:usernext_assigned_group  ->  passed  [10.099s]
usr.sbin/pw/pw_test:longname  ->  ahcich0: Timeout on slot 22 port 0
ahcich0: is 00000000 cs 00000000 ss 00400000 rs 00400000 tfd 50 serr 00000000 cmd 1000d617
(ada0:ahcich0:0:0:0): WRITE_FPDMA_QUEUED. ACB: 61 08 e8 89 14 40 00 00 00 00 00 00
(ada0:ahcich0:0:0:0): CAM status: Command timeout
(ada0:ahcich0:0:0:0): Retrying command
passed  [100.453s]
usr.sbin/sa/legacy_test:main  ->  passed  [4.850s]
usr.sbin/nmtree/nmtree_test:mtree_check  ->  passed  [4.126s]
usr.sbin/nmtree/nmtree_test:mtree_convert_C  ->  passed  [0.102s]
usr.sbin/nmtree/nmtree_test:mtree_convert_C_S  ->  passed  [0.067s]
usr.sbin/nmtree/nmtree_test:mtree_convert_D  ->  passed  [0.062s]
usr.sbin/nmtree/nmtree_test:mtree_convert_D_S  ->  passed  [0.129s]
usr.sbin/nmtree/nmtree_test:mtree_create  ->  passed  [1.854s]
usr.sbin/nmtree/nmtree_test:mtree_ignore  ->  passed  [0.292s]
usr.sbin/nmtree/nmtree_test:mtree_merge  ->  passed  [0.076s]
usr.sbin/nmtree/nmtree_test:mtree_nonemptydir  ->  passed  [0.880s]
usr.sbin/nmtree/nmtree_test:netbsd6_check  ->  passed  [15.004s]
usr.sbin/nmtree/nmtree_test:netbsd6_convert_C  ->  passed  [0.061s]
usr.sbin/nmtree/nmtree_test:netbsd6_convert_C_S  ->  passed  [0.536s]
usr.sbin/nmtree/nmtree_test:netbsd6_convert_D  ->  passed  [0.081s]
usr.sbin/nmtree/nmtree_test:netbsd6_convert_D_S  ->  passed  [0.051s]
usr.sbin/nmtree/nmtree_test:netbsd6_create  ->  passed  [3.046s]
usr.sbin/nmtree/nmtree_test:netbsd6_ignore  ->  passed  [2.564s]
usr.sbin/nmtree/nmtree_test:netbsd6_merge  ->  passed  [0.057s]
usr.sbin/nmtree/nmtree_test:netbsd6_nonemptydir  ->  passed  [0.179s]
usr.sbin/etcupdate/always_test:main  ->  Traceback (most recent call last):
  File "/vm/freebsd-ci/scripts/test/run-tests.py", line 152, in <module>
    main(sys.argv)
  File "/vm/freebsd-ci/scripts/test/run-tests.py", line 80, in main
    runTest()
  File "/vm/freebsd-ci/scripts/test/run-tests.py", line 124, in runTest
    child2.expect(prompt, timeout=7200)
  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 0x803eebad0>
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,tap0,mac=58:9c:fc:00:00:2e', u'-s', u'3:0,ahci-hd,/net/jenkins-10.freebsd.org/builds/Build-UFS-image/image/FreeBSD_HEAD/test.img', u'-l', u'com1,stdio', u'vm_test']
searcher: <pexpect.searcher_re object at 0x803eebb90>
buffer (last 100 chars): 'tree/nmtree_test:netbsd6_nonemptydir  ->  passed  [0.179s]\nusr.sbin/etcupdate/always_test:main  ->  '
before (last 100 chars): 'tree/nmtree_test:netbsd6_nonemptydir  ->  passed  [0.179s]\nusr.sbin/etcupdate/always_test:main  ->  '
after: <class 'pexpect.TIMEOUT'>
match: None
match_index: None
exitstatus: None
flag_eof: False
pid: 38414
child_fd: 4
closed: False
timeout: 30
delimiter: <class 'pexpect.EOF'>
logfile: <open file '<stdout>', mode 'w' at 0x800670150>
logfile_read: None
logfile_send: None
maxread: 2000
ignorecase: False
searchwindowsize: None
delaybeforesend: 0.05
delayafterclose: 0.1
delayafterterminate: 0.1
Build step 'Execute shell' marked build as failure
Recording test results
ERROR: Publisher hudson.tasks.junit.JUnitResultArchiver aborted due to exception
hudson.AbortException: Test reports were found but none of them are new. Did tests run? 
For example, <https://jenkins.freebsd.org/job/FreeBSD_HEAD-tests2/ws/test-report.xml> is 6 hr 26 min old

	at hudson.tasks.junit.TestResult.parse(TestResult.java:178)
	at hudson.tasks.junit.TestResult.parse(TestResult.java:146)
	at hudson.tasks.junit.TestResult.<init>(TestResult.java:122)
	at hudson.tasks.junit.JUnitParser$ParseResultCallable.invoke(JUnitParser.java:119)
	at hudson.tasks.junit.JUnitParser$ParseResultCallable.invoke(JUnitParser.java:93)
	at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2691)
	at hudson.remoting.UserRequest.perform(UserRequest.java:121)
	at hudson.remoting.UserRequest.perform(UserRequest.java:49)
	at hudson.remoting.Request$2.run(Request.java:325)
	at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:68)
	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
	at java.lang.Thread.run(Thread.java:745)
	at ......remote call to havoc.ysv.freebsd.org(Native Method)
	at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1361)
	at hudson.remoting.UserResponse.retrieve(UserRequest.java:221)
	at hudson.remoting.Channel.call(Channel.java:753)
	at hudson.FilePath.act(FilePath.java:980)
	at hudson.FilePath.act(FilePath.java:969)
	at hudson.tasks.junit.JUnitParser.parseResult(JUnitParser.java:90)
	at hudson.tasks.junit.JUnitResultArchiver.parse(JUnitResultArchiver.java:120)
	at hudson.tasks.junit.JUnitResultArchiver.perform(JUnitResultArchiver.java:137)
	at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:75)
	at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
	at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:764)
	at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:724)
	at hudson.model.Build$BuildExecution.post2(Build.java:185)
	at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:671)
	at hudson.model.Run.execute(Run.java:1769)
	at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
	at hudson.model.ResourceController.execute(ResourceController.java:98)
	at hudson.model.Executor.run(Executor.java:374)