more /var/log/ovirt-engine/ovirt-engine-dwh-setup-2014_03_17_14_59_39.log
2014-03-17 14:59:39::DEBUG::ovirt-engine-dwh-setup::430::root:: starting main()
2014-03-17 14:59:39::DEBUG::common_utils::451::root:: running sql query on host: localhost, port: 5432, db: engine, user: engine, query: 'copy (
select option_value from vdc_options
where option_name like 'MinimalETLVersion'
) to stdout with csv header;'.
2014-03-17 14:59:39::DEBUG::common_utils::912::root:: Executing command --> '/usr/bin/psql --pset=tuples_only=on --set ON_ERROR_STOP=1 --dbname engine --host localhost --port 5432 --username
engine -w -c copy (
select option_value from vdc_options
where option_name like 'MinimalETLVersion'
) to stdout with csv header;' in working directory '/var/lib/pgsql/data'
2014-03-17 14:59:39::DEBUG::common_utils::967::root:: output = option_value
3.3.0
2014-03-17 14:59:39::DEBUG::common_utils::968::root:: stderr =
2014-03-17 14:59:39::DEBUG::common_utils::969::root:: retcode = 0
2014-03-17 14:59:39::DEBUG::common_utils::153::root:: Value of MinimalETLVersion is 3.3.0
2014-03-17 14:59:39::DEBUG::common_utils::912::root:: Executing command --> 'rpm -q --queryformat %{VERSION}-%{RELEASE} ovirt-engine-dwh' in working directory '/var/lib/pgsql/data'
2014-03-17 14:59:39::DEBUG::common_utils::967::root:: output = 3.3.4-1.fc19
2014-03-17 14:59:39::DEBUG::common_utils::968::root:: stderr =
2014-03-17 14:59:39::DEBUG::common_utils::969::root:: retcode = 0
2014-03-17 14:59:39::DEBUG::common_utils::617::root:: setting regex (\d+\.\d+)\.(\d+)\-(\d+) againts 3.3.4-1.fc19
2014-03-17 14:59:39::DEBUG::common_utils::622::root:: found version, 3.3
2014-03-17 14:59:39::DEBUG::common_utils::624::root:: found minorVersion 4
2014-03-17 14:59:39::DEBUG::common_utils::626::root:: found release 1
2014-03-17 14:59:39::DEBUG::common_utils::617::root:: setting regex (\d+\.\d+)\.(\d+)\-(\d+) againts 3.3.0-0
2014-03-17 14:59:39::DEBUG::common_utils::622::root:: found version, 3.3
2014-03-17 14:59:39::DEBUG::common_utils::624::root:: found minorVersion 0
2014-03-17 14:59:39::DEBUG::common_utils::626::root:: found release 0
2014-03-17 14:59:39::DEBUG::common_utils::491::root:: checking ovirt-engine service
2014-03-17 14:59:39::DEBUG::common_utils::482::root:: checking the status of ovirt-engine
2014-03-17 14:59:39::DEBUG::common_utils::260::root:: getting status for ovirt-engine
2014-03-17 14:59:39::DEBUG::common_utils::277::root:: executing action ovirt-engine on service status
2014-03-17 14:59:39::DEBUG::common_utils::912::root:: Executing command --> '/sbin/service ovirt-engine status' in working directory '/var/lib/pgsql/data'
2014-03-17 14:59:39::DEBUG::common_utils::967::root:: output = ovirt-engine.service - oVirt Engine
Loaded: loaded (/usr/lib/systemd/system/ovirt-engine.service; enabled)
Active: inactive (dead) since Mon 2014-03-17 14:58:56 EET; 43s ago
Main PID: 5794 (code=exited, status=0/SUCCESS)
CGroup: name=systemd:/system/ovirt-engine.service
Mar 17 14:52:31
vpo-rhevm.stfd.ro systemd[1]: Started oVirt Engine.
Mar 17 14:52:32
vpo-rhevm.stfd.ro ovirt-engine.py[5794]: 2014-03-17 14:52:32,340 ovirt-engine: WARNING _setupEngineApps:176 Application directory '/usr/share/ovirt-engine/ovirt-engine-report
s.war' does not exist, it will be ignored
Mar 17 14:58:55
vpo-rhevm.stfd.ro systemd[1]: Stopping oVirt Engine...
Mar 17 14:58:56
vpo-rhevm.stfd.ro systemd[1]: Stopped oVirt Engine.
2014-03-17 14:59:39::DEBUG::common_utils::968::root:: stderr = Redirecting to /bin/systemctl status ovirt-engine.service
2014-03-17 14:59:39::DEBUG::common_utils::969::root:: retcode = 3
2014-03-17 14:59:39::DEBUG::common_utils::590::root:: Stopping ovirt-engine-dwhd
2014-03-17 14:59:39::DEBUG::common_utils::581::root:: checking the status of dwhd
2014-03-17 14:59:39::DEBUG::common_utils::260::root:: getting status for ovirt-engine-dwhd
2014-03-17 14:59:39::DEBUG::common_utils::277::root:: executing action ovirt-engine-dwhd on service status
2014-03-17 14:59:39::DEBUG::common_utils::912::root:: Executing command --> '/sbin/service ovirt-engine-dwhd status' in working directory '/var/lib/pgsql/data'
2014-03-17 14:59:40::DEBUG::common_utils::967::root:: output = /etc/init.d/ovirt-engine-dwhd is stopped
2014-03-17 14:59:40::DEBUG::common_utils::968::root:: stderr =
2014-03-17 14:59:40::DEBUG::common_utils::969::root:: retcode = 1
2014-03-17 14:59:40::DEBUG::common_utils::912::root:: Executing command --> 'rpm -q --queryformat %{VERSION}-%{RELEASE} ovirt-engine-dwh' in working directory '/var/lib/pgsql/data'
2014-03-17 14:59:40::DEBUG::common_utils::967::root:: output = 3.3.4-1.fc19
2014-03-17 14:59:40::DEBUG::common_utils::968::root:: stderr =
2014-03-17 14:59:40::DEBUG::common_utils::969::root:: retcode = 0
2014-03-17 14:59:40::DEBUG::common_utils::617::root:: setting regex (\d+\.\d+)\.(\d+)\-(\d+) againts 3.3.4-1.fc19
2014-03-17 14:59:40::DEBUG::common_utils::622::root:: found version, 3.3
2014-03-17 14:59:40::DEBUG::common_utils::624::root:: found minorVersion 4
2014-03-17 14:59:40::DEBUG::common_utils::626::root:: found release 1
2014-03-17 14:59:40::DEBUG::ovirt-engine-dwh-setup::391::root:: Setting etlVersion
2014-03-17 14:59:40::DEBUG::ovirt-engine-dwh-setup::392::root:: editing etl connectivity file
2014-03-17 14:59:40::DEBUG::common_utils::912::root:: Executing command --> '/sbin/ip addr' in working directory '/var/lib/pgsql/data'
2014-03-17 14:59:40::DEBUG::common_utils::967::root:: output = 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default
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: bond0: <NO-CARRIER,BROADCAST,MULTICAST,MASTER,UP> mtu 1500 qdisc noqueue state DOWN group default
link/ether c2:71:02:2b:9b:01 brd ff:ff:ff:ff:ff:ff
3: ens32: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP group default qlen 1000
link/ether 00:0c:29:7f:5b:4b brd ff:ff:ff:ff:ff:ff
inet
10.125.1.171/24 brd 10.125.1.255 scope global ens32
valid_lft forever preferred_lft forever
inet6 fe80::20c:29ff:fe7f:5b4b/64 scope link
valid_lft forever preferred_lft forever
4: ;vdsmdummy;: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN group default
link/ether 72:06:8c:05:e3:a3 brd ff:ff:ff:ff:ff:ff
2014-03-17 14:59:40::DEBUG::common_utils::968::root:: stderr =
2014-03-17 14:59:40::DEBUG::common_utils::969::root:: retcode = 0
2014-03-17 14:59:40::DEBUG::common_utils::755::root:: Found IP Address: 10.125.1.171
2014-03-17 14:59:40::DEBUG::common_utils::1406::root:: Updating pghba postgres use
2014-03-17 14:59:40::DEBUG::common_utils::529::root:: checking the status of postgresql
2014-03-17 14:59:40::DEBUG::common_utils::260::root:: getting status for postgresql
2014-03-17 14:59:40::DEBUG::common_utils::277::root:: executing action postgresql on service status
2014-03-17 14:59:40::DEBUG::common_utils::912::root:: Executing command --> '/sbin/service postgresql status' in working directory '/var/lib/pgsql/data'
2014-03-17 14:59:40::DEBUG::common_utils::967::root:: output = postgresql.service - PostgreSQL database server
Loaded: loaded (/usr/lib/systemd/system/postgresql.service; enabled)
Active: active (running) since Mon 2014-03-17 14:59:32 EET; 8s ago
Process: 6503 ExecStop=/usr/bin/pg_ctl stop -D ${PGDATA} -s -m fast (code=exited, status=0/SUCCESS)
Process: 6514 ExecStart=/usr/bin/pg_ctl start -D ${PGDATA} -s -o -p ${PGPORT} -w -t 300 (code=exited, status=0/SUCCESS)
Process: 6507 ExecStartPre=/usr/bin/postgresql-check-db-dir ${PGDATA} (code=exited, status=0/SUCCESS)
Main PID: 6517 (postgres)
CGroup: name=systemd:/system/postgresql.service
├─6517 /usr/bin/postgres -D /var/lib/pgsql/data -p 5432
├─6518 postgres: logger process
├─6520 postgres: checkpointer process
├─6521 postgres: writer process
├─6522 postgres: wal writer process
├─6523 postgres: autovacuum launcher process
└─6524 postgres: stats collector process
Mar 17 14:59:31
vpo-rhevm.stfd.ro systemd[1]: Starting PostgreSQL database server...
Mar 17 14:59:32
vpo-rhevm.stfd.ro systemd[1]: Started PostgreSQL database server.
2014-03-17 14:59:40::DEBUG::common_utils::968::root:: stderr = Redirecting to /bin/systemctl status postgresql.service
2014-03-17 14:59:40::DEBUG::common_utils::969::root:: retcode = 0
2014-03-17 14:59:40::DEBUG::common_utils::573::root:: stopping postgresql
2014-03-17 14:59:40::DEBUG::common_utils::226::root:: stopping postgresql
2014-03-17 14:59:40::DEBUG::common_utils::277::root:: executing action postgresql on service stop
2014-03-17 14:59:40::DEBUG::common_utils::912::root:: Executing command --> '/sbin/service postgresql stop' in working directory '/var/lib/pgsql/data'
2014-03-17 14:59:41::DEBUG::common_utils::967::root:: output =
2014-03-17 14:59:41::DEBUG::common_utils::968::root:: stderr = Redirecting to /bin/systemctl stop postgresql.service
2014-03-17 14:59:41::DEBUG::common_utils::969::root:: retcode = 0
2014-03-17 14:59:41::DEBUG::common_utils::529::root:: checking the status of postgresql
2014-03-17 14:59:41::DEBUG::common_utils::260::root:: getting status for postgresql
2014-03-17 14:59:41::DEBUG::common_utils::277::root:: executing action postgresql on service status
2014-03-17 14:59:41::DEBUG::common_utils::912::root:: Executing command --> '/sbin/service postgresql status' in working directory '/var/lib/pgsql/data'
2014-03-17 14:59:41::DEBUG::common_utils::967::root:: output = postgresql.service - PostgreSQL database server
Loaded: loaded (/usr/lib/systemd/system/postgresql.service; enabled)
Active: inactive (dead) since Mon 2014-03-17 14:59:41 EET; 119ms ago
Process: 6567 ExecStop=/usr/bin/pg_ctl stop -D ${PGDATA} -s -m fast (code=exited, status=0/SUCCESS)
Process: 6514 ExecStart=/usr/bin/pg_ctl start -D ${PGDATA} -s -o -p ${PGPORT} -w -t 300 (code=exited, status=0/SUCCESS)
Process: 6507 ExecStartPre=/usr/bin/postgresql-check-db-dir ${PGDATA} (code=exited, status=0/SUCCESS)
Main PID: 6517 (code=exited, status=0/SUCCESS)
CGroup: name=systemd:/system/postgresql.service
Mar 17 14:59:31
vpo-rhevm.stfd.ro systemd[1]: Starting PostgreSQL database server...
Mar 17 14:59:32
vpo-rhevm.stfd.ro systemd[1]: Started PostgreSQL database server.
Mar 17 14:59:40
vpo-rhevm.stfd.ro systemd[1]: Stopping PostgreSQL database server...
Mar 17 14:59:41
vpo-rhevm.stfd.ro systemd[1]: Stopped PostgreSQL database server.
2014-03-17 14:59:41::DEBUG::common_utils::968::root:: stderr = Redirecting to /bin/systemctl status postgresql.service
2014-03-17 14:59:41::DEBUG::common_utils::969::root:: retcode = 3
2014-03-17 14:59:41::DEBUG::common_utils::568::root:: starting postgresql
2014-03-17 14:59:41::DEBUG::common_utils::216::root:: starting postgresql
2014-03-17 14:59:41::DEBUG::common_utils::277::root:: executing action postgresql on service start
2014-03-17 14:59:41::DEBUG::common_utils::912::root:: Executing command --> '/sbin/service postgresql start' in working directory '/var/lib/pgsql/data'
2014-03-17 14:59:42::DEBUG::common_utils::967::root:: output =
2014-03-17 14:59:43::DEBUG::common_utils::968::root:: stderr = Redirecting to /bin/systemctl start postgresql.service
2014-03-17 14:59:43::DEBUG::common_utils::969::root:: retcode = 0
2014-03-17 14:59:43::DEBUG::common_utils::1460::root:: starting runPostgresSuQuery database: template1 query: "select 1;"
2014-03-17 14:59:43::DEBUG::common_utils::912::root:: Executing command --> '/bin/su -l postgres -c /usr/bin/psql -U postgres -d template1 -tA -c "select 1;"' in working directory '/var/lib/
pgsql/data'
2014-03-17 14:59:43::DEBUG::common_utils::967::root:: output =
2014-03-17 14:59:43::DEBUG::common_utils::968::root:: stderr = Password for user postgres:
psql: fe_sendauth: no password supplied
2014-03-17 14:59:43::DEBUG::common_utils::969::root:: retcode = 2
2014-03-17 14:59:45::DEBUG::common_utils::1460::root:: starting runPostgresSuQuery database: template1 query: "select 1;"
2014-03-17 14:59:45::DEBUG::common_utils::912::root:: Executing command --> '/bin/su -l postgres -c /usr/bin/psql -U postgres -d template1 -tA -c "select 1;"' in working directory '/var/lib/
pgsql/data'
2014-03-17 14:59:45::DEBUG::common_utils::967::root:: output =
2014-03-17 14:59:45::DEBUG::common_utils::968::root:: stderr = Password for user postgres:
psql: fe_sendauth: no password supplied
2014-03-17 14:59:45::DEBUG::common_utils::969::root:: retcode = 2
2014-03-17 14:59:47::DEBUG::common_utils::1460::root:: starting runPostgresSuQuery database: template1 query: "select 1;"
2014-03-17 14:59:47::DEBUG::common_utils::912::root:: Executing command --> '/bin/su -l postgres -c /usr/bin/psql -U postgres -d template1 -tA -c "select 1;"' in working directory '/var/lib/
pgsql/data'
2014-03-17 14:59:47::DEBUG::common_utils::967::root:: output =
2014-03-17 14:59:47::DEBUG::common_utils::968::root:: stderr = Password for user postgres:
psql: fe_sendauth: no password supplied
2014-03-17 14:59:47::DEBUG::common_utils::969::root:: retcode = 2
2014-03-17 14:59:49::DEBUG::common_utils::1460::root:: starting runPostgresSuQuery database: template1 query: "select 1;"
2014-03-17 14:59:49::DEBUG::common_utils::912::root:: Executing command --> '/bin/su -l postgres -c /usr/bin/psql -U postgres -d template1 -tA -c "select 1;"' in working directory '/var/lib/
pgsql/data'
2014-03-17 14:59:49::DEBUG::common_utils::967::root:: output =
2014-03-17 14:59:49::DEBUG::common_utils::968::root:: stderr = Password for user postgres:
psql: fe_sendauth: no password supplied
2014-03-17 14:59:49::DEBUG::common_utils::969::root:: retcode = 2
2014-03-17 14:59:51::DEBUG::common_utils::1460::root:: starting runPostgresSuQuery database: template1 query: "select 1;"
2014-03-17 14:59:51::DEBUG::common_utils::912::root:: Executing command --> '/bin/su -l postgres -c /usr/bin/psql -U postgres -d template1 -tA -c "select 1;"' in working directory '/var/lib/
pgsql/data'
2014-03-17 14:59:52::DEBUG::common_utils::967::root:: output =
2014-03-17 14:59:52::DEBUG::common_utils::968::root:: stderr = Password for user postgres:
psql: fe_sendauth: no password supplied
2014-03-17 14:59:52::DEBUG::common_utils::969::root:: retcode = 2
2014-03-17 14:59:54::ERROR::ovirt-engine-dwh-setup::716::root:: Exception caught!
2014-03-17 14:59:54::ERROR::ovirt-engine-dwh-setup::717::root:: Traceback (most recent call last):
File "/usr/bin/ovirt-engine-dwh-setup", line 474, in main
pg_updated = utils.configHbaIdent()
File "/usr/share/ovirt-engine-dwh/common_utils.py", line 1431, in configHbaIdent
restartPostgres(newval=='md5')
File "/usr/share/ovirt-engine-dwh/common_utils.py", line 887, in restartPostgres
startPostgres()
File "/usr/share/ovirt-engine-dwh/common_utils.py", line 556, in startPostgres
'Error: postgresql service couldn\'t be started. Stopping.'
RuntimeError: Error: postgresql service couldn't be started. Stopping.