Open Stack

See <https://jenkins.openstack.org/job/periodic-tempest-devstack-vm-stress/8/>

------------------------------------------
[...truncated 10505 lines...]
2013-07-16 06:05:55 + return 1
2013-07-16 06:05:55 + is_service_enabled mysql postgresql
2013-07-16 06:05:55 + services='mysql postgresql'
2013-07-16 06:05:55 + for service in '${services}'
2013-07-16 06:05:55 + [[ ,g-api,g-reg,key,n-api,n-crt,n-obj,n-cpu,n-sch,horizon,mysql,rabbit,sysstat,tempest,s-proxy,s-account,s-container,s-object,cinder,c-api,c-vol,c-sch,n-cond,heat,h-api,h-api-cfn,h-api-cw,h-eng,ceilometer-acompute,ceilometer-acentral,ceilometer-collector,ceilometer-api,n-net, =~ ,mysql, ]]
2013-07-16 06:05:55 + return 0
2013-07-16 06:05:55 + configure_database
2013-07-16 06:05:55 + configure_database_mysql
2013-07-16 06:05:55 + echo_summary 'Configuring and starting MySQL'
2013-07-16 06:05:55 + [[ -t 3 ]]
2013-07-16 06:05:55 + echo -e Configuring and starting MySQL
2013-07-16 06:05:55 + is_ubuntu
2013-07-16 06:05:55 + [[ -z deb ]]
2013-07-16 06:05:55 + '[' deb = deb ']'
2013-07-16 06:05:55 + MY_CONF=/etc/mysql/my.cnf
2013-07-16 06:05:55 + MYSQL=mysql
2013-07-16 06:05:55 + is_fedora
2013-07-16 06:05:55 + [[ -z Ubuntu ]]
2013-07-16 06:05:55 + '[' Ubuntu = Fedora ']'
2013-07-16 06:05:55 + '[' Ubuntu = 'Red Hat' ']'
2013-07-16 06:05:55 + '[' Ubuntu = CentOS ']'
2013-07-16 06:05:55 + is_suse
2013-07-16 06:05:55 Configuring and starting MySQL
2013-07-16 06:05:55 + [[ -z Ubuntu ]]
2013-07-16 06:05:55 + '[' Ubuntu = openSUSE ']'
2013-07-16 06:05:55 + '[' Ubuntu = 'SUSE LINUX' ']'
2013-07-16 06:05:55 + is_ubuntu
2013-07-16 06:05:55 + [[ -z deb ]]
2013-07-16 06:05:55 + '[' deb = deb ']'
2013-07-16 06:05:55 + sudo mysql -uroot -psecret -h127.0.0.1 -e 'GRANT ALL PRIVILEGES ON *.* TO '\''root'\''@'\''%'\'' identified by '\''secret'\'';'
2013-07-16 06:05:55 + sudo sed -i '/^bind-address/s/127.0.0.1/0.0.0.0/g' /etc/mysql/my.cnf
2013-07-16 06:05:55 + sudo grep -q default-storage-engine /etc/mysql/my.cnf
2013-07-16 06:05:55 + sudo sed -i -e '/^\[mysqld\]/ a default-storage-engine = InnoDB' /etc/mysql/my.cnf
2013-07-16 06:05:55 + sudo sed -i /log.slow.queries/d /etc/mysql/my.cnf
2013-07-16 06:05:55 + sudo sed -i -e '/^\[mysqld\]/ a log-slow-queries = /var/log/mysql/mysql-slow.log' /etc/mysql/my.cnf
2013-07-16 06:05:55 + sudo sed -i /long.query.time/d /etc/mysql/my.cnf
2013-07-16 06:05:55 + sudo sed -i -e '/^\[mysqld\]/ a long-query-time = 0' /etc/mysql/my.cnf
2013-07-16 06:05:55 + sudo sed -i /log.queries.not.using.indexes/d /etc/mysql/my.cnf
2013-07-16 06:05:55 + sudo sed -i -e '/^\[mysqld\]/ a log-queries-not-using-indexes' /etc/mysql/my.cnf
2013-07-16 06:05:55 + restart_service mysql
2013-07-16 06:05:55 + is_ubuntu
2013-07-16 06:05:55 + [[ -z deb ]]
2013-07-16 06:05:55 + '[' deb = deb ']'
2013-07-16 06:05:55 + sudo /usr/sbin/service mysql restart
2013-07-16 06:05:55 mysql stop/waiting
2013-07-16 06:05:55 mysql start/running, process 10398
2013-07-16 06:05:55 ++ trueorfalse True False
2013-07-16 06:05:55 ++ local default=True
2013-07-16 06:05:55 ++ local testval=False
2013-07-16 06:05:55 ++ [[ -z False ]]
2013-07-16 06:05:55 ++ [[ 0 no false False FALSE =~ False ]]
2013-07-16 06:05:55 ++ echo False
2013-07-16 06:05:55 ++ return
2013-07-16 06:05:55 + USE_SCREEN=False
2013-07-16 06:05:55 + [[ False == \T\r\u\e ]]
2013-07-16 06:05:55 + SCREENRC=/opt/stack/new/devstack/stack-screenrc
2013-07-16 06:05:57 + [[ -e /opt/stack/new/devstack/stack-screenrc ]]
2013-07-16 06:05:57 + init_service_check
+ exec
2013-07-16 06:05:57 + SCREEN_NAME=stack
+ exec
+ [[ -n /opt/stack/new/screen-logs ]]
+ exec
2013-07-16 06:05:57 + SERVICE_DIR=/opt/stack/new/status
2013-07-16 06:05:57 + [[ ! -d /opt/stack/new/status/stack ]]
2013-07-16 06:05:57 + mkdir -p /opt/stack/new/status/stack
2013-07-16 06:05:57 + rm -f '/opt/stack/new/status/stack/*.failure'
2013-07-16 06:05:57 + is_service_enabled sysstat
2013-07-16 06:05:57 + services=sysstat
2013-07-16 06:05:57 + for service in '${services}'
2013-07-16 06:05:57 + [[ ,g-api,g-reg,key,n-api,n-crt,n-obj,n-cpu,n-sch,horizon,mysql,rabbit,sysstat,tempest,s-proxy,s-account,s-container,s-object,cinder,c-api,c-vol,c-sch,n-cond,heat,h-api,h-api-cfn,h-api-cw,h-eng,ceilometer-acompute,ceilometer-acentral,ceilometer-collector,ceilometer-api,n-net, =~ ,sysstat, ]]
2013-07-16 06:05:57 + return 0
2013-07-16 06:05:57 + [[ -n /opt/stack/new/screen-logs ]]
2013-07-16 06:05:57 + screen_it sysstat 'sar -o /opt/stack/new/screen-logs/sysstat.dat 1'
2013-07-16 06:05:57 + SCREEN_NAME=stack
2013-07-16 06:05:57 + SERVICE_DIR=/opt/stack/new/status
2013-07-16 06:05:57 ++ trueorfalse True False
2013-07-16 06:05:57 ++ local default=True
2013-07-16 06:05:57 ++ local testval=False
2013-07-16 06:05:57 ++ [[ -z False ]]
2013-07-16 06:05:57 ++ [[ 0 no false False FALSE =~ False ]]
2013-07-16 06:05:57 ++ echo False
2013-07-16 06:05:57 ++ return
2013-07-16 06:05:57 + USE_SCREEN=False
2013-07-16 06:05:57 + is_service_enabled sysstat
2013-07-16 06:05:57 + services=sysstat
2013-07-16 06:05:57 + for service in '${services}'
2013-07-16 06:05:57 + [[ ,g-api,g-reg,key,n-api,n-crt,n-obj,n-cpu,n-sch,horizon,mysql,rabbit,sysstat,tempest,s-proxy,s-account,s-container,s-object,cinder,c-api,c-vol,c-sch,n-cond,heat,h-api,h-api-cfn,h-api-cw,h-eng,ceilometer-acompute,ceilometer-acentral,ceilometer-collector,ceilometer-api,n-net, =~ ,sysstat, ]]
2013-07-16 06:05:57 + return 0
2013-07-16 06:05:57 + screen_rc sysstat 'sar -o /opt/stack/new/screen-logs/sysstat.dat 1'
2013-07-16 06:05:57 + SCREEN_NAME=stack
2013-07-16 06:05:57 + SCREENRC=/opt/stack/new/devstack/stack-screenrc
2013-07-16 06:05:57 + [[ ! -e /opt/stack/new/devstack/stack-screenrc ]]
2013-07-16 06:05:57 + echo 'sessionname stack'
2013-07-16 06:05:57 + echo 'hardstatus alwayslastline '\'''\'''
2013-07-16 06:05:57 + echo 'setenv PROMPT_COMMAND /bin/true'
2013-07-16 06:05:57 + echo 'screen -t shell bash'
2013-07-16 06:05:57 + grep sysstat /opt/stack/new/devstack/stack-screenrc
2013-07-16 06:05:57 ++ echo -ne '\015'
2013-07-16 06:05:57 + NL=$'\r'
2013-07-16 06:05:57 + echo 'screen -t sysstat bash'
2013-07-16 06:05:57 + echo 'stuff "sar -o /opt/stack/new/screen-logs/sysstat.dat 1
"'
2013-07-16 06:05:57 + [[ False = \T\r\u\e ]]
2013-07-16 06:05:57 + run_process sysstat 'sar -o /opt/stack/new/screen-logs/sysstat.dat 1'
2013-07-16 06:05:57 + local service=sysstat
2013-07-16 06:05:57 + local 'command=sar -o /opt/stack/new/screen-logs/sysstat.dat 1'
2013-07-16 06:05:57 + echo 11032
2013-07-16 06:05:57 + _run_process sysstat 'sar -o /opt/stack/new/screen-logs/sysstat.dat 1'
2013-07-16 06:05:57 + local service=sysstat
2013-07-16 06:05:57 + local 'command=sar -o /opt/stack/new/screen-logs/sysstat.dat 1'
2013-07-16 06:05:57 + exec
2013-07-16 06:05:57 + exec
2013-07-16 06:05:57 + is_service_enabled key
2013-07-16 06:05:57 + services=key
2013-07-16 06:05:57 + for service in '${services}'
2013-07-16 06:05:57 + [[ ,g-api,g-reg,key,n-api,n-crt,n-obj,n-cpu,n-sch,horizon,mysql,rabbit,sysstat,tempest,s-proxy,s-account,s-container,s-object,cinder,c-api,c-vol,c-sch,n-cond,heat,h-api,h-api-cfn,h-api-cw,h-eng,ceilometer-acompute,ceilometer-acentral,ceilometer-collector,ceilometer-api,n-net, =~ ,key, ]]
2013-07-16 06:05:57 + return 0
2013-07-16 06:05:57 + echo_summary 'Starting Keystone'
2013-07-16 06:05:57 + [[ -t 3 ]]
2013-07-16 06:05:57 + echo -e Starting Keystone
2013-07-16 06:05:57 Starting Keystone
2013-07-16 06:05:57 + init_keystone
2013-07-16 06:05:57 + recreate_database keystone utf8
2013-07-16 06:05:57 + local db=keystone
2013-07-16 06:05:57 + local charset=utf8
2013-07-16 06:05:57 + recreate_database_mysql keystone utf8
2013-07-16 06:05:57 + local db=keystone
2013-07-16 06:05:57 + local charset=utf8
2013-07-16 06:05:57 + mysql -uroot -psecret -hlocalhost -e 'DROP DATABASE IF EXISTS keystone;'
2013-07-16 06:05:57 + mysql -uroot -psecret -hlocalhost -e 'CREATE DATABASE keystone CHARACTER SET utf8;'
2013-07-16 06:05:57 + /opt/stack/new/keystone/bin/keystone-manage db_sync
2013-07-16 06:05:57 + [[ PKI == \P\K\I ]]
2013-07-16 06:05:57 + rm -rf /etc/keystone/ssl
2013-07-16 06:05:57 + /opt/stack/new/keystone/bin/keystone-manage pki_setup
2013-07-16 06:05:57 Generating RSA private key, 2048 bit long modulus
2013-07-16 06:05:57 .....................................................+++
2013-07-16 06:05:57 .......................+++
2013-07-16 06:05:57 e is 65537 (0x10001)
2013-07-16 06:05:57 Generating RSA private key, 2048 bit long modulus
2013-07-16 06:05:57 ..................+++
2013-07-16 06:05:57 ......+++
2013-07-16 06:05:57 e is 65537 (0x10001)
2013-07-16 06:05:57 Using configuration from /etc/keystone/ssl/certs/openssl.conf
2013-07-16 06:05:57 Check that the request matches the signature
2013-07-16 06:05:57 Signature ok
2013-07-16 06:05:57 The Subject's Distinguished Name is as follows
2013-07-16 06:05:57 countryName           :PRINTABLE:'US'
2013-07-16 06:05:57 stateOrProvinceName   :PRINTABLE:'Unset'
2013-07-16 06:05:57 localityName          :PRINTABLE:'Unset'
2013-07-16 06:05:57 organizationName      :PRINTABLE:'Unset'
2013-07-16 06:05:57 commonName            :PRINTABLE:'www.example.com'
2013-07-16 06:05:57 Certificate is to be certified until Jul 14 06:05:57 2023 GMT (3650 days)
2013-07-16 06:05:57 
2013-07-16 06:05:57 Write out database with 1 new entries
2013-07-16 06:05:57 Data Base Updated
2013-07-16 06:05:57 + sudo mkdir -p /var/cache/keystone
2013-07-16 06:05:57 + sudo chown stack /var/cache/keystone
2013-07-16 06:05:57 + rm -f '/var/cache/keystone/*'
2013-07-16 06:06:57 + start_keystone
2013-07-16 06:06:57 + local service_port=5000
2013-07-16 06:06:57 + is_service_enabled tls-proxy
2013-07-16 06:06:57 + services=tls-proxy
2013-07-16 06:06:57 + for service in '${services}'
2013-07-16 06:06:57 + [[ ,g-api,g-reg,key,n-api,n-crt,n-obj,n-cpu,n-sch,horizon,mysql,rabbit,sysstat,tempest,s-proxy,s-account,s-container,s-object,cinder,c-api,c-vol,c-sch,n-cond,heat,h-api,h-api-cfn,h-api-cw,h-eng,ceilometer-acompute,ceilometer-acentral,ceilometer-collector,ceilometer-api,n-net, =~ ,tls-proxy, ]]
2013-07-16 06:06:57 + [[ tls-proxy == \n\o\v\a ]]
2013-07-16 06:06:57 + [[ tls-proxy == \c\i\n\d\e\r ]]
2013-07-16 06:06:57 + [[ tls-proxy == \c\e\i\l\o\m\e\t\e\r ]]
2013-07-16 06:06:57 + [[ tls-proxy == \g\l\a\n\c\e ]]
2013-07-16 06:06:57 + [[ tls-proxy == \n\e\u\t\r\o\n ]]
2013-07-16 06:06:57 + [[ tls-proxy == \s\w\i\f\t ]]
2013-07-16 06:06:57 + [[ tls-proxy == s-* ]]
2013-07-16 06:06:57 + return 1
2013-07-16 06:06:57 + screen_it key 'cd /opt/stack/new/keystone && /opt/stack/new/keystone/bin/keystone-all --config-file /etc/keystone/keystone.conf --log-config /etc/keystone/logging.conf -d --debug'
2013-07-16 06:06:57 + SCREEN_NAME=stack
2013-07-16 06:06:57 + SERVICE_DIR=/opt/stack/new/status
2013-07-16 06:06:57 ++ trueorfalse True False
2013-07-16 06:06:57 ++ local default=True
2013-07-16 06:06:57 ++ local testval=False
2013-07-16 06:06:57 ++ [[ -z False ]]
2013-07-16 06:06:57 ++ [[ 0 no false False FALSE =~ False ]]
2013-07-16 06:06:57 ++ echo False
2013-07-16 06:06:57 ++ return
2013-07-16 06:06:57 + USE_SCREEN=False
2013-07-16 06:06:57 + is_service_enabled key
2013-07-16 06:06:57 + services=key
2013-07-16 06:06:57 + for service in '${services}'
2013-07-16 06:06:57 + [[ ,g-api,g-reg,key,n-api,n-crt,n-obj,n-cpu,n-sch,horizon,mysql,rabbit,sysstat,tempest,s-proxy,s-account,s-container,s-object,cinder,c-api,c-vol,c-sch,n-cond,heat,h-api,h-api-cfn,h-api-cw,h-eng,ceilometer-acompute,ceilometer-acentral,ceilometer-collector,ceilometer-api,n-net, =~ ,key, ]]
2013-07-16 06:06:57 + return 0
2013-07-16 06:06:57 + screen_rc key 'cd /opt/stack/new/keystone && /opt/stack/new/keystone/bin/keystone-all --config-file /etc/keystone/keystone.conf --log-config /etc/keystone/logging.conf -d --debug'
2013-07-16 06:06:57 + SCREEN_NAME=stack
2013-07-16 06:06:57 + SCREENRC=/opt/stack/new/devstack/stack-screenrc
2013-07-16 06:06:57 + [[ ! -e /opt/stack/new/devstack/stack-screenrc ]]
2013-07-16 06:06:57 + grep key /opt/stack/new/devstack/stack-screenrc
2013-07-16 06:06:57 ++ echo -ne '\015'
2013-07-16 06:06:57 + NL=$'\r'
2013-07-16 06:06:57 + echo 'screen -t key bash'
2013-07-16 06:06:57 + echo 'stuff "cd /opt/stack/new/keystone && /opt/stack/new/keystone/bin/keystone-all --config-file /etc/keystone/keystone.conf --log-config /etc/keystone/logging.conf -d --debug
"'
2013-07-16 06:06:57 + [[ False = \T\r\u\e ]]
2013-07-16 06:06:57 + run_process key 'cd /opt/stack/new/keystone && /opt/stack/new/keystone/bin/keystone-all --config-file /etc/keystone/keystone.conf --log-config /etc/keystone/logging.conf -d --debug'
2013-07-16 06:06:57 + local service=key
2013-07-16 06:06:57 + local 'command=cd /opt/stack/new/keystone && /opt/stack/new/keystone/bin/keystone-all --config-file /etc/keystone/keystone.conf --log-config /etc/keystone/logging.conf -d --debug'
2013-07-16 06:06:57 + echo 11334
2013-07-16 06:06:57 + _run_process key 'cd /opt/stack/new/keystone && /opt/stack/new/keystone/bin/keystone-all --config-file /etc/keystone/keystone.conf --log-config /etc/keystone/logging.conf -d --debug'
2013-07-16 06:06:57 + echo 'Waiting for keystone to start...'
2013-07-16 06:06:57 Waiting for keystone to start...
2013-07-16 06:06:57 + timeout 60 sh -c 'while ! http_proxy= curl -s http://127.0.0.1:5000/v2.0/ >/dev/null; do sleep 1; done'
2013-07-16 06:06:57 + local service=key
2013-07-16 06:06:57 + local 'command=cd /opt/stack/new/keystone && /opt/stack/new/keystone/bin/keystone-all --config-file /etc/keystone/keystone.conf --log-config /etc/keystone/logging.conf -d --debug'
2013-07-16 06:06:57 + exec
2013-07-16 06:06:57 + exec
2013-07-16 06:06:57 + die 311 'keystone did not start'
2013-07-16 06:06:57 + local exitcode=0
2013-07-16 06:06:57 + set +o xtrace
2013-07-16 06:06:57 [ERROR] ./stack.sh:311 keystone did not start
Build step 'Execute shell' marked build as failure
[SCP] Connecting to static.openstack.org
[SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8
[SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/devstack-gate-cleanup-host.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/devstack-gate-setup-host.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/devstack-gate-setup-workspace-new.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/devstacklog.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/error.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/iptables.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/kern_log.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/localrc.txt.gz'
[SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/mysql
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/mysql/error.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/mysql/mysql-slow.txt.gz'
[SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/rabbitmq
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/rabbitmq/rabbit at devstack-1373939478-sasl.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/rabbitmq/rabbit at devstack-1373939478.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/rabbitmq/startup_log.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/screen-key.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/screen-sysstat.txt.gz'
[SCP] Trying to create /srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/sudoers.d
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/sudoers.d/50_stack_sh.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/sudoers.d/90-cloudimg-ubuntu.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/sudoers.d/README.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/sudoers.d/cinder-rootwrap.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/sudoers.d/jenkins-sudo-grep.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/sudoers.d/nova-rootwrap.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/sudoers.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/syslog.txt.gz'
[SCP] uploading file: '/srv/static/logs/periodic/periodic-tempest-devstack-vm-stress/8/logs/sysstat.dat.gz'
[SCP] Connecting to static.openstack.org
[SCP] Copying console log.