See <http://jenkins.buildacloud.org/job/simulator-singlerun/1120/>
------------------------------------------ [...truncated 10502 lines...] log4j:WARN Please initialize the log4j system properly. log4j:WARN See http://logging.apache.org/log4j/1.2/faq.html#noconfig for more info. ========> WARNING: Provided file does not exist: <http://jenkins.buildacloud.org/job/simulator-singlerun/ws/developer/../utils/conf/db.properties.override> ========> Initializing database=simulator with host=localhost port=3306 username=cloud password=cloud ============> Running query: drop database if exists `simulator` ============> Running query: create database `simulator` ============> Running query: GRANT ALL ON simulator.* to 'cloud'@`localhost` identified by 'cloud' ============> Running query: GRANT ALL ON simulator.* to 'cloud'@`%` identified by 'cloud' ========> Processing SQL file at <http://jenkins.buildacloud.org/job/simulator-singlerun/ws/developer/target/db/create-schema-simulator.sql> ========> Processing SQL file at <http://jenkins.buildacloud.org/job/simulator-singlerun/ws/developer/target/db/templates.simulator.sql> ========> Processing SQL file at <http://jenkins.buildacloud.org/job/simulator-singlerun/ws/developer/target/db/hypervisor_capabilities.simulator.sql> ========> Processing upgrade: com.cloud.upgrade.DatabaseUpgradeChecker [INFO] [INFO] --- maven-site-plugin:3.3:attach-descriptor (attach-descriptor) @ cloud-developer --- [INFO] [INFO] --- maven-install-plugin:2.3.1:install (default-install) @ cloud-developer --- [INFO] Installing <http://jenkins.buildacloud.org/job/simulator-singlerun/ws/developer/pom.xml> to /var/lib/jenkins/.m2/repository/org/apache/cloudstack/cloud-developer/4.6.0-SNAPSHOT/cloud-developer-4.6.0-SNAPSHOT.pom [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 17.136s [INFO] Finished at: Fri Apr 17 05:59:08 EDT 2015 [INFO] Final Memory: 45M/207M [INFO] ------------------------------------------------------------------------ [simulator-singlerun] $ /bin/bash -x /tmp/hudson8225810946323218159.sh + jps -l + grep -q Launcher + rm -f xunit.xml + echo '' + rm -rf /tmp/MarvinLogs + echo Check for initialization of the management server Check for initialization of the management server + COUNTER=0 + SERVER_PID=17463 + mvn -P systemvm,simulator -pl :cloud-client-ui jetty:run + '[' 0 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=1 + '[' 1 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=2 + '[' 2 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=3 + '[' 3 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=4 + '[' 4 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=5 + '[' 5 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=6 + '[' 6 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=7 + '[' 7 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=8 + '[' 8 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=9 + '[' 9 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=10 + '[' 10 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=11 + '[' 11 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=12 + '[' 12 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=13 + '[' 13 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=14 + '[' 14 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=15 + '[' 15 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=16 + '[' 16 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=17 + '[' 17 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=18 + '[' 18 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=19 + '[' 19 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=20 + '[' 20 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=21 + '[' 21 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + sleep 5 + COUNTER=22 + '[' 22 -lt 44 ']' + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + break + grep -q 'Management server node 127.0.0.1 is up' jetty-output.out + echo Started OK pid 17463 Started OK pid 17463 + sleep 20 + export PYTHONPATH=<http://jenkins.buildacloud.org/job/simulator-singlerun/ws/tools/marvin> + PYTHONPATH=<http://jenkins.buildacloud.org/job/simulator-singlerun/ws/tools/marvin> + python2.6 tools/marvin/marvin/deployDataCenter.py -i setup/dev/advanced.cfg /usr/lib/python2.6/site-packages/pycrypto-2.6-py2.6-linux-x86_64.egg/Crypto/Util/number.py:57: PowmInsecureWarning: Not using mpz_powm_sec. You should rebuild using libgmp >= 5 to avoid timing attack vulnerability. _warn("Not using mpz_powm_sec. You should rebuild using libgmp >= 5 to avoid timing attack vulnerability.", PowmInsecureWarning) ==== Log Folder Path: /tmp//MarvinLogs//DeployDataCenter__Apr_17_2015_06_01_21_OKZGTB. All logs will be available here ==== ==== Deploy DC Started ==== === Data Center Settings are dumped to /tmp//MarvinLogs//DeployDataCenter__Apr_17_2015_06_01_21_OKZGTB/dc_entries.obj=== ====Deploy DC Successful===== + pip install --upgrade tools/marvin/dist/Marvin-4.6.0-SNAPSHOT.tar.gz Unpacking ./tools/marvin/dist/Marvin-4.6.0-SNAPSHOT.tar.gz Running setup.py egg_info for package from file://<http://jenkins.buildacloud.org/job/simulator-singlerun/ws/tools/marvin/dist/Marvin-4.6.0-SNAPSHOT.tar.gz> warning: no files found matching '*.txt' under directory 'docs' Downloading/unpacking mysql-connector-python>=1.1.6 from http://cdn.mysql.com/Downloads/Connector-Python/mysql-connector-python-2.0.3.zip#md5=9fda73a7f69e769e6a545c98b6739514 (from Marvin==4.6.0-SNAPSHOT) Running setup.py egg_info for package mysql-connector-python Downloading/unpacking requests>=2.2.1 from https://pypi.python.org/packages/source/r/requests/requests-2.6.0.tar.gz#md5=25287278fa3ea106207461112bb37050 (from Marvin==4.6.0-SNAPSHOT) Running setup.py egg_info for package requests Downloading/unpacking paramiko>=1.13.0 from https://pypi.python.org/packages/source/p/paramiko/paramiko-1.15.2.tar.gz#md5=6bbfb328fe816c3d3652ba6528cc8b4c (from Marvin==4.6.0-SNAPSHOT) Running setup.py egg_info for package paramiko Downloading/unpacking nose>=1.3.3 from https://pypi.python.org/packages/source/n/nose/nose-1.3.6.tar.gz#md5=0ca546d81ca8309080fc80cb389e7a16 (from Marvin==4.6.0-SNAPSHOT) Running setup.py egg_info for package nose no previously-included directories found matching 'doc/.build' Downloading/unpacking ddt>=0.4.0 from https://pypi.python.org/packages/source/d/ddt/ddt-1.0.0.tar.gz#md5=29688456f9ee42d09d7d7c864ce6e17b (from Marvin==4.6.0-SNAPSHOT) Downloading ddt-1.0.0.tar.gz Running setup.py egg_info for package ddt Downloading/unpacking pyvmomi>=5.5.0 (from Marvin==4.6.0-SNAPSHOT) Running setup.py egg_info for package pyvmomi Downloading/unpacking netaddr>=0.7.14 (from Marvin==4.6.0-SNAPSHOT) Running setup.py egg_info for package netaddr warning: no previously-included files matching '*.svn*' found anywhere in distribution warning: no previously-included files matching '*.git*' found anywhere in distribution Downloading/unpacking pycrypto>=2.1,!=2.4 from https://pypi.python.org/packages/source/p/pycrypto/pycrypto-2.6.1.tar.gz#md5=55a61a054aa66812daf5161a0d5d7eda (from paramiko>=1.13.0->Marvin==4.6.0-SNAPSHOT) Running setup.py egg_info for package pycrypto Downloading/unpacking ecdsa>=0.11 from https://pypi.python.org/packages/source/e/ecdsa/ecdsa-0.13.tar.gz#md5=1f60eda9cb5c46722856db41a3ae6670 (from paramiko>=1.13.0->Marvin==4.6.0-SNAPSHOT) Running setup.py egg_info for package ecdsa Downloading/unpacking six>=1.7.3 (from pyvmomi>=5.5.0->Marvin==4.6.0-SNAPSHOT) Downloading six-1.9.0.tar.gz Running setup.py egg_info for package six no previously-included directories found matching 'documentation/_build' Installing collected packages: mysql-connector-python, requests, paramiko, nose, ddt, pyvmomi, netaddr, Marvin, pycrypto, ecdsa, six Found existing installation: mysql-connector-python 1.2.2 Uninstalling mysql-connector-python: Exception: Traceback (most recent call last): File "/usr/local/lib/python2.7/site-packages/pip-1.3.1-py2.7.egg/pip/basecommand.py", line 139, in main status = self.run(options, args) File "/usr/local/lib/python2.7/site-packages/pip-1.3.1-py2.7.egg/pip/commands/install.py", line 271, in run requirement_set.install(install_options, global_options, root=options.root_path) File "/usr/local/lib/python2.7/site-packages/pip-1.3.1-py2.7.egg/pip/req.py", line 1181, in install requirement.uninstall(auto_confirm=True) File "/usr/local/lib/python2.7/site-packages/pip-1.3.1-py2.7.egg/pip/req.py", line 495, in uninstall paths_to_remove.remove(auto_confirm) File "/usr/local/lib/python2.7/site-packages/pip-1.3.1-py2.7.egg/pip/req.py", line 1518, in remove renames(path, new_path) File "/usr/local/lib/python2.7/site-packages/pip-1.3.1-py2.7.egg/pip/util.py", line 293, in renames shutil.move(old, new) File "/usr/local/lib/python2.7/shutil.py", line 290, in move rmtree(src) File "/usr/local/lib/python2.7/shutil.py", line 244, in rmtree rmtree(fullname, ignore_errors, onerror) File "/usr/local/lib/python2.7/shutil.py", line 249, in rmtree onerror(os.remove, fullname, sys.exc_info()) File "/usr/local/lib/python2.7/shutil.py", line 247, in rmtree os.remove(fullname) OSError: [Errno 13] Permission denied: '/usr/local/lib/python2.7/site-packages/mysql_connector_python-1.2.2-py2.7.egg/EGG-INFO/zip-safe' Storing complete log in /var/lib/jenkins/.pip/pip.log + sleep 60 + /usr/local/bin/nosetests-2.7 -v --with-marvin --marvin-config=setup/dev/advanced.cfg --with-xunit --xunit-file=xunit.xml -a tags=advanced,required_hardware=false --zone=Sandbox-simulator --hypervisor=simulator -w test/integration/smoke /usr/local/lib/python2.7/site-packages/nose-1.3.3-py2.7.egg/nose/plugins/manager.py:395: RuntimeWarning: Unable to load plugin marvinPlugin = marvin.marvinPlugin:MarvinPlugin: netaddr>=0.7.14 RuntimeWarning) Usage: nosetests-2.7 [options] nosetests-2.7: error: no such option: --with-marvin + mvn -P systemvm,simulator -pl :cloud-client-ui jetty:stop [INFO] Scanning for projects... [INFO] [INFO] ------------------------------------------------------------------------ [INFO] Building Apache CloudStack Client UI 4.6.0-SNAPSHOT [INFO] ------------------------------------------------------------------------ [INFO] [INFO] --- maven-jetty-plugin:6.1.26:stop (default-cli) @ cloud-client-ui --- [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 4.236s [INFO] Finished at: Fri Apr 17 06:05:44 EDT 2015 [INFO] Final Memory: 26M/112M [INFO] ------------------------------------------------------------------------ + sleep 10 + kill -KILL 17463 /tmp/hudson8225810946323218159.sh: line 45: kill: (17463) - No such process + rm -rf '*.log.*' [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released [xUnit] [INFO] - Starting to record. [xUnit] [INFO] - Processing JUnit [xUnit] [INFO] - [JUnit] - 1 test report file(s) were found with the pattern 'xunit.xml' relative to '<http://jenkins.buildacloud.org/job/simulator-singlerun/ws/'> for the testing framework 'JUnit'. [xUnit] [ERROR] - The result file '<http://jenkins.buildacloud.org/job/simulator-singlerun/ws/xunit.xml'> for the metric 'JUnit' is not valid. The result file has been skipped. [xUnit] [INFO] - Failing BUILD because 'set build failed if errors' option is activated. [xUnit] [INFO] - There are errors when processing test results. [xUnit] [INFO] - Skipping tests recording. [xUnit] [INFO] - Stop build. Build step 'Publish xUnit test result report' changed build result to FAILURE