openstack-manuals

"nova-manage db sync" error

Bug #1511470 reported by venkata teja on 2015-10-29

This bug affects 1 person

Affects		Status	Importance	Assigned to	Milestone
	openstack-manuals	Invalid	Undecided	Unassigned

Bug Description

root@keystone:~# su -s /bin/sh -c "nova-manage db sync" nova
No handlers could be found for logger "oslo_config.cfg"
2015-10-29 22:35:48.998 2652 INFO migrate.versioning.api [-] 215 -> 216...
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters [-] DBAPIError exception wrapped from (pymysql.err.InternalError) (1050, u"Table 'instances' already exists") [SQL: u"\nCREATE TABLE instances (\n\tcreated_at DATETIME, \n\tupdated_at DATETIME, \n\tdeleted_at DATETIME, \n\tid INTEGER NOT NULL AUTO_INCREMENT, \n\tinternal_id INTEGER, \n\tuser_id VARCHAR(255), \n\tproject_id VARCHAR(255), \n\timage_ref VARCHAR(255), \n\tkernel_id VARCHAR(255), \n\tramdisk_id VARCHAR(255), \n\tlaunch_index INTEGER, \n\tkey_name VARCHAR(255), \n\tkey_data MEDIUMTEXT, \n\tpower_state INTEGER, \n\tvm_state VARCHAR(255), \n\tmemory_mb INTEGER, \n\tvcpus INTEGER, \n\thostname VARCHAR(255), \n\thost VARCHAR(255), \n\tuser_data MEDIUMTEXT, \n\treservation_id VARCHAR(255), \n\tscheduled_at DATETIME, \n\tlaunched_at DATETIME, \n\tterminated_at DATETIME, \n\tdisplay_name VARCHAR(255), \n\tdisplay_description VARCHAR(255), \n\tavailability_zone VARCHAR(255), \n\tlocked BOOL, \n\tos_type VARCHAR(255), \n\tlaunched_on MEDIUMTEXT, \n\tinstance_type_id INTEGER, \n\tvm_mode VARCHAR(255), \n\tuuid VARCHAR(36), \n\tarchitecture VARCHAR(255), \n\troot_device_name VARCHAR(255), \n\taccess_ip_v4 VARCHAR(39), \n\taccess_ip_v6 VARCHAR(39), \n\tconfig_drive VARCHAR(255), \n\ttask_state VARCHAR(255), \n\tdefault_ephemeral_device VARCHAR(255), \n\tdefault_swap_device VARCHAR(255), \n\tprogress INTEGER, \n\tauto_disk_config BOOL, \n\tshutdown_terminate BOOL, \n\tdisable_terminate BOOL, \n\troot_gb INTEGER, \n\tephemeral_gb INTEGER, \n\tcell_name VARCHAR(255), \n\tnode VARCHAR(255), \n\tdeleted INTEGER, \n\tlocked_by ENUM('owner','admin'), \n\tcleaned INTEGER, \n\tPRIMARY KEY (id), \n\tCHECK (locked IN (0, 1)), \n\tCHECK (auto_disk_config IN (0, 1)), \n\tCHECK (shutdown_terminate IN (0, 1)), \n\tCHECK (disable_terminate IN (0, 1))\n)ENGINE=InnoDB CHARSET=utf8\n\n"]
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters Traceback (most recent call last):
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters File "/usr/lib/python2.7/dist-packages/sqlalchemy/engine/base.py", line 1139, in _execute_context
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters context)
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters File "/usr/lib/python2.7/dist-packages/sqlalchemy/engine/default.py", line 450, in do_execute
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters cursor.execute(statement, parameters)
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters File "/usr/lib/python2.7/dist-packages/pymysql/cursors.py", line 132, in execute
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters result = self._query(query)
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters File "/usr/lib/python2.7/dist-packages/pymysql/cursors.py", line 271, in _query
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters conn.query(q)
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters File "/usr/lib/python2.7/dist-packages/pymysql/connections.py", line 726, in query
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters self._affected_rows = self._read_query_result(unbuffered=unbuffered)
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters File "/usr/lib/python2.7/dist-packages/pymysql/connections.py", line 861, in _read_query_result
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters result.read()
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters File "/usr/lib/python2.7/dist-packages/pymysql/connections.py", line 1064, in read
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters first_packet = self.connection._read_packet()
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters File "/usr/lib/python2.7/dist-packages/pymysql/connections.py", line 826, in _read_packet
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters packet.check_error()
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters File "/usr/lib/python2.7/dist-packages/pymysql/connections.py", line 370, in check_error
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters raise_mysql_exception(self._data)
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters File "/usr/lib/python2.7/dist-packages/pymysql/err.py", line 116, in raise_mysql_exception
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters _check_mysql_exception(errinfo)
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters File "/usr/lib/python2.7/dist-packages/pymysql/err.py", line 112, in _check_mysql_exception
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters raise InternalError(errno, errorvalue)
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters InternalError: (1050, u"Table 'instances' already exists")
2015-10-29 22:35:49.676 2652 ERROR oslo_db.sqlalchemy.exc_filters
Command failed, please check log for more info
2015-10-29 22:35:49.682 2652 CRITICAL nova [-] DBError: (pymysql.err.InternalError) (1050, u"Table 'instances' already exists") [SQL: u"\nCREATE TABLE instances (\n\tcreated_at DATETIME, \n\tupdated_at DATETIME, \n\tdeleted_at DATETIME, \n\tid INTEGER NOT NULL AUTO_INCREMENT, \n\tinternal_id INTEGER, \n\tuser_id VARCHAR(255), \n\tproject_id VARCHAR(255), \n\timage_ref VARCHAR(255), \n\tkernel_id VARCHAR(255), \n\tramdisk_id VARCHAR(255), \n\tlaunch_index INTEGER, \n\tkey_name VARCHAR(255), \n\tkey_data MEDIUMTEXT, \n\tpower_state INTEGER, \n\tvm_state VARCHAR(255), \n\tmemory_mb INTEGER, \n\tvcpus INTEGER, \n\thostname VARCHAR(255), \n\thost VARCHAR(255), \n\tuser_data MEDIUMTEXT, \n\treservation_id VARCHAR(255), \n\tscheduled_at DATETIME, \n\tlaunched_at DATETIME, \n\tterminated_at DATETIME, \n\tdisplay_name VARCHAR(255), \n\tdisplay_description VARCHAR(255), \n\tavailability_zone VARCHAR(255), \n\tlocked BOOL, \n\tos_type VARCHAR(255), \n\tlaunched_on MEDIUMTEXT, \n\tinstance_type_id INTEGER, \n\tvm_mode VARCHAR(255), \n\tuuid VARCHAR(36), \n\tarchitecture VARCHAR(255), \n\troot_device_name VARCHAR(255), \n\taccess_ip_v4 VARCHAR(39), \n\taccess_ip_v6 VARCHAR(39), \n\tconfig_drive VARCHAR(255), \n\ttask_state VARCHAR(255), \n\tdefault_ephemeral_device VARCHAR(255), \n\tdefault_swap_device VARCHAR(255), \n\tprogress INTEGER, \n\tauto_disk_config BOOL, \n\tshutdown_terminate BOOL, \n\tdisable_terminate BOOL, \n\troot_gb INTEGER, \n\tephemeral_gb INTEGER, \n\tcell_name VARCHAR(255), \n\tnode VARCHAR(255), \n\tdeleted INTEGER, \n\tlocked_by ENUM('owner','admin'), \n\tcleaned INTEGER, \n\tPRIMARY KEY (id), \n\tCHECK (locked IN (0, 1)), \n\tCHECK (auto_disk_config IN (0, 1)), \n\tCHECK (shutdown_terminate IN (0, 1)), \n\tCHECK (disable_terminate IN (0, 1))\n)ENGINE=InnoDB CHARSET=utf8\n\n"]
2015-10-29 22:35:49.682 2652 ERROR nova Traceback (most recent call last):
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/bin/nova-manage", line 10, in <module>
2015-10-29 22:35:49.682 2652 ERROR nova sys.exit(main())
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/nova/cmd/manage.py", line 1443, in main
2015-10-29 22:35:49.682 2652 ERROR nova ret = fn(*fn_args, **fn_kwargs)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/nova/cmd/manage.py", line 910, in sync
2015-10-29 22:35:49.682 2652 ERROR nova return migration.db_sync(version)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/nova/db/migration.py", line 26, in db_sync
2015-10-29 22:35:49.682 2652 ERROR nova return IMPL.db_sync(version=version, database=database)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/nova/db/sqlalchemy/migration.py", line 106, in db_sync
2015-10-29 22:35:49.682 2652 ERROR nova version)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/migrate/versioning/api.py", line 186, in upgrade
2015-10-29 22:35:49.682 2652 ERROR nova return _migrate(url, repository, version, upgrade=True, err=err, **opts)
2015-10-29 22:35:49.682 2652 ERROR nova File "<string>", line 2, in _migrate
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/migrate/versioning/util/__init__.py", line 160, in with_engine
2015-10-29 22:35:49.682 2652 ERROR nova return f(*a, **kw)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/migrate/versioning/api.py", line 366, in _migrate
2015-10-29 22:35:49.682 2652 ERROR nova schema.runchange(ver, change, changeset.step)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/migrate/versioning/schema.py", line 93, in runchange
2015-10-29 22:35:49.682 2652 ERROR nova change.run(self.engine, step)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/migrate/versioning/script/py.py", line 148, in run
2015-10-29 22:35:49.682 2652 ERROR nova script_func(engine)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/nova/db/sqlalchemy/migrate_repo/versions/216_havana.py", line 1077, in upgrade
2015-10-29 22:35:49.682 2652 ERROR nova instances.create()
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/sqlalchemy/sql/schema.py", line 725, in create
2015-10-29 22:35:49.682 2652 ERROR nova checkfirst=checkfirst)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/sqlalchemy/engine/base.py", line 1856, in _run_visitor
2015-10-29 22:35:49.682 2652 ERROR nova conn._run_visitor(visitorcallable, element, **kwargs)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/sqlalchemy/engine/base.py", line 1481, in _run_visitor
2015-10-29 22:35:49.682 2652 ERROR nova **kwargs).traverse_single(element)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/sqlalchemy/sql/visitors.py", line 121, in traverse_single
2015-10-29 22:35:49.682 2652 ERROR nova return meth(obj, **kw)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/sqlalchemy/sql/ddl.py", line 764, in visit_table
2015-10-29 22:35:49.682 2652 ERROR nova include_foreign_key_constraints=include_foreign_key_constraints
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/sqlalchemy/engine/base.py", line 914, in execute
2015-10-29 22:35:49.682 2652 ERROR nova return meth(self, multiparams, params)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/sqlalchemy/sql/ddl.py", line 68, in _execute_on_connection
2015-10-29 22:35:49.682 2652 ERROR nova return connection._execute_ddl(self, multiparams, params)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/sqlalchemy/engine/base.py", line 968, in _execute_ddl
2015-10-29 22:35:49.682 2652 ERROR nova compiled
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/sqlalchemy/engine/base.py", line 1146, in _execute_context
2015-10-29 22:35:49.682 2652 ERROR nova context)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/sqlalchemy/engine/base.py", line 1337, in _handle_dbapi_exception
2015-10-29 22:35:49.682 2652 ERROR nova util.raise_from_cause(newraise, exc_info)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/sqlalchemy/util/compat.py", line 199, in raise_from_cause
2015-10-29 22:35:49.682 2652 ERROR nova reraise(type(exception), exception, tb=exc_tb)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/sqlalchemy/engine/base.py", line 1139, in _execute_context
2015-10-29 22:35:49.682 2652 ERROR nova context)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/sqlalchemy/engine/default.py", line 450, in do_execute
2015-10-29 22:35:49.682 2652 ERROR nova cursor.execute(statement, parameters)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/pymysql/cursors.py", line 132, in execute
2015-10-29 22:35:49.682 2652 ERROR nova result = self._query(query)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/pymysql/cursors.py", line 271, in _query
2015-10-29 22:35:49.682 2652 ERROR nova conn.query(q)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/pymysql/connections.py", line 726, in query
2015-10-29 22:35:49.682 2652 ERROR nova self._affected_rows = self._read_query_result(unbuffered=unbuffered)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/pymysql/connections.py", line 861, in _read_query_result
2015-10-29 22:35:49.682 2652 ERROR nova result.read()
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/pymysql/connections.py", line 1064, in read
2015-10-29 22:35:49.682 2652 ERROR nova first_packet = self.connection._read_packet()
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/pymysql/connections.py", line 826, in _read_packet
2015-10-29 22:35:49.682 2652 ERROR nova packet.check_error()
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/pymysql/connections.py", line 370, in check_error
2015-10-29 22:35:49.682 2652 ERROR nova raise_mysql_exception(self._data)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/pymysql/err.py", line 116, in raise_mysql_exception
2015-10-29 22:35:49.682 2652 ERROR nova _check_mysql_exception(errinfo)
2015-10-29 22:35:49.682 2652 ERROR nova File "/usr/lib/python2.7/dist-packages/pymysql/err.py", line 112, in _check_mysql_exception
2015-10-29 22:35:49.682 2652 ERROR nova raise InternalError(errno, errorvalue)
2015-10-29 22:35:49.682 2652 ERROR nova DBError: (pymysql.err.InternalError) (1050, u"Table 'instances' already exists") [SQL: u"\nCREATE TABLE instances (\n\tcreated_at DATETIME, \n\tupdated_at DATETIME, \n\tdeleted_at DATETIME, \n\tid INTEGER NOT NULL AUTO_INCREMENT, \n\tinternal_id INTEGER, \n\tuser_id VARCHAR(255), \n\tproject_id VARCHAR(255), \n\timage_ref VARCHAR(255), \n\tkernel_id VARCHAR(255), \n\tramdisk_id VARCHAR(255), \n\tlaunch_index INTEGER, \n\tkey_name VARCHAR(255), \n\tkey_data MEDIUMTEXT, \n\tpower_state INTEGER, \n\tvm_state VARCHAR(255), \n\tmemory_mb INTEGER, \n\tvcpus INTEGER, \n\thostname VARCHAR(255), \n\thost VARCHAR(255), \n\tuser_data MEDIUMTEXT, \n\treservation_id VARCHAR(255), \n\tscheduled_at DATETIME, \n\tlaunched_at DATETIME, \n\tterminated_at DATETIME, \n\tdisplay_name VARCHAR(255), \n\tdisplay_description VARCHAR(255), \n\tavailability_zone VARCHAR(255), \n\tlocked BOOL, \n\tos_type VARCHAR(255), \n\tlaunched_on MEDIUMTEXT, \n\tinstance_type_id INTEGER, \n\tvm_mode VARCHAR(255), \n\tuuid VARCHAR(36), \n\tarchitecture VARCHAR(255), \n\troot_device_name VARCHAR(255), \n\taccess_ip_v4 VARCHAR(39), \n\taccess_ip_v6 VARCHAR(39), \n\tconfig_drive VARCHAR(255), \n\ttask_state VARCHAR(255), \n\tdefault_ephemeral_device VARCHAR(255), \n\tdefault_swap_device VARCHAR(255), \n\tprogress INTEGER, \n\tauto_disk_config BOOL, \n\tshutdown_terminate BOOL, \n\tdisable_terminate BOOL, \n\troot_gb INTEGER, \n\tephemeral_gb INTEGER, \n\tcell_name VARCHAR(255), \n\tnode VARCHAR(255), \n\tdeleted INTEGER, \n\tlocked_by ENUM('owner','admin'), \n\tcleaned INTEGER, \n\tPRIMARY KEY (id), \n\tCHECK (locked IN (0, 1)), \n\tCHECK (auto_disk_config IN (0, 1)), \n\tCHECK (shutdown_terminate IN (0, 1)), \n\tCHECK (disable_terminate IN (0, 1))\n)ENGINE=InnoDB CHARSET=utf8\n\n"]
2015-10-29 22:35:49.682 2652 ERROR nova

Revision history for this message

venkata teja (tejab) wrote on 2015-10-29:

i delete nova database in mariadb it's work

Revision history for this message

Matt Kassawara (ionosphere80) wrote on 2015-10-29:

Not an installation problem because database tables already exist. Maybe an upgrade problem?

Changed in openstack-manuals:
status:	New → Invalid

Revision history for this message

venkata teja (tejab) wrote on 2015-10-30:

i upgrade my ubuntu 12.10 it's showing this...

015-10-30 22:15:46.948 19706 ERROR nova DBConnectionError: (pymysql.err.OperationalError) (2013, 'Lost connection to MySQL server during query') [SQL: u'CREATE INDEX project_id ON dns_domains (project_id)']
2015-10-30 22:15:46.948 19706 ERROR nova

Report a bug

This report contains Public information

Everyone can see this information.

You are

Subscribing...

Edit bug mail

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.