Skip to content

Commit

Permalink
fixes for SCVMM
Browse files Browse the repository at this point in the history
- correct scvmm references
- add unit tests for scvmm detection
- ensure all matching devices are mounted and examined for scvmm configuration
- exit once vmm startup script is found and executed
- fixes Azure#185
  • Loading branch information
hglkrijger authored and unknown committed Jun 2, 2016
1 parent 922c569 commit ab2e3c3
Show file tree
Hide file tree
Showing 4 changed files with 148 additions and 29 deletions.
12 changes: 7 additions & 5 deletions azurelinuxagent/distro/default/daemon.py
Original file line number Diff line number Diff line change
Expand Up @@ -79,11 +79,13 @@ def daemon(self):
os.chdir(conf.get_lib_dir())

if conf.get_detect_scvmm_env():
if self.distro.scvmm_handler.run():
return
if self.distro.scvmm_handler.detect_scvmm_env():
# scvmm install script is executed after detection
time.sleep(5)
sys.exit(0)

self.distro.provision_handler.run()

if conf.get_resourcedisk_format():
self.distro.resource_disk_handler.run()

Expand All @@ -92,10 +94,10 @@ def daemon(self):
except ProtocolError as e:
logger.error("Failed to detect protocol, exit", e)
return

self.distro.event_handler.run()
self.distro.env_handler.run()

while self.running:
#Handle extensions
self.distro.ext_handlers_handler.run()
Expand Down
25 changes: 17 additions & 8 deletions azurelinuxagent/distro/default/osutil.py
Original file line number Diff line number Diff line change
Expand Up @@ -277,15 +277,17 @@ def conf_sshd(self, disable_password):


def get_dvd_device(self, dev_dir='/dev'):
patten=r'(sr[0-9]|hd[c-z]|cdrom[0-9]|cd[0-9])'
for dvd in [re.match(patten, dev) for dev in os.listdir(dev_dir)]:
pattern=r'(sr[0-9]|hd[c-z]|cdrom[0-9]|cd[0-9])'
for dvd in [re.match(pattern, dev) for dev in os.listdir(dev_dir)]:
if dvd is not None:
return "/dev/{0}".format(dvd.group(0))
raise OSUtilError("Failed to get dvd device")

def mount_dvd(self, max_retry=6, chk_err=True):
dvd = self.get_dvd_device()
mount_point = conf.get_dvd_mount_point()
def mount_dvd(self, max_retry=6, chk_err=True, dvd=None, mount_point=None):
if dvd is None:
dvd = self.get_dvd_device()
if mount_point is None:
mount_point = conf.get_dvd_mount_point()
mountlist = shellutil.run_get_output("mount")[1]
existing = self.get_mount_point(mountlist, dvd)
if existing is not None: #Already mounted
Expand All @@ -307,8 +309,9 @@ def mount_dvd(self, max_retry=6, chk_err=True):
if chk_err:
raise OSUtilError("Failed to mount dvd.")

def umount_dvd(self, chk_err=True):
mount_point = conf.get_dvd_mount_point()
def umount_dvd(self, chk_err=True, mount_point=None):
if mount_point is None:
mount_point = conf.get_dvd_mount_point()
retcode = self.umount(mount_point, chk_err=chk_err)
if chk_err and retcode != 0:
raise OSUtilError("Failed to umount dvd.")
Expand All @@ -319,7 +322,13 @@ def eject_dvd(self, chk_err=True):
if chk_err and retcode != 0:
raise OSUtilError("Failed to eject dvd: ret={0}".format(retcode))

def load_atappix_mod(self):
def try_load_atapiix_mod(self):
try:
self.load_atapiix_mod()
except:
logger.warn("could not load ATAPI driver")

def load_atapiix_mod(self):
if self.is_atapiix_mod_loaded():
return
ret, kern_version = shellutil.run_get_output("uname -r")
Expand Down
55 changes: 39 additions & 16 deletions azurelinuxagent/distro/default/scvmm.py
Original file line number Diff line number Diff line change
Expand Up @@ -17,32 +17,55 @@
# Requires Python 2.4+ and Openssl 1.0+
#

import re
import os
import subprocess
import azurelinuxagent.logger as logger
import azurelinuxagent.conf as conf

VMM_CONF_FILE_NAME = "linuxosconfiguration.xml"
VMM_STARTUP_SCRIPT_NAME= "install"
VMM_STARTUP_SCRIPT_NAME = "install"

class ScvmmHandler(object):
def __init__(self, distro):
self.distro = distro

def detect_scvmm_env(self):
def detect_scvmm_env(self, dev_dir='/dev/'):
logger.info("Detecting Microsoft System Center VMM Environment")
self.distro.osutil.mount_dvd(max_retry=1, chk_err=False)
mount_point = self.distro.osutil.get_dvd_mount_point()
found = os.path.isfile(os.path.join(mount_point, VMM_CONF_FILE_NAME))
if found:
self.start_scvmm_agent()
else:
self.distro.osutil.umount_dvd(chk_err=False)
return found
found = False

# try to load the ATAPI driver, continue on failure
self.distro.osutil.try_load_atapiix_mod()

def start_scvmm_agent(self):
logger.info("Starting Microsoft System Center VMM Initialization "
"Process")
mount_point = self.distro.osutil.get_dvd_mount_point()
startup_script = os.path.join(mount_point, VMM_STARTUP_SCRIPT_NAME)
subprocess.Popen(["/bin/bash", startup_script, "-p " + mount_point])
# cycle through all available /dev/sr*|hd*|cdrom*|cd* looking for the scvmm configuration file
mount_point = conf.get_dvd_mount_point()
for dvds in [re.match(r'(sr[0-9]|hd[c-z]|cdrom[0-9]?|cd[0-9]+)', dev) for dev in os.listdir(dev_dir)]:
if dvds is None:
continue
dvd = dev_dir + dvds.group(0)
self.distro.osutil.mount_dvd(max_retry=1, chk_err=False, dvd=dvd, mount_point=mount_point)
conf_file_full = os.path.join(mount_point, VMM_CONF_FILE_NAME)
found = os.path.isfile(conf_file_full)
if found:
logger.info("Found VMM configuration at {0}".format(conf_file_full))
install_script_full = os.path.join(mount_point, VMM_STARTUP_SCRIPT_NAME)
if os.path.exists(install_script_full):
logger.info("Found VMM install script at {0}".format(install_script_full))
self.start_scvmm_agent(mount_point=mount_point, startup_script=install_script_full)
break
else:
logger.warn("Microsoft System Center VMM install script was not found at {0}".format(install_script_full))
else:
self.distro.osutil.umount_dvd(chk_err=False, mount_point=mount_point)

return found

def start_scvmm_agent(self, mount_point=None, startup_script=None):
logger.info("Starting VMM Initialization Process")
if mount_point is None:
mount_point = conf.get_dvd_mount_point()
if startup_script is None:
startup_script = os.path.join(mount_point, VMM_STARTUP_SCRIPT_NAME)
script_commands = ["/bin/bash", startup_script, "-p " + mount_point]
logger.info("Starting VMM install process with {0}".format(script_commands))
subprocess.Popen(script_commands)
85 changes: 85 additions & 0 deletions tests/distro/test_scvmm.py
Original file line number Diff line number Diff line change
@@ -0,0 +1,85 @@
# Copyright 2014 Microsoft Corporation
#
# Licensed under the Apache License, Version 2.0 (the "License");
# you may not use this file except in compliance with the License.
# You may obtain a copy of the License at
#
# http://www.apache.org/licenses/LICENSE-2.0
#
# Unless required by applicable law or agreed to in writing, software
# distributed under the License is distributed on an "AS IS" BASIS,
# WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
# See the License for the specific language governing permissions and
# limitations under the License.
#
# Requires Python 2.4+ and Openssl 1.0+
#
# Implements parts of RFC 2131, 1541, 1497 and
# http://msdn.microsoft.com/en-us/library/cc227282%28PROT.10%29.aspx
# http://msdn.microsoft.com/en-us/library/cc227259%28PROT.13%29.aspx

import azurelinuxagent.distro.default.scvmm as scvmm
import azurelinuxagent.distro.default.osutil as osutil
import mock
from tests.tools import *
from azurelinuxagent.distro.loader import get_distro
from azurelinuxagent.distro.default.protocolUtil import *


class TestSCVMM(AgentTestCase):
def test_scvmm_detection_with_file(self):
# setup
conf.get_dvd_mount_point = Mock(return_value=self.tmp_dir)
conf.get_detect_scvmm_env = Mock(return_value=True)
scvmm_file = os.path.join(self.tmp_dir, scvmm.VMM_CONF_FILE_NAME)
fileutil.write_file(scvmm_file, "")

patch = mock.patch.object(scvmm.ScvmmHandler, 'start_scvmm_agent').start()

# execute
get_distro().daemon_handler.daemon()

# assert
patch.assert_called()

# cleanup
os.remove(scvmm_file)


def test_scvmm_detection_with_multiple_cdroms(self):
# setup
conf.get_dvd_mount_point = Mock(return_value=self.tmp_dir)
conf.get_detect_scvmm_env = Mock(return_value=True)

patch_mount = mock.patch.object(osutil.DefaultOSUtil, 'mount_dvd').start()

# execute
with patch('os.listdir', return_value=["sr0", "sr1", "sr2"]):
scvmm.ScvmmHandler(get_distro()).detect_scvmm_env()

# assert
assert patch_mount.call_count == 3
assert patch_mount.call_args_list[0][1]['dvd'] == '/dev/sr0'
assert patch_mount.call_args_list[1][1]['dvd'] == '/dev/sr1'
assert patch_mount.call_args_list[2][1]['dvd'] == '/dev/sr2'


def test_scvmm_detection_without_file(self):
# setup
conf.get_dvd_mount_point = Mock(return_value=self.tmp_dir)
conf.get_detect_scvmm_env = Mock(return_value=True)
scvmm_file = os.path.join(self.tmp_dir, scvmm.VMM_CONF_FILE_NAME)
if os.path.exists(scvmm_file):
os.remove(scvmm_file)

patch_start = mock.patch.object(scvmm.ScvmmHandler, 'start_scvmm_agent').start()

# execute
scvmm.ScvmmHandler(get_distro()).detect_scvmm_env()

# assert
patch_start.assert_not_called()


if __name__ == '__main__':
unittest.main()

11 comments on commit ab2e3c3

@emsi
Copy link

@emsi emsi commented on ab2e3c3 Jun 3, 2016

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

I'm not able to apply it cleanly against 2.1.3.
I'll try to "rebase" your patch and see if I can apply it.

# patch -p2 < ab2e3c3e76359f46be9fdb2821a868ad0a6fde4b.diff
patching file distro/default/daemon.py
patching file distro/default/osutil.py
Hunk #1 FAILED at 277.
Hunk #2 succeeded at 297 (offset -10 lines).
Hunk #3 succeeded at 310 (offset -10 lines).
1 out of 3 hunks FAILED -- saving rejects to file distro/default/osutil.py.rej
patching file distro/default/scvmm.py
patching file distro/test_scvmm.py

@emsi
Copy link

@emsi emsi commented on ab2e3c3 Jun 3, 2016

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

OK, here's what I did:
I used the diff against 2.1.3 that I obtained via this URL:
Azure/WALinuxAgent@b05e389...hglkrijger:bugfix/github-185
I applied this to the code of 2.1.3 shipped with ubuntu cloud image and started the provisioning.
Now it looks better yet still the provisioning loops:

http://screencast.com/t/qCDKXe9ub

and the syslog:
http://screencast.com/t/Vo9YxFPI

I see the scvmm agent is in fact installed in /opt/microsoft so the only missing thing is the restart which never happens. It might be due to systemd behaving differently than upstart or init.

p.s. i rebooted manually yet for whatever reason the scvmm agent is not doing its job. rather than that the waagent is looping again.

@hglkrijger
Copy link
Owner Author

@hglkrijger hglkrijger commented on ab2e3c3 Jun 6, 2016

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Thanks @emsi - could you please provide the scvmm.log from this failure case? (/var/log/microsoft/scvmmguestagent, iirc)

My understanding is that cloud-init does not support VMM provisioning, so that should probably be disabled.

@emsi
Copy link

@emsi emsi commented on ab2e3c3 Jun 7, 2016

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Sure:
http://screencast.com/t/EKRr1NmtQ

Now everything is crystal clear :) The path is missing trailing slash na glues to /mnt/cdrom/securelinuxosconfiguration.xml rather than /mnt/cdrom/secure/linuxosconfiguration.xml

I mean this: http://screencast.com/t/PiD9hUjvU

@emsi
Copy link

@emsi emsi commented on ab2e3c3 Jun 7, 2016

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

To not loose more time I added the missing slash to verify if that ultimately does the trick:

script_commands = ["/bin/bash", startup_script, "-p " + mount_point + "/"]

This time machine got provisioned to a degree. It rebooted immediately and the hostname, timezone, password, ssh keys etc. are setup now. Unfortunately there should be another reboot concluding the provisioning that never occurred. The waagentl.log is clear and says:

2016/06/07 09:33:36.079982 INFO Azure Linux Agent Version:2.1.4
2016/06/07 09:33:36.083224 INFO OS: ubuntu 16.04
2016/06/07 09:33:36.084971 INFO Python: 3.5.1
2016/06/07 09:33:36.086738 INFO Run daemon
2016/06/07 09:33:36.088327 INFO Detecting Microsoft System Center VMM Environment
2016/06/07 09:33:36.097273 WARNING could not load ATAPI driver
2016/06/07 09:33:36.122471 INFO Found VMM configuration at /mnt/cdrom/secure/linuxosconfiguration.xml
2016/06/07 09:33:36.125821 INFO Found VMM install script at /mnt/cdrom/secure/install
2016/06/07 09:33:36.127558 INFO Starting VMM Initialization Process
2016/06/07 09:33:36.129310 INFO Starting VMM install process with ['/bin/bash', '/mnt/cdrom/secure/install', '-p /mnt/cdrom/secure/']

Over and over again...

2016/06/07 09:43:01.804197 INFO Azure Linux Agent Version:2.1.4
2016/06/07 09:43:01.807432 INFO OS: ubuntu 16.04
2016/06/07 09:43:01.809159 INFO Python: 3.5.1
2016/06/07 09:43:01.810925 INFO Run daemon
2016/06/07 09:43:01.812495 INFO Detecting Microsoft System Center VMM Environment
2016/06/07 09:43:01.837871 WARNING could not load ATAPI driver
2016/06/07 09:43:01.851012 INFO Found VMM configuration at /mnt/cdrom/secure/linuxosconfiguration.xml
2016/06/07 09:43:01.854148 INFO Found VMM install script at /mnt/cdrom/secure/install
2016/06/07 09:43:01.855814 INFO Starting VMM Initialization Process
2016/06/07 09:43:01.857527 INFO Starting VMM install process with ['/bin/bash', '/mnt/cdrom/secure/install', '-p /mnt/cdrom/secure/']
2016/06/07 09:43:08.081813 INFO Azure Linux Agent Version:2.1.4
2016/06/07 09:43:08.089954 INFO OS: ubuntu 16.04
2016/06/07 09:43:08.096335 INFO Python: 3.5.1
2016/06/07 09:43:08.102538 INFO Run daemon
2016/06/07 09:43:08.108958 INFO Detecting Microsoft System Center VMM Environment
2016/06/07 09:43:08.122732 WARNING could not load ATAPI driver
2016/06/07 09:43:08.138302 INFO Found VMM configuration at /mnt/cdrom/secure/linuxosconfiguration.xml
2016/06/07 09:43:08.150898 INFO Found VMM install script at /mnt/cdrom/secure/install
2016/06/07 09:43:08.161531 INFO Starting VMM Initialization Process
2016/06/07 09:43:08.170272 INFO Starting VMM install process with ['/bin/bash', '/mnt/cdrom/secure/install', '-p /mnt/cdrom/secure/']
2016/06/07 09:43:14.346014 INFO Azure Linux Agent Version:2.1.4
2016/06/07 09:43:14.354012 INFO OS: ubuntu 16.04
2016/06/07 09:43:14.360333 INFO Python: 3.5.1
2016/06/07 09:43:14.366483 INFO Run daemon
2016/06/07 09:43:14.373127 INFO Detecting Microsoft System Center VMM Environment
2016/06/07 09:43:14.388111 WARNING could not load ATAPI driver
2016/06/07 09:43:14.403271 INFO Found VMM configuration at /mnt/cdrom/secure/linuxosconfiguration.xml
2016/06/07 09:43:14.415715 INFO Found VMM install script at /mnt/cdrom/secure/install
2016/06/07 09:43:14.426268 INFO Starting VMM Initialization Process
2016/06/07 09:43:14.434977 INFO Starting VMM install process with ['/bin/bash', '/mnt/cdrom/secure/install', '-p /mnt/cdrom/secure/']

while scvmmlog looks kile below:

*
* SC VMM Guest Agent
* Build number: 1.0.2-1075
* Process id: 32399
* Process started: 2016-06-07T07:29:00,284Z
*
* Log format: <date> <severity>     [<code module>:<line number>:<process id>:<thread id>] <message>
*
2016-06-07T07:29:00,285Z Info       [scx.vmmguestagent.main.main:88:32399:140261794568000] Initializing VMM Guest Agent
2016-06-07T07:29:00,285Z Info       [scx.vmmguestagent.src.fetcher.isofetcher:371:32402:140261794568000] Attempting to open Specialization file:"/mnt/cdrom/securelinuxosconfiguration.xml"
2016-06-07T07:29:00,285Z Error      [scx.vmmguestagent.src.fetcher.isofetcher:405:32402:140261794568000] Could not read specialization file
*
* SC VMM Guest Agent
* Build number: 1.0.2-1075
* Process id: 32485
* Process started: 2016-06-07T07:29:06,510Z
*
* Log format: <date> <severity>     [<code module>:<line number>:<process id>:<thread id>] <message>
*
2016-06-07T07:29:06,510Z Info       [scx.vmmguestagent.main.main:88:32485:139681822058304] Initializing VMM Guest Agent
2016-06-07T07:29:06,510Z Info       [scx.vmmguestagent.src.fetcher.isofetcher:371:32488:139681822058304] Attempting to open Specialization file:"/mnt/cdrom/secure/linuxosconfiguration.xml"
2016-06-07T07:29:06,511Z Info       [scx.vmmguestagent.src.fetcher.isofetcher:389:32488:139681822058304] File Len:2220
2016-06-07T07:29:06,511Z Info       [scx.vmmguestagent.src.fetcher.isofetcher:400:32488:139681822058304] Specialization file contents len:2217
2016-06-07T07:29:06,511Z Info       [scx.vmmguestagent.osspecializationreader:91:32488:139681822058304] Inside loadXML
2016-06-07T07:29:06,511Z Trace      [scx.vmmguestagent.osspecializationreader:101:32488:139681822058304] XML Root element successfully loaded in OSSpecializationReader::LoadXML
2016-06-07T07:29:06,511Z Trace      [scx.vmmguestagent.osspecializationreader:109:32488:139681822058304] Begin Loading OSSpecialization object from XML
2016-06-07T07:29:06,511Z Trace      [scx.vmmguestagent.osspecializationreader:156:32488:139681822058304] Schema Version read as: 1.00-0
2016-06-07T07:29:06,511Z Trace      [scx.vmmguestagent.osspecializationreader:150:32488:139681822058304] Agent Version read as: 1.0.2.1075
2016-06-07T07:29:06,511Z Trace      [scx.vmmguestagent.osspecializationreader:189:32488:139681822058304] Host Name read as: xenial-05
2016-06-07T07:29:06,511Z Trace      [scx.vmmguestagent.osspecializationreader:203:32488:139681822058304] Time Zone read as: 100
2016-06-07T07:29:06,511Z Trace      [scx.vmmguestagent.osspecializationreader:490:32488:139681822058304] User Name read as: root
2016-06-07T07:29:06,511Z Trace      [scx.vmmguestagent.osspecializationreader:496:32488:139681822058304] Password read.
2016-06-07T07:29:06,511Z Trace      [scx.vmmguestagent.osspecializationreader:502:32488:139681822058304] SSH Key read.
2016-06-07T07:29:06,512Z Trace      [scx.vmmguestagent.osspecializationreader:221:32488:139681822058304] Read of Root User complete.
2016-06-07T07:29:06,512Z Trace      [scx.vmmguestagent.osspecializationreader:163:32488:139681822058304] Read of OS Configuration complete.
2016-06-07T07:29:06,512Z Trace      [scx.vmmguestagent.osspecializationreader:113:32488:139681822058304] Completed Loading OSSpecialization object from XML
2016-06-07T07:29:06,512Z Info       [scx.vmmguestagent.osconfigurator.preconfigurator:46:32488:139681822058304] Executing pre-configuration script
2016-06-07T07:29:06,512Z Info       [scx.vmmguestagent.statusmanager.statusmessage:170:32488:139681822058304] Creating status dir
2016-06-07T07:29:06,512Z Info       [scx.vmmguestagent.statusmanager.statusmessage:191:32488:139681822058304] No status xml files. Adding a new one
2016-06-07T07:29:06,512Z Info       [scx.vmmguestagent.statusmanager.statusmessage:215:32488:139681822058304] Writing following to file:<OSConfigurationStatus SchemaVersion="1.1"/>
2016-06-07T07:29:06,512Z Trace      [scx.vmmguestagent.statusmanager.statusmessage:68:32488:139681822058304] Adding child element to root
2016-06-07T07:29:06,512Z Info       [scx.vmmguestagent.statusmanager.statusmessage:43:32488:139681822058304] Reading file:/opt/microsoft/scvmmguestagent/status/statusmessage.xml
2016-06-07T07:29:06,512Z Info       [scx.vmmguestagent.statusmanager.statusmessage:60:32488:139681822058304] File read:<OSConfigurationStatus SchemaVersion="1.1"/>
2016-06-07T07:29:06,512Z Info       [scx.vmmguestagent.statusmanager.statusmessage:215:32488:139681822058304] Writing following to file:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted>SpecializationStarted</SpecializationStarted></OSConfigurationStatus>
2016-06-07T07:29:06,512Z Info       [scx.vmmguestagent.statusmanager.statusmessage:43:32488:139681822058304] Reading file:/opt/microsoft/scvmmguestagent/status/statusmessage.xml
2016-06-07T07:29:06,512Z Info       [scx.vmmguestagent.statusmanager.statusmessage:60:32488:139681822058304] File read:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted>SpecializationStarted</SpecializationStarted></OSConfigurationStatus>
2016-06-07T07:29:06,512Z Info       [scx.vmmguestagent.statusmanager.statusmessage:101:32488:139681822058304] File read as:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted>SpecializationStarted</SpecializationStarted></OSConfigurationStatus>
2016-06-07T07:29:06,512Z Info       [scx.vmmguestagent.osconfigurator.preconfigurator:59:32488:139681822058304] Pre-Configuration was never run before
2016-06-07T07:29:06,512Z Info       [scx.vmmguestagent.osconfigurator.preconfigurator:67:32488:139681822058304] Shell Command:/opt/microsoft/scvmmguestagent/bin/cfgpre
2016-06-07T07:29:07,513Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:137:32488:139681822058304] Successfully configured Pre-Configurator
2016-06-07T07:29:07,513Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:163:32488:139681822058304] stdout as a result of running command:2016-06-07T 7:29:06Z?Info?cfgpre: Regenerating ssh host keys?Generating public/private dsa key pair.?Your identification has been saved in /etc/ssh/ssh_host_dsa_key.?Your public key has been saved in /etc/ssh/ssh_host_dsa_key.pub.?The key fingerprint is:?SHA256:WE8+eagooQR1Of58CdWGL7UiHQuT0sqfR2rn0mtKmXc root@ubuntu?The key's randomart image is:?+---[DSA 1024]----+?|  . .o . o       |?| . .+ = + +      |?|.  o + =.*..     |?| .  + oo*+oo     |?|  . .+.*S+* .    |?| . . .B+=. o     |?|  . ..=*o E      |?|     o..+.       |?|      .+..       |?+----[SHA256]-----+?Generating public/private rsa key pair.?Your identification has been saved in /etc/ssh/ssh_host_rsa_key.?Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub.?The key fingerprint is:?SHA256:nX6JhygSi6TWskVs85Ur3L6YgDQPF7nlSW5top6Oa9M root@ubuntu?The key's randomart image is:?+---[RSA 2048]----+?|                 |?|    .            |?|   o o           |?|  . B o .. .     |?| +.B.* =S o      |?|.oX.*o= .o o .   |?|.+o*o+.o. + +    |?|.o*Eo.=.   o     |?|.+++ o o.        |?+----[SHA256]-----+?2016-06-07T 7:29:06Z?Info?cfgpre: Creating dhclient enter hooks to disable resolv.conf overwrites in: /etc/dhcp3/dhclient-enter-hooks.d/nodnsupdate?2016-06-07T 7:29:06Z?Info?cfgpre: Disabling resolvconf updates to resolv.conf.? (* Message contained unprintable (?) characters *)
2016-06-07T07:29:07,513Z Error      [scx.vmmguestagent.osconfigurator.commandexecutor:164:32488:139681822058304] stderr as a result of running command:/opt/microsoft/scvmmguestagent/bin/cfgpre: line 32: /etc/dhcp3/dhclient-enter-hooks.d/nodnsupdate: No such file or directory?chmod: cannot access '/etc/dhcp3/dhclient-enter-hooks.d/nodnsupdate': No such file or directory? (* Message contained unprintable (?) characters *)
2016-06-07T07:29:07,513Z Info       [scx.vmmguestagent.osconfigurator.preconfigurator:74:32488:139681822058304] Ret:0
2016-06-07T07:29:07,513Z Info       [scx.vmmguestagent.osconfigurator.preconfigurator:78:32488:139681822058304] Preconfig completed successfully. Will reboot now
2016-06-07T07:29:07,513Z Trace      [scx.vmmguestagent.statusmanager.statusmessage:68:32488:139681822058304] Adding child element to root
2016-06-07T07:29:07,513Z Info       [scx.vmmguestagent.statusmanager.statusmessage:43:32488:139681822058304] Reading file:/opt/microsoft/scvmmguestagent/status/statusmessage.xml
2016-06-07T07:29:07,513Z Info       [scx.vmmguestagent.statusmanager.statusmessage:60:32488:139681822058304] File read:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted>SpecializationStarted</SpecializationStarted></OSConfigurationStatus>
2016-06-07T07:29:07,513Z Info       [scx.vmmguestagent.statusmanager.statusmessage:215:32488:139681822058304] Writing following to file:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><PreConfiguratorStatus>Successful</PreConfiguratorStatus></OSConfigurationStatus>
2016-06-07T07:29:08,514Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:137:32488:139681822058304] Successfully configured grub-editenv
2016-06-07T07:29:08,514Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:163:32488:139681822058304] stdout as a result of running command:
2016-06-07T07:29:08,514Z Error      [scx.vmmguestagent.osconfigurator.commandexecutor:164:32488:139681822058304] stderr as a result of running command:
2016-06-07T07:29:08,514Z Info       [scx.vmmguestagent.src.fetcher.isofetcher:546:32488:139681822058304] set recordfail=0 in /boot/grub/grubenv
*
* SC VMM Guest Agent
* Build number: 1.0.2-1075
* Process id: 1250
* Process started: 2016-06-07T07:29:33,795Z
*
* Log format: <date> <severity>     [<code module>:<line number>:<process id>:<thread id>] <message>
*
2016-06-07T07:29:33,795Z Info       [scx.vmmguestagent.main.main:88:1250:140676908296000] Initializing VMM Guest Agent
2016-06-07T07:29:33,796Z Info       [scx.vmmguestagent.src.fetcher.isofetcher:371:1253:140676908296000] Attempting to open Specialization file:"/mnt/cdrom/secure/linuxosconfiguration.xml"
2016-06-07T07:29:33,796Z Info       [scx.vmmguestagent.src.fetcher.isofetcher:389:1253:140676908296000] File Len:2220
2016-06-07T07:29:33,797Z Info       [scx.vmmguestagent.src.fetcher.isofetcher:400:1253:140676908296000] Specialization file contents len:2217
2016-06-07T07:29:33,797Z Info       [scx.vmmguestagent.osspecializationreader:91:1253:140676908296000] Inside loadXML
2016-06-07T07:29:33,797Z Trace      [scx.vmmguestagent.osspecializationreader:101:1253:140676908296000] XML Root element successfully loaded in OSSpecializationReader::LoadXML
2016-06-07T07:29:33,797Z Trace      [scx.vmmguestagent.osspecializationreader:109:1253:140676908296000] Begin Loading OSSpecialization object from XML
2016-06-07T07:29:33,797Z Trace      [scx.vmmguestagent.osspecializationreader:156:1253:140676908296000] Schema Version read as: 1.00-0
2016-06-07T07:29:33,797Z Trace      [scx.vmmguestagent.osspecializationreader:150:1253:140676908296000] Agent Version read as: 1.0.2.1075
2016-06-07T07:29:33,797Z Trace      [scx.vmmguestagent.osspecializationreader:189:1253:140676908296000] Host Name read as: xenial-05
2016-06-07T07:29:33,797Z Trace      [scx.vmmguestagent.osspecializationreader:203:1253:140676908296000] Time Zone read as: 100
2016-06-07T07:29:33,797Z Trace      [scx.vmmguestagent.osspecializationreader:490:1253:140676908296000] User Name read as: root
2016-06-07T07:29:33,797Z Trace      [scx.vmmguestagent.osspecializationreader:496:1253:140676908296000] Password read.
2016-06-07T07:29:33,797Z Trace      [scx.vmmguestagent.osspecializationreader:502:1253:140676908296000] SSH Key read.
2016-06-07T07:29:33,797Z Trace      [scx.vmmguestagent.osspecializationreader:221:1253:140676908296000] Read of Root User complete.
2016-06-07T07:29:33,797Z Trace      [scx.vmmguestagent.osspecializationreader:163:1253:140676908296000] Read of OS Configuration complete.
2016-06-07T07:29:33,797Z Trace      [scx.vmmguestagent.osspecializationreader:113:1253:140676908296000] Completed Loading OSSpecialization object from XML
2016-06-07T07:29:33,797Z Info       [scx.vmmguestagent.osconfigurator.preconfigurator:46:1253:140676908296000] Executing pre-configuration script
2016-06-07T07:29:33,797Z Info       [scx.vmmguestagent.statusmanager.statusmessage:179:1253:140676908296000] Status dir existed from before
2016-06-07T07:29:33,797Z Info       [scx.vmmguestagent.statusmanager.statusmessage:196:1253:140676908296000] Status XML file exists.
2016-06-07T07:29:33,797Z Trace      [scx.vmmguestagent.statusmanager.statusmessage:68:1253:140676908296000] Adding child element to root
2016-06-07T07:29:33,797Z Info       [scx.vmmguestagent.statusmanager.statusmessage:43:1253:140676908296000] Reading file:/opt/microsoft/scvmmguestagent/status/statusmessage.xml
2016-06-07T07:29:33,798Z Info       [scx.vmmguestagent.statusmanager.statusmessage:60:1253:140676908296000] File read:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><PreConfiguratorStatus>Successful</PreConfiguratorStatus></OSConfigurationStatus>
2016-06-07T07:29:33,798Z Info       [scx.vmmguestagent.statusmanager.statusmessage:215:1253:140676908296000] Writing following to file:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><PreConfiguratorStatus SchemaVersion="1.1">Successful</PreConfiguratorStatus><SpecializationStarted>SpecializationStarted</SpecializationStarted></OSConfigurationStatus>
2016-06-07T07:29:33,798Z Info       [scx.vmmguestagent.statusmanager.statusmessage:43:1253:140676908296000] Reading file:/opt/microsoft/scvmmguestagent/status/statusmessage.xml
2016-06-07T07:29:33,798Z Info       [scx.vmmguestagent.statusmanager.statusmessage:60:1253:140676908296000] File read:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><PreConfiguratorStatus SchemaVersion="1.1">Successful</PreConfiguratorStatus><SpecializationStarted>SpecializationStarted</SpecializationStarted></OSConfigurationStatus>
2016-06-07T07:29:33,798Z Info       [scx.vmmguestagent.statusmanager.statusmessage:101:1253:140676908296000] File read as:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><PreConfiguratorStatus SchemaVersion="1.1">Successful</PreConfiguratorStatus><SpecializationStarted>SpecializationStarted</SpecializationStarted></OSConfigurationStatus>
2016-06-07T07:29:33,798Z Info       [scx.vmmguestagent.osconfigurator.preconfigurator:107:1253:140676908296000] Preconfigurator was run previously sucessfully. Continuing with other configurations
2016-06-07T07:29:33,798Z Info       [scx.vmmguestagent.osconfigurator.timezoneconfigurator:94:1253:140676908296000] Executing time zone configuration script
2016-06-07T07:29:33,798Z Info       [scx.vmmguestagent.osconfigurator.timezoneconfigurator:104:1253:140676908296000] Shell command:/opt/microsoft/scvmmguestagent/bin/cfgtimezone tzid=100
2016-06-07T07:29:34,798Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:137:1253:140676908296000] Successfully configured TimeZone-Configurator
2016-06-07T07:29:34,798Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:163:1253:140676908296000] stdout as a result of running command:2016-06-07T 7:29:33Z?Info?cfgtimezone: All inputs validated??2016-06-07T 7:29:33Z?Info?cfgtimezone: Time zone successfully set to /usr/share/zoneinfo/Europe/Warsaw? (* Message contained unprintable (?) characters *)
2016-06-07T07:29:34,798Z Error      [scx.vmmguestagent.osconfigurator.commandexecutor:164:1253:140676908296000] stderr as a result of running command:
2016-06-07T07:29:34,798Z Info       [scx.vmmguestagent.osconfigurator.hostdomainconfigurator:48:1253:140676908296000] Executing host-domain configuration
2016-06-07T07:29:34,798Z Info       [scx.vmmguestagent.osconfigurator.hostdomainconfigurator:66:1253:140676908296000] It was not a * hostname
2016-06-07T07:29:35,799Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:137:1253:140676908296000] Successfully configured HostDomain-Configurator
2016-06-07T07:29:35,799Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:163:1253:140676908296000] stdout as a result of running command:2016-06-07T 7:29:34Z?Info?cfghostdomain: All inputs validated?2016-06-07T 7:29:34Z?Info?cfghostdomain: Removing old host entries from /etc/hosts???2016-06-07T 7:29:34Z?Info?cfghostdomain: Setting hostname to xenial-05?? (* Message contained unprintable (?) characters *)
2016-06-07T07:29:35,799Z Error      [scx.vmmguestagent.osconfigurator.commandexecutor:164:1253:140676908296000] stderr as a result of running command:
2016-06-07T07:29:35,799Z Info       [scx.vmmguestagent.osconfigurator.usersconfigurator:42:1253:140676908296000] Executing users-configuration script
2016-06-07T07:29:36,800Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:137:1253:140676908296000] Successfully configured User-Configurator
2016-06-07T07:29:36,800Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:163:1253:140676908296000] stdout as a result of running command:2016-06-07T 7:29:35Z?Info?cfgroot: All inputs validated?2016-06-07T 7:29:35Z?Info?cfgroot: Setting root user password?sent invalidate(passwd) request, exiting?2016-06-07T 7:29:35Z?Info?cfgroot: Writing ssh key to /root/.ssh/authorized_keys????? (* Message contained unprintable (?) characters *)
2016-06-07T07:29:36,800Z Error      [scx.vmmguestagent.osconfigurator.commandexecutor:164:1253:140676908296000] stderr as a result of running command:
2016-06-07T07:29:36,800Z Info       [scx.vmmguestagent.osconfigurator.networkconfigurator:40:1253:140676908296000] Executing network configuration
2016-06-07T07:29:36,800Z Info       [scx.vmmguestagent.osconfigurator.networkconfigurator:52:1253:140676908296000] Unable to find any network configurations in configuration file.
2016-06-07T07:29:36,800Z Info       [scx.vmmguestagent.osconfigurator.networkconfigurator:63:1253:140676908296000] Configuring unspecified network adapters as DHCP
2016-06-07T07:29:36,800Z Info       [scx.vmmguestagent.osconfigurator.networkconfigurator:70:1253:140676908296000] Shell Command:/opt/microsoft/scvmmguestagent/bin/cfgdynnetadapter
*
* SC VMM Guest Agent
* Build number: 1.0.2-1075
* Process id: 2141
* Process started: 2016-06-07T07:29:40,159Z
*
* Log format: <date> <severity>     [<code module>:<line number>:<process id>:<thread id>] <message>
*
2016-06-07T07:29:40,159Z Info       [scx.vmmguestagent.main.main:88:2141:140637708498752] Initializing VMM Guest Agent
2016-06-07T07:29:40,160Z Info       [scx.vmmguestagent.src.fetcher.isofetcher:371:2144:140637708498752] Attempting to open Specialization file:"/mnt/cdrom/secure/linuxosconfiguration.xml"
2016-06-07T07:29:40,160Z Info       [scx.vmmguestagent.src.fetcher.isofetcher:389:2144:140637708498752] File Len:2220
2016-06-07T07:29:40,160Z Info       [scx.vmmguestagent.src.fetcher.isofetcher:400:2144:140637708498752] Specialization file contents len:2217
2016-06-07T07:29:40,160Z Info       [scx.vmmguestagent.osspecializationreader:91:2144:140637708498752] Inside loadXML
2016-06-07T07:29:40,160Z Trace      [scx.vmmguestagent.osspecializationreader:101:2144:140637708498752] XML Root element successfully loaded in OSSpecializationReader::LoadXML
2016-06-07T07:29:40,160Z Trace      [scx.vmmguestagent.osspecializationreader:109:2144:140637708498752] Begin Loading OSSpecialization object from XML
2016-06-07T07:29:40,160Z Trace      [scx.vmmguestagent.osspecializationreader:156:2144:140637708498752] Schema Version read as: 1.00-0
2016-06-07T07:29:40,160Z Trace      [scx.vmmguestagent.osspecializationreader:150:2144:140637708498752] Agent Version read as: 1.0.2.1075
2016-06-07T07:29:40,160Z Trace      [scx.vmmguestagent.osspecializationreader:189:2144:140637708498752] Host Name read as: xenial-05
2016-06-07T07:29:40,160Z Trace      [scx.vmmguestagent.osspecializationreader:203:2144:140637708498752] Time Zone read as: 100
2016-06-07T07:29:40,160Z Trace      [scx.vmmguestagent.osspecializationreader:490:2144:140637708498752] User Name read as: root
2016-06-07T07:29:40,160Z Trace      [scx.vmmguestagent.osspecializationreader:496:2144:140637708498752] Password read.
2016-06-07T07:29:40,160Z Trace      [scx.vmmguestagent.osspecializationreader:502:2144:140637708498752] SSH Key read.
2016-06-07T07:29:40,160Z Trace      [scx.vmmguestagent.osspecializationreader:221:2144:140637708498752] Read of Root User complete.
2016-06-07T07:29:40,160Z Trace      [scx.vmmguestagent.osspecializationreader:163:2144:140637708498752] Read of OS Configuration complete.
2016-06-07T07:29:40,160Z Trace      [scx.vmmguestagent.osspecializationreader:113:2144:140637708498752] Completed Loading OSSpecialization object from XML
2016-06-07T07:29:40,160Z Info       [scx.vmmguestagent.osconfigurator.preconfigurator:46:2144:140637708498752] Executing pre-configuration script
2016-06-07T07:29:40,160Z Info       [scx.vmmguestagent.statusmanager.statusmessage:179:2144:140637708498752] Status dir existed from before
2016-06-07T07:29:40,160Z Info       [scx.vmmguestagent.statusmanager.statusmessage:196:2144:140637708498752] Status XML file exists.
2016-06-07T07:29:40,160Z Trace      [scx.vmmguestagent.statusmanager.statusmessage:68:2144:140637708498752] Adding child element to root
2016-06-07T07:29:40,160Z Info       [scx.vmmguestagent.statusmanager.statusmessage:43:2144:140637708498752] Reading file:/opt/microsoft/scvmmguestagent/status/statusmessage.xml
2016-06-07T07:29:40,160Z Info       [scx.vmmguestagent.statusmanager.statusmessage:60:2144:140637708498752] File read:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><PreConfiguratorStatus SchemaVersion="1.1">Successful</PreConfiguratorStatus><SpecializationStarted>SpecializationStarted</SpecializationStarted></OSConfigurationStatus>
2016-06-07T07:29:40,160Z Info       [scx.vmmguestagent.statusmanager.statusmessage:215:2144:140637708498752] Writing following to file:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><PreConfiguratorStatus SchemaVersion="1.1">Successful</PreConfiguratorStatus><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted>SpecializationStarted</SpecializationStarted></OSConfigurationStatus>
2016-06-07T07:29:40,160Z Info       [scx.vmmguestagent.statusmanager.statusmessage:43:2144:140637708498752] Reading file:/opt/microsoft/scvmmguestagent/status/statusmessage.xml
2016-06-07T07:29:40,161Z Info       [scx.vmmguestagent.statusmanager.statusmessage:60:2144:140637708498752] File read:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><PreConfiguratorStatus SchemaVersion="1.1">Successful</PreConfiguratorStatus><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted>SpecializationStarted</SpecializationStarted></OSConfigurationStatus>
2016-06-07T07:29:40,161Z Info       [scx.vmmguestagent.statusmanager.statusmessage:101:2144:140637708498752] File read as:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><PreConfiguratorStatus SchemaVersion="1.1">Successful</PreConfiguratorStatus><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted>SpecializationStarted</SpecializationStarted></OSConfigurationStatus>
2016-06-07T07:29:40,161Z Info       [scx.vmmguestagent.osconfigurator.preconfigurator:107:2144:140637708498752] Preconfigurator was run previously sucessfully. Continuing with other configurations
2016-06-07T07:29:40,161Z Info       [scx.vmmguestagent.osconfigurator.timezoneconfigurator:94:2144:140637708498752] Executing time zone configuration script
2016-06-07T07:29:40,161Z Info       [scx.vmmguestagent.osconfigurator.timezoneconfigurator:104:2144:140637708498752] Shell command:/opt/microsoft/scvmmguestagent/bin/cfgtimezone tzid=100
2016-06-07T07:29:41,161Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:137:2144:140637708498752] Successfully configured TimeZone-Configurator
2016-06-07T07:29:41,161Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:163:2144:140637708498752] stdout as a result of running command:2016-06-07T 7:29:40Z?Info?cfgtimezone: All inputs validated??2016-06-07T 7:29:40Z?Info?cfgtimezone: Time zone successfully set to /usr/share/zoneinfo/Europe/Warsaw? (* Message contained unprintable (?) characters *)
2016-06-07T07:29:41,161Z Error      [scx.vmmguestagent.osconfigurator.commandexecutor:164:2144:140637708498752] stderr as a result of running command:
2016-06-07T07:29:41,161Z Info       [scx.vmmguestagent.osconfigurator.hostdomainconfigurator:48:2144:140637708498752] Executing host-domain configuration
2016-06-07T07:29:41,161Z Info       [scx.vmmguestagent.osconfigurator.hostdomainconfigurator:66:2144:140637708498752] It was not a * hostname
2016-06-07T07:29:42,162Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:137:2144:140637708498752] Successfully configured HostDomain-Configurator
2016-06-07T07:29:42,162Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:163:2144:140637708498752] stdout as a result of running command:2016-06-07T 7:29:41Z?Info?cfghostdomain: All inputs validated?2016-06-07T 7:29:41Z?Info?cfghostdomain: Removing old host entries from /etc/hosts???2016-06-07T 7:29:41Z?Info?cfghostdomain: Setting hostname to xenial-05?? (* Message contained unprintable (?) characters *)
2016-06-07T07:29:42,162Z Error      [scx.vmmguestagent.osconfigurator.commandexecutor:164:2144:140637708498752] stderr as a result of running command:
2016-06-07T07:29:42,162Z Info       [scx.vmmguestagent.osconfigurator.usersconfigurator:42:2144:140637708498752] Executing users-configuration script
2016-06-07T07:29:43,162Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:137:2144:140637708498752] Successfully configured User-Configurator
2016-06-07T07:29:43,162Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:163:2144:140637708498752] stdout as a result of running command:2016-06-07T 7:29:42Z?Info?cfgroot: All inputs validated?2016-06-07T 7:29:42Z?Info?cfgroot: Setting root user password?sent invalidate(passwd) request, exiting?2016-06-07T 7:29:42Z?Info?cfgroot: Writing ssh key to /root/.ssh/authorized_keys??? (* Message contained unprintable (?) characters *)
2016-06-07T07:29:43,162Z Error      [scx.vmmguestagent.osconfigurator.commandexecutor:164:2144:140637708498752] stderr as a result of running command:
2016-06-07T07:29:43,162Z Info       [scx.vmmguestagent.osconfigurator.networkconfigurator:40:2144:140637708498752] Executing network configuration
2016-06-07T07:29:43,162Z Info       [scx.vmmguestagent.osconfigurator.networkconfigurator:52:2144:140637708498752] Unable to find any network configurations in configuration file.
2016-06-07T07:29:43,162Z Info       [scx.vmmguestagent.osconfigurator.networkconfigurator:63:2144:140637708498752] Configuring unspecified network adapters as DHCP
2016-06-07T07:29:43,162Z Info       [scx.vmmguestagent.osconfigurator.networkconfigurator:70:2144:140637708498752] Shell Command:/opt/microsoft/scvmmguestagent/bin/cfgdynnetadapter
2016-06-07T07:29:44,163Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:137:2144:140637708498752] Successfully configured Network-Configurator
2016-06-07T07:29:44,163Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:163:2144:140637708498752] stdout as a result of running command:eth0? (* Message contained unprintable (?) characters *)
2016-06-07T07:29:44,163Z Error      [scx.vmmguestagent.osconfigurator.commandexecutor:164:2144:140637708498752] stderr as a result of running command:
2016-06-07T07:29:44,163Z Info       [scx.vmmguestagent.osconfigurator.executevisitor:97:2144:140637708498752] Unable to find any run-once commands in configuration file.
2016-06-07T07:29:44,163Z Info       [scx.vmmguestagent.osconfigurator.postconfigurator:101:2144:140637708498752] Executing post-configuration script
2016-06-07T07:29:44,163Z Info       [scx.vmmguestagent.osconfigurator.postconfigurator:112:2144:140637708498752] Shell Command:/opt/microsoft/scvmmguestagent/bin/cfgpost

Then it repeats all over again the set of messages witch each set ending with: Command:/opt/microsoft/scvmmguestagent/bin/cfgpost and the mesage grows in size incrementaly

*
* SC VMM Guest Agent
* Build number: 1.0.2-1075
* Process id: 24189
* Process started: 2016-06-07T07:36:12,440Z
*
* Log format: <date> <severity>     [<code module>:<line number>:<process id>:<thread id>] <message>
*
2016-06-07T07:36:12,440Z Info       [scx.vmmguestagent.main.main:88:24189:139916557260608] Initializing VMM Guest Agent
2016-06-07T07:36:12,440Z Info       [scx.vmmguestagent.src.fetcher.isofetcher:371:24192:139916557260608] Attempting to open Specialization file:"/mnt/cdrom/secure/linuxosconfiguration.xml"
2016-06-07T07:36:12,441Z Info       [scx.vmmguestagent.src.fetcher.isofetcher:389:24192:139916557260608] File Len:2220
2016-06-07T07:36:12,441Z Info       [scx.vmmguestagent.src.fetcher.isofetcher:400:24192:139916557260608] Specialization file contents len:2217
2016-06-07T07:36:12,441Z Info       [scx.vmmguestagent.osspecializationreader:91:24192:139916557260608] Inside loadXML
2016-06-07T07:36:12,441Z Trace      [scx.vmmguestagent.osspecializationreader:101:24192:139916557260608] XML Root element successfully loaded in OSSpecializationReader::LoadXML
2016-06-07T07:36:12,441Z Trace      [scx.vmmguestagent.osspecializationreader:109:24192:139916557260608] Begin Loading OSSpecialization object from XML
2016-06-07T07:36:12,441Z Trace      [scx.vmmguestagent.osspecializationreader:156:24192:139916557260608] Schema Version read as: 1.00-0
2016-06-07T07:36:12,441Z Trace      [scx.vmmguestagent.osspecializationreader:150:24192:139916557260608] Agent Version read as: 1.0.2.1075
2016-06-07T07:36:12,441Z Trace      [scx.vmmguestagent.osspecializationreader:189:24192:139916557260608] Host Name read as: xenial-05
2016-06-07T07:36:12,441Z Trace      [scx.vmmguestagent.osspecializationreader:203:24192:139916557260608] Time Zone read as: 100
2016-06-07T07:36:12,441Z Trace      [scx.vmmguestagent.osspecializationreader:490:24192:139916557260608] User Name read as: root
2016-06-07T07:36:12,441Z Trace      [scx.vmmguestagent.osspecializationreader:496:24192:139916557260608] Password read.
2016-06-07T07:36:12,441Z Trace      [scx.vmmguestagent.osspecializationreader:502:24192:139916557260608] SSH Key read.
2016-06-07T07:36:12,441Z Trace      [scx.vmmguestagent.osspecializationreader:221:24192:139916557260608] Read of Root User complete.
2016-06-07T07:36:12,441Z Trace      [scx.vmmguestagent.osspecializationreader:163:24192:139916557260608] Read of OS Configuration complete.
2016-06-07T07:36:12,441Z Trace      [scx.vmmguestagent.osspecializationreader:113:24192:139916557260608] Completed Loading OSSpecialization object from XML
2016-06-07T07:36:12,441Z Info       [scx.vmmguestagent.osconfigurator.preconfigurator:46:24192:139916557260608] Executing pre-configuration script
2016-06-07T07:36:12,441Z Info       [scx.vmmguestagent.statusmanager.statusmessage:179:24192:139916557260608] Status dir existed from before
2016-06-07T07:36:12,441Z Info       [scx.vmmguestagent.statusmanager.statusmessage:196:24192:139916557260608] Status XML file exists.
2016-06-07T07:36:12,441Z Trace      [scx.vmmguestagent.statusmanager.statusmessage:68:24192:139916557260608] Adding child element to root
2016-06-07T07:36:12,441Z Info       [scx.vmmguestagent.statusmanager.statusmessage:43:24192:139916557260608] Reading file:/opt/microsoft/scvmmguestagent/status/statusmessage.xml
2016-06-07T07:36:12,441Z Info       [scx.vmmguestagent.statusmanager.statusmessage:60:24192:139916557260608] File read:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><PreConfiguratorStatus SchemaVersion="1.1">Successful</PreConfiguratorStatus><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted>SpecializationStarted</SpecializationStarted></OSConfigurationStatus>
2016-06-07T07:36:12,445Z Info       [scx.vmmguestagent.statusmanager.statusmessage:215:24192:139916557260608] Writing following to file:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><PreConfiguratorStatus SchemaVersion="1.1">Successful</PreConfiguratorStatus><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted>SpecializationStarted</SpecializationStarted></OSConfigurationStatus>
2016-06-07T07:36:12,445Z Info       [scx.vmmguestagent.statusmanager.statusmessage:43:24192:139916557260608] Reading file:/opt/microsoft/scvmmguestagent/status/statusmessage.xml
2016-06-07T07:36:12,445Z Info       [scx.vmmguestagent.statusmanager.statusmessage:60:24192:139916557260608] File read:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><PreConfiguratorStatus SchemaVersion="1.1">Successful</PreConfiguratorStatus><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted>SpecializationStarted</SpecializationStarted></OSConfigurationStatus>
2016-06-07T07:36:12,445Z Info       [scx.vmmguestagent.statusmanager.statusmessage:101:24192:139916557260608] File read as:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><PreConfiguratorStatus SchemaVersion="1.1">Successful</PreConfiguratorStatus><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted>SpecializationStarted</SpecializationStarted></OSConfigurationStatus>
2016-06-07T07:36:12,449Z Info       [scx.vmmguestagent.osconfigurator.preconfigurator:107:24192:139916557260608] Preconfigurator was run previously sucessfully. Continuing with other configurations
2016-06-07T07:36:12,449Z Info       [scx.vmmguestagent.osconfigurator.timezoneconfigurator:94:24192:139916557260608] Executing time zone configuration script
2016-06-07T07:36:12,449Z Info       [scx.vmmguestagent.osconfigurator.timezoneconfigurator:104:24192:139916557260608] Shell command:/opt/microsoft/scvmmguestagent/bin/cfgtimezone tzid=100
2016-06-07T07:36:13,449Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:137:24192:139916557260608] Successfully configured TimeZone-Configurator
2016-06-07T07:36:13,450Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:163:24192:139916557260608] stdout as a result of running command:2016-06-07T 7:36:12Z?Info?cfgtimezone: All inputs validated??2016-06-07T 7:36:12Z?Info?cfgtimezone: Time zone successfully set to /usr/share/zoneinfo/Europe/Warsaw? (* Message contained unprintable (?) characters *)
2016-06-07T07:36:13,450Z Error      [scx.vmmguestagent.osconfigurator.commandexecutor:164:24192:139916557260608] stderr as a result of running command:
2016-06-07T07:36:13,450Z Info       [scx.vmmguestagent.osconfigurator.hostdomainconfigurator:48:24192:139916557260608] Executing host-domain configuration
2016-06-07T07:36:13,450Z Info       [scx.vmmguestagent.osconfigurator.hostdomainconfigurator:66:24192:139916557260608] It was not a * hostname
2016-06-07T07:36:14,450Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:137:24192:139916557260608] Successfully configured HostDomain-Configurator
2016-06-07T07:36:14,450Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:163:24192:139916557260608] stdout as a result of running command:2016-06-07T 7:36:13Z?Info?cfghostdomain: All inputs validated?2016-06-07T 7:36:13Z?Info?cfghostdomain: Removing old host entries from /etc/hosts???2016-06-07T 7:36:13Z?Info?cfghostdomain: Setting hostname to xenial-05?? (* Message contained unprintable (?) characters *)
2016-06-07T07:36:14,450Z Error      [scx.vmmguestagent.osconfigurator.commandexecutor:164:24192:139916557260608] stderr as a result of running command:
2016-06-07T07:36:14,450Z Info       [scx.vmmguestagent.osconfigurator.usersconfigurator:42:24192:139916557260608] Executing users-configuration script
2016-06-07T07:36:15,451Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:137:24192:139916557260608] Successfully configured User-Configurator
2016-06-07T07:36:15,451Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:163:24192:139916557260608] stdout as a result of running command:2016-06-07T 7:36:14Z?Info?cfgroot: All inputs validated?2016-06-07T 7:36:14Z?Info?cfgroot: Setting root user password?sent invalidate(passwd) request, exiting?2016-06-07T 7:36:14Z?Info?cfgroot: Writing ssh key to /root/.ssh/authorized_keys??? (* Message contained unprintable (?) characters *)
2016-06-07T07:36:15,451Z Error      [scx.vmmguestagent.osconfigurator.commandexecutor:164:24192:139916557260608] stderr as a result of running command:
2016-06-07T07:36:15,451Z Info       [scx.vmmguestagent.osconfigurator.networkconfigurator:40:24192:139916557260608] Executing network configuration
2016-06-07T07:36:15,451Z Info       [scx.vmmguestagent.osconfigurator.networkconfigurator:52:24192:139916557260608] Unable to find any network configurations in configuration file.
2016-06-07T07:36:15,451Z Info       [scx.vmmguestagent.osconfigurator.networkconfigurator:63:24192:139916557260608] Configuring unspecified network adapters as DHCP
2016-06-07T07:36:15,451Z Info       [scx.vmmguestagent.osconfigurator.networkconfigurator:70:24192:139916557260608] Shell Command:/opt/microsoft/scvmmguestagent/bin/cfgdynnetadapter
2016-06-07T07:36:16,452Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:137:24192:139916557260608] Successfully configured Network-Configurator
2016-06-07T07:36:16,452Z Info       [scx.vmmguestagent.osconfigurator.commandexecutor:163:24192:139916557260608] stdout as a result of running command:eth0? (* Message contained unprintable (?) characters *)
2016-06-07T07:36:16,452Z Error      [scx.vmmguestagent.osconfigurator.commandexecutor:164:24192:139916557260608] stderr as a result of running command:
2016-06-07T07:36:16,452Z Info       [scx.vmmguestagent.osconfigurator.executevisitor:97:24192:139916557260608] Unable to find any run-once commands in configuration file.
2016-06-07T07:36:16,452Z Info       [scx.vmmguestagent.osconfigurator.postconfigurator:101:24192:139916557260608] Executing post-configuration script
2016-06-07T07:36:16,452Z Info       [scx.vmmguestagent.osconfigurator.postconfigurator:112:24192:139916557260608] Shell Command:/opt/microsoft/scvmmguestagent/bin/cfgpost

I mean it loops with Writing following to file:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted Schem.... and Reading file:/opt/microsoft/scvmmguestagent/status/statusmessage.xml then File read:<OSConfigurationStatus SchemaVersion="1.1"><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><PreConfiguratorStatus SchemaVersion="1.1">Successful</PreConfiguratorStatus><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">SpecializationStarted</SpecializationStarted><SpecializationStarted SchemaVersion="1.1">... and it gets longer and longer again.

So there's another loop. I guess it's probably due to waalinuxagent not turning itself off after the first reboot when the scvmmagent already took its job.

@emsi
Copy link

@emsi emsi commented on ab2e3c3 Jun 13, 2016

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

@hglkrijger: I think that azure agent should step back once it installed scvmmagent. Do you have any clue?

@hglkrijger
Copy link
Owner Author

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

@emsi, the current version of scvmm install does not support systemd, so waagent is not stopped correctly and goes into this loop. I have put an updated version of this script with a systemd fix here. If you are able to verify this works as expected, that would be very helpful.

@emsi
Copy link

@emsi emsi commented on ab2e3c3 Jun 14, 2016

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

@hglkrijger: Still the same - loops as before. The installer does the reboot yet after that it still loops.
I patched the installer on my vmm server with following patch and I checked that it ended in the Linux machine being provisioned:

+
+   #SystemD
+   if [ -x /bin/systemctl  -o -x /usr/bin/systemctl ]
+   then
+       if [ `systemctl is-enabled waagent 2>/dev/null|grep enabled|wc -l` -eq 1
+       then
+               systemctl disable waagent
+               systemctl stop waagent
+       fi
+   fi
+

I checked if the waagent.service is started and systemd says it's not:

# systemctl stop waagent
Failed to stop waagent.service: Unit waagent.service not loaded.

However what I found is that there is no such service. What is installed is walinuxagent:

# systemctl list-units| grep agent
  walinuxagent.service                                                                                                      loaded active running   Azure Linux Agent
# dpkg -l walinuxagent
Desired=Unknown/Install/Remove/Purge/Hold
| Status=Not/Inst/Conf-files/Unpacked/halF-conf/Half-inst/trig-aWait/Trig-pend
|/ Err?=(none)/Reinst-required (Status,Err: uppercase=bad)
||/ Name           Version      Architecture Description
+++-==============-============-============-=================================
ii  walinuxagent   2.1.3-0ubunt amd64        Windows Azure Linux Agent

BTW: does your patch address the missing slash I mentioned in my previous comment?
script_commands = ["/bin/bash", startup_script, "-p " + mount_point + "/"]

p.s. manually shutting down the machine concluded the process but I had to stop and disable walinuxagent prior to that. vmm says it's provisioned now.

@hglkrijger
Copy link
Owner Author

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

thanks @emsi, I will update the service name.

yes, the missing slash is included.

@emsi
Copy link

@emsi emsi commented on ab2e3c3 Jun 15, 2016

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

@hglkrijger: Thank you very much.
Unfortunately it looks like scvmmagent also needs update for systemd. I had to manually reboot the machine to finalize the provisioning process. Do you know if such update is possible?

@hglkrijger
Copy link
Owner Author

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Please sign in to comment.