Attachment 572728 Details for Bug 806837 – engine-setup.log

engine-setup.log

engine-setup_2012_03_26_12_38_57.log (text/plain), 15.86 KB, created by Ortal on 2012-03-26 10:41:02 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Ortal

Created: 2012-03-26 10:41:02 UTC

Size: 15.86 KB

patch

obsolete

>2012-03-26 12:38:57::DEBUG::engine-setup::2444::root:: importing module example_plugin_000, from file example_plugin_000.py
>2012-03-26 12:38:57::DEBUG::example_plugin_000::16::root:: plugin example_plugin_000 loaded
>2012-03-26 12:38:57::DEBUG::common_utils::258::root:: cmd = /sbin/ip addr
>2012-03-26 12:38:57::DEBUG::common_utils::263::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:1a brd ff:ff:ff:ff:ff:ff
>    inet 10.35.97.26/24 brd 10.35.97.255 scope global eth0
>    inet6 fe80::21a:4aff:fe16:971a/64 scope link 
>       valid_lft forever preferred_lft forever
>
>2012-03-26 12:38:57::DEBUG::common_utils::264::root:: stderr = 
>2012-03-26 12:38:57::DEBUG::common_utils::265::root:: retcode = 0
>2012-03-26 12:38:57::DEBUG::common_utils::405::root:: Found IP Address: 10.35.97.26
>2012-03-26 12:38:57::DEBUG::engine-setup::2364::root:: checking total memory
>2012-03-26 12:38:57::DEBUG::common_utils::258::root:: cmd = /usr/bin/free -g
>2012-03-26 12:38:57::DEBUG::common_utils::263::root:: output =              total       used       free     shared    buffers     cached
>Mem:             3          1          2          0          0          1
>-/+ buffers/cache:          0          3
>Swap:            0          0          0
>
>2012-03-26 12:38:57::DEBUG::common_utils::264::root:: stderr = 
>2012-03-26 12:38:57::DEBUG::common_utils::265::root:: retcode = 0
>2012-03-26 12:38:57::DEBUG::engine-setup::2374::root:: Found a match, amount of memory: 3
>2012-03-26 12:38:57::WARNING::engine-setup::2384::root:: There is less then 4 available memory 
>2012-03-26 12:38:57::DEBUG::engine-setup::2394::root:: initiating command line option parser
>2012-03-26 12:38:57::DEBUG::engine-setup::2284::root:: Entered main(configFile='None')
>2012-03-26 12:38:57::DEBUG::engine-setup::1691::root:: checking the status of jboss
>2012-03-26 12:38:57::DEBUG::common_utils::789::root:: getting status for jboss-as
>2012-03-26 12:38:57::DEBUG::common_utils::798::root:: executing action jboss-as on service status
>2012-03-26 12:38:57::DEBUG::common_utils::281::root:: Executing command --> '/sbin/service jboss-as status'
>2012-03-26 12:38:57::DEBUG::common_utils::307::root:: output = jboss-as is not running
>
>2012-03-26 12:38:57::DEBUG::common_utils::308::root:: stderr = 
>2012-03-26 12:38:57::DEBUG::common_utils::309::root:: retcode = 3
>2012-03-26 12:38:57::DEBUG::engine-setup::1451::root:: going over group ALL_PARAMS
>2012-03-26 12:38:57::INFO::engine-setup::1460::root:: Comparing pre-conditions; condition: 'True', and match: 'True'
>2012-03-26 12:38:58::DEBUG::engine_validators::48::root:: Validating 8888 as a valid TCP Port
>2012-03-26 12:38:58::DEBUG::common_utils::237::root:: Checking if TCP port 8888 is open by any process
>2012-03-26 12:38:58::DEBUG::common_utils::258::root:: cmd = /usr/sbin/lsof -i -n -P
>2012-03-26 12:38:59::DEBUG::common_utils::263::root:: output = COMMAND     PID     USER   FD   TYPE   DEVICE SIZE/OFF NODE NAME
>dhclient   1229     root    5u  IPv4    10673      0t0  UDP *:68 
>rpc.statd  1367  rpcuser    5u  IPv4    11254      0t0  UDP *:695 
>rpc.statd  1367  rpcuser    8u  IPv4    11259      0t0  UDP *:662 
>rpc.statd  1367  rpcuser    9u  IPv4    11263      0t0  TCP *:662 (LISTEN)
>rpc.statd  1367  rpcuser   10u  IPv6    11267      0t0  UDP *:662 
>rpc.statd  1367  rpcuser   11u  IPv6    11271      0t0  TCP *:662 (LISTEN)
>cupsd      1502     root    6u  IPv6    11613      0t0  TCP [::1]:631 (LISTEN)
>cupsd      1502     root    7u  IPv4    11614      0t0  TCP 127.0.0.1:631 (LISTEN)
>cupsd      1502     root    9u  IPv4    11617      0t0  UDP *:631 
>sshd       1679     root    3u  IPv4    12461      0t0  TCP *:22 (LISTEN)
>sshd       1679     root    4u  IPv6    12470      0t0  TCP *:22 (LISTEN)
>master     1928     root   12u  IPv4    13235      0t0  TCP 127.0.0.1:25 (LISTEN)
>master     1928     root   13u  IPv6    13237      0t0  TCP [::1]:25 (LISTEN)
>rpcbind   12343      rpc    6u  IPv4  8058405      0t0  UDP *:111 
>rpcbind   12343      rpc    7u  IPv4  8058409      0t0  UDP *:646 
>rpcbind   12343      rpc    8u  IPv4  8058410      0t0  TCP *:111 (LISTEN)
>rpcbind   12343      rpc    9u  IPv6  8058412      0t0  UDP *:111 
>rpcbind   12343      rpc   10u  IPv6  8058414      0t0  UDP *:646 
>rpcbind   12343      rpc   11u  IPv6  8058415      0t0  TCP *:111 (LISTEN)
>rpc.rquot 12428     root    3u  IPv4  8058657      0t0  UDP *:875 
>rpc.rquot 12428     root    4u  IPv4  8058662      0t0  TCP *:875 (LISTEN)
>rpc.mount 12444     root    7u  IPv4  8058706      0t0  UDP *:892 
>rpc.mount 12444     root    8u  IPv4  8058710      0t0  TCP *:892 (LISTEN)
>rpc.mount 12444     root    9u  IPv6  8058714      0t0  UDP *:892 
>rpc.mount 12444     root   10u  IPv6  8058718      0t0  TCP *:892 (LISTEN)
>sshd      23696     root    3r  IPv4 11358564      0t0  TCP 10.35.97.26:22->10.35.3.179:35278 (ESTABLISHED)
>sshd      25293     root    3r  IPv4 11380706      0t0  TCP 10.35.97.26:22->10.35.3.179:35589 (ESTABLISHED)
>postmaste 27006 postgres    3u  IPv4 11397873      0t0  TCP *:5432 (LISTEN)
>postmaste 27006 postgres    4u  IPv6 11397874      0t0  TCP *:5432 (LISTEN)
>postmaste 27006 postgres    7u  IPv6 11397885      0t0  UDP [::1]:38850->[::1]:38850 
>postmaste 27010 postgres    7u  IPv6 11397885      0t0  UDP [::1]:38850->[::1]:38850 
>postmaste 27011 postgres    7u  IPv6 11397885      0t0  UDP [::1]:38850->[::1]:38850 
>postmaste 27012 postgres    7u  IPv6 11397885      0t0  UDP [::1]:38850->[::1]:38850 
>postmaste 27013 postgres    7u  IPv6 11397885      0t0  UDP [::1]:38850->[::1]:38850 
>
>2012-03-26 12:38:59::DEBUG::common_utils::264::root:: stderr = 
>2012-03-26 12:38:59::DEBUG::common_utils::265::root:: retcode = 0
>2012-03-26 12:39:00::DEBUG::engine_validators::48::root:: Validating 9999 as a valid TCP Port
>2012-03-26 12:39:00::DEBUG::common_utils::237::root:: Checking if TCP port 9999 is open by any process
>2012-03-26 12:39:00::DEBUG::common_utils::258::root:: cmd = /usr/sbin/lsof -i -n -P
>2012-03-26 12:39:00::DEBUG::common_utils::263::root:: output = COMMAND     PID     USER   FD   TYPE   DEVICE SIZE/OFF NODE NAME
>dhclient   1229     root    5u  IPv4    10673      0t0  UDP *:68 
>rpc.statd  1367  rpcuser    5u  IPv4    11254      0t0  UDP *:695 
>rpc.statd  1367  rpcuser    8u  IPv4    11259      0t0  UDP *:662 
>rpc.statd  1367  rpcuser    9u  IPv4    11263      0t0  TCP *:662 (LISTEN)
>rpc.statd  1367  rpcuser   10u  IPv6    11267      0t0  UDP *:662 
>rpc.statd  1367  rpcuser   11u  IPv6    11271      0t0  TCP *:662 (LISTEN)
>cupsd      1502     root    6u  IPv6    11613      0t0  TCP [::1]:631 (LISTEN)
>cupsd      1502     root    7u  IPv4    11614      0t0  TCP 127.0.0.1:631 (LISTEN)
>cupsd      1502     root    9u  IPv4    11617      0t0  UDP *:631 
>sshd       1679     root    3u  IPv4    12461      0t0  TCP *:22 (LISTEN)
>sshd       1679     root    4u  IPv6    12470      0t0  TCP *:22 (LISTEN)
>master     1928     root   12u  IPv4    13235      0t0  TCP 127.0.0.1:25 (LISTEN)
>master     1928     root   13u  IPv6    13237      0t0  TCP [::1]:25 (LISTEN)
>rpcbind   12343      rpc    6u  IPv4  8058405      0t0  UDP *:111 
>rpcbind   12343      rpc    7u  IPv4  8058409      0t0  UDP *:646 
>rpcbind   12343      rpc    8u  IPv4  8058410      0t0  TCP *:111 (LISTEN)
>rpcbind   12343      rpc    9u  IPv6  8058412      0t0  UDP *:111 
>rpcbind   12343      rpc   10u  IPv6  8058414      0t0  UDP *:646 
>rpcbind   12343      rpc   11u  IPv6  8058415      0t0  TCP *:111 (LISTEN)
>rpc.rquot 12428     root    3u  IPv4  8058657      0t0  UDP *:875 
>rpc.rquot 12428     root    4u  IPv4  8058662      0t0  TCP *:875 (LISTEN)
>rpc.mount 12444     root    7u  IPv4  8058706      0t0  UDP *:892 
>rpc.mount 12444     root    8u  IPv4  8058710      0t0  TCP *:892 (LISTEN)
>rpc.mount 12444     root    9u  IPv6  8058714      0t0  UDP *:892 
>rpc.mount 12444     root   10u  IPv6  8058718      0t0  TCP *:892 (LISTEN)
>sshd      23696     root    3r  IPv4 11358564      0t0  TCP 10.35.97.26:22->10.35.3.179:35278 (ESTABLISHED)
>sshd      25293     root    3r  IPv4 11380706      0t0  TCP 10.35.97.26:22->10.35.3.179:35589 (ESTABLISHED)
>postmaste 27006 postgres    3u  IPv4 11397873      0t0  TCP *:5432 (LISTEN)
>postmaste 27006 postgres    4u  IPv6 11397874      0t0  TCP *:5432 (LISTEN)
>postmaste 27006 postgres    7u  IPv6 11397885      0t0  UDP [::1]:38850->[::1]:38850 
>postmaste 27010 postgres    7u  IPv6 11397885      0t0  UDP [::1]:38850->[::1]:38850 
>postmaste 27011 postgres    7u  IPv6 11397885      0t0  UDP [::1]:38850->[::1]:38850 
>postmaste 27012 postgres    7u  IPv6 11397885      0t0  UDP [::1]:38850->[::1]:38850 
>postmaste 27013 postgres    7u  IPv6 11397885      0t0  UDP [::1]:38850->[::1]:38850 
>
>2012-03-26 12:39:00::DEBUG::common_utils::264::root:: stderr = 
>2012-03-26 12:39:00::DEBUG::common_utils::265::root:: retcode = 0
>2012-03-26 12:39:00::DEBUG::engine-setup::477::root:: setting default value (00:1A:4A:23:61:00-00:1A:4A:23:61:FF) for key (MAC_RANGE)
>2012-03-26 12:39:01::INFO::engine_validators::178::root:: Validating ortal-rhevm3.qa.lab.tlv.redhat.com as a FQDN
>2012-03-26 12:39:01::INFO::engine_validators::96::root:: validating ortal-rhevm3.qa.lab.tlv.redhat.com as a valid domain string
>2012-03-26 12:39:01::DEBUG::common_utils::258::root:: cmd = /sbin/ip addr
>2012-03-26 12:39:01::DEBUG::common_utils::263::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:1a brd ff:ff:ff:ff:ff:ff
>    inet 10.35.97.26/24 brd 10.35.97.255 scope global eth0
>    inet6 fe80::21a:4aff:fe16:971a/64 scope link 
>       valid_lft forever preferred_lft forever
>
>2012-03-26 12:39:01::DEBUG::common_utils::264::root:: stderr = 
>2012-03-26 12:39:01::DEBUG::common_utils::265::root:: retcode = 0
>2012-03-26 12:39:01::DEBUG::common_utils::405::root:: Found IP Address: 10.35.97.26
>2012-03-26 12:39:01::DEBUG::common_utils::258::root:: cmd = /usr/bin/nslookup ortal-rhevm3.qa.lab.tlv.redhat.com
>2012-03-26 12:39:01::DEBUG::common_utils::263::root:: output = Server:		10.35.64.1
>Address:	10.35.64.1#53
>
>Name:	ortal-rhevm3.qa.lab.tlv.redhat.com
>Address: 10.35.97.26
>
>
>2012-03-26 12:39:01::DEBUG::common_utils::264::root:: stderr = 
>2012-03-26 12:39:01::DEBUG::common_utils::265::root:: retcode = 0
>2012-03-26 12:39:01::DEBUG::engine_validators::391::root:: ortal-rhevm3.qa.lab.tlv.redhat.com resolved into 10.35.97.26
>2012-03-26 12:39:01::DEBUG::common_utils::258::root:: cmd = /usr/bin/nslookup 10.35.97.26
>2012-03-26 12:39:01::DEBUG::common_utils::263::root:: output = Server:		10.35.64.1
>Address:	10.35.64.1#53
>
>26.97.35.10.in-addr.arpa	name = ortal-rhevm3.qa.lab.tlv.redhat.com.
>
>
>2012-03-26 12:39:01::DEBUG::common_utils::264::root:: stderr = 
>2012-03-26 12:39:01::DEBUG::common_utils::265::root:: retcode = 0
>2012-03-26 12:39:01::DEBUG::engine_validators::391::root:: 10.35.97.26 resolved into ortal-rhevm3.qa.lab.tlv.redhat.com
>2012-03-26 12:39:03::DEBUG::engine_validators::71::root:: Validating password
>2012-03-26 12:39:03::WARNING::engine_validators::77::root:: Password failed check
>2012-03-26 12:39:03::WARNING::engine_validators::78::root:: Traceback (most recent call last):
>  File "/usr/share/ovirt-engine/scripts/engine_validators.py", line 75, in validatePassword
>    cracklib.FascistCheck(param)
>ValueError: it is too simplistic/systematic
>
>2012-03-26 12:39:04::DEBUG::engine-setup::552::root:: Param confirmation passed, value for both questions is identical
>2012-03-26 12:39:06::INFO::engine_validators::259::root:: validating organization name
>2012-03-26 12:39:09::INFO::engine_validators::84::root:: Validating ISCSI as part of ['NFS', 'FC', 'ISCSI']
>2012-03-26 12:39:11::INFO::engine_validators::84::root:: Validating remote as part of ['remote', 'local']
>2012-03-26 12:39:11::DEBUG::engine-setup::1451::root:: going over group REMOTE_DB
>2012-03-26 12:39:11::INFO::engine-setup::1460::root:: Comparing pre-conditions; condition: 'True', and match: 'True'
>2012-03-26 12:39:13::DEBUG::common_utils::281::root:: Executing command --> '/bin/ping -c 1 10.35.97.'
>2012-03-26 12:39:13::DEBUG::common_utils::307::root:: output = 
>2012-03-26 12:39:13::DEBUG::common_utils::308::root:: stderr = ping: unknown host 10.35.97.
>
>2012-03-26 12:39:13::DEBUG::common_utils::309::root:: retcode = 2
>2012-03-26 12:39:13::DEBUG::engine-setup::564::root:: asking user: User input failed validation, do you still wish to use it? (yes|no): 
>2012-03-26 12:39:15::DEBUG::engine-setup::568::root:: user answered: no
>2012-03-26 12:39:18::DEBUG::common_utils::281::root:: Executing command --> '/bin/ping -c 1 10.35.97.94'
>2012-03-26 12:39:18::DEBUG::common_utils::307::root:: output = PING 10.35.97.94 (10.35.97.94) 56(84) bytes of data.
>64 bytes from 10.35.97.94: icmp_seq=1 ttl=64 time=1.63 ms
>
>--- 10.35.97.94 ping statistics ---
>1 packets transmitted, 1 received, 0% packet loss, time 1ms
>rtt min/avg/max/mdev = 1.637/1.637/1.637/0.000 ms
>
>2012-03-26 12:39:18::DEBUG::common_utils::308::root:: stderr = 
>2012-03-26 12:39:18::DEBUG::common_utils::309::root:: retcode = 0
>2012-03-26 12:39:24::INFO::engine_validators::111::root:: validating ortal-test as a valid user name
>2012-03-26 12:39:28::DEBUG::engine_validators::71::root:: Validating password
>2012-03-26 12:39:28::WARNING::engine_validators::77::root:: Password failed check
>2012-03-26 12:39:28::WARNING::engine_validators::78::root:: Traceback (most recent call last):
>  File "/usr/share/ovirt-engine/scripts/engine_validators.py", line 75, in validatePassword
>    cracklib.FascistCheck(param)
>ValueError: it is too simplistic/systematic
>
>2012-03-26 12:39:29::DEBUG::engine-setup::552::root:: Param confirmation passed, value for both questions is identical
>2012-03-26 12:39:31::INFO::engine_validators::84::root:: Validating no as part of ['yes', 'no']
>2012-03-26 12:39:31::INFO::engine_validators::138::root:: Validating 10.35.97.94 as a RemoteDb
>2012-03-26 12:39:31::DEBUG::common_utils::258::root:: cmd = /sbin/ip addr
>2012-03-26 12:39:31::DEBUG::common_utils::263::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:1a brd ff:ff:ff:ff:ff:ff
>    inet 10.35.97.26/24 brd 10.35.97.255 scope global eth0
>    inet6 fe80::21a:4aff:fe16:971a/64 scope link 
>       valid_lft forever preferred_lft forever
>
>2012-03-26 12:39:31::DEBUG::common_utils::264::root:: stderr = 
>2012-03-26 12:39:31::DEBUG::common_utils::265::root:: retcode = 0
>2012-03-26 12:39:31::DEBUG::common_utils::405::root:: Found IP Address: 10.35.97.26
>2012-03-26 12:39:31::DEBUG::engine_validators::336::root:: found existing pgpass file, backing current to /root/.pgpass.2012_03_26_12_39_31 for validation
>2012-03-26 12:39:31::INFO::engine_validators::290::root:: Trying to connect to the remote database with provided credentials.
>2012-03-26 12:39:31::DEBUG::common_utils::322::root:: running sql query 'select 1' on db server: '10.35.97.94'.
>2012-03-26 12:39:31::DEBUG::common_utils::258::root:: cmd = /usr/bin/psql -h 10.35.97.94 -p 5544 -U ortal-test -d postgres -c "select 1"
>2012-03-26 12:39:31::DEBUG::common_utils::263::root:: output = 
>2012-03-26 12:39:31::DEBUG::common_utils::264::root:: stderr = psql: FATAL:  password authentication failed for user "ortal-test"
>
>2012-03-26 12:39:31::DEBUG::common_utils::265::root:: retcode = 2
>2012-03-26 12:39:31::ERROR::engine_validators::296::root:: Could not connect to host 10.35.97.94 with provided credentials. Check that your settings are correct.

Actions: View

Attachments on bug 806837: 572728