[Users] ovirt-engine-dwh-setup fails

Wed Mar 19 19:35:16 UTC 2014

Didi, can you take a look? 

Yaniv 

----- Original Message -----

> From: "Gabi C" <gabicr at gmail.com>
> To: users at ovirt.org
> Sent: Tuesday, March 18, 2014 12:29:41 PM
> Subject: [Users] ovirt-engine-dwh-setup fails

> Hello!

> ovirt-image-uploader-3.3.3-1.fc19.noarch
> ovirt-engine-dbscripts-3.3.4-1.fc19.noarch
> ovirt-host-deploy-1.1.3-1.fc19.noarch
> ovirt-engine-webadmin-portal-3.3.4-1.fc19.noarch
> ovirt-host-deploy-offline-1.1.3-1.fc19.noarch
> ovirt-engine-setup-3.3.4-1.fc19.noarch
> ovirt-engine-backend-3.3.4-1.fc19.noarch
> ovirt-host-deploy-java-1.1.3-1.fc19.noarch
> ovirt-engine-3.3.4-1.fc19.noarch
> ovirt-engine-dwh-3.3.4-1.fc19.noarch
> ovirt-engine-reports-3.3.4-1.fc19.noarch
> ovirt-engine-restapi-3.3.4-1.fc19.noarch
> ovirt-log-collector-3.3.4-1.fc19.noarch
> ovirt-iso-uploader-3.3.3-1.fc19.noarch
> ovirt-engine-lib-3.3.4-1.fc19.noarch
> ovirt-engine-tools-3.3.4-1.fc19.noarch
> ovirt-release-fedora-10.0.1-3.noarch
> ovirt-engine-cli-3.3.0.6-1.fc19.noarch
> ovirt-engine-websocket-proxy-3.3.4-1.fc19.noarch
> ovirt-engine-sdk-python-3.3.3.1-1.fc19.noarch
> ovirt-release-11.0.2-1.noarch
> ovirt-engine-userportal-3.3.4-1.fc19.noarch

> Trying to run ovirt-engine-dwh-setup I get

> Stopping ovirt-engine... [ DONE ]
> Error encountered while installing ovirt-engine-dwh, please consult the log
> file: ........

> logfile follows

> 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.

> _______________________________________________
> Users mailing list
> Users at ovirt.org
> http://lists.ovirt.org/mailman/listinfo/users
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ovirt.org/pipermail/users/attachments/20140319/c8302a8e/attachment-0001.html>