2018-12-08 08:56:10 DEBUG otopi.context context._executeMethod:128 Stage closeup METHOD otopi.plugins.ovirt_host_deploy.tune.iosched.Plugin._refresh 2018-12-08 08:56:10 DEBUG otopi.plugins.ovirt_host_deploy.tune.iosched plugin.executeRaw:813 execute: ['/bin/udevadm', 'trigger', '--type=devices', '--action=change'], executable='None', cwd='None', env=None 2018-12-08 08:56:10 DEBUG otopi.plugins.ovirt_host_deploy.tune.iosched plugin.executeRaw:863 execute-result: ['/bin/udevadm', 'trigger', '--type=devices', '--action=change'], rc=0 2018-12-08 08:56:10 DEBUG otopi.plugins.ovirt_host_deploy.tune.iosched plugin.execute:921 execute-output: ['/bin/udevadm', 'trigger', '--type=devices', '--action=change'] stdout: 2018-12-08 08:56:10 DEBUG otopi.plugins.ovirt_host_deploy.tune.iosched plugin.execute:926 execute-output: ['/bin/udevadm', 'trigger', '--type=devices', '--action=change'] stderr: 2018-12-08 08:56:10 DEBUG otopi.context context._executeMethod:128 Stage closeup METHOD otopi.plugins.ovirt_host_deploy.vdsm.packages.Plugin._start 2018-12-08 08:56:10 INFO otopi.plugins.ovirt_host_deploy.vdsm.packages packages._start:163 Stopping libvirtd 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd systemd.exists:73 check if service libvirtd exists 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'show', '-p', 'LoadState', 'libvirtd.service'), executable='None', cwd='None', env=None 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'show', '-p', 'LoadState', 'libvirtd.service'), rc=0 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'show', '-p', 'LoadState', 'libvirtd.service') stdout: LoadState=loaded 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'show', '-p', 'LoadState', 'libvirtd.service') stderr: 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd systemd.state:130 stopping service libvirtd 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'stop', 'libvirtd.service'), executable='None', cwd='None', env=None 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'stop', 'libvirtd.service'), rc=0 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'stop', 'libvirtd.service') stdout: 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'stop', 'libvirtd.service') stderr: 2018-12-08 08:56:10 INFO otopi.plugins.ovirt_host_deploy.vdsm.packages packages._start:167 Starting vdsm 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd systemd.exists:73 check if service NetworkManager exists 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'show', '-p', 'LoadState', 'NetworkManager.service'), executable='None', cwd='None', env=None 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'show', '-p', 'LoadState', 'NetworkManager.service'), rc=0 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'show', '-p', 'LoadState', 'NetworkManager.service') stdout: LoadState=not-found 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'show', '-p', 'LoadState', 'NetworkManager.service') stderr: 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd systemd.exists:73 check if service network exists 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'show', '-p', 'LoadState', 'network.service'), executable='None', cwd='None', env=None 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'show', '-p', 'LoadState', 'network.service'), rc=0 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'show', '-p', 'LoadState', 'network.service') stdout: LoadState=loaded 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'show', '-p', 'LoadState', 'network.service') stderr: 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd systemd.status:90 check service network status 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'status', 'network.service'), executable='None', cwd='None', env=None 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'status', 'network.service'), rc=0 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'status', 'network.service') stdout: ● network.service - LSB: Bring up/down networking Loaded: loaded (/etc/rc.d/init.d/network; bad; vendor preset: disabled) Active: active (exited) since Sat 2018-12-08 07:15:58 UTC; 1h 40min ago Docs: man:systemd-sysv-generator(8) Dec 08 07:15:45 localhost.localdomain systemd[1]: Starting LSB: Bring up/down networking... Dec 08 07:15:45 localhost.localdomain network[597]: Bringing up loopback interface: [ OK ] Dec 08 07:15:54 localhost.localdomain network[597]: Bringing up interface eth0: RTNETLINK answers: File exists Dec 08 07:15:54 localhost.localdomain network[597]: [ OK ] Dec 08 07:15:58 localhost.localdomain network[597]: Bringing up interface eth1: RTNETLINK answers: File exists Dec 08 07:15:58 localhost.localdomain network[597]: [ OK ] Dec 08 07:15:58 localhost.localdomain systemd[1]: Started LSB: Bring up/down networking. 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'status', 'network.service') stderr: 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd systemd.startup:99 set service network startup to True 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'show', '-p', 'Id', 'network.service'), executable='None', cwd='None', env=None 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'show', '-p', 'Id', 'network.service'), rc=0 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'show', '-p', 'Id', 'network.service') stdout: Id=network.service 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'show', '-p', 'Id', 'network.service') stderr: 2018-12-08 08:56:10 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'enable', u'network.service'), executable='None', cwd='None', env=None 2018-12-08 08:56:11 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'enable', u'network.service'), rc=0 2018-12-08 08:56:11 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'enable', u'network.service') stdout: 2018-12-08 08:56:11 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'enable', u'network.service') stderr: network.service is not a native service, redirecting to /sbin/chkconfig. Executing /sbin/chkconfig network on 2018-12-08 08:56:11 DEBUG otopi.plugins.otopi.services.systemd systemd.state:130 starting service vdsmd 2018-12-08 08:56:11 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:813 execute: ('/bin/systemctl', 'start', 'vdsmd.service'), executable='None', cwd='None', env=None 2018-12-08 08:56:22 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:863 execute-result: ('/bin/systemctl', 'start', 'vdsmd.service'), rc=1 2018-12-08 08:56:22 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:921 execute-output: ('/bin/systemctl', 'start', 'vdsmd.service') stdout: 2018-12-08 08:56:22 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:926 execute-output: ('/bin/systemctl', 'start', 'vdsmd.service') stderr: A dependency job for vdsmd.service failed. See 'journalctl -xe' for details. 2018-12-08 08:56:22 DEBUG otopi.context context._executeMethod:142 method exception Traceback (most recent call last): File "/tmp/ovirt-k9euqzUiWK/pythonlib/otopi/context.py", line 132, in _executeMethod method['method']() File "/tmp/ovirt-k9euqzUiWK/otopi-plugins/ovirt-host-deploy/vdsm/packages.py", line 209, in _start self.services.state('vdsmd', True) File "/tmp/ovirt-k9euqzUiWK/otopi-plugins/otopi/services/systemd.py", line 141, in state service=name, RuntimeError: Failed to start service 'vdsmd' 2018-12-08 08:56:22 ERROR otopi.context context._executeMethod:151 Failed to execute stage 'Closing up': Failed to start service 'vdsmd' 2018-12-08 08:56:22 DEBUG otopi.context context.dumpEnvironment:760 ENVIRONMENT DUMP - BEGIN 2018-12-08 08:56:22 DEBUG otopi.context context.dumpEnvironment:770 ENV BASE/error=bool:'True' 2018-12-08 08:56:22 DEBUG otopi.context context.dumpEnvironment:770 ENV BASE/exceptionInfo=list:'[(<type 'exceptions.RuntimeError'>, RuntimeError("Failed to start service 'vdsmd'",), <traceback object at 0x7f83c380e908>)]' 2018-12-08 08:56:22 DEBUG otopi.context context.dumpEnvironment:774 ENVIRONMENT DUMP - END 2018-12-08 08:56:22 INFO otopi.context context.runSequence:687 Stage: Pre-termination 2018-12-08 08:56:22 DEBUG otopi.context context.runSequence:691 STAGE pre-terminate 2018-12-08 08:56:22 DEBUG otopi.context context._executeMethod:128 Stage pre-terminate METHOD otopi.plugins.otopi.core.misc.Plugin._preTerminate