Attachment 834798 Details for Bug 1040029 – dwh setup logs

dwh setup logs

rhevm-dwh-setup-2013_12_03_19_17_58.log (text/plain), 18.50 KB, created by Barak Dagan on 2013-12-10 14:41:36 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Barak Dagan

Created: 2013-12-10 14:41:36 UTC

Size: 18.50 KB

patch

obsolete

>2013-12-03 19:17:58::DEBUG::rhevm-dwh-setup::376::root:: starting main()
>2013-12-03 19:17:58::DEBUG::common_utils::445::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;'.
>2013-12-03 19:17:58::DEBUG::common_utils::864::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 '/etc/yum.repos.d'
>2013-12-03 19:17:59::DEBUG::common_utils::906::root:: output = option_value
>3.3.0
>
>2013-12-03 19:17:59::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:17:59::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:17:59::DEBUG::common_utils::152::root:: Value of MinimalETLVersion is 3.3.0
>2013-12-03 19:17:59::DEBUG::common_utils::864::root:: Executing command --> 'rpm -q --queryformat %{VERSION}-%{RELEASE} rhevm-dwh' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:00::DEBUG::common_utils::906::root:: output = 3.3.0-24.el6ev
>2013-12-03 19:18:00::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:00::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:18:00::DEBUG::common_utils::599::root:: setting regex (\d+\.\d+)\.(\d+)\-(\d+) againts 3.3.0-24.el6ev
>2013-12-03 19:18:00::DEBUG::common_utils::604::root:: found version, 3.3
>2013-12-03 19:18:00::DEBUG::common_utils::606::root:: found minorVersion 0
>2013-12-03 19:18:00::DEBUG::common_utils::608::root:: found release 24
>2013-12-03 19:18:00::DEBUG::common_utils::599::root:: setting regex (\d+\.\d+)\.(\d+)\-(\d+) againts 3.3.0-0
>2013-12-03 19:18:00::DEBUG::common_utils::604::root:: found version, 3.3
>2013-12-03 19:18:00::DEBUG::common_utils::606::root:: found minorVersion 0
>2013-12-03 19:18:00::DEBUG::common_utils::608::root:: found release 0
>2013-12-03 19:18:00::DEBUG::common_utils::473::root:: checking ovirt-engine service
>2013-12-03 19:18:00::DEBUG::common_utils::464::root:: checking the status of ovirt-engine
>2013-12-03 19:18:00::DEBUG::common_utils::259::root:: getting status for ovirt-engine
>2013-12-03 19:18:00::DEBUG::common_utils::276::root:: executing action ovirt-engine on service status
>2013-12-03 19:18:00::DEBUG::common_utils::864::root:: Executing command --> '/sbin/service ovirt-engine status' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:00::DEBUG::common_utils::906::root:: output = ovirt-engine (pid  9703) is running...
>
>2013-12-03 19:18:00::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:00::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:18:00::DEBUG::common_utils::475::root:: ovirt-engine is up and running
>2013-12-03 19:18:00::DEBUG::decorators::18::root:: running stopEngineService
>2013-12-03 19:18:00::DEBUG::common_utils::488::root:: Stopping ovirt-engine
>2013-12-03 19:18:00::DEBUG::common_utils::225::root:: stopping ovirt-engine
>2013-12-03 19:18:00::DEBUG::common_utils::276::root:: executing action ovirt-engine on service stop
>2013-12-03 19:18:00::DEBUG::common_utils::864::root:: Executing command --> '/sbin/service ovirt-engine stop' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:02::DEBUG::common_utils::906::root:: output = Stopping oVirt Engine: [  OK  ]
>
>2013-12-03 19:18:02::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:02::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:18:02::DEBUG::common_utils::572::root:: Stopping ovirt-engine-dwhd
>2013-12-03 19:18:02::DEBUG::common_utils::563::root:: checking the status of dwhd
>2013-12-03 19:18:02::DEBUG::common_utils::259::root:: getting status for ovirt-engine-dwhd
>2013-12-03 19:18:02::DEBUG::common_utils::276::root:: executing action ovirt-engine-dwhd on service status
>2013-12-03 19:18:02::DEBUG::common_utils::864::root:: Executing command --> '/sbin/service ovirt-engine-dwhd status' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:02::DEBUG::common_utils::906::root:: output = /etc/init.d/ovirt-engine-dwhd is stopped
>
>2013-12-03 19:18:02::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:02::DEBUG::common_utils::908::root:: retcode = 1
>2013-12-03 19:18:02::DEBUG::common_utils::864::root:: Executing command --> 'rpm -q --queryformat %{VERSION}-%{RELEASE} rhevm-dwh' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:02::DEBUG::common_utils::906::root:: output = 3.3.0-24.el6ev
>2013-12-03 19:18:02::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:02::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:18:02::DEBUG::common_utils::599::root:: setting regex (\d+\.\d+)\.(\d+)\-(\d+) againts 3.3.0-24.el6ev
>2013-12-03 19:18:02::DEBUG::common_utils::604::root:: found version, 3.3
>2013-12-03 19:18:02::DEBUG::common_utils::606::root:: found minorVersion 0
>2013-12-03 19:18:02::DEBUG::common_utils::608::root:: found release 24
>2013-12-03 19:18:02::DEBUG::rhevm-dwh-setup::350::root:: Setting etlVersion
>2013-12-03 19:18:02::DEBUG::rhevm-dwh-setup::351::root:: editing etl connectivity file
>2013-12-03 19:18:02::DEBUG::common_utils::864::root:: Executing command --> '/sbin/ip addr' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:02::DEBUG::common_utils::906::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:16:97:ea brd ff:ff:ff:ff:ff:ff
>    inet 10.35.161.234/24 brd 10.35.161.255 scope global eth0
>    inet6 fe80::21a:4aff:fe16:97ea/64 scope link 
>       valid_lft forever preferred_lft forever
>
>2013-12-03 19:18:02::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:02::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:18:02::DEBUG::common_utils::709::root:: Found IP Address: 10.35.161.234
>2013-12-03 19:18:02::DEBUG::common_utils::1316::root:: Updating pghba postgres use
>2013-12-03 19:18:02::DEBUG::common_utils::629::root:: checking if ovirt_engine_history db already exists
>2013-12-03 19:18:02::DEBUG::common_utils::445::root:: running sql query on host: localhost, port: 5432, db: ovirt_engine_history, user: engine_history, query: 'select 1'.
>2013-12-03 19:18:02::DEBUG::common_utils::864::root:: Executing command --> '/usr/bin/psql --pset=tuples_only=on --set ON_ERROR_STOP=1 --dbname ovirt_engine_history --host localhost --port 5432 --username engine_history -w -c select 1' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:02::DEBUG::common_utils::906::root:: output = 
>2013-12-03 19:18:02::DEBUG::common_utils::907::root:: stderr = psql: FATAL:  Ident authentication failed for user "engine_history"
>
>2013-12-03 19:18:02::DEBUG::common_utils::908::root:: retcode = 2
>2013-12-03 19:18:02::DEBUG::common_utils::864::root:: Executing command --> '/bin/su -l postgres -c /usr/bin/psql -U postgres -d ovirt_engine_history -tAc "select 1;"' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:03::DEBUG::common_utils::906::root:: output = 
>2013-12-03 19:18:03::DEBUG::common_utils::907::root:: stderr = psql: FATAL:  database "ovirt_engine_history" does not exist
>
>2013-12-03 19:18:03::DEBUG::common_utils::908::root:: retcode = 2
>2013-12-03 19:18:03::DEBUG::common_utils::864::root:: Executing command --> '/sbin/ip addr' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:03::DEBUG::common_utils::906::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:16:97:ea brd ff:ff:ff:ff:ff:ff
>    inet 10.35.161.234/24 brd 10.35.161.255 scope global eth0
>    inet6 fe80::21a:4aff:fe16:97ea/64 scope link 
>       valid_lft forever preferred_lft forever
>
>2013-12-03 19:18:03::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:03::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:18:03::DEBUG::common_utils::709::root:: Found IP Address: 10.35.161.234
>2013-12-03 19:18:03::DEBUG::common_utils::1104::root:: Adding user engine_history
>2013-12-03 19:18:03::DEBUG::common_utils::864::root:: Executing command --> '/bin/su -l postgres -c /usr/bin/psql -U postgres -c "CREATE ROLE engine_history with createdb login encrypted password '6095BIgZ';"' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:03::DEBUG::common_utils::906::root:: output = CREATE ROLE
>
>2013-12-03 19:18:03::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:03::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:18:03::DEBUG::common_utils::864::root:: Executing command --> '/bin/su -l postgres -c /usr/bin/psql -U postgres -tAc "CREATE DATABASE ovirt_engine_history encoding 'UTF8' LC_COLLATE 'en_US.UTF-8' LC_CTYPE 'en_US.UTF-8' template template0 owner engine_history;"' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:04::DEBUG::common_utils::906::root:: output = CREATE DATABASE
>
>2013-12-03 19:18:04::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:04::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:18:05::DEBUG::common_utils::1285::root:: Updating pghba
>2013-12-03 19:18:05::DEBUG::common_utils::511::root:: checking the status of postgresql
>2013-12-03 19:18:05::DEBUG::common_utils::259::root:: getting status for postgresql
>2013-12-03 19:18:05::DEBUG::common_utils::276::root:: executing action postgresql on service status
>2013-12-03 19:18:05::DEBUG::common_utils::864::root:: Executing command --> '/sbin/service postgresql status' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:05::DEBUG::common_utils::906::root:: output = postmaster (pid  2729) is running...
>
>2013-12-03 19:18:05::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:05::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:18:05::DEBUG::common_utils::555::root:: stopping postgresql
>2013-12-03 19:18:05::DEBUG::common_utils::225::root:: stopping postgresql
>2013-12-03 19:18:05::DEBUG::common_utils::276::root:: executing action postgresql on service stop
>2013-12-03 19:18:05::DEBUG::common_utils::864::root:: Executing command --> '/sbin/service postgresql stop' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:06::DEBUG::common_utils::906::root:: output = Stopping postgresql service: [  OK  ]
>
>2013-12-03 19:18:06::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:06::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:18:06::DEBUG::common_utils::511::root:: checking the status of postgresql
>2013-12-03 19:18:06::DEBUG::common_utils::259::root:: getting status for postgresql
>2013-12-03 19:18:06::DEBUG::common_utils::276::root:: executing action postgresql on service status
>2013-12-03 19:18:06::DEBUG::common_utils::864::root:: Executing command --> '/sbin/service postgresql status' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:06::DEBUG::common_utils::906::root:: output = postmaster is stopped
>
>2013-12-03 19:18:06::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:06::DEBUG::common_utils::908::root:: retcode = 3
>2013-12-03 19:18:06::DEBUG::common_utils::550::root:: starting postgresql
>2013-12-03 19:18:06::DEBUG::common_utils::215::root:: starting postgresql
>2013-12-03 19:18:06::DEBUG::common_utils::276::root:: executing action postgresql on service start
>2013-12-03 19:18:06::DEBUG::common_utils::864::root:: Executing command --> '/sbin/service postgresql start' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:08::DEBUG::common_utils::906::root:: output = Starting postgresql service: [  OK  ]
>
>2013-12-03 19:18:08::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:08::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:18:08::DEBUG::common_utils::864::root:: Executing command --> '/bin/su -l postgres -c /usr/bin/psql -U postgres -d template1 -tAc "select 1;"' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:08::DEBUG::common_utils::906::root:: output = 1
>
>2013-12-03 19:18:08::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:08::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:18:08::DEBUG::decorators::18::root:: running createDbSchema
>2013-12-03 19:18:08::DEBUG::rhevm-dwh-setup::148::root:: installing ovirt_engine_history db
>2013-12-03 19:18:08::DEBUG::rhevm-dwh-setup::151::root:: ovirt engine history db creation is logged at /var/log/ovirt-engine/ovirt-history-db-install-2013_12_03_19_18_08.log
>2013-12-03 19:18:08::DEBUG::common_utils::864::root:: Executing command --> '/usr/share/ovirt-engine-dwh/db-scripts/create_schema.sh -l ovirt-history-db-install-2013_12_03_19_18_08.log -u engine_history -s localhost -p 5432' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:19::DEBUG::common_utils::906::root:: output = user name is: engine_history
>Creating tables...
>Creating functions...
>Creating common functions...
>Inserting data  ...
>Inserting Timekeeping Values  ...
>Inserting ENUM Values ...
>Inserting Calendar Table's Values ...
>Running upgrade scripts...
>upgrade script detected a change in Config, View or Stored Procedure...
>Running upgrade sql script upgrade/pre_upgrade/add_comment_col_to_schema_version.sql ...
>Running upgrade sql script upgrade/pre_upgrade/add_function_to_update_enum_table.sql ...
>Running upgrade sql script upgrade/pre_upgrade/set_default_lang_code.sql ...
>Running upgrade sql script upgrade/pre_upgrade/set_etl_minimal_version.sql ...
>Running upgrade sql script upgrade/03_02_0010_add_vm_users_usage_tables.sql ...
>Running upgrade sql script upgrade/03_02_0020_add_storage_domain_status_columns.sql ...
>Running upgrade sql script upgrade/03_02_0030_drop_auto_suspend_column.sql ...
>Running upgrade sql script upgrade/03_02_0040_add_more_reports_enum_values.sql ...
>Running upgrade sql script upgrade/03_02_0050_add_function_to_update_enum_table.sql ...
>Running upgrade sql script upgrade/03_02_0060_updated_enums.sql ...
>Running upgrade sql script upgrade/03_02_0070_changed_vm_ip_column_to_text.sql ...
>Running upgrade sql script upgrade/03_02_0080_added_missing_os_enums.sql ...
>Running upgrade sql script upgrade/03_02_0090_updated_enums.sql ...
>Running upgrade sql script upgrade/03_03_0010_add_vm_client_ip.sql ...
>Running upgrade sql script upgrade/03_03_0020_add_pool_info.sql ...
>Running upgrade sql script upgrade/03_03_0030_change_vm_disks_tables.sql ...
>Running upgrade sql script upgrade/03_03_0040_add_vm_guest_user_login_info.sql ...
>Running upgrade sql script upgrade/03_03_0050_changed_users_vm_ip_column_to_text.sql ...
>Creating views API 3.0...
>Creating views API 3.1...
>Creating views API 3.2...
>Creating views API 3.3...
>Creating ovirt engine reports views...
>Creating stored procedures...
>Creating stored procedures from common_sp.sql ...
>Creating stored procedures from create_sp.sql ...
>Running upgrade sql script upgrade/post_upgrade/0010_change_enum_lang_code.sql ...
>
>2013-12-03 19:18:19::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:19::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:18:19::DEBUG::rhevm-dwh-setup::168::root:: Successfully installed ovirt_engine_history DB
>2013-12-03 19:18:19::DEBUG::decorators::18::root:: running setDbPass
>2013-12-03 19:18:19::DEBUG::rhevm-dwh-setup::314::root:: Setting DB pass
>2013-12-03 19:18:19::DEBUG::rhevm-dwh-setup::315::root:: editing etl db connectivity file
>2013-12-03 19:18:19::DEBUG::common_utils::464::root:: checking the status of ovirt-engine
>2013-12-03 19:18:19::DEBUG::common_utils::259::root:: getting status for ovirt-engine
>2013-12-03 19:18:19::DEBUG::common_utils::276::root:: executing action ovirt-engine on service status
>2013-12-03 19:18:19::DEBUG::common_utils::864::root:: Executing command --> '/sbin/service ovirt-engine status' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:19::DEBUG::common_utils::906::root:: output = ovirt-engine is stopped
>
>2013-12-03 19:18:19::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:19::DEBUG::common_utils::908::root:: retcode = 3
>2013-12-03 19:18:19::DEBUG::decorators::18::root:: running startEngineService
>2013-12-03 19:18:19::DEBUG::common_utils::503::root:: Starting ovirt-engine
>2013-12-03 19:18:19::DEBUG::common_utils::215::root:: starting ovirt-engine
>2013-12-03 19:18:19::DEBUG::common_utils::276::root:: executing action ovirt-engine on service start
>2013-12-03 19:18:19::DEBUG::common_utils::864::root:: Executing command --> '/sbin/service ovirt-engine start' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:20::DEBUG::common_utils::906::root:: output = Starting oVirt Engine: [  OK  ]
>
>2013-12-03 19:18:20::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:20::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:18:40::DEBUG::decorators::18::root:: running startEtl
>2013-12-03 19:18:40::DEBUG::common_utils::864::root:: Executing command --> '/sbin/chkconfig ovirt-engine-dwhd on' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:41::DEBUG::common_utils::906::root:: output = 
>2013-12-03 19:18:41::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:41::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:18:41::DEBUG::common_utils::563::root:: checking the status of dwhd
>2013-12-03 19:18:41::DEBUG::common_utils::259::root:: getting status for ovirt-engine-dwhd
>2013-12-03 19:18:41::DEBUG::common_utils::276::root:: executing action ovirt-engine-dwhd on service status
>2013-12-03 19:18:41::DEBUG::common_utils::864::root:: Executing command --> '/sbin/service ovirt-engine-dwhd status' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:41::DEBUG::common_utils::906::root:: output = /etc/init.d/ovirt-engine-dwhd is stopped
>
>2013-12-03 19:18:41::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:41::DEBUG::common_utils::908::root:: retcode = 1
>2013-12-03 19:18:41::DEBUG::common_utils::215::root:: starting ovirt-engine-dwhd
>2013-12-03 19:18:41::DEBUG::common_utils::276::root:: executing action ovirt-engine-dwhd on service start
>2013-12-03 19:18:41::DEBUG::common_utils::864::root:: Executing command --> '/sbin/service ovirt-engine-dwhd start' in working directory '/etc/yum.repos.d'
>2013-12-03 19:18:51::DEBUG::common_utils::906::root:: output = Starting ovirt-engine-dwhd: at Tue Dec  3 19:18:41 IST 2013[  OK  ]
>
>2013-12-03 19:18:51::DEBUG::common_utils::907::root:: stderr = 
>2013-12-03 19:18:51::DEBUG::common_utils::908::root:: retcode = 0
>2013-12-03 19:18:51::DEBUG::rhevm-dwh-setup::602::root:: main() ended

Actions: View

Attachments on bug 1040029: 834798