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

Monday, 31 August 2015

On Mon, Aug 31, 2015 at 1:02 PM, Richard Neuboeck <hawk(a)tbi.univie.ac.at&gt;
wrote:

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

Could you please attach also its log?

...
 # 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-20150831123258-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::(handle_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=11
 Reactor thread::INFO::2015-08-31

 12:32:48,429::protocoldetector::118::ProtocolDetector.Detector::(handle_read)
 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::(handle_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=11
 Reactor thread::INFO::2015-08-31

 12:33:03,465::protocoldetector::118::ProtocolDetector.Detector::(handle_read)
 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::(handle_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=11
 Reactor thread::INFO::2015-08-31

 12:33:04,783::protocoldetector::118::ProtocolDetector.Detector::(handle_read)
 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 = 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::(handle_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=11
 Reactor thread::INFO::2015-08-31

 12:33:05,812::protocoldetector::118::ProtocolDetector.Detector::(handle_read)
 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 = 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::(handle_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=11
 Reactor thread::INFO::2015-08-31

 12:33:06,836::protocoldetector::118::ProtocolDetector.Detector::(handle_read)
 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 = 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::(handle_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=11
 Reactor thread::INFO::2015-08-31

 12:33:07,859::protocoldetector::118::ProtocolDetector.Detector::(handle_read)
 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 = 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::(handle_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=11
 Reactor thread::INFO::2015-08-31

 12:33:08,882::protocoldetector::118::ProtocolDetector.Detector::(handle_read)
 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 = 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::(handle_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=11
 Reactor thread::INFO::2015-08-31

 12:33:09,904::protocoldetector::118::ProtocolDetector.Detector::(handle_read)
 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 = 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::(handle_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=11
 Reactor thread::INFO::2015-08-31

 12:33:10,926::protocoldetector::118::ProtocolDetector.Detector::(handle_read)
 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 = 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::(handle_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=11
 Reactor thread::INFO::2015-08-31

 12:33:11,948::protocoldetector::118::ProtocolDetector.Detector::(handle_read)
 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 = 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::(handle_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=11
 Reactor thread::INFO::2015-08-31

 12:33:12,970::protocoldetector::118::ProtocolDetector.Detector::(handle_read)
 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 = 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::(handle_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=11
 Reactor thread::INFO::2015-08-31

 12:33:13,992::protocoldetector::118::ProtocolDetector.Detector::(handle_read)
 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 = 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::(handle_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=11
 Reactor thread::INFO::2015-08-31

 12:33:15,054::protocoldetector::118::ProtocolDetector.Detector::(handle_read)
 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> = ''; <rc> =
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> = '/dev/mem:
 Operation not permitted\n'; <rc> = 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,17,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_tsc,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_timer,aes,xsave,avx,f16c,rdrand,lahf_lm,ida,arat,epb,xsaveopt,pln,pts,dtherm,tpr_shadow,vnmi,flexpriority,ept,vpid,fsgsbase,smep,erms,model_Nehalem,model_Conroe,model_coreduo,model_core2duo,model_Penryn,model_Westmere,model_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=100
 mode=802.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::(handle_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=11
 Reactor thread::INFO::2015-08-31

 12:33:18,499::protocoldetector::118::ProtocolDetector.Detector::(handle_read)
 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::(handle_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=11
 Reactor thread::INFO::2015-08-31

 12:33:33,533::protocoldetector::118::ProtocolDetector.Detector::(handle_read)
 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

 --
 /dev/null

 _______________________________________________
 Users mailing list
 Users(a)ovirt.org
 http://lists.ovirt.org/mailman/listinfo/users

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

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