[Engine-devel] Upgrading Database Schema...error

Chen, Wei D wei.d.chen at intel.com
Fri Jan 18 13:39:28 UTC 2013


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

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ovirt.org/pipermail/devel/attachments/20130118/e9dd6555/attachment-0002.html>


More information about the Devel mailing list