2019-03-21 05:40:42,447+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,447+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/log=bool:'True'
2019-03-21 05:40:42,447+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFileHandle=file:'<open file '/var/log/ovirt-engine/setup/ovirt-engine-provisiondb-20190321054042-a0zzpi.log', mode 'a' at 0x7f855faa1ed0>'
2019-03-21 05:40:42,447+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFileName=str:'/var/log/ovirt-engine/setup/ovirt-engine-provisiondb-20190321054042-a0zzpi.log'
2019-03-21 05:40:42,447+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFilter=_MyLoggerFilter:'filter'
2019-03-21 05:40:42,448+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFilterRe=list:'[<_sre.SRE_Pattern object at 0x20fcc70>]'
2019-03-21 05:40:42,448+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logRemoveAtExit=bool:'False'
2019-03-21 05:40:42,448+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,448+0900 DEBUG otopi.context context._executeMethod:128 Stage boot METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database.Plugin._boot
2019-03-21 05:40:42,448+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,449+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFilterKeys=list:'['OVESETUP_PROVISION_DB/password']'
2019-03-21 05:40:42,449+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,449+0900 DEBUG otopi.context context._executeMethod:128 Stage boot METHOD otopi.plugins.otopi.core.misc.Plugin._init
2019-03-21 05:40:42,449+0900 DEBUG otopi.context context.dumpSequence:793 SEQUENCE DUMP - BEGIN
2019-03-21 05:40:42,449+0900 DEBUG otopi.context context.dumpSequence:795 STAGE boot
2019-03-21 05:40:42,450+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.core.misc.Plugin._preinit (None)
2019-03-21 05:40:42,450+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.log.Plugin._init (otopi.core.log.init)
2019-03-21 05:40:42,450+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database.Plugin._boot (None)
2019-03-21 05:40:42,450+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.misc.Plugin._init (None)
2019-03-21 05:40:42,450+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.dialog.misc.Plugin._init (otopi.dialog.misc.boot)
2019-03-21 05:40:42,450+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.system.info.Plugin._init (None)
2019-03-21 05:40:42,451+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.dialog.human.Plugin._init (None)
2019-03-21 05:40:42,451+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.dialog.machine.Plugin._init (None)
2019-03-21 05:40:42,451+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.dialog.misc.Plugin._boot_misc_done (otopi.dialog.boot.done)
2019-03-21 05:40:42,451+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.packagers.dnfpackager.Plugin._boot (None)
2019-03-21 05:40:42,451+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.packagers.yumpackager.Plugin._boot (otopi.packagers.yum.boot)
2019-03-21 05:40:42,451+0900 DEBUG otopi.context context.dumpSequence:795 STAGE init
2019-03-21 05:40:42,451+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.config.Plugin._init (otopi.core.config.init)
2019-03-21 05:40:42,452+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.packagers.dnfpackager.Plugin._init (None)
2019-03-21 05:40:42,452+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.packagers.yumpackager.Plugin._init (None)
2019-03-21 05:40:42,452+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.system.command.Plugin._init (None)
2019-03-21 05:40:42,452+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.packagers.core.Plugin._init (otopi.packagers.detection)
2019-03-21 05:40:42,452+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.transaction.Plugin._init (otopi.core.transactions.init)
2019-03-21 05:40:42,452+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.dialog.answer_file.Plugin._init (None)
2019-03-21 05:40:42,452+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.dialog.cli.Plugin._init (None)
2019-03-21 05:40:42,453+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.dialog.machine.Plugin._init_machine_events_stuff (None)
2019-03-21 05:40:42,453+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.firewalld.Plugin._init (None)
2019-03-21 05:40:42,453+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.iptables.Plugin._init (None)
2019-03-21 05:40:42,453+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.ssh.Plugin._init (None)
2019-03-21 05:40:42,453+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.system.clock.Plugin._init (None)
2019-03-21 05:40:42,453+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.system.reboot.Plugin._init (None)
2019-03-21 05:40:42,454+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.core.misc.Plugin._init (None)
2019-03-21 05:40:42,454+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.core.offlinepackager.Plugin._init (None)
2019-03-21 05:40:42,454+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.postgres.Plugin._init (None)
2019-03-21 05:40:42,454+0900 DEBUG otopi.context context.dumpSequence:795 STAGE setup
2019-03-21 05:40:42,454+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.packagers.dnfpackager.Plugin._setup_existence (None)
2019-03-21 05:40:42,454+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.packagers.yumpackager.Plugin._setup_existence (None)
2019-03-21 05:40:42,454+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.config.Plugin._post_init (None)
2019-03-21 05:40:42,455+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.log.Plugin._setup (None)
2019-03-21 05:40:42,455+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.misc.Plugin._setup (None)
2019-03-21 05:40:42,455+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.packagers.dnfpackager.Plugin._setup (None)
2019-03-21 05:40:42,455+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.packagers.yumpackager.Plugin._setup (None)
2019-03-21 05:40:42,455+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.firewalld.Plugin._setup (None)
2019-03-21 05:40:42,455+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.hostname.Plugin._setup (None)
2019-03-21 05:40:42,456+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.services.openrc.Plugin._setup (None)
2019-03-21 05:40:42,456+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.services.rhel.Plugin._setup (None)
2019-03-21 05:40:42,456+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.services.systemd.Plugin._setup (None)
2019-03-21 05:40:42,456+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.system.clock.Plugin._setup (None)
2019-03-21 05:40:42,456+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.system.reboot.Plugin._setup (None)
2019-03-21 05:40:42,456+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database.Plugin._setup (None)
2019-03-21 05:40:42,456+0900 DEBUG otopi.context context.dumpSequence:795 STAGE internal_packages
2019-03-21 05:40:42,457+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.transaction.Plugin._pre_prepare (None)
2019-03-21 05:40:42,457+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.packagers.dnfpackager.Plugin._internal_packages_end (None)
2019-03-21 05:40:42,457+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.packagers.yumpackager.Plugin._internal_packages_end (None)
2019-03-21 05:40:42,457+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.transaction.Plugin._pre_end (None)
2019-03-21 05:40:42,457+0900 DEBUG otopi.context context.dumpSequence:795 STAGE programs
2019-03-21 05:40:42,457+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.system.command.Plugin._programs (otopi.system.command.detection)
2019-03-21 05:40:42,457+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.services.openrc.Plugin._programs (None)
2019-03-21 05:40:42,458+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.services.rhel.Plugin._programs (None)
2019-03-21 05:40:42,458+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.services.systemd.Plugin._programs (None)
2019-03-21 05:40:42,458+0900 DEBUG otopi.context context.dumpSequence:795 STAGE customization
2019-03-21 05:40:42,458+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.firewalld.Plugin._customization (None)
2019-03-21 05:40:42,458+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.config.Plugin._customize1 (None)
2019-03-21 05:40:42,458+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.dialog.cli.Plugin._customize (otopi.dialog.cli.customization)
2019-03-21 05:40:42,458+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database.Plugin._customization (None)
2019-03-21 05:40:42,459+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.config.Plugin._customize2 (None)
2019-03-21 05:40:42,459+0900 DEBUG otopi.context context.dumpSequence:795 STAGE validation
2019-03-21 05:40:42,459+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.misc.Plugin._validation (None)
2019-03-21 05:40:42,459+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.log.Plugin._validation (None)
2019-03-21 05:40:42,459+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.firewalld.Plugin._validation (otopi.network.firewalld.validation)
2019-03-21 05:40:42,459+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.hostname.Plugin._validation (None)
2019-03-21 05:40:42,459+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.iptables.Plugin._validate (otopi.network.iptables.validation)
2019-03-21 05:40:42,460+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.ssh.Plugin._validation (None)
2019-03-21 05:40:42,460+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database.Plugin._validation (None)
2019-03-21 05:40:42,460+0900 DEBUG otopi.context context.dumpSequence:795 STAGE transaction-prepare
2019-03-21 05:40:42,460+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.transaction.Plugin._main_prepare (None)
2019-03-21 05:40:42,460+0900 DEBUG otopi.context context.dumpSequence:795 STAGE early_misc
2019-03-21 05:40:42,460+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.firewalld.Plugin._early_misc (None)
2019-03-21 05:40:42,460+0900 DEBUG otopi.context context.dumpSequence:795 STAGE packages
2019-03-21 05:40:42,461+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.iptables.Plugin._packages (None)
2019-03-21 05:40:42,461+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.packagers.dnfpackager.Plugin._packages (None)
2019-03-21 05:40:42,461+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.packagers.yumpackager.Plugin._packages (None)
2019-03-21 05:40:42,461+0900 DEBUG otopi.context context.dumpSequence:795 STAGE misc
2019-03-21 05:40:42,461+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.system.command.Plugin._misc (otopi.system.command.redetection)
2019-03-21 05:40:42,461+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.firewalld.Plugin._misc (None)
2019-03-21 05:40:42,461+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.iptables.Plugin._store_iptables (None)
2019-03-21 05:40:42,462+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.ssh.Plugin._append_key (None)
2019-03-21 05:40:42,462+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.system.clock.Plugin._set_clock (None)
2019-03-21 05:40:42,462+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database.Plugin._misc (None)
2019-03-21 05:40:42,462+0900 DEBUG otopi.context context.dumpSequence:795 STAGE cleanup
2019-03-21 05:40:42,462+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.transaction.Plugin._main_end (None)
2019-03-21 05:40:42,462+0900 DEBUG otopi.context context.dumpSequence:795 STAGE closeup
2019-03-21 05:40:42,463+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.firewalld.Plugin._closeup (None)
2019-03-21 05:40:42,463+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.network.iptables.Plugin._closeup (None)
2019-03-21 05:40:42,463+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.system.reboot.Plugin._closeup (None)
2019-03-21 05:40:42,463+0900 DEBUG otopi.context context.dumpSequence:795 STAGE cleanup
2019-03-21 05:40:42,463+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.dialog.answer_file.Plugin._generate_answer_file (otopi.core.answer.file.generated)
2019-03-21 05:40:42,463+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.core.misc.Plugin._cleanup (None)
2019-03-21 05:40:42,463+0900 DEBUG otopi.context context.dumpSequence:795 STAGE pre-terminate
2019-03-21 05:40:42,464+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.misc.Plugin._preTerminate (None)
2019-03-21 05:40:42,464+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.dialog.cli.Plugin._pre_terminate (otopi.dialog.cli.termination)
2019-03-21 05:40:42,464+0900 DEBUG otopi.context context.dumpSequence:795 STAGE terminate
2019-03-21 05:40:42,464+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.core.misc.Plugin._terminate (None)
2019-03-21 05:40:42,464+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.dialog.human.Plugin._terminate (None)
2019-03-21 05:40:42,464+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.dialog.machine.Plugin._terminate (None)
2019-03-21 05:40:42,464+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.core.log.Plugin._terminate (None)
2019-03-21 05:40:42,465+0900 DEBUG otopi.context context.dumpSequence:795 STAGE reboot
2019-03-21 05:40:42,465+0900 DEBUG otopi.context context.dumpSequence:800     METHOD otopi.plugins.otopi.system.reboot.Plugin._reboot (None)
2019-03-21 05:40:42,465+0900 DEBUG otopi.context context.dumpSequence:802 SEQUENCE DUMP - END
2019-03-21 05:40:42,465+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,465+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/aborted=bool:'False'
2019-03-21 05:40:42,465+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/debug=int:'0'
2019-03-21 05:40:42,465+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/error=bool:'False'
2019-03-21 05:40:42,466+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/exceptionInfo=list:'[]'
2019-03-21 05:40:42,466+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/executionDirectory=str:'/root'
2019-03-21 05:40:42,466+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/exitCode=list:'[{'priority': 90001, 'code': 0}]'
2019-03-21 05:40:42,466+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/log=bool:'True'
2019-03-21 05:40:42,466+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/pluginGroups=str:'otopi:ovirt-engine-provisiondb'
2019-03-21 05:40:42,466+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/pluginPath=str:'/usr/share/otopi/plugins:/usr/share/ovirt-engine/setup/bin/../plugins'
2019-03-21 05:40:42,466+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/suppressEnvironmentKeys=list:'[]'
2019-03-21 05:40:42,467+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/configFileAppend=str:':/tmp/engine-backup.8a4U5hLYCx/pg-provision-answer-file'
2019-03-21 05:40:42,467+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/configFileName=str:'/etc/ovirt-engine-setup.conf'
2019-03-21 05:40:42,467+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/failOnPrioOverride=bool:'True'
2019-03-21 05:40:42,467+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logDir=str:'/var/log/ovirt-engine/setup'
2019-03-21 05:40:42,467+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFileHandle=file:'<open file '/var/log/ovirt-engine/setup/ovirt-engine-provisiondb-20190321054042-a0zzpi.log', mode 'a' at 0x7f855faa1ed0>'
2019-03-21 05:40:42,467+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFileName=str:'/var/log/ovirt-engine/setup/ovirt-engine-provisiondb-20190321054042-a0zzpi.log'
2019-03-21 05:40:42,467+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFileNamePrefix=str:'ovirt-engine-provisiondb'
2019-03-21 05:40:42,468+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFilter=_MyLoggerFilter:'filter'
2019-03-21 05:40:42,468+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFilterKeys=list:'['OVESETUP_PROVISION_DB/password']'
2019-03-21 05:40:42,468+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFilterRe=list:'[<_sre.SRE_Pattern object at 0x20fcc70>]'
2019-03-21 05:40:42,468+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logRemoveAtExit=bool:'False'
2019-03-21 05:40:42,468+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/randomizeEvents=bool:'False'
2019-03-21 05:40:42,468+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV INFO/PACKAGE_NAME=str:'otopi'
2019-03-21 05:40:42,468+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV INFO/PACKAGE_VERSION=str:'1.7.8'
2019-03-21 05:40:42,469+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/offlinePackager=bool:'True'
2019-03-21 05:40:42,469+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/yumpackagerEnabled=bool:'False'
2019-03-21 05:40:42,469+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,469+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,469+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV INFO/PACKAGE_NAME=str:'otopi'
2019-03-21 05:40:42,469+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV INFO/PACKAGE_VERSION=str:'1.7.8'
2019-03-21 05:40:42,470+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,470+0900 DEBUG otopi.context context._executeMethod:128 Stage boot METHOD otopi.plugins.otopi.dialog.misc.Plugin._init
2019-03-21 05:40:42,470+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,470+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/dialect=str:'human'
2019-03-21 05:40:42,470+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,471+0900 DEBUG otopi.context context._executeMethod:128 Stage boot METHOD otopi.plugins.otopi.system.info.Plugin._init
2019-03-21 05:40:42,471+0900 DEBUG otopi.plugins.otopi.system.info info._init:39 SYSTEM INFORMATION - BEGIN
2019-03-21 05:40:42,471+0900 DEBUG otopi.plugins.otopi.system.info info._init:40 executable /bin/python
2019-03-21 05:40:42,471+0900 DEBUG otopi.plugins.otopi.system.info info._init:41 python /bin/python
2019-03-21 05:40:42,471+0900 DEBUG otopi.plugins.otopi.system.info info._init:42 platform linux2
2019-03-21 05:40:42,472+0900 DEBUG otopi.plugins.otopi.system.info info._init:43 distribution ('CentOS Linux', '7.6.1810', 'Core')
2019-03-21 05:40:42,472+0900 DEBUG otopi.plugins.otopi.system.info info._init:44 host 'ovirt-en.vsjak'
2019-03-21 05:40:42,472+0900 DEBUG otopi.plugins.otopi.system.info info._init:50 uid 0 euid 0 gid 0 egid 0
2019-03-21 05:40:42,472+0900 DEBUG otopi.plugins.otopi.system.info info._init:52 SYSTEM INFORMATION - END
2019-03-21 05:40:42,472+0900 DEBUG otopi.context context._executeMethod:128 Stage boot METHOD otopi.plugins.otopi.dialog.human.Plugin._init
2019-03-21 05:40:42,567+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,567+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/autoAcceptDefault=bool:'False'
2019-03-21 05:40:42,568+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/boundary=str:'--=451b80dc-996f-432e-9e4f-2b29ef6d1141=--'
2019-03-21 05:40:42,568+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,568+0900 DEBUG otopi.context context._executeMethod:128 Stage boot METHOD otopi.plugins.otopi.dialog.machine.Plugin._init
2019-03-21 05:40:42,569+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:42,569+0900 DEBUG otopi.context context._executeMethod:128 Stage boot METHOD otopi.plugins.otopi.dialog.misc.Plugin._boot_misc_done
2019-03-21 05:40:42,570+0900 DEBUG otopi.context context._executeMethod:128 Stage boot METHOD otopi.plugins.otopi.packagers.dnfpackager.Plugin._boot
2019-03-21 05:40:42,571+0900 DEBUG otopi.plugins.otopi.packagers.dnfpackager dnfpackager._boot:173 Cannot initialize minidnf
2019-03-21 05:40:42,572+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,572+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/dnfDisabledPlugins=list:'[]'
2019-03-21 05:40:42,572+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/dnfExpireCache=bool:'True'
2019-03-21 05:40:42,573+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/dnfRollback=bool:'True'
2019-03-21 05:40:42,573+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/dnfpackagerEnabled=bool:'True'
2019-03-21 05:40:42,573+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/keepAliveInterval=int:'30'
2019-03-21 05:40:42,573+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,574+0900 DEBUG otopi.context context._executeMethod:128 Stage boot METHOD otopi.plugins.otopi.packagers.yumpackager.Plugin._boot
2019-03-21 05:40:42,574+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,575+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/yumDisabledPlugins=list:'[]'
2019-03-21 05:40:42,575+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/yumEnabledPlugins=list:'[]'
2019-03-21 05:40:42,575+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/yumExpireCache=bool:'True'
2019-03-21 05:40:42,575+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/yumRollback=bool:'True'
2019-03-21 05:40:42,576+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,576+0900 INFO otopi.context context.runSequence:741 Stage: Initializing
2019-03-21 05:40:42,576+0900 DEBUG otopi.context context.runSequence:745 STAGE init
2019-03-21 05:40:42,577+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.otopi.core.config.Plugin._init
2019-03-21 05:40:42,580+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,580+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_APACHE/configureRootRedirectionDefault=bool:'True'
2019-03-21 05:40:42,580+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_APACHE/configured=bool:'True'
2019-03-21 05:40:42,580+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/adminUser=str:'admin@internal'
2019-03-21 05:40:42,581+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/adminUserAuthzName=str:'internal-authz'
2019-03-21 05:40:42,581+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/adminUserAuthzType=str:'ovirt-engine-extension-aaa-jdbc'
2019-03-21 05:40:42,581+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/adminUserId=str:'3bddea78-ee81-4775-88d3-083b1d81c1a8'
2019-03-21 05:40:42,581+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/adminUserNamespace=str:'*'
2019-03-21 05:40:42,582+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/firewallManager=NoneType:'None'
2019-03-21 05:40:42,582+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/fqdn=str:'ovirt-en.vs.jak'
2019-03-21 05:40:42,582+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/imageioProxyConfig=bool:'True'
2019-03-21 05:40:42,582+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/isoDomainExists=bool:'True'
2019-03-21 05:40:42,583+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/isoDomainMountPoint=str:'/var/lib/exports/iso'
2019-03-21 05:40:42,583+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/isoDomainName=str:'ISO_DOMAIN'
2019-03-21 05:40:42,583+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/isoDomainSdUuid=str:'a3a535e4-c74e-4cff-a5a3-1930af2837f2'
2019-03-21 05:40:42,583+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/isoDomainStorageDir=NoneType:'None'
2019-03-21 05:40:42,583+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/jbossHome=str:'/usr/share/ovirt-engine-wildfly'
2019-03-21 05:40:42,584+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/sanWipeAfterDelete=bool:'False'
2019-03-21 05:40:42,584+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/websocketProxyConfig=bool:'True'
2019-03-21 05:40:42,584+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/engineStop=bool:'False'
2019-03-21 05:40:42,584+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/generatedByVersion=str:'4.1.1.8'
2019-03-21 05:40:42,585+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_DWH_CONFIG/remoteEngineConfigured=bool:'False'
2019-03-21 05:40:42,585+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_DWH_CONFIG/scale=str:'1'
2019-03-21 05:40:42,585+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_DWH_CORE/enable=bool:'True'
2019-03-21 05:40:42,585+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_ENGINE_CORE/enable=bool:'True'
2019-03-21 05:40:42,586+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PKI/country=str:'US'
2019-03-21 05:40:42,586+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PKI/organization=str:'lan'
2019-03-21 05:40:42,586+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/database=str:'engine'
2019-03-21 05:40:42,586+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/dumper=str:'pg_custom'
2019-03-21 05:40:42,586+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/filter=NoneType:'None'
2019-03-21 05:40:42,587+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/host=str:'localhost'
2019-03-21 05:40:42,587+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/password=str:'**FILTERED**'
2019-03-21 05:40:42,587+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/port=int:'5432'
2019-03-21 05:40:42,587+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/restoreJobs=int:'2'
2019-03-21 05:40:42,588+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/secured=bool:'False'
2019-03-21 05:40:42,588+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/securedHostValidation=bool:'False'
2019-03-21 05:40:42,588+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/user=str:'engine'
2019-03-21 05:40:42,588+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_RPMDISTRO/jbossPackages=str:'ovirt-engine-wildfly,ovirt-engine-wildfly-overlay'
2019-03-21 05:40:42,588+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_SYSTEM/nfsConfigEnabled_legacyInPostInstall=bool:'False'
2019-03-21 05:40:42,589+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_VMCONSOLE_PROXY_CONFIG/vmconsoleProxyConfig=bool:'True'
2019-03-21 05:40:42,589+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,590+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.otopi.packagers.dnfpackager.Plugin._init
2019-03-21 05:40:42,590+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:42,591+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.otopi.packagers.yumpackager.Plugin._init
2019-03-21 05:40:42,591+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:42,592+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.otopi.system.command.Plugin._init
2019-03-21 05:40:42,593+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,593+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/commandPath=str:'/sbin:/bin:/usr/sbin:/usr/bin'
2019-03-21 05:40:42,594+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,594+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.otopi.packagers.core.Plugin._init
2019-03-21 05:40:42,595+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.otopi.core.transaction.Plugin._init
2019-03-21 05:40:42,596+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,596+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/internalPackageTransaction=Transaction:'transaction'
2019-03-21 05:40:42,596+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/mainTransaction=Transaction:'transaction'
2019-03-21 05:40:42,597+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/modifiedFiles=list:'[]'
2019-03-21 05:40:42,597+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,598+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.otopi.dialog.answer_file.Plugin._init
2019-03-21 05:40:42,598+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,598+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/answerFile=NoneType:'None'
2019-03-21 05:40:42,599+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,600+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.otopi.dialog.cli.Plugin._init
2019-03-21 05:40:42,600+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,600+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/cliVersion=int:'1'
2019-03-21 05:40:42,600+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/customization=bool:'False'
2019-03-21 05:40:42,601+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,602+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.otopi.dialog.machine.Plugin._init_machine_events_stuff
2019-03-21 05:40:42,602+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:42,603+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.otopi.network.firewalld.Plugin._init
2019-03-21 05:40:42,603+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,604+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/firewalldAvailable=bool:'False'
2019-03-21 05:40:42,604+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/firewalldDisableServices=list:'[]'
2019-03-21 05:40:42,604+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/firewalldEnable=bool:'False'
2019-03-21 05:40:42,605+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,605+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.otopi.network.iptables.Plugin._init
2019-03-21 05:40:42,606+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,606+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/iptablesEnable=bool:'False'
2019-03-21 05:40:42,606+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/iptablesRules=NoneType:'None'
2019-03-21 05:40:42,607+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,607+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.otopi.network.ssh.Plugin._init
2019-03-21 05:40:42,608+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,608+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/sshEnable=bool:'False'
2019-03-21 05:40:42,608+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/sshKey=NoneType:'None'
2019-03-21 05:40:42,608+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/sshUser=str:''
2019-03-21 05:40:42,609+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,610+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.otopi.system.clock.Plugin._init
2019-03-21 05:40:42,610+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,610+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/clockMaxGap=int:'5'
2019-03-21 05:40:42,611+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/clockSet=bool:'False'
2019-03-21 05:40:42,611+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,612+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.otopi.system.reboot.Plugin._init
2019-03-21 05:40:42,612+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,613+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/reboot=bool:'False'
2019-03-21 05:40:42,613+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/rebootAllow=bool:'True'
2019-03-21 05:40:42,613+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/rebootDeferTime=int:'10'
2019-03-21 05:40:42,613+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,614+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.core.misc.Plugin._init
2019-03-21 05:40:42,614+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.core.misc misc._init:89 Package: ovirt-engine-4.1.9.1 (4.1.9.1-1.el7.centos)
2019-03-21 05:40:42,615+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,615+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/action=str:'provisiondb'
2019-03-21 05:40:42,615+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/developerMode=NoneType:'None'
2019-03-21 05:40:42,615+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/setupAttributesModules=list:'[<module 'ovirt_engine_setup.constants' from '/usr/share/ovirt-engine/setup/ovirt_engine_setup/constants.pyc'>]'
2019-03-21 05:40:42,615+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/uninstallUnremovableFiles=list:'[]'
2019-03-21 05:40:42,616+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_SYSTEM/userPostgres=str:'postgres'
2019-03-21 05:40:42,616+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,617+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.core.offlinepackager.Plugin._init
2019-03-21 05:40:42,617+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.core.offlinepackager offlinepackager._init:84 Registering offline packager
2019-03-21 05:40:42,618+0900 DEBUG otopi.context context._executeMethod:128 Stage init METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.postgres.Plugin._init
2019-03-21 05:40:42,619+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,619+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresAutovacuumAnalyzeScaleFactor=float:'0.075'
2019-03-21 05:40:42,619+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresAutovacuumMaintenanceWorkMem=int:'65536'
2019-03-21 05:40:42,620+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresAutovacuumMaxWorkers=int:'6'
2019-03-21 05:40:42,620+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresAutovacuumVacuumScaleFactor=float:'0.01'
2019-03-21 05:40:42,620+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresConf=str:'/var/lib/pgsql/data/postgresql.conf'
2019-03-21 05:40:42,620+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresLCMessages=str:''en_US.UTF-8''
2019-03-21 05:40:42,620+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresListenAddress=str:''*''
2019-03-21 05:40:42,620+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresMaxConn=int:'150'
2019-03-21 05:40:42,621+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresPgHba=str:'/var/lib/pgsql/data/pg_hba.conf'
2019-03-21 05:40:42,621+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresPgVersion=str:'/var/lib/pgsql/data/PG_VERSION'
2019-03-21 05:40:42,621+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresService=str:'postgresql'
2019-03-21 05:40:42,621+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,622+0900 INFO otopi.context context.runSequence:741 Stage: Environment setup
2019-03-21 05:40:42,622+0900 DEBUG otopi.context context.runSequence:745 STAGE setup
2019-03-21 05:40:42,623+0900 DEBUG otopi.context context._executeMethod:128 Stage setup METHOD otopi.plugins.otopi.packagers.dnfpackager.Plugin._setup_existence
2019-03-21 05:40:42,623+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:42,624+0900 DEBUG otopi.context context._executeMethod:128 Stage setup METHOD otopi.plugins.otopi.packagers.yumpackager.Plugin._setup_existence
2019-03-21 05:40:42,624+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:42,626+0900 DEBUG otopi.context context._executeMethod:128 Stage setup METHOD otopi.plugins.otopi.core.config.Plugin._post_init
2019-03-21 05:40:42,626+0900 DEBUG otopi.plugins.otopi.dialog.human dialog.__logString:204 DIALOG:SEND                 Configuration files: ['/etc/ovirt-engine-setup.conf.d/10-packaging-jboss.conf', '/etc/ovirt-engine-setup.conf.d/10-packaging.conf', '/etc/ovirt-engine-setup.conf.d/20-setup-ovirt-post.conf', '/tmp/engine-backup.8a4U5hLYCx/pg-provision-answer-file']
2019-03-21 05:40:42,676+0900 DEBUG otopi.context context._executeMethod:128 Stage setup METHOD otopi.plugins.otopi.core.log.Plugin._setup
2019-03-21 05:40:42,676+0900 DEBUG otopi.plugins.otopi.dialog.human dialog.__logString:204 DIALOG:SEND                 Log file: /var/log/ovirt-engine/setup/ovirt-engine-provisiondb-20190321054042-a0zzpi.log
2019-03-21 05:40:42,726+0900 DEBUG otopi.context context._executeMethod:128 Stage setup METHOD otopi.plugins.otopi.core.misc.Plugin._setup
2019-03-21 05:40:42,726+0900 DEBUG otopi.plugins.otopi.dialog.human dialog.__logString:204 DIALOG:SEND                 Version: otopi-1.7.8 (otopi-1.7.8-1.el7)
2019-03-21 05:40:42,776+0900 DEBUG otopi.context context._executeMethod:128 Stage setup METHOD otopi.plugins.otopi.packagers.dnfpackager.Plugin._setup
2019-03-21 05:40:42,776+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:42,777+0900 DEBUG otopi.context context._executeMethod:128 Stage setup METHOD otopi.plugins.otopi.packagers.yumpackager.Plugin._setup
2019-03-21 05:40:42,778+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:42,779+0900 DEBUG otopi.context context._executeMethod:128 Stage setup METHOD otopi.plugins.otopi.network.firewalld.Plugin._setup
2019-03-21 05:40:42,779+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,780+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/firewall-cmd=NoneType:'None'
2019-03-21 05:40:42,780+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,781+0900 DEBUG otopi.context context._executeMethod:128 Stage setup METHOD otopi.plugins.otopi.network.hostname.Plugin._setup
2019-03-21 05:40:42,781+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,782+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/ip=NoneType:'None'
2019-03-21 05:40:42,782+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,783+0900 DEBUG otopi.context context._executeMethod:128 Stage setup METHOD otopi.plugins.otopi.services.openrc.Plugin._setup
2019-03-21 05:40:42,784+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,784+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/rc=NoneType:'None'
2019-03-21 05:40:42,784+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/rc-update=NoneType:'None'
2019-03-21 05:40:42,785+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,785+0900 DEBUG otopi.context context._executeMethod:128 Stage setup METHOD otopi.plugins.otopi.services.rhel.Plugin._setup
2019-03-21 05:40:42,786+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,786+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/chkconfig=NoneType:'None'
2019-03-21 05:40:42,786+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/initctl=NoneType:'None'
2019-03-21 05:40:42,786+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/service=NoneType:'None'
2019-03-21 05:40:42,787+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/systemctl=NoneType:'None'
2019-03-21 05:40:42,787+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,788+0900 DEBUG otopi.context context._executeMethod:128 Stage setup METHOD otopi.plugins.otopi.services.systemd.Plugin._setup
2019-03-21 05:40:42,790+0900 DEBUG otopi.context context._executeMethod:128 Stage setup METHOD otopi.plugins.otopi.system.clock.Plugin._setup
2019-03-21 05:40:42,790+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,790+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/chronyc=NoneType:'None'
2019-03-21 05:40:42,790+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/date=NoneType:'None'
2019-03-21 05:40:42,790+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/hwclock=NoneType:'None'
2019-03-21 05:40:42,791+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/ntpq=NoneType:'None'
2019-03-21 05:40:42,791+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,792+0900 DEBUG otopi.context context._executeMethod:128 Stage setup METHOD otopi.plugins.otopi.system.reboot.Plugin._setup
2019-03-21 05:40:42,793+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,793+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/reboot=NoneType:'None'
2019-03-21 05:40:42,794+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,794+0900 DEBUG otopi.context context._executeMethod:128 Stage setup METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database.Plugin._setup
2019-03-21 05:40:42,795+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,795+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/postgresql-setup=NoneType:'None'
2019-03-21 05:40:42,795+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/psql=NoneType:'None'
2019-03-21 05:40:42,796+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,796+0900 INFO otopi.context context.runSequence:741 Stage: Environment packages setup
2019-03-21 05:40:42,797+0900 DEBUG otopi.context context.runSequence:745 STAGE internal_packages
2019-03-21 05:40:42,797+0900 DEBUG otopi.context context._executeMethod:128 Stage internal_packages METHOD otopi.plugins.otopi.core.transaction.Plugin._pre_prepare
2019-03-21 05:40:42,799+0900 DEBUG otopi.context context._executeMethod:128 Stage internal_packages METHOD otopi.plugins.otopi.packagers.dnfpackager.Plugin._internal_packages_end
2019-03-21 05:40:42,799+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:42,801+0900 DEBUG otopi.context context._executeMethod:128 Stage internal_packages METHOD otopi.plugins.otopi.packagers.yumpackager.Plugin._internal_packages_end
2019-03-21 05:40:42,801+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:42,802+0900 DEBUG otopi.context context._executeMethod:128 Stage internal_packages METHOD otopi.plugins.otopi.core.transaction.Plugin._pre_end
2019-03-21 05:40:42,803+0900 INFO otopi.context context.runSequence:741 Stage: Programs detection
2019-03-21 05:40:42,803+0900 DEBUG otopi.context context.runSequence:745 STAGE programs
2019-03-21 05:40:42,804+0900 DEBUG otopi.context context._executeMethod:128 Stage programs METHOD otopi.plugins.otopi.system.command.Plugin._programs
2019-03-21 05:40:42,806+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,806+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/chkconfig=str:'/sbin/chkconfig'
2019-03-21 05:40:42,806+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/chronyc=str:'/bin/chronyc'
2019-03-21 05:40:42,807+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/date=str:'/bin/date'
2019-03-21 05:40:42,807+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/firewall-cmd=str:'/bin/firewall-cmd'
2019-03-21 05:40:42,807+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/hwclock=str:'/sbin/hwclock'
2019-03-21 05:40:42,807+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/ip=str:'/sbin/ip'
2019-03-21 05:40:42,807+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/postgresql-setup=str:'/bin/postgresql-setup'
2019-03-21 05:40:42,807+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/psql=str:'/bin/psql'
2019-03-21 05:40:42,808+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/reboot=str:'/sbin/reboot'
2019-03-21 05:40:42,808+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/service=str:'/sbin/service'
2019-03-21 05:40:42,808+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/systemctl=str:'/bin/systemctl'
2019-03-21 05:40:42,809+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,809+0900 DEBUG otopi.context context._executeMethod:128 Stage programs METHOD otopi.plugins.otopi.services.openrc.Plugin._programs
2019-03-21 05:40:42,811+0900 DEBUG otopi.context context._executeMethod:128 Stage programs METHOD otopi.plugins.otopi.services.rhel.Plugin._programs
2019-03-21 05:40:42,811+0900 DEBUG otopi.plugins.otopi.services.rhel plugin.executeRaw:813 execute: ('/bin/systemctl', 'show-environment'), executable='None', cwd='None', env=None
2019-03-21 05:40:42,822+0900 DEBUG otopi.plugins.otopi.services.rhel plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'show-environment'), rc=0
2019-03-21 05:40:42,823+0900 DEBUG otopi.plugins.otopi.services.rhel plugin.execute:921 execute-output: ('/bin/systemctl', 'show-environment') stdout:
LANG=ru_RU.UTF-8
PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin

2019-03-21 05:40:42,823+0900 DEBUG otopi.plugins.otopi.services.rhel plugin.execute:926 execute-output: ('/bin/systemctl', 'show-environment') stderr:


2019-03-21 05:40:42,825+0900 DEBUG otopi.context context._executeMethod:128 Stage programs METHOD otopi.plugins.otopi.services.systemd.Plugin._programs
2019-03-21 05:40:42,825+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'show-environment'), executable='None', cwd='None', env=None
2019-03-21 05:40:42,831+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'show-environment'), rc=0
2019-03-21 05:40:42,832+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'show-environment') stdout:
LANG=ru_RU.UTF-8
PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin

2019-03-21 05:40:42,832+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'show-environment') stderr:


2019-03-21 05:40:42,832+0900 DEBUG otopi.plugins.otopi.services.systemd systemd._programs:49 registering systemd provider
2019-03-21 05:40:42,833+0900 INFO otopi.context context.runSequence:741 Stage: Environment customization
2019-03-21 05:40:42,834+0900 DEBUG otopi.context context.runSequence:745 STAGE customization
2019-03-21 05:40:42,834+0900 DEBUG otopi.context context._executeMethod:128 Stage customization METHOD otopi.plugins.otopi.network.firewalld.Plugin._customization
2019-03-21 05:40:42,835+0900 DEBUG otopi.plugins.otopi.services.systemd systemd.exists:73 check if service firewalld exists
2019-03-21 05:40:42,835+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'show', '-p', 'LoadState', 'firewalld.service'), executable='None', cwd='None', env=None
2019-03-21 05:40:42,843+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'show', '-p', 'LoadState', 'firewalld.service'), rc=0
2019-03-21 05:40:42,843+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'show', '-p', 'LoadState', 'firewalld.service') stdout:
LoadState=loaded

2019-03-21 05:40:42,843+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'show', '-p', 'LoadState', 'firewalld.service') stderr:


2019-03-21 05:40:42,844+0900 DEBUG otopi.plugins.otopi.network.firewalld firewalld._get_firewalld_cmd_version:105 firewalld version: 0.5.3
2019-03-21 05:40:42,845+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,845+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/firewalldAvailable=bool:'True'
2019-03-21 05:40:42,845+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,846+0900 DEBUG otopi.context context._executeMethod:128 Stage customization METHOD otopi.plugins.otopi.core.config.Plugin._customize1
2019-03-21 05:40:42,847+0900 DEBUG otopi.context context._executeMethod:128 Stage customization METHOD otopi.plugins.otopi.dialog.cli.Plugin._customize
2019-03-21 05:40:42,848+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:42,849+0900 DEBUG otopi.context context._executeMethod:128 Stage customization METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database.Plugin._customization
2019-03-21 05:40:42,850+0900 DEBUG otopi.context context._executeMethod:128 Stage customization METHOD otopi.plugins.otopi.core.config.Plugin._customize2
2019-03-21 05:40:42,851+0900 INFO otopi.context context.runSequence:741 Stage: Setup validation
2019-03-21 05:40:42,851+0900 DEBUG otopi.context context.runSequence:745 STAGE validation
2019-03-21 05:40:42,852+0900 DEBUG otopi.context context._executeMethod:128 Stage validation METHOD otopi.plugins.otopi.core.misc.Plugin._validation
2019-03-21 05:40:42,852+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:42,852+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/aborted=bool:'False'
2019-03-21 05:40:42,852+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/debug=int:'0'
2019-03-21 05:40:42,852+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/error=bool:'False'
2019-03-21 05:40:42,853+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/exceptionInfo=list:'[]'
2019-03-21 05:40:42,853+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/executionDirectory=str:'/root'
2019-03-21 05:40:42,853+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/exitCode=list:'[{'priority': 90001, 'code': 0}]'
2019-03-21 05:40:42,853+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/log=bool:'True'
2019-03-21 05:40:42,853+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/pluginGroups=str:'otopi:ovirt-engine-provisiondb'
2019-03-21 05:40:42,853+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/pluginPath=str:'/usr/share/otopi/plugins:/usr/share/ovirt-engine/setup/bin/../plugins'
2019-03-21 05:40:42,854+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/suppressEnvironmentKeys=list:'[]'
2019-03-21 05:40:42,854+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/chkconfig=str:'/sbin/chkconfig'
2019-03-21 05:40:42,854+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/chronyc=str:'/bin/chronyc'
2019-03-21 05:40:42,854+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/date=str:'/bin/date'
2019-03-21 05:40:42,854+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/firewall-cmd=str:'/bin/firewall-cmd'
2019-03-21 05:40:42,854+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/hwclock=str:'/sbin/hwclock'
2019-03-21 05:40:42,854+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/initctl=NoneType:'None'
2019-03-21 05:40:42,855+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/ip=str:'/sbin/ip'
2019-03-21 05:40:42,855+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/ntpq=NoneType:'None'
2019-03-21 05:40:42,855+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/postgresql-setup=str:'/bin/postgresql-setup'
2019-03-21 05:40:42,855+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/psql=str:'/bin/psql'
2019-03-21 05:40:42,855+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/rc=NoneType:'None'
2019-03-21 05:40:42,855+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/rc-update=NoneType:'None'
2019-03-21 05:40:42,855+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/reboot=str:'/sbin/reboot'
2019-03-21 05:40:42,856+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/service=str:'/sbin/service'
2019-03-21 05:40:42,856+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/systemctl=str:'/bin/systemctl'
2019-03-21 05:40:42,856+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/configFileAppend=str:':/tmp/engine-backup.8a4U5hLYCx/pg-provision-answer-file'
2019-03-21 05:40:42,856+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/configFileName=str:'/etc/ovirt-engine-setup.conf'
2019-03-21 05:40:42,856+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/failOnPrioOverride=bool:'True'
2019-03-21 05:40:42,856+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/internalPackageTransaction=Transaction:'transaction'
2019-03-21 05:40:42,857+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logDir=str:'/var/log/ovirt-engine/setup'
2019-03-21 05:40:42,857+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFileHandle=file:'<open file '/var/log/ovirt-engine/setup/ovirt-engine-provisiondb-20190321054042-a0zzpi.log', mode 'a' at 0x7f855faa1ed0>'
2019-03-21 05:40:42,857+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFileName=str:'/var/log/ovirt-engine/setup/ovirt-engine-provisiondb-20190321054042-a0zzpi.log'
2019-03-21 05:40:42,857+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFileNamePrefix=str:'ovirt-engine-provisiondb'
2019-03-21 05:40:42,857+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFilter=_MyLoggerFilter:'filter'
2019-03-21 05:40:42,857+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFilterKeys=list:'['OVESETUP_PROVISION_DB/password']'
2019-03-21 05:40:42,857+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFilterRe=list:'[<_sre.SRE_Pattern object at 0x20fcc70>]'
2019-03-21 05:40:42,858+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logRemoveAtExit=bool:'False'
2019-03-21 05:40:42,858+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/mainTransaction=Transaction:'transaction'
2019-03-21 05:40:42,858+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/modifiedFiles=list:'[]'
2019-03-21 05:40:42,858+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/randomizeEvents=bool:'False'
2019-03-21 05:40:42,858+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/answerFile=NoneType:'None'
2019-03-21 05:40:42,858+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/autoAcceptDefault=bool:'False'
2019-03-21 05:40:42,858+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/boundary=str:'--=451b80dc-996f-432e-9e4f-2b29ef6d1141=--'
2019-03-21 05:40:42,859+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/cliVersion=int:'1'
2019-03-21 05:40:42,859+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/customization=bool:'False'
2019-03-21 05:40:42,859+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/dialect=str:'human'
2019-03-21 05:40:42,859+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV INFO/PACKAGE_NAME=str:'otopi'
2019-03-21 05:40:42,859+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV INFO/PACKAGE_VERSION=str:'1.7.8'
2019-03-21 05:40:42,859+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/firewalldAvailable=bool:'True'
2019-03-21 05:40:42,859+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/firewalldDisableServices=list:'[]'
2019-03-21 05:40:42,860+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/firewalldEnable=bool:'False'
2019-03-21 05:40:42,860+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/iptablesEnable=bool:'False'
2019-03-21 05:40:42,860+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/iptablesRules=NoneType:'None'
2019-03-21 05:40:42,860+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/sshEnable=bool:'False'
2019-03-21 05:40:42,860+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/sshKey=NoneType:'None'
2019-03-21 05:40:42,860+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/sshUser=str:''
2019-03-21 05:40:42,861+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_APACHE/configureRootRedirectionDefault=bool:'True'
2019-03-21 05:40:42,861+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_APACHE/configured=bool:'True'
2019-03-21 05:40:42,861+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/adminUser=str:'admin@internal'
2019-03-21 05:40:42,861+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/adminUserAuthzName=str:'internal-authz'
2019-03-21 05:40:42,861+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/adminUserAuthzType=str:'ovirt-engine-extension-aaa-jdbc'
2019-03-21 05:40:42,861+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/adminUserId=str:'3bddea78-ee81-4775-88d3-083b1d81c1a8'
2019-03-21 05:40:42,861+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/adminUserNamespace=str:'*'
2019-03-21 05:40:42,862+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/firewallManager=NoneType:'None'
2019-03-21 05:40:42,862+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/fqdn=str:'ovirt-en.vs.jak'
2019-03-21 05:40:42,862+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/imageioProxyConfig=bool:'True'
2019-03-21 05:40:42,862+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/isoDomainExists=bool:'True'
2019-03-21 05:40:42,862+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/isoDomainMountPoint=str:'/var/lib/exports/iso'
2019-03-21 05:40:42,862+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/isoDomainName=str:'ISO_DOMAIN'
2019-03-21 05:40:42,862+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/isoDomainSdUuid=str:'a3a535e4-c74e-4cff-a5a3-1930af2837f2'
2019-03-21 05:40:42,863+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/isoDomainStorageDir=NoneType:'None'
2019-03-21 05:40:42,863+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/jbossHome=str:'/usr/share/ovirt-engine-wildfly'
2019-03-21 05:40:42,863+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/sanWipeAfterDelete=bool:'False'
2019-03-21 05:40:42,863+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/websocketProxyConfig=bool:'True'
2019-03-21 05:40:42,863+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/action=str:'provisiondb'
2019-03-21 05:40:42,863+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/developerMode=NoneType:'None'
2019-03-21 05:40:42,863+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/engineStop=bool:'False'
2019-03-21 05:40:42,864+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/generatedByVersion=str:'4.1.1.8'
2019-03-21 05:40:42,864+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/offlinePackager=bool:'True'
2019-03-21 05:40:42,864+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/setupAttributesModules=list:'[<module 'ovirt_engine_setup.constants' from '/usr/share/ovirt-engine/setup/ovirt_engine_setup/constants.pyc'>]'
2019-03-21 05:40:42,864+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/uninstallUnremovableFiles=list:'[]'
2019-03-21 05:40:42,864+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_DWH_CONFIG/remoteEngineConfigured=bool:'False'
2019-03-21 05:40:42,864+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_DWH_CONFIG/scale=str:'1'
2019-03-21 05:40:42,864+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_DWH_CORE/enable=bool:'True'
2019-03-21 05:40:42,865+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_ENGINE_CORE/enable=bool:'True'
2019-03-21 05:40:42,865+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PKI/country=str:'US'
2019-03-21 05:40:42,865+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PKI/organization=str:'lan'
2019-03-21 05:40:42,865+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresAutovacuumAnalyzeScaleFactor=float:'0.075'
2019-03-21 05:40:42,865+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresAutovacuumMaintenanceWorkMem=int:'65536'
2019-03-21 05:40:42,865+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresAutovacuumMaxWorkers=int:'6'
2019-03-21 05:40:42,865+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresAutovacuumVacuumScaleFactor=float:'0.01'
2019-03-21 05:40:42,866+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresConf=str:'/var/lib/pgsql/data/postgresql.conf'
2019-03-21 05:40:42,866+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresLCMessages=str:''en_US.UTF-8''
2019-03-21 05:40:42,866+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresListenAddress=str:''*''
2019-03-21 05:40:42,866+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresMaxConn=int:'150'
2019-03-21 05:40:42,866+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresPgHba=str:'/var/lib/pgsql/data/pg_hba.conf'
2019-03-21 05:40:42,866+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresPgVersion=str:'/var/lib/pgsql/data/PG_VERSION'
2019-03-21 05:40:42,866+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresService=str:'postgresql'
2019-03-21 05:40:42,867+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/database=str:'engine'
2019-03-21 05:40:42,867+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/dumper=str:'pg_custom'
2019-03-21 05:40:42,867+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/filter=NoneType:'None'
2019-03-21 05:40:42,867+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/host=str:'localhost'
2019-03-21 05:40:42,867+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/password=str:'**FILTERED**'
2019-03-21 05:40:42,867+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/port=int:'5432'
2019-03-21 05:40:42,867+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/restoreJobs=int:'2'
2019-03-21 05:40:42,868+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/secured=bool:'False'
2019-03-21 05:40:42,868+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/securedHostValidation=bool:'False'
2019-03-21 05:40:42,868+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/user=str:'engine'
2019-03-21 05:40:42,868+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_RPMDISTRO/jbossPackages=str:'ovirt-engine-wildfly,ovirt-engine-wildfly-overlay'
2019-03-21 05:40:42,868+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_SYSTEM/nfsConfigEnabled_legacyInPostInstall=bool:'False'
2019-03-21 05:40:42,868+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_SYSTEM/userPostgres=str:'postgres'
2019-03-21 05:40:42,868+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_VMCONSOLE_PROXY_CONFIG/vmconsoleProxyConfig=bool:'True'
2019-03-21 05:40:42,869+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/dnfDisabledPlugins=list:'[]'
2019-03-21 05:40:42,869+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/dnfExpireCache=bool:'True'
2019-03-21 05:40:42,869+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/dnfRollback=bool:'True'
2019-03-21 05:40:42,869+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/dnfpackagerEnabled=bool:'True'
2019-03-21 05:40:42,869+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/keepAliveInterval=int:'30'
2019-03-21 05:40:42,869+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/yumDisabledPlugins=list:'[]'
2019-03-21 05:40:42,869+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/yumEnabledPlugins=list:'[]'
2019-03-21 05:40:42,870+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/yumExpireCache=bool:'True'
2019-03-21 05:40:42,870+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/yumRollback=bool:'True'
2019-03-21 05:40:42,870+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/yumpackagerEnabled=bool:'False'
2019-03-21 05:40:42,870+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/clockMaxGap=int:'5'
2019-03-21 05:40:42,870+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/clockSet=bool:'False'
2019-03-21 05:40:42,870+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/commandPath=str:'/sbin:/bin:/usr/sbin:/usr/bin'
2019-03-21 05:40:42,870+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/reboot=bool:'False'
2019-03-21 05:40:42,871+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/rebootAllow=bool:'True'
2019-03-21 05:40:42,871+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/rebootDeferTime=int:'10'
2019-03-21 05:40:42,871+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:42,872+0900 DEBUG otopi.context context._executeMethod:128 Stage validation METHOD otopi.plugins.otopi.core.log.Plugin._validation
2019-03-21 05:40:42,872+0900 DEBUG otopi.plugins.otopi.core.log log._validation:384 _filtered_keys_at_setup: []
2019-03-21 05:40:42,872+0900 DEBUG otopi.plugins.otopi.core.log log._validation:388 LOG_FILTER_KEYS: ['OVESETUP_PROVISION_DB/password']
2019-03-21 05:40:42,873+0900 DEBUG otopi.context context._executeMethod:128 Stage validation METHOD otopi.plugins.otopi.network.firewalld.Plugin._validation
2019-03-21 05:40:42,875+0900 DEBUG otopi.context context._executeMethod:128 Stage validation METHOD otopi.plugins.otopi.network.hostname.Plugin._validation
2019-03-21 05:40:42,875+0900 DEBUG otopi.plugins.otopi.network.hostname hostname._validation:55 my name: ovirt-en.vsjak
2019-03-21 05:40:43,336+0900 DEBUG otopi.plugins.otopi.network.hostname plugin.executeRaw:813 execute: ('/sbin/ip', 'addr', 'show'), executable='None', cwd='None', env=None
2019-03-21 05:40:43,345+0900 DEBUG otopi.plugins.otopi.network.hostname plugin.executeRaw:863 execute-result: ('/sbin/ip', 'addr', 'show'), rc=0
2019-03-21 05:40:43,346+0900 DEBUG otopi.plugins.otopi.network.hostname plugin.execute:921 execute-output: ('/sbin/ip', 'addr', 'show') stdout:
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default qlen 1000
    link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
    inet 127.0.0.1/8 scope host lo
       valid_lft forever preferred_lft forever
    inet6 ::1/128 scope host 
       valid_lft forever preferred_lft forever
2: enp2s0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP group default qlen 1000
    link/ether bc:5f:f4:6f:14:4d brd ff:ff:ff:ff:ff:ff
    inet 192.168.1.100/24 brd 192.168.1.255 scope global noprefixroute enp2s0
       valid_lft forever preferred_lft forever
    inet6 fe80::41f9:7e40:30af:85f7/64 scope link noprefixroute 
       valid_lft forever preferred_lft forever

2019-03-21 05:40:43,346+0900 DEBUG otopi.plugins.otopi.network.hostname plugin.execute:926 execute-output: ('/sbin/ip', 'addr', 'show') stderr:


2019-03-21 05:40:43,346+0900 DEBUG otopi.plugins.otopi.network.hostname hostname._validation:100 my addresses: ['fe80::41f9:7e40:30af:85f7%enp2s0', 'fe80::41f9:7e40:30af:85f7%enp2s0', 'fe80::41f9:7e40:30af:85f7%enp2s0', '192.168.1.100', '192.168.1.100', '192.168.1.100']
2019-03-21 05:40:43,347+0900 DEBUG otopi.plugins.otopi.network.hostname hostname._validation:101 local addresses: [u'192.168.1.100', u'fe80::41f9:7e40:30af:85f7']
2019-03-21 05:40:43,349+0900 DEBUG otopi.context context._executeMethod:128 Stage validation METHOD otopi.plugins.otopi.network.iptables.Plugin._validate
2019-03-21 05:40:43,349+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:43,351+0900 DEBUG otopi.context context._executeMethod:128 Stage validation METHOD otopi.plugins.otopi.network.ssh.Plugin._validation
2019-03-21 05:40:43,351+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:43,353+0900 DEBUG otopi.context context._executeMethod:128 Stage validation METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database.Plugin._validation
2019-03-21 05:40:43,353+0900 DEBUG otopi.plugins.otopi.services.systemd systemd.exists:73 check if service postgresql exists
2019-03-21 05:40:43,354+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'show', '-p', 'LoadState', 'postgresql.service'), executable='None', cwd='None', env=None
2019-03-21 05:40:43,363+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'show', '-p', 'LoadState', 'postgresql.service'), rc=0
2019-03-21 05:40:43,363+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'show', '-p', 'LoadState', 'postgresql.service') stdout:
LoadState=loaded

2019-03-21 05:40:43,363+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'show', '-p', 'LoadState', 'postgresql.service') stderr:


2019-03-21 05:40:43,365+0900 INFO otopi.context context.runSequence:741 Stage: Transaction setup
2019-03-21 05:40:43,365+0900 DEBUG otopi.context context.runSequence:745 STAGE transaction-prepare
2019-03-21 05:40:43,366+0900 DEBUG otopi.context context._executeMethod:128 Stage transaction-prepare METHOD otopi.plugins.otopi.core.transaction.Plugin._main_prepare
2019-03-21 05:40:43,367+0900 INFO otopi.context context.runSequence:741 Stage: Misc configuration
2019-03-21 05:40:43,368+0900 DEBUG otopi.context context.runSequence:745 STAGE early_misc
2019-03-21 05:40:43,369+0900 DEBUG otopi.context context._executeMethod:128 Stage early_misc METHOD otopi.plugins.otopi.network.firewalld.Plugin._early_misc
2019-03-21 05:40:43,369+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:43,370+0900 INFO otopi.context context.runSequence:741 Stage: Package installation
2019-03-21 05:40:43,370+0900 DEBUG otopi.context context.runSequence:745 STAGE packages
2019-03-21 05:40:43,371+0900 DEBUG otopi.context context._executeMethod:128 Stage packages METHOD otopi.plugins.otopi.network.iptables.Plugin._packages
2019-03-21 05:40:43,371+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:43,373+0900 DEBUG otopi.context context._executeMethod:128 Stage packages METHOD otopi.plugins.otopi.packagers.dnfpackager.Plugin._packages
2019-03-21 05:40:43,373+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:43,375+0900 DEBUG otopi.context context._executeMethod:128 Stage packages METHOD otopi.plugins.otopi.packagers.yumpackager.Plugin._packages
2019-03-21 05:40:43,375+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:43,376+0900 INFO otopi.context context.runSequence:741 Stage: Misc configuration
2019-03-21 05:40:43,376+0900 DEBUG otopi.context context.runSequence:745 STAGE misc
2019-03-21 05:40:43,377+0900 DEBUG otopi.context context._executeMethod:128 Stage misc METHOD otopi.plugins.otopi.system.command.Plugin._misc
2019-03-21 05:40:43,379+0900 DEBUG otopi.context context._executeMethod:128 Stage misc METHOD otopi.plugins.otopi.network.firewalld.Plugin._misc
2019-03-21 05:40:43,380+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:43,381+0900 DEBUG otopi.context context._executeMethod:128 Stage misc METHOD otopi.plugins.otopi.network.iptables.Plugin._store_iptables
2019-03-21 05:40:43,381+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:43,383+0900 DEBUG otopi.context context._executeMethod:128 Stage misc METHOD otopi.plugins.otopi.network.ssh.Plugin._append_key
2019-03-21 05:40:43,383+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:43,385+0900 DEBUG otopi.context context._executeMethod:128 Stage misc METHOD otopi.plugins.otopi.system.clock.Plugin._set_clock
2019-03-21 05:40:43,385+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:43,386+0900 DEBUG otopi.context context._executeMethod:128 Stage misc METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database.Plugin._misc
2019-03-21 05:40:43,387+0900 INFO otopi.ovirt_engine_setup.engine_common.postgres postgres._initDbIfRequired:240 Initializing PostgreSQL
2019-03-21 05:40:43,387+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/postgresql-setup', 'initdb'), executable='None', cwd='None', env=None
2019-03-21 05:40:46,790+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/postgresql-setup', 'initdb'), rc=0
2019-03-21 05:40:46,791+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/postgresql-setup', 'initdb') stdout:
Initializing database ... OK


2019-03-21 05:40:46,791+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/postgresql-setup', 'initdb') stderr:


2019-03-21 05:40:46,791+0900 INFO otopi.ovirt_engine_setup.engine_common.postgres postgres.provision:485 Creating PostgreSQL 'engine' database
2019-03-21 05:40:46,792+0900 DEBUG otopi.transaction transaction._prepare:61 preparing 'File transaction for '/var/lib/pgsql/data/pg_hba.conf''
2019-03-21 05:40:46,792+0900 DEBUG otopi.filetransaction filetransaction.prepare:185 file '/var/lib/pgsql/data/pg_hba.conf' exists
2019-03-21 05:40:46,792+0900 DEBUG otopi.filetransaction filetransaction.prepare:219 backup '/var/lib/pgsql/data/pg_hba.conf'->'/var/lib/pgsql/data/pg_hba.conf.20190321054046'
2019-03-21 05:40:46,921+0900 DEBUG otopi.plugins.otopi.services.systemd systemd.state:130 stopping service postgresql
2019-03-21 05:40:46,921+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'stop', 'postgresql.service'), executable='None', cwd='None', env=None
2019-03-21 05:40:46,931+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'stop', 'postgresql.service'), rc=0
2019-03-21 05:40:46,932+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'stop', 'postgresql.service') stdout:


2019-03-21 05:40:46,932+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'stop', 'postgresql.service') stderr:


2019-03-21 05:40:46,933+0900 DEBUG otopi.plugins.otopi.services.systemd systemd.state:130 starting service postgresql
2019-03-21 05:40:46,933+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'start', 'postgresql.service'), executable='None', cwd='None', env=None
2019-03-21 05:40:47,989+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'start', 'postgresql.service'), rc=0
2019-03-21 05:40:47,990+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'start', 'postgresql.service') stdout:


2019-03-21 05:40:47,990+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'start', 'postgresql.service') stderr:


2019-03-21 05:40:47,991+0900 DEBUG otopi.ovirt_engine_setup.engine_common.postgres postgres._waitForDatabase:407 Attempting to connect database
2019-03-21 05:40:47,991+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:212 Database: 'None', Statement: '
                    select 1
                ', args: {}
2019-03-21 05:40:47,991+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:217 Creating own connection
2019-03-21 05:40:48,000+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:262 Result: [{'?column?': 1}]
2019-03-21 05:40:48,001+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.tryDatabaseConnect:369 Connection succeeded
2019-03-21 05:40:48,001+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:212 Database: 'None', Statement: '
                select count(*) as count
                from pg_database
                where datname = %(database)s
            ', args: {'database': 'engine'}
2019-03-21 05:40:48,001+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:217 Creating own connection
2019-03-21 05:40:48,007+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:262 Result: [{'count': 0L}]
2019-03-21 05:40:48,007+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:212 Database: 'None', Statement: '
                select count(*) as count
                from pg_user
                where usename = %(user)s
            ', args: {'user': 'engine'}
2019-03-21 05:40:48,007+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:217 Creating own connection
2019-03-21 05:40:48,013+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:262 Result: [{'count': 0L}]
2019-03-21 05:40:48,013+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:212 Database: 'None', Statement: '
                        create role engine
                        with
                            login
                            encrypted password %(password)s
                    ', args: {'password': '**FILTERED**'}
2019-03-21 05:40:48,013+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:217 Creating own connection
2019-03-21 05:40:48,538+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:262 Result: []
2019-03-21 05:40:48,539+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:212 Database: 'None', Statement: '
                        create database engine
                        owner  engine
                        
                        template template0
                        encoding 'UTF8'
                        lc_collate 'en_US.UTF-8'
                        lc_ctype 'en_US.UTF-8'
                    
                    ', args: {'password': '**FILTERED**'}
2019-03-21 05:40:48,539+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:217 Creating own connection
2019-03-21 05:40:50,223+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:262 Result: []
2019-03-21 05:40:50,223+0900 DEBUG otopi.transaction transaction.abort:119 aborting 'File transaction for '/var/lib/pgsql/data/pg_hba.conf''
2019-03-21 05:40:50,224+0900 INFO otopi.ovirt_engine_setup.engine_common.postgres postgres.provision:529 Configuring PostgreSQL
2019-03-21 05:40:50,226+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/psql', '-V'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,234+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/psql', '-V'), rc=0
2019-03-21 05:40:50,235+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/psql', '-V') stdout:
psql (PostgreSQL) 9.2.24

2019-03-21 05:40:50,235+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/psql', '-V') stderr:


2019-03-21 05:40:50,236+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/psql', '-V'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,244+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/psql', '-V'), rc=0
2019-03-21 05:40:50,244+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/psql', '-V') stdout:
psql (PostgreSQL) 9.2.24

2019-03-21 05:40:50,245+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/psql', '-V') stderr:


2019-03-21 05:40:50,245+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/psql', '-V'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,253+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/psql', '-V'), rc=0
2019-03-21 05:40:50,253+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/psql', '-V') stdout:
psql (PostgreSQL) 9.2.24

2019-03-21 05:40:50,254+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/psql', '-V') stderr:


2019-03-21 05:40:50,254+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/psql', '-V'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,262+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/psql', '-V'), rc=0
2019-03-21 05:40:50,262+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/psql', '-V') stdout:
psql (PostgreSQL) 9.2.24

2019-03-21 05:40:50,262+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/psql', '-V') stderr:


2019-03-21 05:40:50,263+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/psql', '-V'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,270+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/psql', '-V'), rc=0
2019-03-21 05:40:50,271+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/psql', '-V') stdout:
psql (PostgreSQL) 9.2.24

2019-03-21 05:40:50,271+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/psql', '-V') stderr:


2019-03-21 05:40:50,271+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/psql', '-V'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,278+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/psql', '-V'), rc=0
2019-03-21 05:40:50,279+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/psql', '-V') stdout:
psql (PostgreSQL) 9.2.24

2019-03-21 05:40:50,279+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/psql', '-V') stderr:


2019-03-21 05:40:50,279+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/psql', '-V'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,286+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/psql', '-V'), rc=0
2019-03-21 05:40:50,287+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/psql', '-V') stdout:
psql (PostgreSQL) 9.2.24

2019-03-21 05:40:50,287+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/psql', '-V') stderr:


2019-03-21 05:40:50,287+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/psql', '-V'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,294+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/psql', '-V'), rc=0
2019-03-21 05:40:50,294+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/psql', '-V') stdout:
psql (PostgreSQL) 9.2.24

2019-03-21 05:40:50,294+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/psql', '-V') stderr:


2019-03-21 05:40:50,295+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/psql', '-V'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,301+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/psql', '-V'), rc=0
2019-03-21 05:40:50,302+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/psql', '-V') stdout:
psql (PostgreSQL) 9.2.24

2019-03-21 05:40:50,302+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/psql', '-V') stderr:


2019-03-21 05:40:50,303+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/psql', '-V'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,309+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/psql', '-V'), rc=0
2019-03-21 05:40:50,309+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/psql', '-V') stdout:
psql (PostgreSQL) 9.2.24

2019-03-21 05:40:50,309+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/psql', '-V') stderr:


2019-03-21 05:40:50,310+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/psql', '-V'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,316+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/psql', '-V'), rc=0
2019-03-21 05:40:50,316+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/psql', '-V') stdout:
psql (PostgreSQL) 9.2.24

2019-03-21 05:40:50,316+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/psql', '-V') stderr:


2019-03-21 05:40:50,317+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/psql', '-V'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,323+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/psql', '-V'), rc=0
2019-03-21 05:40:50,323+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/psql', '-V') stdout:
psql (PostgreSQL) 9.2.24

2019-03-21 05:40:50,323+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/psql', '-V') stderr:


2019-03-21 05:40:50,324+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/psql', '-V'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,329+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/psql', '-V'), rc=0
2019-03-21 05:40:50,330+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/psql', '-V') stdout:
psql (PostgreSQL) 9.2.24

2019-03-21 05:40:50,330+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/psql', '-V') stderr:


2019-03-21 05:40:50,330+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/psql', '-V'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,336+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/psql', '-V'), rc=0
2019-03-21 05:40:50,336+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/psql', '-V') stdout:
psql (PostgreSQL) 9.2.24

2019-03-21 05:40:50,337+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/psql', '-V') stderr:


2019-03-21 05:40:50,337+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/psql', '-V'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,343+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/psql', '-V'), rc=0
2019-03-21 05:40:50,343+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/psql', '-V') stdout:
psql (PostgreSQL) 9.2.24

2019-03-21 05:40:50,343+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/psql', '-V') stderr:


2019-03-21 05:40:50,344+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:813 execute: ('/bin/psql', '-V'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,349+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.executeRaw:863 execute-result: ('/bin/psql', '-V'), rc=0
2019-03-21 05:40:50,349+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:921 execute-output: ('/bin/psql', '-V') stdout:
psql (PostgreSQL) 9.2.24

2019-03-21 05:40:50,350+0900 DEBUG otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.provisioning.database plugin.execute:926 execute-output: ('/bin/psql', '-V') stderr:


2019-03-21 05:40:50,353+0900 DEBUG otopi.transaction transaction._prepare:61 preparing 'File transaction for '/var/lib/pgsql/data/postgresql.conf''
2019-03-21 05:40:50,353+0900 DEBUG otopi.filetransaction filetransaction.prepare:185 file '/var/lib/pgsql/data/postgresql.conf' exists
2019-03-21 05:40:50,353+0900 DEBUG otopi.filetransaction filetransaction.prepare:219 backup '/var/lib/pgsql/data/postgresql.conf'->'/var/lib/pgsql/data/postgresql.conf.20190321054050'
2019-03-21 05:40:50,424+0900 DEBUG otopi.transaction transaction._prepare:61 preparing 'File transaction for '/var/lib/pgsql/data/pg_hba.conf''
2019-03-21 05:40:50,424+0900 DEBUG otopi.filetransaction filetransaction.prepare:185 file '/var/lib/pgsql/data/pg_hba.conf' exists
2019-03-21 05:40:50,425+0900 DEBUG otopi.filetransaction filetransaction.prepare:219 backup '/var/lib/pgsql/data/pg_hba.conf'->'/var/lib/pgsql/data/pg_hba.conf.20190321054050'
2019-03-21 05:40:50,499+0900 DEBUG otopi.transaction transaction.commit:147 committing 'File transaction for '/var/lib/pgsql/data/pg_hba.conf''
2019-03-21 05:40:50,499+0900 DEBUG otopi.filetransaction filetransaction.commit:316 Executing restorecon for /var/lib/pgsql/data/pg_hba.conf
2019-03-21 05:40:50,509+0900 DEBUG otopi.filetransaction filetransaction.commit:330 restorecon result rc=0, stdout=, stderr=
2019-03-21 05:40:50,510+0900 DEBUG otopi.transaction transaction.commit:147 committing 'File transaction for '/var/lib/pgsql/data/postgresql.conf''
2019-03-21 05:40:50,511+0900 DEBUG otopi.filetransaction filetransaction.commit:316 Executing restorecon for /var/lib/pgsql/data/postgresql.conf
2019-03-21 05:40:50,520+0900 DEBUG otopi.filetransaction filetransaction.commit:330 restorecon result rc=0, stdout=, stderr=
2019-03-21 05:40:50,521+0900 DEBUG otopi.plugins.otopi.services.systemd systemd.startup:99 set service postgresql startup to True
2019-03-21 05:40:50,521+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'show', '-p', 'Id', 'postgresql.service'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,531+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'show', '-p', 'Id', 'postgresql.service'), rc=0
2019-03-21 05:40:50,531+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'show', '-p', 'Id', 'postgresql.service') stdout:
Id=postgresql.service

2019-03-21 05:40:50,532+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'show', '-p', 'Id', 'postgresql.service') stderr:


2019-03-21 05:40:50,532+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'enable', u'postgresql.service'), executable='None', cwd='None', env=None
2019-03-21 05:40:50,633+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'enable', u'postgresql.service'), rc=0
2019-03-21 05:40:50,633+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'enable', u'postgresql.service') stdout:


2019-03-21 05:40:50,633+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'enable', u'postgresql.service') stderr:
Created symlink from /etc/systemd/system/multi-user.target.wants/postgresql.service to /usr/lib/systemd/system/postgresql.service.

2019-03-21 05:40:50,634+0900 DEBUG otopi.plugins.otopi.services.systemd systemd.state:130 stopping service postgresql
2019-03-21 05:40:50,634+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'stop', 'postgresql.service'), executable='None', cwd='None', env=None
2019-03-21 05:40:51,653+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'stop', 'postgresql.service'), rc=0
2019-03-21 05:40:51,654+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'stop', 'postgresql.service') stdout:


2019-03-21 05:40:51,654+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'stop', 'postgresql.service') stderr:


2019-03-21 05:40:51,655+0900 DEBUG otopi.plugins.otopi.services.systemd systemd.state:130 starting service postgresql
2019-03-21 05:40:51,655+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'start', 'postgresql.service'), executable='None', cwd='None', env=None
2019-03-21 05:40:52,699+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'start', 'postgresql.service'), rc=0
2019-03-21 05:40:52,700+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'start', 'postgresql.service') stdout:


2019-03-21 05:40:52,700+0900 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'start', 'postgresql.service') stderr:


2019-03-21 05:40:52,701+0900 DEBUG otopi.ovirt_engine_setup.engine_common.postgres postgres._waitForDatabase:407 Attempting to connect database
2019-03-21 05:40:52,701+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:212 Database: 'None', Statement: '
                    select 1
                ', args: {}
2019-03-21 05:40:52,701+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:217 Creating own connection
2019-03-21 05:40:52,710+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.execute:262 Result: [{'?column?': 1}]
2019-03-21 05:40:52,710+0900 DEBUG otopi.ovirt_engine_setup.engine_common.database database.tryDatabaseConnect:369 Connection succeeded
2019-03-21 05:40:52,711+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:52,711+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/modifiedFiles=list:'['/var/lib/pgsql/data/pg_hba.conf', '/var/lib/pgsql/data/postgresql.conf']'
2019-03-21 05:40:52,711+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/uninstallUnremovableFiles=list:'['/var/lib/pgsql/data/postgresql.conf', '/var/lib/pgsql/data/pg_hba.conf']'
2019-03-21 05:40:52,712+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:52,712+0900 INFO otopi.context context.runSequence:741 Stage: Transaction commit
2019-03-21 05:40:52,713+0900 DEBUG otopi.context context.runSequence:745 STAGE cleanup
2019-03-21 05:40:52,714+0900 DEBUG otopi.context context._executeMethod:128 Stage cleanup METHOD otopi.plugins.otopi.core.transaction.Plugin._main_end
2019-03-21 05:40:52,715+0900 INFO otopi.context context.runSequence:741 Stage: Closing up
2019-03-21 05:40:52,715+0900 DEBUG otopi.context context.runSequence:745 STAGE closeup
2019-03-21 05:40:52,716+0900 DEBUG otopi.context context._executeMethod:128 Stage closeup METHOD otopi.plugins.otopi.network.firewalld.Plugin._closeup
2019-03-21 05:40:52,716+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:52,718+0900 DEBUG otopi.context context._executeMethod:128 Stage closeup METHOD otopi.plugins.otopi.network.iptables.Plugin._closeup
2019-03-21 05:40:52,718+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:52,720+0900 DEBUG otopi.context context._executeMethod:128 Stage closeup METHOD otopi.plugins.otopi.system.reboot.Plugin._closeup
2019-03-21 05:40:52,720+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:52,721+0900 INFO otopi.context context.runSequence:741 Stage: Clean up
2019-03-21 05:40:52,721+0900 DEBUG otopi.context context.runSequence:745 STAGE cleanup
2019-03-21 05:40:52,722+0900 DEBUG otopi.context context._executeMethod:128 Stage cleanup METHOD otopi.plugins.otopi.dialog.answer_file.Plugin._generate_answer_file
2019-03-21 05:40:52,723+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:52,723+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/answerFileContent=str:'# OTOPI answer file, generated by human dialog
[environment:default]
'
2019-03-21 05:40:52,724+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:52,724+0900 DEBUG otopi.context context._executeMethod:128 Stage cleanup METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.core.misc.Plugin._cleanup
2019-03-21 05:40:52,725+0900 DEBUG otopi.plugins.otopi.dialog.human dialog.__logString:204 DIALOG:SEND                 Log file is located at /var/log/ovirt-engine/setup/ovirt-engine-provisiondb-20190321054042-a0zzpi.log
2019-03-21 05:40:52,817+0900 INFO otopi.context context.runSequence:741 Stage: Pre-termination
2019-03-21 05:40:52,818+0900 DEBUG otopi.context context.runSequence:745 STAGE pre-terminate
2019-03-21 05:40:52,819+0900 DEBUG otopi.context context._executeMethod:128 Stage pre-terminate METHOD otopi.plugins.otopi.core.misc.Plugin._preTerminate
2019-03-21 05:40:52,819+0900 DEBUG otopi.context context.dumpEnvironment:859 ENVIRONMENT DUMP - BEGIN
2019-03-21 05:40:52,819+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/aborted=bool:'False'
2019-03-21 05:40:52,819+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/debug=int:'0'
2019-03-21 05:40:52,820+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/error=bool:'False'
2019-03-21 05:40:52,820+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/exceptionInfo=list:'[]'
2019-03-21 05:40:52,820+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/executionDirectory=str:'/root'
2019-03-21 05:40:52,820+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/exitCode=list:'[{'priority': 90001, 'code': 0}]'
2019-03-21 05:40:52,821+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/log=bool:'True'
2019-03-21 05:40:52,821+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/pluginGroups=str:'otopi:ovirt-engine-provisiondb'
2019-03-21 05:40:52,821+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/pluginPath=str:'/usr/share/otopi/plugins:/usr/share/ovirt-engine/setup/bin/../plugins'
2019-03-21 05:40:52,821+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV BASE/suppressEnvironmentKeys=list:'[]'
2019-03-21 05:40:52,822+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/chkconfig=str:'/sbin/chkconfig'
2019-03-21 05:40:52,822+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/chronyc=str:'/bin/chronyc'
2019-03-21 05:40:52,822+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/date=str:'/bin/date'
2019-03-21 05:40:52,822+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/firewall-cmd=str:'/bin/firewall-cmd'
2019-03-21 05:40:52,822+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/hwclock=str:'/sbin/hwclock'
2019-03-21 05:40:52,823+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/initctl=NoneType:'None'
2019-03-21 05:40:52,823+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/ip=str:'/sbin/ip'
2019-03-21 05:40:52,823+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/ntpq=NoneType:'None'
2019-03-21 05:40:52,823+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/postgresql-setup=str:'/bin/postgresql-setup'
2019-03-21 05:40:52,824+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/psql=str:'/bin/psql'
2019-03-21 05:40:52,824+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/rc=NoneType:'None'
2019-03-21 05:40:52,824+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/rc-update=NoneType:'None'
2019-03-21 05:40:52,824+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/reboot=str:'/sbin/reboot'
2019-03-21 05:40:52,824+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/service=str:'/sbin/service'
2019-03-21 05:40:52,825+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV COMMAND/systemctl=str:'/bin/systemctl'
2019-03-21 05:40:52,825+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/configFileAppend=str:':/tmp/engine-backup.8a4U5hLYCx/pg-provision-answer-file'
2019-03-21 05:40:52,825+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/configFileName=str:'/etc/ovirt-engine-setup.conf'
2019-03-21 05:40:52,825+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/failOnPrioOverride=bool:'True'
2019-03-21 05:40:52,826+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/internalPackageTransaction=Transaction:'transaction'
2019-03-21 05:40:52,826+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logDir=str:'/var/log/ovirt-engine/setup'
2019-03-21 05:40:52,826+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFileHandle=file:'<open file '/var/log/ovirt-engine/setup/ovirt-engine-provisiondb-20190321054042-a0zzpi.log', mode 'a' at 0x7f855faa1ed0>'
2019-03-21 05:40:52,826+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFileName=str:'/var/log/ovirt-engine/setup/ovirt-engine-provisiondb-20190321054042-a0zzpi.log'
2019-03-21 05:40:52,827+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFileNamePrefix=str:'ovirt-engine-provisiondb'
2019-03-21 05:40:52,827+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFilter=_MyLoggerFilter:'filter'
2019-03-21 05:40:52,827+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFilterKeys=list:'['OVESETUP_PROVISION_DB/password']'
2019-03-21 05:40:52,827+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logFilterRe=list:'[<_sre.SRE_Pattern object at 0x20fcc70>]'
2019-03-21 05:40:52,828+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/logRemoveAtExit=bool:'False'
2019-03-21 05:40:52,828+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/mainTransaction=Transaction:'transaction'
2019-03-21 05:40:52,828+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/modifiedFiles=list:'['/var/lib/pgsql/data/pg_hba.conf', '/var/lib/pgsql/data/postgresql.conf']'
2019-03-21 05:40:52,828+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV CORE/randomizeEvents=bool:'False'
2019-03-21 05:40:52,828+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/answerFile=NoneType:'None'
2019-03-21 05:40:52,829+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/answerFileContent=str:'# OTOPI answer file, generated by human dialog
[environment:default]
'
2019-03-21 05:40:52,829+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/autoAcceptDefault=bool:'False'
2019-03-21 05:40:52,829+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/boundary=str:'--=451b80dc-996f-432e-9e4f-2b29ef6d1141=--'
2019-03-21 05:40:52,829+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/cliVersion=int:'1'
2019-03-21 05:40:52,829+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/customization=bool:'False'
2019-03-21 05:40:52,830+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV DIALOG/dialect=str:'human'
2019-03-21 05:40:52,830+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV INFO/PACKAGE_NAME=str:'otopi'
2019-03-21 05:40:52,830+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV INFO/PACKAGE_VERSION=str:'1.7.8'
2019-03-21 05:40:52,830+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/firewalldAvailable=bool:'True'
2019-03-21 05:40:52,831+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/firewalldDisableServices=list:'[]'
2019-03-21 05:40:52,831+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/firewalldEnable=bool:'False'
2019-03-21 05:40:52,831+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/iptablesEnable=bool:'False'
2019-03-21 05:40:52,831+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/iptablesRules=NoneType:'None'
2019-03-21 05:40:52,831+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/sshEnable=bool:'False'
2019-03-21 05:40:52,832+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/sshKey=NoneType:'None'
2019-03-21 05:40:52,832+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV NETWORK/sshUser=str:''
2019-03-21 05:40:52,832+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_APACHE/configureRootRedirectionDefault=bool:'True'
2019-03-21 05:40:52,832+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_APACHE/configured=bool:'True'
2019-03-21 05:40:52,832+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/adminUser=str:'admin@internal'
2019-03-21 05:40:52,833+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/adminUserAuthzName=str:'internal-authz'
2019-03-21 05:40:52,833+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/adminUserAuthzType=str:'ovirt-engine-extension-aaa-jdbc'
2019-03-21 05:40:52,833+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/adminUserId=str:'3bddea78-ee81-4775-88d3-083b1d81c1a8'
2019-03-21 05:40:52,833+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/adminUserNamespace=str:'*'
2019-03-21 05:40:52,834+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/firewallManager=NoneType:'None'
2019-03-21 05:40:52,834+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/fqdn=str:'ovirt-en.vs.jak'
2019-03-21 05:40:52,834+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/imageioProxyConfig=bool:'True'
2019-03-21 05:40:52,834+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/isoDomainExists=bool:'True'
2019-03-21 05:40:52,834+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/isoDomainMountPoint=str:'/var/lib/exports/iso'
2019-03-21 05:40:52,835+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/isoDomainName=str:'ISO_DOMAIN'
2019-03-21 05:40:52,835+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/isoDomainSdUuid=str:'a3a535e4-c74e-4cff-a5a3-1930af2837f2'
2019-03-21 05:40:52,835+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/isoDomainStorageDir=NoneType:'None'
2019-03-21 05:40:52,835+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/jbossHome=str:'/usr/share/ovirt-engine-wildfly'
2019-03-21 05:40:52,835+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/sanWipeAfterDelete=bool:'False'
2019-03-21 05:40:52,836+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CONFIG/websocketProxyConfig=bool:'True'
2019-03-21 05:40:52,836+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/action=str:'provisiondb'
2019-03-21 05:40:52,836+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/developerMode=NoneType:'None'
2019-03-21 05:40:52,836+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/engineStop=bool:'False'
2019-03-21 05:40:52,837+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/generatedByVersion=str:'4.1.1.8'
2019-03-21 05:40:52,837+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/offlinePackager=bool:'True'
2019-03-21 05:40:52,837+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/setupAttributesModules=list:'[<module 'ovirt_engine_setup.constants' from '/usr/share/ovirt-engine/setup/ovirt_engine_setup/constants.pyc'>]'
2019-03-21 05:40:52,837+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_CORE/uninstallUnremovableFiles=list:'['/var/lib/pgsql/data/postgresql.conf', '/var/lib/pgsql/data/pg_hba.conf']'
2019-03-21 05:40:52,837+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_DWH_CONFIG/remoteEngineConfigured=bool:'False'
2019-03-21 05:40:52,838+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_DWH_CONFIG/scale=str:'1'
2019-03-21 05:40:52,838+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_DWH_CORE/enable=bool:'True'
2019-03-21 05:40:52,838+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_ENGINE_CORE/enable=bool:'True'
2019-03-21 05:40:52,838+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PKI/country=str:'US'
2019-03-21 05:40:52,838+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PKI/organization=str:'lan'
2019-03-21 05:40:52,839+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresAutovacuumAnalyzeScaleFactor=float:'0.075'
2019-03-21 05:40:52,839+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresAutovacuumMaintenanceWorkMem=int:'65536'
2019-03-21 05:40:52,839+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresAutovacuumMaxWorkers=int:'6'
2019-03-21 05:40:52,839+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresAutovacuumVacuumScaleFactor=float:'0.01'
2019-03-21 05:40:52,840+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresConf=str:'/var/lib/pgsql/data/postgresql.conf'
2019-03-21 05:40:52,840+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresLCMessages=str:''en_US.UTF-8''
2019-03-21 05:40:52,840+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresListenAddress=str:''*''
2019-03-21 05:40:52,840+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresMaxConn=int:'150'
2019-03-21 05:40:52,840+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresPgHba=str:'/var/lib/pgsql/data/pg_hba.conf'
2019-03-21 05:40:52,841+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresPgVersion=str:'/var/lib/pgsql/data/PG_VERSION'
2019-03-21 05:40:52,841+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISIONING/postgresService=str:'postgresql'
2019-03-21 05:40:52,841+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/database=str:'engine'
2019-03-21 05:40:52,841+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/dumper=str:'pg_custom'
2019-03-21 05:40:52,841+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/filter=NoneType:'None'
2019-03-21 05:40:52,842+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/host=str:'localhost'
2019-03-21 05:40:52,842+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/password=str:'**FILTERED**'
2019-03-21 05:40:52,842+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/port=int:'5432'
2019-03-21 05:40:52,842+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/restoreJobs=int:'2'
2019-03-21 05:40:52,842+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/secured=bool:'False'
2019-03-21 05:40:52,843+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/securedHostValidation=bool:'False'
2019-03-21 05:40:52,843+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_PROVISION_DB/user=str:'engine'
2019-03-21 05:40:52,843+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_RPMDISTRO/jbossPackages=str:'ovirt-engine-wildfly,ovirt-engine-wildfly-overlay'
2019-03-21 05:40:52,843+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_SYSTEM/nfsConfigEnabled_legacyInPostInstall=bool:'False'
2019-03-21 05:40:52,843+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_SYSTEM/userPostgres=str:'postgres'
2019-03-21 05:40:52,844+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV OVESETUP_VMCONSOLE_PROXY_CONFIG/vmconsoleProxyConfig=bool:'True'
2019-03-21 05:40:52,844+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/dnfDisabledPlugins=list:'[]'
2019-03-21 05:40:52,844+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/dnfExpireCache=bool:'True'
2019-03-21 05:40:52,844+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/dnfRollback=bool:'True'
2019-03-21 05:40:52,844+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/dnfpackagerEnabled=bool:'True'
2019-03-21 05:40:52,845+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/keepAliveInterval=int:'30'
2019-03-21 05:40:52,845+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/yumDisabledPlugins=list:'[]'
2019-03-21 05:40:52,845+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/yumEnabledPlugins=list:'[]'
2019-03-21 05:40:52,845+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/yumExpireCache=bool:'True'
2019-03-21 05:40:52,845+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/yumRollback=bool:'True'
2019-03-21 05:40:52,846+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV PACKAGER/yumpackagerEnabled=bool:'False'
2019-03-21 05:40:52,846+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/clockMaxGap=int:'5'
2019-03-21 05:40:52,846+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/clockSet=bool:'False'
2019-03-21 05:40:52,846+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/commandPath=str:'/sbin:/bin:/usr/sbin:/usr/bin'
2019-03-21 05:40:52,846+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/reboot=bool:'False'
2019-03-21 05:40:52,847+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/rebootAllow=bool:'True'
2019-03-21 05:40:52,847+0900 DEBUG otopi.context context.dumpEnvironment:869 ENV SYSTEM/rebootDeferTime=int:'10'
2019-03-21 05:40:52,847+0900 DEBUG otopi.context context.dumpEnvironment:873 ENVIRONMENT DUMP - END
2019-03-21 05:40:52,849+0900 DEBUG otopi.context context._executeMethod:128 Stage pre-terminate METHOD otopi.plugins.otopi.dialog.cli.Plugin._pre_terminate
2019-03-21 05:40:52,849+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:52,850+0900 INFO otopi.context context.runSequence:741 Stage: Termination
2019-03-21 05:40:52,850+0900 DEBUG otopi.context context.runSequence:745 STAGE terminate
2019-03-21 05:40:52,851+0900 DEBUG otopi.context context._executeMethod:128 Stage terminate METHOD otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.core.misc.Plugin._terminate
2019-03-21 05:40:52,851+0900 INFO otopi.plugins.ovirt_engine_provisiondb.ovirt_engine_common.core.misc misc._terminate:131 Execution of provisiondb completed successfully
2019-03-21 05:40:52,853+0900 DEBUG otopi.context context._executeMethod:128 Stage terminate METHOD otopi.plugins.otopi.dialog.human.Plugin._terminate
2019-03-21 05:40:52,927+0900 DEBUG otopi.context context._executeMethod:128 Stage terminate METHOD otopi.plugins.otopi.dialog.machine.Plugin._terminate
2019-03-21 05:40:52,927+0900 DEBUG otopi.context context._executeMethod:135 condition False
2019-03-21 05:40:52,929+0900 DEBUG otopi.context context._executeMethod:128 Stage terminate METHOD otopi.plugins.otopi.core.log.Plugin._terminate
