[ovirt-users] ovirt 3.6 Failed to execute stage 'Environment setup'

31 Aug 2015

      This is an OpenPGP/MIME signed message (RFC 4880 and 3156)
--3W6fXveMv22FLGti6dq6jEIOIdx6nU7sR
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: quoted-printable

Hi,

I'm trying to test a self hosted engine oVirt 3.6 setup on a CentOS
7.1 minimal installation. But it fails quite early after running
hosted-engine --deploy with
[ ERROR ] Failed to execute stage 'Environment setup': <Fault 1:
"<type 'exceptions.TypeError'>:cannot marshal None unless allow_none
is enabled">

So far I've followed the repository installation instructions as
mentioned on http://www.ovirt.org/OVirt_3.6_Release_Management, and
added the current gluster repo to the default minimal CentOS 7.1 setup.

The output of hosted-engine --deploy is as follows:

# hosted-engine --deploy
[ INFO  ] Stage: Initializing
[ INFO  ] Generating a temporary VNC password.
[ INFO  ] Stage: Environment setup
          Continuing will configure this host for serving as
hypervisor and create a VM where you have to install oVirt Engine
afterwards.
          Are you sure you want to continue? (Yes, No)[Yes]:
          Configuration files: []
          Log file:
/var/log/ovirt-hosted-engine-setup/ovirt-hosted-engine-setup-201508311232=
58-w2syys.log
          Version: otopi-1.4.0_master
(otopi-1.4.0-0.0.master.20150727232243.git04fa8c9.el7)
          It has been detected that this program is executed through
an SSH connection without using screen.
          Continuing with the installation may lead to broken
installation if the network connection fails.
          It is highly recommended to abort the installation and run
it inside a screen session using command "screen".
          Do you want to continue anyway? (Yes, No)[No]: yes
[ INFO  ] Hardware supports virtualization
[ INFO  ] Stage: Environment packages setup
[ INFO  ] Stage: Programs detection
[ INFO  ] Stage: Environment setup
[ INFO  ] Waiting for VDSM hardware info
[ INFO  ] Waiting for VDSM hardware info
[ INFO  ] Waiting for VDSM hardware info
[ INFO  ] Waiting for VDSM hardware info
[ INFO  ] Waiting for VDSM hardware info
[ INFO  ] Waiting for VDSM hardware info
[ INFO  ] Waiting for VDSM hardware info
[ INFO  ] Waiting for VDSM hardware info
[ INFO  ] Waiting for VDSM hardware info
[ INFO  ] Waiting for VDSM hardware info
[ ERROR ] Failed to execute stage 'Environment setup': <Fault 1:
"<type 'exceptions.TypeError'>:cannot marshal None unless allow_none
is enabled">
[ INFO  ] Stage: Clean up
[ INFO  ] Generating answer file
'/var/lib/ovirt-hosted-engine-setup/answers/answers-20150831123315.conf'
[ INFO  ] Stage: Pre-termination
[ INFO  ] Stage: Termination

The VDSM log shows that it fails to run dmidecode to gather hardware
information. I've had the same issue with oVirt 3.5 but the access
restriction to /dev/mem is kernel imposed so I'm not sure what to
make of it since this kernel option is enabled on all the systems
I've tested by default.

There seem to be some gluster packages missing but I'm guessing
that's not the problem at hand.

I'm not sure what to search for in the logs so I'm kind of stuck as
to what to try next. Any help is greatly appreciated.

All the best
Richard

The rest of the VDSM log during the hosted-engine setup is as follows:

BindingXMLRPC::INFO::2015-08-31
12:32:33,395::xmlrpc::73::vds.XMLRPCServer::(handle_request)
Starting request handler for 127.0.0.1:47391
Thread-51::INFO::2015-08-31
12:32:33,396::xmlrpc::84::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47391 started
Thread-51::INFO::2015-08-31
12:32:33,399::xmlrpc::92::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47391 stopped
Reactor thread::INFO::2015-08-31
12:32:48,416::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handl=
e_accept)
Accepting connection from 127.0.0.1:47392
Reactor thread::DEBUG::2015-08-31
12:32:48,428::protocoldetector::82::ProtocolDetector.Detector::(__init__)=

Using required_size=3D11
Reactor thread::INFO::2015-08-31
12:32:48,429::protocoldetector::118::ProtocolDetector.Detector::(handle_r=
ead)
Detected protocol xml from 127.0.0.1:47392
Reactor thread::DEBUG::2015-08-31
12:32:48,429::bindingxmlrpc::1296::XmlDetector::(handle_socket) xml
over http detected from ('127.0.0.1', 47392)
BindingXMLRPC::INFO::2015-08-31
12:32:48,429::xmlrpc::73::vds.XMLRPCServer::(handle_request)
Starting request handler for 127.0.0.1:47392
Thread-52::INFO::2015-08-31
12:32:48,430::xmlrpc::84::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47392 started
Thread-52::INFO::2015-08-31
12:32:48,434::xmlrpc::92::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47392 stopped
Reactor thread::INFO::2015-08-31
12:33:03,452::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handl=
e_accept)
Accepting connection from 127.0.0.1:47393
Reactor thread::DEBUG::2015-08-31
12:33:03,464::protocoldetector::82::ProtocolDetector.Detector::(__init__)=

Using required_size=3D11
Reactor thread::INFO::2015-08-31
12:33:03,465::protocoldetector::118::ProtocolDetector.Detector::(handle_r=
ead)
Detected protocol xml from 127.0.0.1:47393
Reactor thread::DEBUG::2015-08-31
12:33:03,465::bindingxmlrpc::1296::XmlDetector::(handle_socket) xml
over http detected from ('127.0.0.1', 47393)
BindingXMLRPC::INFO::2015-08-31
12:33:03,465::xmlrpc::73::vds.XMLRPCServer::(handle_request)
Starting request handler for 127.0.0.1:47393
Thread-53::INFO::2015-08-31
12:33:03,466::xmlrpc::84::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47393 started
Thread-53::INFO::2015-08-31
12:33:03,469::xmlrpc::92::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47393 stopped
Reactor thread::INFO::2015-08-31
12:33:04,772::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handl=
e_accept)
Accepting connection from 127.0.0.1:47394
Reactor thread::DEBUG::2015-08-31
12:33:04,783::protocoldetector::82::ProtocolDetector.Detector::(__init__)=

Using required_size=3D11
Reactor thread::INFO::2015-08-31
12:33:04,783::protocoldetector::118::ProtocolDetector.Detector::(handle_r=
ead)
Detected protocol xml from 127.0.0.1:47394
Reactor thread::DEBUG::2015-08-31
12:33:04,784::bindingxmlrpc::1296::XmlDetector::(handle_socket) xml
over http detected from ('127.0.0.1', 47394)
BindingXMLRPC::INFO::2015-08-31
12:33:04,784::xmlrpc::73::vds.XMLRPCServer::(handle_request)
Starting request handler for 127.0.0.1:47394
Thread-54::INFO::2015-08-31
12:33:04,786::xmlrpc::84::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47394 started
Thread-54::DEBUG::2015-08-31
12:33:04,787::bindingxmlrpc::1256::vds::(wrapper) client
[127.0.0.1]::call getHardwareInfo with () {}
Thread-54::ERROR::2015-08-31
12:33:04,791::API::1328::vds::(getHardwareInfo) failed to retrieve
hardware info
Traceback (most recent call last):
  File "/usr/share/vdsm/API.py", line 1325, in getHardwareInfo
    hw =3D supervdsm.getProxy().getHardwareInfo()
  File "/usr/share/vdsm/supervdsm.py", line 50, in __call__
    return callMethod()
  File "/usr/share/vdsm/supervdsm.py", line 48, in <lambda>
    **kwargs)
  File "<string>", line 2, in getHardwareInfo
  File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773,
in _callmethod
    raise convert_to_error(kind, result)
RuntimeError: [src/dmidecodemodule.c:317] Error decoding DMI data
Thread-54::DEBUG::2015-08-31
12:33:04,793::bindingxmlrpc::1263::vds::(wrapper) return
getHardwareInfo with {'status': {'message': 'Failed to read hardware
information', 'code': 57}}
Thread-54::INFO::2015-08-31
12:33:04,795::xmlrpc::92::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47394 stopped
Reactor thread::INFO::2015-08-31
12:33:05,798::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handl=
e_accept)
Accepting connection from 127.0.0.1:47395
Reactor thread::DEBUG::2015-08-31
12:33:05,812::protocoldetector::82::ProtocolDetector.Detector::(__init__)=

Using required_size=3D11
Reactor thread::INFO::2015-08-31
12:33:05,812::protocoldetector::118::ProtocolDetector.Detector::(handle_r=
ead)
Detected protocol xml from 127.0.0.1:47395
Reactor thread::DEBUG::2015-08-31
12:33:05,812::bindingxmlrpc::1296::XmlDetector::(handle_socket) xml
over http detected from ('127.0.0.1', 47395)
BindingXMLRPC::INFO::2015-08-31
12:33:05,813::xmlrpc::73::vds.XMLRPCServer::(handle_request)
Starting request handler for 127.0.0.1:47395
Thread-55::INFO::2015-08-31
12:33:05,814::xmlrpc::84::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47395 started
Thread-55::DEBUG::2015-08-31
12:33:05,815::bindingxmlrpc::1256::vds::(wrapper) client
[127.0.0.1]::call getHardwareInfo with () {}
Thread-55::ERROR::2015-08-31
12:33:05,818::API::1328::vds::(getHardwareInfo) failed to retrieve
hardware info
Traceback (most recent call last):
  File "/usr/share/vdsm/API.py", line 1325, in getHardwareInfo
    hw =3D supervdsm.getProxy().getHardwareInfo()
  File "/usr/share/vdsm/supervdsm.py", line 50, in __call__
    return callMethod()
  File "/usr/share/vdsm/supervdsm.py", line 48, in <lambda>
    **kwargs)
  File "<string>", line 2, in getHardwareInfo
  File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773,
in _callmethod
    raise convert_to_error(kind, result)
RuntimeError: [src/dmidecodemodule.c:317] Error decoding DMI data
Thread-55::DEBUG::2015-08-31
12:33:05,818::bindingxmlrpc::1263::vds::(wrapper) return
getHardwareInfo with {'status': {'message': 'Failed to read hardware
information', 'code': 57}}
Thread-55::INFO::2015-08-31
12:33:05,821::xmlrpc::92::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47395 stopped
Reactor thread::INFO::2015-08-31
12:33:06,824::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handl=
e_accept)
Accepting connection from 127.0.0.1:47396
Reactor thread::DEBUG::2015-08-31
12:33:06,836::protocoldetector::82::ProtocolDetector.Detector::(__init__)=

Using required_size=3D11
Reactor thread::INFO::2015-08-31
12:33:06,836::protocoldetector::118::ProtocolDetector.Detector::(handle_r=
ead)
Detected protocol xml from 127.0.0.1:47396
Reactor thread::DEBUG::2015-08-31
12:33:06,837::bindingxmlrpc::1296::XmlDetector::(handle_socket) xml
over http detected from ('127.0.0.1', 47396)
BindingXMLRPC::INFO::2015-08-31
12:33:06,837::xmlrpc::73::vds.XMLRPCServer::(handle_request)
Starting request handler for 127.0.0.1:47396
Thread-56::INFO::2015-08-31
12:33:06,838::xmlrpc::84::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47396 started
Thread-56::DEBUG::2015-08-31
12:33:06,839::bindingxmlrpc::1256::vds::(wrapper) client
[127.0.0.1]::call getHardwareInfo with () {}
Thread-56::ERROR::2015-08-31
12:33:06,842::API::1328::vds::(getHardwareInfo) failed to retrieve
hardware info
Traceback (most recent call last):
  File "/usr/share/vdsm/API.py", line 1325, in getHardwareInfo
    hw =3D supervdsm.getProxy().getHardwareInfo()
  File "/usr/share/vdsm/supervdsm.py", line 50, in __call__
    return callMethod()
  File "/usr/share/vdsm/supervdsm.py", line 48, in <lambda>
    **kwargs)
  File "<string>", line 2, in getHardwareInfo
  File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773,
in _callmethod
    raise convert_to_error(kind, result)
RuntimeError: [src/dmidecodemodule.c:317] Error decoding DMI data
Thread-56::DEBUG::2015-08-31
12:33:06,842::bindingxmlrpc::1263::vds::(wrapper) return
getHardwareInfo with {'status': {'message': 'Failed to read hardware
information', 'code': 57}}
Thread-56::INFO::2015-08-31
12:33:06,844::xmlrpc::92::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47396 stopped
Reactor thread::INFO::2015-08-31
12:33:07,847::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handl=
e_accept)
Accepting connection from 127.0.0.1:47397
Reactor thread::DEBUG::2015-08-31
12:33:07,859::protocoldetector::82::ProtocolDetector.Detector::(__init__)=

Using required_size=3D11
Reactor thread::INFO::2015-08-31
12:33:07,859::protocoldetector::118::ProtocolDetector.Detector::(handle_r=
ead)
Detected protocol xml from 127.0.0.1:47397
Reactor thread::DEBUG::2015-08-31
12:33:07,860::bindingxmlrpc::1296::XmlDetector::(handle_socket) xml
over http detected from ('127.0.0.1', 47397)
BindingXMLRPC::INFO::2015-08-31
12:33:07,860::xmlrpc::73::vds.XMLRPCServer::(handle_request)
Starting request handler for 127.0.0.1:47397
Thread-57::INFO::2015-08-31
12:33:07,861::xmlrpc::84::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47397 started
Thread-57::DEBUG::2015-08-31
12:33:07,862::bindingxmlrpc::1256::vds::(wrapper) client
[127.0.0.1]::call getHardwareInfo with () {}
Thread-57::ERROR::2015-08-31
12:33:07,865::API::1328::vds::(getHardwareInfo) failed to retrieve
hardware info
Traceback (most recent call last):
  File "/usr/share/vdsm/API.py", line 1325, in getHardwareInfo
    hw =3D supervdsm.getProxy().getHardwareInfo()
  File "/usr/share/vdsm/supervdsm.py", line 50, in __call__
    return callMethod()
  File "/usr/share/vdsm/supervdsm.py", line 48, in <lambda>
    **kwargs)
  File "<string>", line 2, in getHardwareInfo
  File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773,
in _callmethod
    raise convert_to_error(kind, result)
RuntimeError: [src/dmidecodemodule.c:317] Error decoding DMI data
Thread-57::DEBUG::2015-08-31
12:33:07,865::bindingxmlrpc::1263::vds::(wrapper) return
getHardwareInfo with {'status': {'message': 'Failed to read hardware
information', 'code': 57}}
Thread-57::INFO::2015-08-31
12:33:07,867::xmlrpc::92::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47397 stopped
Reactor thread::INFO::2015-08-31
12:33:08,870::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handl=
e_accept)
Accepting connection from 127.0.0.1:47398
Reactor thread::DEBUG::2015-08-31
12:33:08,881::protocoldetector::82::ProtocolDetector.Detector::(__init__)=

Using required_size=3D11
Reactor thread::INFO::2015-08-31
12:33:08,882::protocoldetector::118::ProtocolDetector.Detector::(handle_r=
ead)
Detected protocol xml from 127.0.0.1:47398
Reactor thread::DEBUG::2015-08-31
12:33:08,882::bindingxmlrpc::1296::XmlDetector::(handle_socket) xml
over http detected from ('127.0.0.1', 47398)
BindingXMLRPC::INFO::2015-08-31
12:33:08,882::xmlrpc::73::vds.XMLRPCServer::(handle_request)
Starting request handler for 127.0.0.1:47398
Thread-58::INFO::2015-08-31
12:33:08,884::xmlrpc::84::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47398 started
Thread-58::DEBUG::2015-08-31
12:33:08,885::bindingxmlrpc::1256::vds::(wrapper) client
[127.0.0.1]::call getHardwareInfo with () {}
Thread-58::ERROR::2015-08-31
12:33:08,887::API::1328::vds::(getHardwareInfo) failed to retrieve
hardware info
Traceback (most recent call last):
  File "/usr/share/vdsm/API.py", line 1325, in getHardwareInfo
    hw =3D supervdsm.getProxy().getHardwareInfo()
  File "/usr/share/vdsm/supervdsm.py", line 50, in __call__
    return callMethod()
  File "/usr/share/vdsm/supervdsm.py", line 48, in <lambda>
    **kwargs)
  File "<string>", line 2, in getHardwareInfo
  File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773,
in _callmethod
    raise convert_to_error(kind, result)
RuntimeError: [src/dmidecodemodule.c:317] Error decoding DMI data
Thread-58::DEBUG::2015-08-31
12:33:08,888::bindingxmlrpc::1263::vds::(wrapper) return
getHardwareInfo with {'status': {'message': 'Failed to read hardware
information', 'code': 57}}
Thread-58::INFO::2015-08-31
12:33:08,890::xmlrpc::92::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47398 stopped
Reactor thread::INFO::2015-08-31
12:33:09,892::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handl=
e_accept)
Accepting connection from 127.0.0.1:47399
Reactor thread::DEBUG::2015-08-31
12:33:09,904::protocoldetector::82::ProtocolDetector.Detector::(__init__)=

Using required_size=3D11
Reactor thread::INFO::2015-08-31
12:33:09,904::protocoldetector::118::ProtocolDetector.Detector::(handle_r=
ead)
Detected protocol xml from 127.0.0.1:47399
Reactor thread::DEBUG::2015-08-31
12:33:09,905::bindingxmlrpc::1296::XmlDetector::(handle_socket) xml
over http detected from ('127.0.0.1', 47399)
BindingXMLRPC::INFO::2015-08-31
12:33:09,905::xmlrpc::73::vds.XMLRPCServer::(handle_request)
Starting request handler for 127.0.0.1:47399
Thread-59::INFO::2015-08-31
12:33:09,906::xmlrpc::84::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47399 started
Thread-59::DEBUG::2015-08-31
12:33:09,907::bindingxmlrpc::1256::vds::(wrapper) client
[127.0.0.1]::call getHardwareInfo with () {}
Thread-59::ERROR::2015-08-31
12:33:09,909::API::1328::vds::(getHardwareInfo) failed to retrieve
hardware info
Traceback (most recent call last):
  File "/usr/share/vdsm/API.py", line 1325, in getHardwareInfo
    hw =3D supervdsm.getProxy().getHardwareInfo()
  File "/usr/share/vdsm/supervdsm.py", line 50, in __call__
    return callMethod()
  File "/usr/share/vdsm/supervdsm.py", line 48, in <lambda>
    **kwargs)
  File "<string>", line 2, in getHardwareInfo
  File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773,
in _callmethod
    raise convert_to_error(kind, result)
RuntimeError: [src/dmidecodemodule.c:317] Error decoding DMI data
Thread-59::DEBUG::2015-08-31
12:33:09,910::bindingxmlrpc::1263::vds::(wrapper) return
getHardwareInfo with {'status': {'message': 'Failed to read hardware
information', 'code': 57}}
Thread-59::INFO::2015-08-31
12:33:09,912::xmlrpc::92::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47399 stopped
Reactor thread::INFO::2015-08-31
12:33:10,914::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handl=
e_accept)
Accepting connection from 127.0.0.1:47400
Reactor thread::DEBUG::2015-08-31
12:33:10,926::protocoldetector::82::ProtocolDetector.Detector::(__init__)=

Using required_size=3D11
Reactor thread::INFO::2015-08-31
12:33:10,926::protocoldetector::118::ProtocolDetector.Detector::(handle_r=
ead)
Detected protocol xml from 127.0.0.1:47400
Reactor thread::DEBUG::2015-08-31
12:33:10,927::bindingxmlrpc::1296::XmlDetector::(handle_socket) xml
over http detected from ('127.0.0.1', 47400)
BindingXMLRPC::INFO::2015-08-31
12:33:10,927::xmlrpc::73::vds.XMLRPCServer::(handle_request)
Starting request handler for 127.0.0.1:47400
Thread-60::INFO::2015-08-31
12:33:10,928::xmlrpc::84::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47400 started
Thread-60::DEBUG::2015-08-31
12:33:10,929::bindingxmlrpc::1256::vds::(wrapper) client
[127.0.0.1]::call getHardwareInfo with () {}
Thread-60::ERROR::2015-08-31
12:33:10,931::API::1328::vds::(getHardwareInfo) failed to retrieve
hardware info
Traceback (most recent call last):
  File "/usr/share/vdsm/API.py", line 1325, in getHardwareInfo
    hw =3D supervdsm.getProxy().getHardwareInfo()
  File "/usr/share/vdsm/supervdsm.py", line 50, in __call__
    return callMethod()
  File "/usr/share/vdsm/supervdsm.py", line 48, in <lambda>
    **kwargs)
  File "<string>", line 2, in getHardwareInfo
  File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773,
in _callmethod
    raise convert_to_error(kind, result)
RuntimeError: [src/dmidecodemodule.c:317] Error decoding DMI data
Thread-60::DEBUG::2015-08-31
12:33:10,932::bindingxmlrpc::1263::vds::(wrapper) return
getHardwareInfo with {'status': {'message': 'Failed to read hardware
information', 'code': 57}}
Thread-60::INFO::2015-08-31
12:33:10,934::xmlrpc::92::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47400 stopped
Reactor thread::INFO::2015-08-31
12:33:11,936::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handl=
e_accept)
Accepting connection from 127.0.0.1:47401
Reactor thread::DEBUG::2015-08-31
12:33:11,948::protocoldetector::82::ProtocolDetector.Detector::(__init__)=

Using required_size=3D11
Reactor thread::INFO::2015-08-31
12:33:11,948::protocoldetector::118::ProtocolDetector.Detector::(handle_r=
ead)
Detected protocol xml from 127.0.0.1:47401
Reactor thread::DEBUG::2015-08-31
12:33:11,948::bindingxmlrpc::1296::XmlDetector::(handle_socket) xml
over http detected from ('127.0.0.1', 47401)
BindingXMLRPC::INFO::2015-08-31
12:33:11,949::xmlrpc::73::vds.XMLRPCServer::(handle_request)
Starting request handler for 127.0.0.1:47401
Thread-61::INFO::2015-08-31
12:33:11,949::xmlrpc::84::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47401 started
Thread-61::DEBUG::2015-08-31
12:33:11,950::bindingxmlrpc::1256::vds::(wrapper) client
[127.0.0.1]::call getHardwareInfo with () {}
Thread-61::ERROR::2015-08-31
12:33:11,953::API::1328::vds::(getHardwareInfo) failed to retrieve
hardware info
Traceback (most recent call last):
  File "/usr/share/vdsm/API.py", line 1325, in getHardwareInfo
    hw =3D supervdsm.getProxy().getHardwareInfo()
  File "/usr/share/vdsm/supervdsm.py", line 50, in __call__
    return callMethod()
  File "/usr/share/vdsm/supervdsm.py", line 48, in <lambda>
    **kwargs)
  File "<string>", line 2, in getHardwareInfo
  File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773,
in _callmethod
    raise convert_to_error(kind, result)
RuntimeError: [src/dmidecodemodule.c:317] Error decoding DMI data
Thread-61::DEBUG::2015-08-31
12:33:11,954::bindingxmlrpc::1263::vds::(wrapper) return
getHardwareInfo with {'status': {'message': 'Failed to read hardware
information', 'code': 57}}
Thread-61::INFO::2015-08-31
12:33:11,955::xmlrpc::92::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47401 stopped
Reactor thread::INFO::2015-08-31
12:33:12,958::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handl=
e_accept)
Accepting connection from 127.0.0.1:47402
Reactor thread::DEBUG::2015-08-31
12:33:12,969::protocoldetector::82::ProtocolDetector.Detector::(__init__)=

Using required_size=3D11
Reactor thread::INFO::2015-08-31
12:33:12,970::protocoldetector::118::ProtocolDetector.Detector::(handle_r=
ead)
Detected protocol xml from 127.0.0.1:47402
Reactor thread::DEBUG::2015-08-31
12:33:12,970::bindingxmlrpc::1296::XmlDetector::(handle_socket) xml
over http detected from ('127.0.0.1', 47402)
BindingXMLRPC::INFO::2015-08-31
12:33:12,970::xmlrpc::73::vds.XMLRPCServer::(handle_request)
Starting request handler for 127.0.0.1:47402
Thread-62::INFO::2015-08-31
12:33:12,971::xmlrpc::84::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47402 started
Thread-62::DEBUG::2015-08-31
12:33:12,972::bindingxmlrpc::1256::vds::(wrapper) client
[127.0.0.1]::call getHardwareInfo with () {}
Thread-62::ERROR::2015-08-31
12:33:12,975::API::1328::vds::(getHardwareInfo) failed to retrieve
hardware info
Traceback (most recent call last):
  File "/usr/share/vdsm/API.py", line 1325, in getHardwareInfo
    hw =3D supervdsm.getProxy().getHardwareInfo()
  File "/usr/share/vdsm/supervdsm.py", line 50, in __call__
    return callMethod()
  File "/usr/share/vdsm/supervdsm.py", line 48, in <lambda>
    **kwargs)
  File "<string>", line 2, in getHardwareInfo
  File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773,
in _callmethod
    raise convert_to_error(kind, result)
RuntimeError: [src/dmidecodemodule.c:317] Error decoding DMI data
Thread-62::DEBUG::2015-08-31
12:33:12,976::bindingxmlrpc::1263::vds::(wrapper) return
getHardwareInfo with {'status': {'message': 'Failed to read hardware
information', 'code': 57}}
Thread-62::INFO::2015-08-31
12:33:12,977::xmlrpc::92::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47402 stopped
Reactor thread::INFO::2015-08-31
12:33:13,980::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handl=
e_accept)
Accepting connection from 127.0.0.1:47403
Reactor thread::DEBUG::2015-08-31
12:33:13,991::protocoldetector::82::ProtocolDetector.Detector::(__init__)=

Using required_size=3D11
Reactor thread::INFO::2015-08-31
12:33:13,992::protocoldetector::118::ProtocolDetector.Detector::(handle_r=
ead)
Detected protocol xml from 127.0.0.1:47403
Reactor thread::DEBUG::2015-08-31
12:33:13,992::bindingxmlrpc::1296::XmlDetector::(handle_socket) xml
over http detected from ('127.0.0.1', 47403)
BindingXMLRPC::INFO::2015-08-31
12:33:13,993::xmlrpc::73::vds.XMLRPCServer::(handle_request)
Starting request handler for 127.0.0.1:47403
Thread-63::INFO::2015-08-31
12:33:13,994::xmlrpc::84::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47403 started
Thread-63::DEBUG::2015-08-31
12:33:13,995::bindingxmlrpc::1256::vds::(wrapper) client
[127.0.0.1]::call getHardwareInfo with () {}
Thread-63::ERROR::2015-08-31
12:33:13,998::API::1328::vds::(getHardwareInfo) failed to retrieve
hardware info
Traceback (most recent call last):
  File "/usr/share/vdsm/API.py", line 1325, in getHardwareInfo
    hw =3D supervdsm.getProxy().getHardwareInfo()
  File "/usr/share/vdsm/supervdsm.py", line 50, in __call__
    return callMethod()
  File "/usr/share/vdsm/supervdsm.py", line 48, in <lambda>
    **kwargs)
  File "<string>", line 2, in getHardwareInfo
  File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773,
in _callmethod
    raise convert_to_error(kind, result)
RuntimeError: [src/dmidecodemodule.c:317] Error decoding DMI data
Thread-63::DEBUG::2015-08-31
12:33:13,998::bindingxmlrpc::1263::vds::(wrapper) return
getHardwareInfo with {'status': {'message': 'Failed to read hardware
information', 'code': 57}}
Thread-63::INFO::2015-08-31
12:33:14,000::xmlrpc::92::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47403 stopped
Reactor thread::INFO::2015-08-31
12:33:15,042::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handl=
e_accept)
Accepting connection from 127.0.0.1:47404
Reactor thread::DEBUG::2015-08-31
12:33:15,054::protocoldetector::82::ProtocolDetector.Detector::(__init__)=

Using required_size=3D11
Reactor thread::INFO::2015-08-31
12:33:15,054::protocoldetector::118::ProtocolDetector.Detector::(handle_r=
ead)
Detected protocol xml from 127.0.0.1:47404
Reactor thread::DEBUG::2015-08-31
12:33:15,054::bindingxmlrpc::1296::XmlDetector::(handle_socket) xml
over http detected from ('127.0.0.1', 47404)
BindingXMLRPC::INFO::2015-08-31
12:33:15,055::xmlrpc::73::vds.XMLRPCServer::(handle_request)
Starting request handler for 127.0.0.1:47404
Thread-64::INFO::2015-08-31
12:33:15,056::xmlrpc::84::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47404 started
Thread-64::DEBUG::2015-08-31
12:33:15,057::bindingxmlrpc::1256::vds::(wrapper) client
[127.0.0.1]::call getCapabilities with () {}
Thread-64::DEBUG::2015-08-31
12:33:15,111::utils::661::root::(execCmd) /usr/sbin/tc qdisc show
(cwd None)
Thread-64::DEBUG::2015-08-31
12:33:15,124::utils::679::root::(execCmd) SUCCESS: <err> =3D ''; <rc> =3D=
 0
Thread-64::DEBUG::2015-08-31
12:33:15,127::utils::661::root::(execCmd) /usr/bin/sudo -n
/usr/sbin/dmidecode -s system-uuid (cwd None)
Thread-64::DEBUG::2015-08-31
12:33:15,153::utils::679::root::(execCmd) FAILED: <err> =3D '/dev/mem:
Operation not permitted\n'; <rc> =3D 1
Thread-64::WARNING::2015-08-31
12:33:15,154::utils::812::root::(getHostUUID) Could not find host UUID.
Thread-64::DEBUG::2015-08-31
12:33:15,156::caps::780::root::(_getKeyPackages) rpm package
('glusterfs-rdma',) not found
Thread-64::DEBUG::2015-08-31
12:33:15,158::caps::780::root::(_getKeyPackages) rpm package
('gluster-swift',) not found
Thread-64::DEBUG::2015-08-31
12:33:15,160::caps::780::root::(_getKeyPackages) rpm package
('gluster-swift-object',) not found
Thread-64::DEBUG::2015-08-31
12:33:15,161::caps::780::root::(_getKeyPackages) rpm package
('gluster-swift-plugin',) not found
Thread-64::DEBUG::2015-08-31
12:33:15,164::caps::780::root::(_getKeyPackages) rpm package
('gluster-swift-account',) not found
Thread-64::DEBUG::2015-08-31
12:33:15,164::caps::780::root::(_getKeyPackages) rpm package
('gluster-swift-proxy',) not found
Thread-64::DEBUG::2015-08-31
12:33:15,165::caps::780::root::(_getKeyPackages) rpm package
('gluster-swift-doc',) not found
Thread-64::DEBUG::2015-08-31
12:33:15,165::caps::780::root::(_getKeyPackages) rpm package
('gluster-swift-container',) not found
Thread-64::DEBUG::2015-08-31
12:33:15,170::bindingxmlrpc::1263::vds::(wrapper) return
getCapabilities with {'status': {'message': 'Done', 'code': 0},
'info': {'HBAInventory': {'iSCSI': [{'InitiatorName':
'iqn.1994-05.com.redhat:aa2f33e6faca'}], 'FC': []}, 'packages2':
{'kernel': {'release': '229.el7.x86_64', 'buildtime': 1425638202.0,
'version': '3.10.0'}, 'glusterfs-fuse': {'release': '1.el7',
'buildtime': 1438093544L, 'version': '3.7.3'}, 'spice-server':
{'release': '9.el7', 'buildtime': 1426031557L, 'version': '0.12.4'},
'librbd1': {'release': '2.el7', 'buildtime': 1425594433L, 'version':
'0.80.7'}, 'vdsm': {'release': '0.el7.centos', 'buildtime':
1440055696L, 'version': '4.17.3'}, 'qemu-kvm': {'release':
'23.el7_1.6.1', 'buildtime': 1438078890L, 'version': '2.1.2'},
'qemu-img': {'release': '23.el7_1.6.1', 'buildtime': 1438078890L,
'version': '2.1.2'}, 'libvirt': {'release': '16.el7_1.3',
'buildtime': 1431461920L, 'version': '1.2.8'}, 'glusterfs':
{'release': '1.el7', 'buildtime': 1438093544L, 'version': '3.7.3'},
'mom': {'release': '1.el7.centos', 'buildtime': 1436814841L,
'version': '0.5.0'}, 'glusterfs-server': {'release': '1.el7',
'buildtime': 1438093544L, 'version': '3.7.3'},
'glusterfs-geo-replication': {'release': '1.el7', 'buildtime':
1438093544L, 'version': '3.7.3'}}, 'numaNodeDistance': {'1': [21,
10], '0': [10, 21]}, 'cpuModel': 'Intel(R) Xeon(R) CPU E5-2690 v2 @
3.00GHz', 'liveMerge': 'true', 'hooks': {'before_vm_start':
{'50_hostedengine': {'md5': '2a6d96c26a3599812be6cf1a13d9f485'}}},
'vmTypes': ['kvm'], 'selinux': {'mode': '1'}, 'liveSnapshot':
'true', 'kdumpStatus': 0, 'networks': {}, 'bridges': {}, 'uuid':
None, 'onlineCpus':
'0,1,2,3,4,5,6,7,8,9,20,21,22,23,24,25,26,27,28,29,10,11,12,13,14,15,16,1=
7,18,19,30,31,32,33,34,35,36,37,38,39',
'nics': {'eno1': {'permhwaddr': '00:1e:67:b9:33:f9', 'addr': '',
'ipv6gateway': '::', 'ipv6addrs': [], 'mtu': '1500', 'dhcpv4':
False, 'netmask': '', 'dhcpv6': False, 'ipv4addrs': [], 'cfg':
{'SLAVE': 'yes', 'BOOTPROTO': 'none', 'MASTER': 'bond0', 'DEVICE':
'eno1', 'TYPE': 'Ethernet', 'ONBOOT': 'yes'}, 'hwaddr':
'00:1e:67:b9:33:f9', 'speed': 1000, 'gateway': ''}, 'eno2':
{'permhwaddr': '00:1e:67:b9:33:fa', 'addr': '', 'ipv6gateway': '::',
'ipv6addrs': [], 'mtu': '1500', 'dhcpv4': False, 'netmask': '',
'dhcpv6': False, 'ipv4addrs': [], 'cfg': {'SLAVE': 'yes',
'BOOTPROTO': 'none', 'MASTER': 'bond0', 'DEVICE': 'eno2', 'TYPE':
'Ethernet', 'ONBOOT': 'yes'}, 'hwaddr': '00:1e:67:b9:33:f9',
'speed': 1000, 'gateway': ''}, 'eno3': {'permhwaddr':
'00:1e:67:b9:33:fb', 'addr': '', 'ipv6gateway': '::', 'ipv6addrs':
[], 'mtu': '1500', 'dhcpv4': False, 'netmask': '', 'dhcpv6': False,
'ipv4addrs': [], 'cfg': {'SLAVE': 'yes', 'BOOTPROTO': 'none',
'MASTER': 'bond0', 'DEVICE': 'eno3', 'TYPE': 'Ethernet', 'ONBOOT':
'yes'}, 'hwaddr': '00:1e:67:b9:33:f9', 'speed': 1000, 'gateway':
''}, 'eno4': {'permhwaddr': '00:1e:67:b9:33:fc', 'addr': '',
'ipv6gateway': '::', 'ipv6addrs': [], 'mtu': '1500', 'dhcpv4':
False, 'netmask': '', 'dhcpv6': False, 'ipv4addrs': [], 'cfg':
{'SLAVE': 'yes', 'BOOTPROTO': 'none', 'MASTER': 'bond0', 'DEVICE':
'eno4', 'TYPE': 'Ethernet', 'ONBOOT': 'yes'}, 'hwaddr':
'00:1e:67:b9:33:f9', 'speed': 1000, 'gateway': ''}},
'software_revision': '0', 'hostdevPassthrough': 'false',
'clusterLevels': ['3.4', '3.5', '3.6'], 'cpuFlags':
'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,=
clflush,dts,acpi,mmx,fxsr,sse,sse2,ss,ht,tm,pbe,syscall,nx,pdpe1gb,rdtscp=
,lm,constant_tsc,arch_perfmon,pebs,bts,rep_good,nopl,xtopology,nonstop_ts=
c,aperfmperf,eagerfpu,pni,pclmulqdq,dtes64,monitor,ds_cpl,vmx,smx,est,tm2=
,ssse3,cx16,xtpr,pdcm,pcid,dca,sse4_1,sse4_2,x2apic,popcnt,tsc_deadline_t=
imer,aes,xsave,avx,f16c,rdrand,lahf_lm,ida,arat,epb,xsaveopt,pln,pts,dthe=
rm,tpr_shadow,vnmi,flexpriority,ept,vpid,fsgsbase,smep,erms,model_Nehalem=
,model_Conroe,model_coreduo,model_core2duo,model_Penryn,model_Westmere,mo=
del_n270,model_SandyBridge',
'ISCSIInitiatorName': 'iqn.1994-05.com.redhat:aa2f33e6faca',
'netConfigDirty': 'False', 'supportedENGINEs': ['3.4', '3.5',
'3.6'], 'autoNumaBalancing': 1, 'additionalFeatures':
['GLUSTER_SNAPSHOT', 'GLUSTER_GEO_REPLICATION',
'GLUSTER_BRICK_MANAGEMENT'], 'reservedMem': '321', 'bondings':
{'bond0': {'ipv4addrs': ['131.130.44.101/24'], 'addr':
'131.130.44.101', 'cfg': {'IPV6INIT': 'no', 'BONDING_MASTER': 'yes',
'IPADDR': '131.130.44.101', 'IPV4_FAILURE_FATAL': 'no', 'PREFIX':
'24', 'BOOTPROTO': 'none', 'BONDING_OPTS': 'miimon=3D100
mode=3D802.3ad', 'DEVICE': 'bond0', 'TYPE': 'Bond', 'ONBOOT': 'yes',
'NAME': 'Bond connection bond0'}, 'ipv6addrs':
['fe80::21e:67ff:feb9:33f9/64'], 'active_slave': '', 'mtu': '1500',
'dhcpv4': False, 'netmask': '255.255.255.0', 'dhcpv6': False,
'slaves': ['eno1', 'eno2', 'eno3', 'eno4'], 'hwaddr':
'00:1e:67:b9:33:f9', 'ipv6gateway': '::', 'gateway': '131.130.44.1',
'opts': {'miimon': '100', 'mode': '4'}}}, 'software_version':
'4.17', 'memSize': '515720', 'cpuSpeed': '1272.304', 'numaNodes':
{'1': {'totalMemory': '262144', 'cpus': [10, 11, 12, 13, 14, 15, 16,
17, 18, 19, 30, 31, 32, 33, 34, 35, 36, 37, 38, 39]}, '0':
{'totalMemory': '262065', 'cpus': [0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 20,
21, 22, 23, 24, 25, 26, 27, 28, 29]}}, 'cpuSockets': '2', 'vlans':
{}, 'lastClientIface': 'lo', 'cpuCores': '20', 'kvmEnabled': 'true',
'guestOverhead': '65', 'version_name': 'Snow Man', 'cpuThreads':
'40', 'emulatedMachines': ['pc-i440fx-rhel7.1.0', 'rhel6.3.0',
'pc-q35-rhel7.0.0', 'rhel6.1.0', 'rhel6.6.0', 'rhel6.2.0', 'pc',
'pc-q35-rhel7.1.0', 'q35', 'rhel6.4.0', 'rhel6.0.0', 'rhel6.5.0',
'pc-i440fx-rhel7.0.0'], 'rngSources': ['random'], 'operatingSystem':
{'release': '1.1503.el7.centos.2.8', 'version': '7', 'name':
'RHEL'}, 'lastClient': '127.0.0.1'}}
Thread-64::INFO::2015-08-31
12:33:15,298::xmlrpc::92::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47404 stopped
Reactor thread::INFO::2015-08-31
12:33:18,486::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handl=
e_accept)
Accepting connection from 127.0.0.1:47405
Reactor thread::DEBUG::2015-08-31
12:33:18,498::protocoldetector::82::ProtocolDetector.Detector::(__init__)=

Using required_size=3D11
Reactor thread::INFO::2015-08-31
12:33:18,499::protocoldetector::118::ProtocolDetector.Detector::(handle_r=
ead)
Detected protocol xml from 127.0.0.1:47405
Reactor thread::DEBUG::2015-08-31
12:33:18,499::bindingxmlrpc::1296::XmlDetector::(handle_socket) xml
over http detected from ('127.0.0.1', 47405)
BindingXMLRPC::INFO::2015-08-31
12:33:18,499::xmlrpc::73::vds.XMLRPCServer::(handle_request)
Starting request handler for 127.0.0.1:47405
Thread-65::INFO::2015-08-31
12:33:18,501::xmlrpc::84::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47405 started
Thread-65::INFO::2015-08-31
12:33:18,504::xmlrpc::92::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47405 stopped
Reactor thread::INFO::2015-08-31
12:33:33,520::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handl=
e_accept)
Accepting connection from 127.0.0.1:47406
Reactor thread::DEBUG::2015-08-31
12:33:33,532::protocoldetector::82::ProtocolDetector.Detector::(__init__)=

Using required_size=3D11
Reactor thread::INFO::2015-08-31
12:33:33,533::protocoldetector::118::ProtocolDetector.Detector::(handle_r=
ead)
Detected protocol xml from 127.0.0.1:47406
Reactor thread::DEBUG::2015-08-31
12:33:33,533::bindingxmlrpc::1296::XmlDetector::(handle_socket) xml
over http detected from ('127.0.0.1', 47406)
BindingXMLRPC::INFO::2015-08-31
12:33:33,533::xmlrpc::73::vds.XMLRPCServer::(handle_request)
Starting request handler for 127.0.0.1:47406
Thread-66::INFO::2015-08-31
12:33:33,535::xmlrpc::84::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47406 started
Thread-66::INFO::2015-08-31
12:33:33,538::xmlrpc::92::vds.XMLRPCServer::(_process_requests)
Request handler for 127.0.0.1:47406 stopped

--=20
/dev/null

--3W6fXveMv22FLGti6dq6jEIOIdx6nU7sR
Content-Type: application/pgp-signature; name="signature.asc"
Content-Description: OpenPGP digital signature
Content-Disposition: attachment; filename="signature.asc"

-----BEGIN PGP SIGNATURE-----
Version: GnuPG v2

iEYEARECAAYFAlXkND4ACgkQnGohgOrO9GEHSQCfS+zyjah2+6JV0GVK1MzIfESJ
POYAoNOeh/pz1DIE7wKZOLKAXJfXdB79
=JwoY
-----END PGP SIGNATURE-----

--3W6fXveMv22FLGti6dq6jEIOIdx6nU7sR--

[ovirt-users] ovirt 3.6 Failed to execute stage 'Environment setup'

Richard Neuboeck