Bug 1058536

Summary: hosted-engine --deploy (Fedora 19) on first run: Command '/bin/systemctl' failed to execute (for VDSM)
Product: [Retired] oVirt Reporter: Douglas Schilling Landgraf <dougsland>
Component: ovirt-hosted-engine-setupAssignee: Douglas Schilling Landgraf <dougsland>
Status: CLOSED DUPLICATE QA Contact: Leonid Natapov <lnatapov>
Severity: unspecified Docs Contact:
Priority: unspecified    
Version: 3.4CC: acathrow, danken, gklein, iheim, ybronhei, yeylon
Target Milestone: ---   
Target Release: ---   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard:
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: 2014-01-28 05:33:31 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:
Attachments:
Description Flags
logs
none
supervdsm none

Description Douglas Schilling Landgraf 2014-01-28 01:39:49 UTC
Description of problem:

This bug is similar to BZ#1055153 but during the start there is no vdsm.log owned by root.

- Install Fedora 19 on clean host
- yum update
- Add the repo:
   /etc/yum.repos.d/ovirt.repo
  [ovirt-3.4.0-beta]
  name=3.4.0 beta testing repo for the oVirt project
  baseurl=http://ovirt.org/releases/3.4.0-beta/rpm/Fedora/$releasever/
  enabled=1
  skip_if_unavailable=1
  gpgcheck=0

# yum install ovirt-hosted-engine-setup (On second time running it doesn't show this error)

# hosted-engine --deploy
[ INFO  ] Stage: Initializing
          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]: Yes
          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  ] Generating a temporary VNC password.
[ INFO  ] Stage: Environment setup
          Configuration files: []
          Log file: /var/log/ovirt-hosted-engine-setup/ovirt-hosted-engine-setup-20140127233210.log
          Version: otopi-1.2.0_beta (otopi-1.2.0-0.1.beta.fc19)
[ INFO  ] Hardware supports virtualization
[ INFO  ] Stage: Environment packages setup
[ INFO  ] Stage: Programs detection
[ INFO  ] Stage: Environment setup
[ ERROR ] Failed to execute stage 'Environment setup': Command '/bin/systemctl' failed to execute
[ INFO  ] Stage: Clean up
[ INFO  ] Stage: Pre-termination
[ INFO  ] Stage: Termination


# ls -la /var/log/vdsm/
total 16
drwxr-xr-x.  3 vdsm kvm  4096 Jan 27 23:32 .
drwxr-xr-x. 15 root root 4096 Jan 27 23:32 ..
drwxr-xr-x.  2 vdsm kvm  4096 Jan 21 11:20 backup
-rw-r--r--.  1 root root 1112 Jan 27 23:32 supervdsm.log



From /var/log/ovirt-hosted-engine-setup/ovirt-hosted-engine-setup-20140127233210.log
==================
2014-01-27 23:32:20 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:383 execute-result: ('/bin/systemctl', 'status', 'vdsmd.service'), rc=3
2014-01-27 23:32:20 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:441 execute-output: ('/bin/systemctl', 'status', 'vdsmd.service') stdout:
vdsmd.service - Virtual Desktop Server Manager
   Loaded: loaded (/usr/lib/systemd/system/vdsmd.service; enabled)
   Active: inactive (dead)


2014-01-27 23:32:20 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:446 execute-output: ('/bin/systemctl', 'status', 'vdsmd.service') stderr:


2014-01-27 23:32:20 DEBUG otopi.plugins.ovirt_hosted_engine_setup.system.vdsmenv plugin.executeRaw:366 execute: ('/bin/vdsm-tool', 'configure', '--force'), executable='None', cwd='None', env=None
2014-01-27 23:32:21 DEBUG otopi.plugins.ovirt_hosted_engine_setup.system.vdsmenv plugin.executeRaw:383 execute-result: ('/bin/vdsm-tool', 'configure', '--force'), rc=0
2014-01-27 23:32:21 DEBUG otopi.plugins.ovirt_hosted_engine_setup.system.vdsmenv plugin.execute:441 execute-output: ('/bin/vdsm-tool', 'configure', '--force') stdout:

Checking configuration status...

SUCCESS: ssl configured to true. No conflicts
sanlock service is not running
sanlock service is already configured

Running configure...
Reconfiguration of libvirt is done.

Done configuring modules to VDSM.

2014-01-27 23:32:21 DEBUG otopi.plugins.ovirt_hosted_engine_setup.system.vdsmenv plugin.execute:446 execute-output: ('/bin/vdsm-tool', 'configure', '--force') stderr:


2014-01-27 23:32:21 DEBUG otopi.plugins.otopi.services.systemd systemd.state:134 starting service vdsmd
2014-01-27 23:32:21 DEBUG otopi.plugins.otopi.services.systemd systemd.state:134 starting service vdsmd
2014-01-27 23:32:21 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:366 execute: ('/bin/systemctl', 'start', 'vdsmd.service'), executable='None', cwd='None', env=None
2014-01-27 23:32:21 DEBUG otopi.plugins.otopi.services.systemd plugin.executeRaw:383 execute-result: ('/bin/systemctl', 'start', 'vdsmd.service'), rc=1
2014-01-27 23:32:21 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:441 execute-output: ('/bin/systemctl', 'start', 'vdsmd.service') stdout:


2014-01-27 23:32:21 DEBUG otopi.plugins.otopi.services.systemd plugin.execute:446 execute-output: ('/bin/systemctl', 'start', 'vdsmd.service') stderr:
Job for vdsmd.service failed. See 'systemctl status vdsmd.service' and 'journalctl -xn' for details.

2014-01-27 23:32:21 DEBUG otopi.context context._executeMethod:152 method exception
Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/otopi/context.py", line 142, in _executeMethod
    method['method']()
  File "/usr/share/ovirt-hosted-engine-setup/scripts/../plugins/ovirt-hosted-engine-setup/system/vdsmenv.py", line 154, in _late_setup
    state=True
  File "/usr/share/otopi/plugins/otopi/services/systemd.py", line 138, in state
    'start' if state else 'stop'
  File "/usr/share/otopi/plugins/otopi/services/systemd.py", line 77, in _executeServiceCommand
    raiseOnError=raiseOnError
  File "/usr/lib/python2.7/site-packages/otopi/plugin.py", line 451, in execute
    command=args[0],
RuntimeError: Command '/bin/systemctl' failed to execute
2014-01-27 23:32:21 ERROR otopi.context context._executeMethod:161 Failed to execute stage 'Environment setup': Command '/bin/systemctl' failed to execute


=============

Comment 1 Douglas Schilling Landgraf 2014-01-28 01:41:35 UTC
Created attachment 856333 [details]
logs

Comment 2 Douglas Schilling Landgraf 2014-01-28 01:42:15 UTC
Created attachment 856334 [details]
supervdsm

Comment 3 Douglas Schilling Landgraf 2014-01-28 03:14:33 UTC
/var/log/messages
===================

Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: vdsm: Running mkdirs
Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: vdsm: Running configure_coredump
Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: vdsm: Running run_init_hooks
Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: vdsm: Running gencerts
Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: vdsm: Running check_is_configured
Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: libvirt is already configured for vdsm
Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: sanlock service requires restart
Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: Modules sanlock are not configured
Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: Traceback (most recent call last):
Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: File "/usr/bin/vdsm-tool", line 145, in <module>
Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: sys.exit(main())
Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: File "/usr/bin/vdsm-tool", line 142, in main
Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: return tool_command[cmd]["command"](*args[1:])
Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: File "/usr/lib64/python2.7/site-packages/vdsm/tool/configurator.py", line 261, in isconfigured
Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: raise RuntimeError(msg)
Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: RuntimeError:
Jan 28 01:12:17 localhost vdsmd_init_common.sh[16447]: vdsm: stopped during execute check_is_configured task (task returned with error code 1).

Comment 4 Douglas Schilling Landgraf 2014-01-28 05:33:31 UTC
Resolved with: http://gerrit.ovirt.org/#/c/23788/  but I am closing as duplicate of bz#1055153 for now.

*** This bug has been marked as a duplicate of bug 1055153 ***