Attachment 919825 Details for Bug 1121943 – log file

log file

rhevm-dwh-setup-2014_07_22_10_13_25.log (text/x-log), 12.74 KB, created by Petr Beňas on 2014-07-22 08:17:41 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Petr Beňas

Created: 2014-07-22 08:17:41 UTC

Size: 12.74 KB

patch

obsolete

>2014-07-22 10:13:25::DEBUG::rhevm-dwh-setup::417::root:: starting main()
>2014-07-22 10:13:25::DEBUG::configfile::70::ovirt.engine.ovirt_engine.configfile:: loading config '/etc/ovirt-engine/engine.conf.d/10-setup-database.conf'
>2014-07-22 10:13:25::DEBUG::configfile::70::ovirt.engine.ovirt_engine.configfile:: loading config '/etc/ovirt-engine/engine.conf.d/10-setup-database.conf'
>2014-07-22 10:13:25::DEBUG::configfile::70::ovirt.engine.ovirt_engine.configfile:: loading config '/etc/ovirt-engine/engine.conf.d/10-setup-database.conf'
>2014-07-22 10:13:25::DEBUG::common_utils::454::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-07-22 10:13:25::DEBUG::common_utils::915::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 '/root'
>2014-07-22 10:13:25::DEBUG::common_utils::970::root:: output = option_value
>3.3.0
>
>2014-07-22 10:13:25::DEBUG::common_utils::971::root:: stderr = 
>2014-07-22 10:13:25::DEBUG::common_utils::972::root:: retcode = 0
>2014-07-22 10:13:25::DEBUG::common_utils::157::root:: Value of MinimalETLVersion is 3.3.0
>2014-07-22 10:13:25::DEBUG::common_utils::915::root:: Executing command --> 'rpm -q --queryformat %{VERSION}-%{RELEASE} rhevm-dwh' in working directory '/root'
>2014-07-22 10:13:25::DEBUG::common_utils::970::root:: output = 3.3.4-1.el6ev
>2014-07-22 10:13:25::DEBUG::common_utils::971::root:: stderr = 
>2014-07-22 10:13:25::DEBUG::common_utils::972::root:: retcode = 0
>2014-07-22 10:13:25::DEBUG::common_utils::620::root:: setting regex (\d+\.\d+)\.(\d+)\-(\d+) againts 3.3.4-1.el6ev
>2014-07-22 10:13:25::DEBUG::common_utils::625::root:: found version, 3.3
>2014-07-22 10:13:25::DEBUG::common_utils::627::root:: found minorVersion 4
>2014-07-22 10:13:25::DEBUG::common_utils::629::root:: found release 1
>2014-07-22 10:13:25::DEBUG::common_utils::620::root:: setting regex (\d+\.\d+)\.(\d+)\-(\d+) againts 3.3.0-0
>2014-07-22 10:13:25::DEBUG::common_utils::625::root:: found version, 3.3
>2014-07-22 10:13:25::DEBUG::common_utils::627::root:: found minorVersion 0
>2014-07-22 10:13:25::DEBUG::common_utils::629::root:: found release 0
>2014-07-22 10:13:25::DEBUG::common_utils::494::root:: checking ovirt-engine service
>2014-07-22 10:13:25::DEBUG::common_utils::485::root:: checking the status of ovirt-engine
>2014-07-22 10:13:25::DEBUG::common_utils::264::root:: getting status for ovirt-engine
>2014-07-22 10:13:25::DEBUG::common_utils::274::root:: executing action ovirt-engine on service status
>2014-07-22 10:13:25::DEBUG::common_utils::915::root:: Executing command --> '/sbin/service ovirt-engine status' in working directory '/root'
>2014-07-22 10:13:25::DEBUG::common_utils::970::root:: output = ovirt-engine (pid  5589) is running...
>
>2014-07-22 10:13:25::DEBUG::common_utils::971::root:: stderr = 
>2014-07-22 10:13:25::DEBUG::common_utils::972::root:: retcode = 0
>2014-07-22 10:13:25::DEBUG::common_utils::496::root:: ovirt-engine is up and running
>2014-07-22 10:13:25::DEBUG::common_utils::398::root:: asking user: Would you like to stop the ovirt-engine service (yes|no): 
>2014-07-22 10:13:28::DEBUG::common_utils::402::root:: user answered: yes
>2014-07-22 10:13:28::DEBUG::decorators::18::root:: running stopEngineService
>2014-07-22 10:13:28::DEBUG::common_utils::509::root:: Stopping ovirt-engine
>2014-07-22 10:13:28::DEBUG::common_utils::230::root:: stopping ovirt-engine
>2014-07-22 10:13:28::DEBUG::common_utils::274::root:: executing action ovirt-engine on service stop
>2014-07-22 10:13:28::DEBUG::common_utils::915::root:: Executing command --> '/sbin/service ovirt-engine stop' in working directory '/root'
>2014-07-22 10:13:30::DEBUG::common_utils::970::root:: output = Stopping oVirt Engine: [  OK  ]
>
>2014-07-22 10:13:30::DEBUG::common_utils::971::root:: stderr = 
>2014-07-22 10:13:30::DEBUG::common_utils::972::root:: retcode = 0
>2014-07-22 10:13:30::DEBUG::common_utils::593::root:: Stopping ovirt-engine-dwhd
>2014-07-22 10:13:30::DEBUG::common_utils::584::root:: checking the status of dwhd
>2014-07-22 10:13:30::DEBUG::common_utils::264::root:: getting status for ovirt-engine-dwhd
>2014-07-22 10:13:30::DEBUG::common_utils::274::root:: executing action ovirt-engine-dwhd on service status
>2014-07-22 10:13:30::DEBUG::common_utils::915::root:: Executing command --> '/sbin/service ovirt-engine-dwhd status' in working directory '/root'
>2014-07-22 10:13:30::DEBUG::common_utils::970::root:: output = /etc/init.d/ovirt-engine-dwhd is stopped
>
>2014-07-22 10:13:30::DEBUG::common_utils::971::root:: stderr = 
>2014-07-22 10:13:30::DEBUG::common_utils::972::root:: retcode = 1
>2014-07-22 10:13:30::DEBUG::common_utils::915::root:: Executing command --> 'rpm -q --queryformat %{VERSION}-%{RELEASE} rhevm-dwh' in working directory '/root'
>2014-07-22 10:13:30::DEBUG::common_utils::970::root:: output = 3.3.4-1.el6ev
>2014-07-22 10:13:30::DEBUG::common_utils::971::root:: stderr = 
>2014-07-22 10:13:30::DEBUG::common_utils::972::root:: retcode = 0
>2014-07-22 10:13:30::DEBUG::common_utils::620::root:: setting regex (\d+\.\d+)\.(\d+)\-(\d+) againts 3.3.4-1.el6ev
>2014-07-22 10:13:30::DEBUG::common_utils::625::root:: found version, 3.3
>2014-07-22 10:13:30::DEBUG::common_utils::627::root:: found minorVersion 4
>2014-07-22 10:13:30::DEBUG::common_utils::629::root:: found release 1
>2014-07-22 10:13:30::DEBUG::rhevm-dwh-setup::378::root:: Setting etlVersion
>2014-07-22 10:13:30::DEBUG::rhevm-dwh-setup::379::root:: editing etl connectivity file
>2014-07-22 10:13:30::DEBUG::common_utils::915::root:: Executing command --> '/sbin/ip addr' in working directory '/root'
>2014-07-22 10:13:30::DEBUG::common_utils::970::root:: output = 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 16436 qdisc noqueue state UNKNOWN 
>    link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
>    inet 127.0.0.1/8 scope host lo
>    inet6 ::1/128 scope host 
>       valid_lft forever preferred_lft forever
>2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP qlen 1000
>    link/ether 00:1a:4a:01:3f:83 brd ff:ff:ff:ff:ff:ff
>    inet 10.34.62.99/22 brd 10.34.63.255 scope global eth0
>    inet6 2620:52:0:223c:21a:4aff:fe01:3f83/64 scope global dynamic 
>       valid_lft 2591900sec preferred_lft 604700sec
>    inet6 fe80::21a:4aff:fe01:3f83/64 scope link 
>       valid_lft forever preferred_lft forever
>
>2014-07-22 10:13:30::DEBUG::common_utils::971::root:: stderr = 
>2014-07-22 10:13:30::DEBUG::common_utils::972::root:: retcode = 0
>2014-07-22 10:13:30::DEBUG::common_utils::758::root:: Found IP Address: 10.34.62.99
>2014-07-22 10:13:30::DEBUG::common_utils::1433::root:: Updating pghba postgres use
>2014-07-22 10:13:30::DEBUG::common_utils::532::root:: checking the status of postgresql
>2014-07-22 10:13:30::DEBUG::common_utils::264::root:: getting status for postgresql
>2014-07-22 10:13:30::DEBUG::common_utils::274::root:: executing action postgresql on service status
>2014-07-22 10:13:30::DEBUG::common_utils::915::root:: Executing command --> '/sbin/service postgresql status' in working directory '/root'
>2014-07-22 10:13:30::DEBUG::common_utils::970::root:: output = postmaster (pid  2573) is running...
>
>2014-07-22 10:13:30::DEBUG::common_utils::971::root:: stderr = 
>2014-07-22 10:13:30::DEBUG::common_utils::972::root:: retcode = 0
>2014-07-22 10:13:30::DEBUG::common_utils::576::root:: stopping postgresql
>2014-07-22 10:13:30::DEBUG::common_utils::230::root:: stopping postgresql
>2014-07-22 10:13:30::DEBUG::common_utils::274::root:: executing action postgresql on service stop
>2014-07-22 10:13:30::DEBUG::common_utils::915::root:: Executing command --> '/sbin/service postgresql stop' in working directory '/root'
>2014-07-22 10:13:32::DEBUG::common_utils::970::root:: output = Stopping postgresql service: [  OK  ]
>
>2014-07-22 10:13:32::DEBUG::common_utils::971::root:: stderr = 
>2014-07-22 10:13:32::DEBUG::common_utils::972::root:: retcode = 0
>2014-07-22 10:13:32::DEBUG::common_utils::532::root:: checking the status of postgresql
>2014-07-22 10:13:32::DEBUG::common_utils::264::root:: getting status for postgresql
>2014-07-22 10:13:32::DEBUG::common_utils::274::root:: executing action postgresql on service status
>2014-07-22 10:13:32::DEBUG::common_utils::915::root:: Executing command --> '/sbin/service postgresql status' in working directory '/root'
>2014-07-22 10:13:32::DEBUG::common_utils::970::root:: output = postmaster is stopped
>
>2014-07-22 10:13:32::DEBUG::common_utils::971::root:: stderr = 
>2014-07-22 10:13:32::DEBUG::common_utils::972::root:: retcode = 3
>2014-07-22 10:13:32::DEBUG::common_utils::571::root:: starting postgresql
>2014-07-22 10:13:32::DEBUG::common_utils::220::root:: starting postgresql
>2014-07-22 10:13:32::DEBUG::common_utils::274::root:: executing action postgresql on service start
>2014-07-22 10:13:32::DEBUG::common_utils::915::root:: Executing command --> '/sbin/service postgresql start' in working directory '/root'
>2014-07-22 10:13:34::DEBUG::common_utils::970::root:: output = Starting postgresql service: [  OK  ]
>
>2014-07-22 10:13:34::DEBUG::common_utils::971::root:: stderr = 
>2014-07-22 10:13:34::DEBUG::common_utils::972::root:: retcode = 0
>2014-07-22 10:13:34::DEBUG::common_utils::1477::root:: starting runPostgresSuQuery database: template1 query: "select 1;"
>2014-07-22 10:13:34::DEBUG::common_utils::915::root:: Executing command --> '/bin/su -l postgres -c /usr/bin/psql -U postgres -d template1 -tA -c "select 1;"' in working directory '/root'
>2014-07-22 10:13:34::DEBUG::common_utils::970::root:: output = 
>2014-07-22 10:13:34::DEBUG::common_utils::971::root:: stderr = Password for user postgres: 
>psql: fe_sendauth: no password supplied
>
>2014-07-22 10:13:34::DEBUG::common_utils::972::root:: retcode = 2
>2014-07-22 10:13:36::DEBUG::common_utils::1477::root:: starting runPostgresSuQuery database: template1 query: "select 1;"
>2014-07-22 10:13:36::DEBUG::common_utils::915::root:: Executing command --> '/bin/su -l postgres -c /usr/bin/psql -U postgres -d template1 -tA -c "select 1;"' in working directory '/root'
>2014-07-22 10:13:36::DEBUG::common_utils::970::root:: output = 
>2014-07-22 10:13:36::DEBUG::common_utils::971::root:: stderr = Password for user postgres: 
>psql: fe_sendauth: no password supplied
>
>2014-07-22 10:13:36::DEBUG::common_utils::972::root:: retcode = 2
>2014-07-22 10:13:38::DEBUG::common_utils::1477::root:: starting runPostgresSuQuery database: template1 query: "select 1;"
>2014-07-22 10:13:38::DEBUG::common_utils::915::root:: Executing command --> '/bin/su -l postgres -c /usr/bin/psql -U postgres -d template1 -tA -c "select 1;"' in working directory '/root'
>2014-07-22 10:13:38::DEBUG::common_utils::970::root:: output = 
>2014-07-22 10:13:38::DEBUG::common_utils::971::root:: stderr = Password for user postgres: 
>psql: fe_sendauth: no password supplied
>
>2014-07-22 10:13:38::DEBUG::common_utils::972::root:: retcode = 2
>2014-07-22 10:13:40::DEBUG::common_utils::1477::root:: starting runPostgresSuQuery database: template1 query: "select 1;"
>2014-07-22 10:13:40::DEBUG::common_utils::915::root:: Executing command --> '/bin/su -l postgres -c /usr/bin/psql -U postgres -d template1 -tA -c "select 1;"' in working directory '/root'
>2014-07-22 10:13:40::DEBUG::common_utils::970::root:: output = 
>2014-07-22 10:13:40::DEBUG::common_utils::971::root:: stderr = Password for user postgres: 
>psql: fe_sendauth: no password supplied
>
>2014-07-22 10:13:40::DEBUG::common_utils::972::root:: retcode = 2
>2014-07-22 10:13:42::DEBUG::common_utils::1477::root:: starting runPostgresSuQuery database: template1 query: "select 1;"
>2014-07-22 10:13:42::DEBUG::common_utils::915::root:: Executing command --> '/bin/su -l postgres -c /usr/bin/psql -U postgres -d template1 -tA -c "select 1;"' in working directory '/root'
>2014-07-22 10:13:42::DEBUG::common_utils::970::root:: output = 
>2014-07-22 10:13:42::DEBUG::common_utils::971::root:: stderr = Password for user postgres: 
>psql: fe_sendauth: no password supplied
>
>2014-07-22 10:13:42::DEBUG::common_utils::972::root:: retcode = 2
>2014-07-22 10:13:44::ERROR::rhevm-dwh-setup::703::root:: Exception caught!
>2014-07-22 10:13:44::ERROR::rhevm-dwh-setup::704::root:: Traceback (most recent call last):
>  File "/usr/bin/rhevm-dwh-setup", line 461, in main
>    pg_updated = utils.configHbaIdent()
>  File "/usr/share/ovirt-engine-dwh/common_utils.py", line 1448, in configHbaIdent
>    restartPostgres(newval=='md5')
>  File "/usr/share/ovirt-engine-dwh/common_utils.py", line 890, in restartPostgres
>    startPostgres()
>  File "/usr/share/ovirt-engine-dwh/common_utils.py", line 559, in startPostgres
>    'Error: postgresql service couldn\'t be started. Stopping.'
>RuntimeError: Error: postgresql service couldn't be started. Stopping.
>

Actions: View

Attachments on bug 1121943: 919825