[Engine-devel] Upgrading Database Schema...error
Yair Zaslavsky
yzaslavs at redhat.com
Mon Jan 21 07:03:42 UTC 2013
Hi,
I see the following erorr -
xecuting command --> '/usr/bin/pg_dump -C -E UTF8 --disable-dollar-quoting --disable-triggers --format=p -f /var/lib/ovirt-engine/backups/tmpmGCzCX.sql -U engine -h localhost -p 5432 engine'
2013-01-18 08:06:20: EBUG::common_utils::468::root:: output =
2013-01-18 08:06:20: EBUG::common_utils::469::root:: stderr = pg_dump: SQL command failed
pg_dump: Error message from server: ERROR: permission denied for relation tags_vm_pool_map
Can you please provide more info on how you created the db? (i.e - command line of running create_db.sh or create_db_devel.sh)
When I encountered such issues it was because I created the db (i.e - run create_db.sh or create_db_devel.sh) with user A, but tried to backup/restore with user B
Thanks,
Yair
----- Original Message -----
> From: "Wei D Chen" <wei.d.chen at intel.com>
> To: "engine-devel at ovirt.org" <engine-devel at ovirt.org>
> Cc: "Quan Xu" <quan.xu at intel.com>
> Sent: Friday, January 18, 2013 3:39:28 PM
> Subject: [Engine-devel] Upgrading Database Schema...error
> Hi ,
> There is a problem in engine-setup-- “Upgrading Database
> Schema...error”. The env is setup with ovirt-engine 3.2.0 from
> http://www.ovirt.org/Building_oVirt_engine ,
> The below are printing out info and log. Could you help me to check
> it? Thanks in advance.
> ***
> Installing:
> Configuring oVirt-engine... [ DONE ]
> Configuring JVM... [ DONE ]
> Creating CA... [ DONE ]
> Updating ovirt-engine service... [ DONE ]
> Setting Database Configuration... [ DONE ]
> Setting Database Security... [ DONE ]
> Upgrading Database Schema... [ ERROR ]
> Database backup failed
> ############### log ###################
> 2013-01-18 08:05:50::DEBUG::common_utils::469::root:: stderr =
> 2013-01-18 08:05:50::DEBUG::common_utils::470::root:: retcode = 0
> 2013-01-18 08:05:50::DEBUG::engine-setup::2125::root:: Found a match,
> amount of memory: 7945
> 2013-01-18 08:05:50::DEBUG::engine-setup::2015::root:: Entered
> main(configFile='None')
> 2013-01-18 08:05:50::DEBUG::engine-setup::1809::root:: stopping
> ovirt-engine service
> 2013-01-18 08:05:50::DEBUG::common_utils::967::root:: asking user:
> Would you like to stop the ovirt-engine service? (yes|no):
> 2013-01-18 08:05:51::DEBUG::common_utils::971::root:: user answered:
> yes
> 2013-01-18 08:05:51::DEBUG::common_utils::1213::root:: stopping
> ovirt-engine
> 2013-01-18 08:05:51::DEBUG::common_utils::1250::root:: executing
> action ovirt-engine on service stop
> 2013-01-18 08:05:51::DEBUG::common_utils::430::root:: Executing
> command --> '/sbin/service ovirt-engine stop'
> 2013-01-18 08:05:51::DEBUG::common_utils::468::root:: output =
> 2013-01-18 08:05:51::DEBUG::common_utils::469::root:: stderr =
> 2013-01-18 08:05:51::DEBUG::common_utils::470::root:: retcode = 0
> 2013-01-18 08:05:51::DEBUG::engine-setup::1539::root:: going over
> group PORTS
> 2013-01-18 08:05:51::DEBUG::common_utils::1291::root:: retrieving
> version for ipa-server rpm
> 2013-01-18 08:05:51::DEBUG::common_utils::1312::root:: getRpmVersion
> failed
> Traceback (most recent call last):
> File "/usr/local/share/ovirt-engine/scripts/common_utils.py", line
> 1309, in installed
> getRpmVersion(rpmName=rpm)
> File "/usr/local/share/ovirt-engine/scripts/common_utils.py", line
> 1303, in getRpmVersion
> raise Exception(output_messages.ERR_READ_RPM_VER % rpmName)
> Exception: Error reading version number for package ipa-server
> 2013-01-18 08:05:51::DEBUG::common_utils::1291::root:: retrieving
> version for freeipa-server rpm
> 2013-01-18 08:05:51::DEBUG::common_utils::1312::root:: getRpmVersion
> failed
> Traceback (most recent call last):
> File "/usr/local/share/ovirt-engine/scripts/common_utils.py", line
> 1309, in installed
> getRpmVersion(rpmName=rpm)
> File "/usr/local/share/ovirt-engine/scripts/common_utils.py", line
> 1303, in getRpmVersion
> raise Exception(output_messages.ERR_READ_RPM_VER % rpmName)
> Exception: Error reading version number for package freeipa-server
> 2013-01-18 08:05:51::INFO::engine-setup::1548::root:: Comparing
> pre-conditions; condition: 'True', and match: 'True'
> 2013-01-18 08:05:52::INFO::engine_validators::199::root::
> validateOverrideHttpdConfAndChangePortsAccordingly yes as part of
> ['yes', 'no']
> 2013-01-18 08:05:52::INFO::engine_validators::182::root:: Validating
> yes as part of ['yes', 'no']
> 2013-01-18 08:05:52::DEBUG::engine_validators::207::root:: stopping
> httpd service
> 2013-01-18 08:05:52::DEBUG::common_utils::1213::root:: stopping httpd
> 2013-01-18 08:05:52::DEBUG::common_utils::1250::root:: executing
> action httpd on service stop
> 2013-01-18 08:05:52::DEBUG::common_utils::430::root:: Executing
> command --> '/sbin/service httpd stop'
> 2013-01-18 08:05:52::DEBUG::common_utils::468::root:: output =
> 2013-01-18 08:05:52::DEBUG::common_utils::469::root:: stderr =
> 2013-01-18 08:05:52::DEBUG::common_utils::470::root:: retcode = 0
> 2013-01-18 08:05:52::DEBUG::engine-setup::1539::root:: going over
> group ALL_PARAMS
> 2013-01-18 08:05:52::INFO::engine-setup::1548::root:: Comparing
> pre-conditions; condition: 'True', and match: 'True'
> 2013-01-18 08:05:52::DEBUG::engine-setup::551::root:: setting default
> value (no) for key (RANDOM_PASSWORDS)
> 2013-01-18 08:05:52::DEBUG::engine-setup::551::root:: setting default
> value (00:1A:4A:EF:83:00-00:1A:4A:EF:83:FF) for key (MAC_RANGE)
> 2013-01-18 08:05:56::INFO::engine_validators::307::root:: Validating
> localhost as a FQDN
> 2013-01-18 08:05:56::INFO::engine_validators::216::root:: validating
> localhost as a valid domain string
> 2013-01-18 08:05:56::DEBUG::common_utils::967::root:: asking user:
> User input failed validation, do you still wish to use it? (yes|no):
> 2013-01-18 08:05:57::DEBUG::common_utils::971::root:: user answered:
> yes
> 2013-01-18 08:05:59::DEBUG::engine_validators::161::root:: Validating
> password
> 2013-01-18 08:05:59::WARNING::engine_validators::175::root:: Password
> failed check
> 2013-01-18 08:05:59::WARNING::engine_validators::176::root::
> Traceback (most recent call last):
> File "/usr/local/share/ovirt-engine/scripts/engine_validators.py",
> line 173, in validatePassword
> cracklib.FascistCheck(param)
> ValueError: it is too simplistic/systematic
> 2013-01-18 08:06:01::DEBUG::engine-setup::630::root:: Param
> confirmation passed, value for both questions is identical
> 2013-01-18 08:06:05::INFO::engine_validators::388::root:: validating
> organization name
> 2013-01-18 08:06:07::INFO::engine_validators::182::root:: Validating
> NFS as part of ['NFS', 'FC', 'ISCSI', 'POSIXFS']
> 2013-01-18 08:06:09::INFO::engine_validators::182::root:: Validating
> local as part of ['remote', 'local']
> 2013-01-18 08:06:09::DEBUG::engine-setup::1539::root:: going over
> group REMOTE_DB
> 2013-01-18 08:06:09::INFO::engine-setup::1548::root:: Comparing
> pre-conditions; condition: 'False', and match: 'True'
> 2013-01-18 08:06:09::DEBUG::engine-setup::1577::root:: no post
> condition check for group REMOTE_DB
> 2013-01-18 08:06:09::DEBUG::engine-setup::1539::root:: going over
> group LOCAL_DB
> 2013-01-18 08:06:09::INFO::engine-setup::1548::root:: Comparing
> pre-conditions; condition: 'False', and match: 'False'
> 2013-01-18 08:06:11::DEBUG::engine_validators::161::root:: Validating
> password
> 2013-01-18 08:06:11::WARNING::engine_validators::175::root:: Password
> failed check
> 2013-01-18 08:06:11::WARNING::engine_validators::176::root::
> Traceback (most recent call last):
> File "/usr/local/share/ovirt-engine/scripts/engine_validators.py",
> line 173, in validatePassword
> cracklib.FascistCheck(param)
> ValueError: it is too simplistic/systematic
> 2013-01-18 08:06:13::DEBUG::engine-setup::630::root:: Param
> confirmation passed, value for both questions is identical
> 2013-01-18 08:06:13::DEBUG::engine-setup::1539::root:: going over
> group NFS
> 2013-01-18 08:06:14::INFO::engine_validators::182::root:: Validating
> yes as part of ['yes', 'no']
> 2013-01-18 08:06:14::INFO::engine-setup::1548::root:: Comparing
> pre-conditions; condition: 'yes', and match: 'yes'
> 2013-01-18 08:06:17::INFO::engine_validators::26::root:: validating
> /var/lib/exports/iso as a valid mount point
> 2013-01-18 08:06:17::DEBUG::engine_validators::552::root:: attempting
> to write temp file to /var/lib
> 2013-01-18 08:06:17::DEBUG::common_utils::636::root:: Checking
> available space on /var/lib
> 2013-01-18 08:06:17::DEBUG::common_utils::641::root:: Available space
> on /var/lib is 45004
> 2013-01-18 08:06:17::DEBUG::engine-setup::551::root:: setting default
> value (ISO_DOMAIN) for key (ISO_DOMAIN_NAME)
> 2013-01-18 08:06:17::DEBUG::engine-setup::1539::root:: going over
> group IPTABLES
> 2013-01-18 08:06:17::INFO::engine-setup::1548::root:: Comparing
> pre-conditions; condition: 'True', and match: 'True'
> 2013-01-18 08:06:19::INFO::engine_validators::182::root:: Validating
> None as part of ['None']
> 2013-01-18 08:06:19::INFO::engine-setup::1618::root:: *** User input
> summary ***
> 2013-01-18 08:06:19::INFO::engine-setup::1631::root::
> override-httpd-config: yes
> 2013-01-18 08:06:19::INFO::engine-setup::1631::root:: http-port: 80
> 2013-01-18 08:06:19::INFO::engine-setup::1631::root:: https-port: 443
> 2013-01-18 08:06:19::INFO::engine-setup::1631::root:: host-fqdn:
> localhost
> 2013-01-18 08:06:19::INFO::engine-setup::1627::root:: auth-pass:
> ********
> 2013-01-18 08:06:19::INFO::engine-setup::1631::root:: org-name:
> sh.intel.com
> 2013-01-18 08:06:19::INFO::engine-setup::1631::root::
> default-dc-type: NFS
> 2013-01-18 08:06:19::INFO::engine-setup::1631::root::
> db-remote-install: local
> 2013-01-18 08:06:19::INFO::engine-setup::1627::root:: db-local-pass:
> ********
> 2013-01-18 08:06:19::INFO::engine-setup::1631::root:: nfs-mp:
> /var/lib/exports/iso
> 2013-01-18 08:06:19::INFO::engine-setup::1631::root:: config-nfs: yes
> 2013-01-18 08:06:19::INFO::engine-setup::1631::root::
> override-firewall: None
> 2013-01-18 08:06:19::INFO::engine-setup::1633::root:: *** User input
> summary ***
> 2013-01-18 08:06:19::DEBUG::common_utils::967::root:: asking user:
> Proceed with the configuration listed above? (yes|no):
> 2013-01-18 08:06:20::DEBUG::common_utils::971::root:: user answered:
> yes
> 2013-01-18 08:06:20::DEBUG::engine-setup::1652::root:: user chose to
> accept user parameters
> 2013-01-18 08:06:20::DEBUG::engine-setup::2044::root:: {'ORG_NAME':
> 'sh.intel.com', 'HOST_FQDN': 'localhost', 'OVERRIDE_FIREWALL':
> 'None', 'HTTP_PORT': '80', 'HTTPS_PORT': '443', 'DC_TYPE': 'NFS',
> 'DC_TYPE_ENUM': <common_utils.Enum object at 0x19b9bd0>,
> 'AUTH_PASS': '********', 'DB_LOCAL_PASS': '********',
> 'RANDOM_PASSWORDS': 'no', 'ISO_DOMAIN_NAME': 'ISO_DOMAIN',
> 'AUTH_PASS_CONFIRMED': '********', 'OVERRIDE_HTTPD_CONFIG': 'yes',
> 'MAC_RANGE': '00:1A:4A:EF:83:00-00:1A:4A:EF:83:FF',
> 'DB_LOCAL_PASS_CONFIRMED': '********', 'CONFIG_NFS': 'yes',
> 'DB_REMOTE_INSTALL': 'local', 'NFS_MP': '/var/lib/exports/iso'}
> 2013-01-18 08:06:20::DEBUG::engine-setup::2047::root:: Entered
> Configuration stage
> 2013-01-18 08:06:20::DEBUG::setup_sequences::59::root:: running
> setMaxSharedMemory
> 2013-01-18 08:06:20::DEBUG::common_utils::430::root:: Executing
> command --> '/sbin/sysctl -b kernel.shmmax'
> 2013-01-18 08:06:20::DEBUG::common_utils::468::root:: output =
> 35554432
> 2013-01-18 08:06:20::DEBUG::common_utils::469::root:: stderr =
> 2013-01-18 08:06:20::DEBUG::common_utils::470::root:: retcode = 0
> 2013-01-18 08:06:20::DEBUG::engine-setup::1721::root:: current shared
> memory max in kernel is 35554432, there is no need to update the
> kernel parameters
> 2013-01-18 08:06:20::DEBUG::setup_sequences::59::root:: running
> _findJavaHome
> 2013-01-18 08:06:20::DEBUG::common_utils::430::root:: Executing
> command --> '/usr/lib/jvm/java-1.7.0-openjdk.x86_64/bin/java
> -version'
> 2013-01-18 08:06:20::DEBUG::common_utils::468::root:: output =
> 2013-01-18 08:06:20::DEBUG::common_utils::469::root:: stderr = java
> version "1.7.0_b147-icedtea"
> OpenJDK Runtime Environment (fedora-2.1.fc17.6-x86_64)
> OpenJDK 64-Bit Server VM (build 22.0-b10, mixed mode)
> 2013-01-18 08:06:20::DEBUG::common_utils::470::root:: retcode = 0
> 2013-01-18 08:06:20::DEBUG::common_utils::430::root:: Executing
> command --> '/usr/lib/jvm/jre-1.7.0-openjdk.x86_64/bin/java
> -version'
> 2013-01-18 08:06:20::DEBUG::common_utils::468::root:: output =
> 2013-01-18 08:06:20::DEBUG::common_utils::469::root:: stderr = java
> version "1.7.0_b147-icedtea"
> OpenJDK Runtime Environment (fedora-2.1.fc17.6-x86_64)
> OpenJDK 64-Bit Server VM (build 22.0-b10, mixed mode)
> 2013-01-18 08:06:20::DEBUG::common_utils::470::root:: retcode = 0
> 2013-01-18 08:06:20::DEBUG::common_utils::1395::root:: JVM path
> "/usr/lib/jvm/jre-1.7.0-openjdk.x86_64" doesn't contain the Java
> compiler.
> 2013-01-18 08:06:20::DEBUG::setup_sequences::59::root:: running
> _createCA
> 2013-01-18 08:06:20::WARNING::engine-setup::867::root:: Keystore
> already exists, skipped certificates creation phase
> 2013-01-18 08:06:20::DEBUG::setup_sequences::59::root:: running
> _editSysconfig
> 2013-01-18 08:06:20::DEBUG::common_utils::777::root:: found existing
> pgpass file /etc/ovirt-engine/.pgpass, fetching DB user value
> 2013-01-18 08:06:20::DEBUG::common_utils::1121::root:: Loading text
> file handler
> 2013-01-18 08:06:20::DEBUG::common_utils::1153::root:: Engine has
> been configured
> 2013-01-18 08:06:20::DEBUG::setup_sequences::59::root:: running
> _updatePgPassFile
> 2013-01-18 08:06:20::DEBUG::engine-setup::1289::root:: found existing
> pgpass file, backing current to
> /etc/ovirt-engine/.pgpass.2013_01_18_08_06_20
> 2013-01-18 08:06:20::INFO::engine-setup::1313::root:: Using default
> db credentials
> 2013-01-18 08:06:20::DEBUG::engine-setup::1890::root:: Checking if db
> is already installed..
> 2013-01-18 08:06:20::DEBUG::engine-setup::1891::root:: Checking the
> presence of .pgpass file
> 2013-01-18 08:06:20::DEBUG::common_utils::477::root:: running sql
> query 'select 1' on db server: 'localhost'.
> 2013-01-18 08:06:20::DEBUG::common_utils::430::root:: Executing
> command --> '/usr/bin/psql -h localhost -p 5432 -U postgres -d
> engine -c select 1'
> 2013-01-18 08:06:20::DEBUG::common_utils::468::root:: output =
> ?column?
> ----------
> 1
> (1 row)
> 2013-01-18 08:06:20::DEBUG::common_utils::469::root:: stderr =
> 2013-01-18 08:06:20::DEBUG::common_utils::470::root:: retcode = 0
> 2013-01-18 08:06:20::DEBUG::setup_sequences::59::root:: running
> _encryptDBPass
> 2013-01-18 08:06:20::DEBUG::setup_sequences::59::root:: running
> _configEncryptedPass
> 2013-01-18 08:06:20::DEBUG::common_utils::1183::root:: Encrypting
> database password.
> 2013-01-18 08:06:20::DEBUG::common_utils::777::root:: found existing
> pgpass file /etc/ovirt-engine/.pgpass, fetching DB user value
> 2013-01-18 08:06:20::DEBUG::setup_sequences::59::root:: running
> stopRhevmDbRelatedServices
> 2013-01-18 08:06:20::DEBUG::common_utils::1237::root:: Service was
> not stopped. there for we're not starting it
> 2013-01-18 08:06:20::DEBUG::common_utils::1237::root:: Service was
> not stopped. there for we're not starting it
> 2013-01-18 08:06:20::DEBUG::setup_sequences::59::root:: running
> _upgradeDB
> 2013-01-18 08:06:20::DEBUG::engine-setup::1082::root:: backing up
> engine db to file /var/lib/ovirt-engine/backups/tmpmGCzCX.sql
> 2013-01-18 08:06:20::DEBUG::common_utils::823::root:: engine DB
> Backup started
> 2013-01-18 08:06:20::DEBUG::common_utils::430::root:: Executing
> command --> '/usr/bin/pg_dump -C -E UTF8 --disable-dollar-quoting
> --disable-triggers --format=p -f
> /var/lib/ovirt-engine/backups/tmpmGCzCX.sql -U engine -h localhost
> -p 5432 engine'
> 2013-01-18 08:06:20::DEBUG::common_utils::468::root:: output =
> 2013-01-18 08:06:20::DEBUG::common_utils::469::root:: stderr =
> pg_dump: SQL command failed
> pg_dump: Error message from server: ERROR: permission denied for
> relation tags_vm_pool_map
> pg_dump: The command was: LOCK TABLE public.tags_vm_pool_map IN
> ACCESS SHARE MODE
> 2013-01-18 08:06:20::DEBUG::common_utils::470::root:: retcode = 1
> 2013-01-18 08:06:20::DEBUG::setup_sequences::62::root:: Traceback
> (most recent call last):
> File "/usr/local/share/ovirt-engine/scripts/setup_sequences.py", line
> 60, in run
> function()
> File "/usr/local/bin/engine-setup", line 1085, in _upgradeDB
> utils.backupDB(basedefs.DB_NAME, getDbUser(), dbBackupFile,
> getDbHostName(), getDbPort())
> File "/usr/local/share/ovirt-engine/scripts/common_utils.py", line
> 837, in backupDB
> output, rc = execCmd(cmdList=cmd, failOnError=True,
> msg=output_messages.ERR_DB_BACKUP, envDict=getPgPassEnv())
> File "/usr/local/share/ovirt-engine/scripts/common_utils.py", line
> 473, in execCmd
> raise Exception(msg)
> Exception: Database backup failed
> 2013-01-18 08:06:20::DEBUG::engine-setup::1882::root:: *** The
> following params were used as user input:
> 2013-01-18 08:06:20::DEBUG::engine-setup::1887::root::
> override-httpd-config: yes
> 2013-01-18 08:06:20::DEBUG::engine-setup::1887::root:: http-port: 80
> 2013-01-18 08:06:20::DEBUG::engine-setup::1887::root:: https-port:
> 443
> 2013-01-18 08:06:20::DEBUG::engine-setup::1887::root::
> random-passwords: no
> 2013-01-18 08:06:20::DEBUG::engine-setup::1887::root:: mac-range:
> 00:1A:4A:EF:83:00-00:1A:4A:EF:83:FF
> 2013-01-18 08:06:20::DEBUG::engine-setup::1887::root:: host-fqdn:
> localhost
> 2013-01-18 08:06:20::DEBUG::engine-setup::1887::root:: auth-pass:
> ********
> 2013-01-18 08:06:20::DEBUG::engine-setup::1887::root:: org-name:
> sh.intel.com
> 2013-01-18 08:06:20::DEBUG::engine-setup::1887::root::
> default-dc-type: NFS
> 2013-01-18 08:06:20::DEBUG::engine-setup::1887::root::
> db-remote-install: local
> 2013-01-18 08:06:20::DEBUG::engine-setup::1887::root:: db-host:
> localhost
> 2013-01-18 08:06:20::DEBUG::engine-setup::1887::root:: db-local-pass:
> ********
> 2013-01-18 08:06:20::DEBUG::engine-setup::1887::root:: nfs-mp:
> /var/lib/exports/iso
> 2013-01-18 08:06:20::DEBUG::engine-setup::1887::root::
> iso-domain-name: ISO_DOMAIN
> 2013-01-18 08:06:20::DEBUG::engine-setup::1887::root:: config-nfs:
> yes
> 2013-01-18 08:06:20::DEBUG::engine-setup::1887::root::
> override-firewall: None
> 2013-01-18 08:06:20::ERROR::engine-setup::2303::root:: Traceback
> (most recent call last):
> File "/usr/local/bin/engine-setup", line 2297, in <module>
> main(confFile)
> File "/usr/local/bin/engine-setup", line 2080, in main
> runSequences()
> File "/usr/local/bin/engine-setup", line 2002, in runSequences
> controller.runAllSequences()
> File "/usr/local/share/ovirt-engine/scripts/setup_controller.py",
> line 54, in runAllSequences
> sequence.run()
> File "/usr/local/share/ovirt-engine/scripts/setup_sequences.py", line
> 154, in run
> step.run()
> File "/usr/local/share/ovirt-engine/scripts/setup_sequences.py", line
> 60, in run
> function()
> File "/usr/local/bin/engine-setup", line 1085, in _upgradeDB
> utils.backupDB(basedefs.DB_NAME, getDbUser(), dbBackupFile,
> getDbHostName(), getDbPort())
> File "/usr/local/share/ovirt-engine/scripts/common_utils.py", line
> 837, in backupDB
> output, rc = execCmd(cmdList=cmd, failOnError=True,
> msg=output_messages.ERR_DB_BACKUP, envDict=getPgPassEnv())
> File "/usr/local/share/ovirt-engine/scripts/common_utils.py", line
> 473, in execCmd
> raise Exception(msg)
> Exception: Database backup failed
> Best Regards,
> Dave Chen
> _______________________________________________
> Engine-devel mailing list
> Engine-devel at ovirt.org
> http://lists.ovirt.org/mailman/listinfo/engine-devel
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ovirt.org/pipermail/devel/attachments/20130121/285c0efd/attachment-0002.html>
More information about the Devel
mailing list