Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

cloud-init fails to set user password on Windows Azure #2388

Closed
ubuntu-server-builder opened this issue May 10, 2023 · 5 comments
Closed

cloud-init fails to set user password on Windows Azure #2388

ubuntu-server-builder opened this issue May 10, 2023 · 5 comments
Labels
launchpad Migrated from Launchpad priority Fix soon

Comments

@ubuntu-server-builder
Copy link
Collaborator

This bug was originally filed in Launchpad as LP: #1212723

Launchpad details 
affected_projects = ['cloud-init (Ubuntu)']
assignee = smoser
assignee_name = Scott Moser
date_closed = 2013-11-19T21:57:15.411078+00:00
date_created = 2013-08-15T15:10:16.959989+00:00
date_fix_committed = 2013-08-15T17:39:20.032026+00:00
date_fix_released = 2013-11-19T21:57:15.411078+00:00
id = 1212723
importance = high
is_complete = True
lp_url = https://bugs.launchpad.net/cloud-init/+bug/1212723
milestone = None
owner = smoser
owner_name = Scott Moser
private = False
status = fix_released
submitter = darkmuggle-deactivatedaccount
submitter_name = Ben Howard
tags = ['amd64', 'apport-bug', 'cloud-images', 'saucy']
duplicates = [1212471]

Launchpad user Ben Howard(darkmuggle-deactivatedaccount) wrote on 2013-08-15T15:10:16.959989+00:00

Cloud-init provisioning on Windows Azure is failing to set the userpassword.

snippet from /var/lib/waagent/ovf-env.xml:
wa:ProvisioningSectionwa:Version1.0</wa:Version>LinuxProvisioningConfigurationutl-0815-s14autlemmingSuperAwesomePasswordfalse</wa:ProvisioningSection>

$ sudo grep utlemming /etc/shadow
utlemming:!:15932:0:99999:7:::

ProblemType: Bug
DistroRelease: Ubuntu 13.10
Package: cloud-init 0.7.3~bzr851-0ubuntu1
ProcVersionSignature: Ubuntu 3.11.0-1.4-generic 3.11.0-rc4
Uname: Linux 3.11.0-1-generic x86_64
ApportVersion: 2.12-0ubuntu3
Architecture: amd64
Date: Thu Aug 15 14:56:45 2013
MarkForUpload: True
PackageArchitecture: all
SourcePackage: cloud-init
UpgradeStatus: No upgrade log present (probably fresh install)
@ubuntu-server-builder ubuntu-server-builder added launchpad Migrated from Launchpad priority Fix soon labels May 10, 2023
@ubuntu-server-builder
Copy link
Collaborator Author

Launchpad user Ben Howard(darkmuggle-deactivatedaccount) wrote on 2013-08-15T15:10:16.959989+00:00

Launchpad attachments: Dependencies.txt,ProcEnviron.txt

@ubuntu-server-builder
Copy link
Collaborator Author

Launchpad user Ben Howard(darkmuggle-deactivatedaccount) wrote on 2013-08-15T15:10:45.939925+00:00

2013-08-15 14:13:22,488 - util.py[DEBUG]: Cloud-init v. 0.7.3 running 'init-local' at Thu, 15 Aug 2013 14:13:22 +0000. Up 24.37 seconds.
2013-08-15 14:13:22,494 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [420] 0 bytes
2013-08-15 14:13:22,498 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 101:4
2013-08-15 14:13:22,498 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance/boot-finished
2013-08-15 14:13:22,499 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
2013-08-15 14:13:22,499 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/data/no-net
2013-08-15 14:13:22,503 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
2013-08-15 14:13:22,504 - importer.py[DEBUG]: Looking for modules ['ubuntu', 'cloudinit.distros.ubuntu'] that have attributes ['Distro']
2013-08-15 14:13:22,510 - importer.py[DEBUG]: Found ubuntu with attributes ['Distro'] in ['cloudinit.distros.ubuntu']
2013-08-15 14:13:22,510 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.ubuntu.Distro'>
2013-08-15 14:13:22,510 - init.py[DEBUG]: Looking for for data source in: ['NoCloud', 'ConfigDrive', 'Azure', 'AltCloud', 'OVF', 'MAAS', 'Ec2', 'CloudStack', 'None'], via packages ['', 'cloudinit.sources'] that matches dependencies ['FILESYSTEM']
2013-08-15 14:13:22,510 - importer.py[DEBUG]: Looking for modules ['DataSourceNoCloud', 'cloudinit.sources.DataSourceNoCloud'] that have attributes ['get_datasource_list']
2013-08-15 14:13:22,530 - importer.py[DEBUG]: Found DataSourceNoCloud with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceNoCloud']
2013-08-15 14:13:22,530 - importer.py[DEBUG]: Looking for modules ['DataSourceConfigDrive', 'cloudinit.sources.DataSourceConfigDrive'] that have attributes ['get_datasource_list']
2013-08-15 14:13:22,532 - importer.py[DEBUG]: Found DataSourceConfigDrive with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceConfigDrive']
2013-08-15 14:13:22,533 - importer.py[DEBUG]: Looking for modules ['DataSourceAzure', 'cloudinit.sources.DataSourceAzure'] that have attributes ['get_datasource_list']
2013-08-15 14:13:22,544 - importer.py[DEBUG]: Found DataSourceAzure with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceAzure']
2013-08-15 14:13:22,544 - importer.py[DEBUG]: Looking for modules ['DataSourceAltCloud', 'cloudinit.sources.DataSourceAltCloud'] that have attributes ['get_datasource_list']
2013-08-15 14:13:22,546 - importer.py[DEBUG]: Found DataSourceAltCloud with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceAltCloud']
2013-08-15 14:13:22,546 - importer.py[DEBUG]: Looking for modules ['DataSourceOVF', 'cloudinit.sources.DataSourceOVF'] that have attributes ['get_datasource_list']
2013-08-15 14:13:22,547 - importer.py[DEBUG]: Found DataSourceOVF with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceOVF']
2013-08-15 14:13:22,547 - importer.py[DEBUG]: Looking for modules ['DataSourceMAAS', 'cloudinit.sources.DataSourceMAAS'] that have attributes ['get_datasource_list']
2013-08-15 14:13:22,552 - importer.py[DEBUG]: Found DataSourceMAAS with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceMAAS']
2013-08-15 14:13:22,552 - importer.py[DEBUG]: Looking for modules ['DataSourceEc2', 'cloudinit.sources.DataSourceEc2'] that have attributes ['get_datasource_list']
2013-08-15 14:13:23,354 - importer.py[DEBUG]: Found DataSourceEc2 with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceEc2']
2013-08-15 14:13:23,354 - importer.py[DEBUG]: Looking for modules ['DataSourceCloudStack', 'cloudinit.sources.DataSourceCloudStack'] that have attributes ['get_datasource_list']
2013-08-15 14:13:23,355 - importer.py[DEBUG]: Found DataSourceCloudStack with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceCloudStack']
2013-08-15 14:13:23,356 - importer.py[DEBUG]: Looking for modules ['DataSourceNone', 'cloudinit.sources.DataSourceNone'] that have attributes ['get_datasource_list']
2013-08-15 14:13:23,356 - importer.py[DEBUG]: Found DataSourceNone with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceNone']
2013-08-15 14:13:23,356 - init.py[DEBUG]: Searching for data source in: ['DataSourceNoCloud', 'DataSourceConfigDrive', 'DataSourceOVF']
2013-08-15 14:13:23,357 - init.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceNoCloud.DataSourceNoCloud'>
2013-08-15 14:13:23,357 - util.py[DEBUG]: Reading from /proc/cmdline (quiet=False)
2013-08-15 14:13:23,357 - util.py[DEBUG]: Read 151 bytes from /proc/cmdline
2013-08-15 14:13:23,357 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud/meta-data (quiet=False)
2013-08-15 14:13:23,357 - util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/sr0'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:23,370 - util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/sr1'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:23,374 - util.py[DEBUG]: Running command ['blkid', '-tTYPE=vfat', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:23,391 - util.py[DEBUG]: Running command ['blkid', '-tTYPE=iso9660', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:23,395 - util.py[DEBUG]: Running command ['blkid', '-tLABEL=cidata', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:23,400 - init.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceConfigDrive.DataSourceConfigDrive'>
2013-08-15 14:13:23,401 - util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/sr0'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:23,404 - util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/sr1'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:23,408 - util.py[DEBUG]: Running command ['blkid', '-tTYPE=vfat', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:23,412 - util.py[DEBUG]: Running command ['blkid', '-tTYPE=iso9660', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:23,416 - util.py[DEBUG]: Running command ['blkid', '-tLABEL=config-2', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:23,420 - init.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceOVF.DataSourceOVF'>
2013-08-15 14:13:23,421 - util.py[DEBUG]: Reading from /proc/mounts (quiet=False)
2013-08-15 14:13:23,421 - util.py[DEBUG]: Read 860 bytes from /proc/mounts
2013-08-15 14:13:23,421 - util.py[DEBUG]: Fetched {'none': {'mountpoint': '/run/user', 'opts': 'rw,nosuid,nodev,noexec,relatime,size=102400k,mode=755', 'fstype': 'tmpfs'}, 'devpts': {'mountpoint': '/dev/pts', 'opts': 'rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000', 'fstype': 'devpts'}, '/dev/disk/by-uuid/4ae33cd0-c2df-4cf2-9b3e-89f52fda8e16': {'mountpoint': '/', 'opts': 'rw,relatime,discard,data=ordered', 'fstype': 'ext4'}, 'sysfs': {'mountpoint': '/sys', 'opts': 'rw,nosuid,nodev,noexec,relatime', 'fstype': 'sysfs'}, 'udev': {'mountpoint': '/dev', 'opts': 'rw,relatime,size=847192k,nr_inodes=211798,mode=755', 'fstype': 'devtmpfs'}, 'tmpfs': {'mountpoint': '/run', 'opts': 'rw,nosuid,noexec,relatime,size=171896k,mode=755', 'fstype': 'tmpfs'}, 'proc': {'mountpoint': '/proc', 'opts': 'rw,nosuid,nodev,noexec,relatime', 'fstype': 'proc'}, 'rootfs': {'mountpoint': '/', 'opts': 'rw', 'fstype': 'rootfs'}} mounts from /proc/mounts
2013-08-15 14:13:23,422 - util.py[DEBUG]: Peeking at /dev/sr0 (max_bytes=512)
2013-08-15 14:13:23,428 - util.py[DEBUG]: Reading from /proc/mounts (quiet=False)
2013-08-15 14:13:23,428 - util.py[DEBUG]: Read 860 bytes from /proc/mounts
2013-08-15 14:13:23,428 - util.py[DEBUG]: Fetched {'none': {'mountpoint': '/run/user', 'opts': 'rw,nosuid,nodev,noexec,relatime,size=102400k,mode=755', 'fstype': 'tmpfs'}, 'devpts': {'mountpoint': '/dev/pts', 'opts': 'rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000', 'fstype': 'devpts'}, '/dev/disk/by-uuid/4ae33cd0-c2df-4cf2-9b3e-89f52fda8e16': {'mountpoint': '/', 'opts': 'rw,relatime,discard,data=ordered', 'fstype': 'ext4'}, 'sysfs': {'mountpoint': '/sys', 'opts': 'rw,nosuid,nodev,noexec,relatime', 'fstype': 'sysfs'}, 'udev': {'mountpoint': '/dev', 'opts': 'rw,relatime,size=847192k,nr_inodes=211798,mode=755', 'fstype': 'devtmpfs'}, 'tmpfs': {'mountpoint': '/run', 'opts': 'rw,nosuid,noexec,relatime,size=171896k,mode=755', 'fstype': 'tmpfs'}, 'proc': {'mountpoint': '/proc', 'opts': 'rw,nosuid,nodev,noexec,relatime', 'fstype': 'proc'}, 'rootfs': {'mountpoint': '/', 'opts': 'rw', 'fstype': 'rootfs'}} mounts from /proc/mounts
2013-08-15 14:13:23,429 - util.py[DEBUG]: Running command ['mount', '-o', 'ro,sync', '-t', 'iso9660', '/dev/sr0', '/tmp/tmpx1Xq8s'] with allowed return codes [0] (shell=False, capture=True)
2013-08-15 14:13:23,570 - util.py[DEBUG]: Recursively deleting /tmp/tmpx1Xq8s
2013-08-15 14:13:23,571 - DataSourceOVF.py[DEBUG]: /dev/sr0 not mountable as iso9660
2013-08-15 14:13:23,571 - cloud-init[DEBUG]: No local datasource found
2013-08-15 14:13:23,969 - util.py[DEBUG]: Cloud-init v. 0.7.3 running 'init' at Thu, 15 Aug 2013 14:13:23 +0000. Up 25.97 seconds.
2013-08-15 14:13:23,973 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [420] 0 bytes
2013-08-15 14:13:23,973 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 101:4
2013-08-15 14:13:23,973 - util.py[DEBUG]: Running command ['ifconfig', '-a'] with allowed return codes [0] (shell=False, capture=True)
2013-08-15 14:13:23,979 - util.py[DEBUG]: Running command ['route', '-n'] with allowed return codes [0] (shell=False, capture=True)
2013-08-15 14:13:24,050 - cloud-init[DEBUG]: Checking to see if files that we need already exist from a previous run that would allow us to stop early.
2013-08-15 14:13:24,050 - util.py[DEBUG]: Reading from /var/lib/cloud/data/no-net (quiet=False)
2013-08-15 14:13:24,051 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
2013-08-15 14:13:24,051 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
2013-08-15 14:13:24,053 - importer.py[DEBUG]: Looking for modules ['ubuntu', 'cloudinit.distros.ubuntu'] that have attributes ['Distro']
2013-08-15 14:13:24,054 - importer.py[DEBUG]: Found ubuntu with attributes ['Distro'] in ['cloudinit.distros.ubuntu']
2013-08-15 14:13:24,054 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.ubuntu.Distro'>
2013-08-15 14:13:24,054 - init.py[DEBUG]: Looking for for data source in: ['NoCloud', 'ConfigDrive', 'Azure', 'AltCloud', 'OVF', 'MAAS', 'Ec2', 'CloudStack', 'None'], via packages ['', 'cloudinit.sources'] that matches dependencies ['FILESYSTEM', 'NETWORK']
2013-08-15 14:13:24,054 - importer.py[DEBUG]: Looking for modules ['DataSourceNoCloud', 'cloudinit.sources.DataSourceNoCloud'] that have attributes ['get_datasource_list']
2013-08-15 14:13:24,055 - importer.py[DEBUG]: Found DataSourceNoCloud with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceNoCloud']
2013-08-15 14:13:24,055 - importer.py[DEBUG]: Looking for modules ['DataSourceConfigDrive', 'cloudinit.sources.DataSourceConfigDrive'] that have attributes ['get_datasource_list']
2013-08-15 14:13:24,056 - importer.py[DEBUG]: Found DataSourceConfigDrive with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceConfigDrive']
2013-08-15 14:13:24,056 - importer.py[DEBUG]: Looking for modules ['DataSourceAzure', 'cloudinit.sources.DataSourceAzure'] that have attributes ['get_datasource_list']
2013-08-15 14:13:24,060 - importer.py[DEBUG]: Found DataSourceAzure with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceAzure']
2013-08-15 14:13:24,060 - importer.py[DEBUG]: Looking for modules ['DataSourceAltCloud', 'cloudinit.sources.DataSourceAltCloud'] that have attributes ['get_datasource_list']
2013-08-15 14:13:24,060 - importer.py[DEBUG]: Found DataSourceAltCloud with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceAltCloud']
2013-08-15 14:13:24,061 - importer.py[DEBUG]: Looking for modules ['DataSourceOVF', 'cloudinit.sources.DataSourceOVF'] that have attributes ['get_datasource_list']
2013-08-15 14:13:24,061 - importer.py[DEBUG]: Found DataSourceOVF with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceOVF']
2013-08-15 14:13:24,061 - importer.py[DEBUG]: Looking for modules ['DataSourceMAAS', 'cloudinit.sources.DataSourceMAAS'] that have attributes ['get_datasource_list']
2013-08-15 14:13:24,063 - importer.py[DEBUG]: Found DataSourceMAAS with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceMAAS']
2013-08-15 14:13:24,064 - importer.py[DEBUG]: Looking for modules ['DataSourceEc2', 'cloudinit.sources.DataSourceEc2'] that have attributes ['get_datasource_list']
2013-08-15 14:13:24,077 - importer.py[DEBUG]: Found DataSourceEc2 with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceEc2']
2013-08-15 14:13:24,077 - importer.py[DEBUG]: Looking for modules ['DataSourceCloudStack', 'cloudinit.sources.DataSourceCloudStack'] that have attributes ['get_datasource_list']
2013-08-15 14:13:24,077 - importer.py[DEBUG]: Found DataSourceCloudStack with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceCloudStack']
2013-08-15 14:13:24,078 - importer.py[DEBUG]: Looking for modules ['DataSourceNone', 'cloudinit.sources.DataSourceNone'] that have attributes ['get_datasource_list']
2013-08-15 14:13:24,078 - importer.py[DEBUG]: Found DataSourceNone with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceNone']
2013-08-15 14:13:24,078 - init.py[DEBUG]: Searching for data source in: ['DataSourceNoCloudNet', 'DataSourceConfigDriveNet', 'DataSourceAzureNet', 'DataSourceAltCloud', 'DataSourceOVFNet', 'DataSourceMAAS', 'DataSourceEc2', 'DataSourceCloudStack', 'DataSourceNone']
2013-08-15 14:13:24,078 - init.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceNoCloud.DataSourceNoCloudNet'>
2013-08-15 14:13:24,078 - util.py[DEBUG]: Reading from /proc/cmdline (quiet=False)
2013-08-15 14:13:24,079 - util.py[DEBUG]: Read 151 bytes from /proc/cmdline
2013-08-15 14:13:24,079 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/nocloud-net/meta-data (quiet=False)
2013-08-15 14:13:24,079 - util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/sr0'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:24,083 - util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/sr1'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:24,087 - util.py[DEBUG]: Running command ['blkid', '-tTYPE=vfat', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:24,097 - util.py[DEBUG]: Running command ['blkid', '-tTYPE=iso9660', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:24,103 - util.py[DEBUG]: Running command ['blkid', '-tLABEL=cidata', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:24,112 - init.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceConfigDrive.DataSourceConfigDriveNet'>
2013-08-15 14:13:24,113 - util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/sr0'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:24,118 - util.py[DEBUG]: Running command ['blkid', '-odevice', '/dev/sr1'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:24,122 - util.py[DEBUG]: Running command ['blkid', '-tTYPE=vfat', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:24,127 - util.py[DEBUG]: Running command ['blkid', '-tTYPE=iso9660', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:24,131 - util.py[DEBUG]: Running command ['blkid', '-tLABEL=config-2', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:24,135 - init.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceAzure.DataSourceAzureNet'>
2013-08-15 14:13:24,136 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:24,136 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:24,136 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:24,136 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:24,136 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:24,136 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:24,136 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:24,136 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:24,137 - util.py[DEBUG]: Running command ['blkid', '-tTYPE=iso9660', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:24,140 - util.py[DEBUG]: Running command ['blkid', '-tTYPE=udf', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
2013-08-15 14:13:24,145 - util.py[DEBUG]: Reading from /proc/mounts (quiet=False)
2013-08-15 14:13:24,145 - util.py[DEBUG]: Read 860 bytes from /proc/mounts
2013-08-15 14:13:24,145 - util.py[DEBUG]: Fetched {'none': {'mountpoint': '/run/user', 'opts': 'rw,nosuid,nodev,noexec,relatime,size=102400k,mode=755', 'fstype': 'tmpfs'}, 'devpts': {'mountpoint': '/dev/pts', 'opts': 'rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000', 'fstype': 'devpts'}, '/dev/disk/by-uuid/4ae33cd0-c2df-4cf2-9b3e-89f52fda8e16': {'mountpoint': '/', 'opts': 'rw,relatime,discard,data=ordered', 'fstype': 'ext4'}, 'sysfs': {'mountpoint': '/sys', 'opts': 'rw,nosuid,nodev,noexec,relatime', 'fstype': 'sysfs'}, 'udev': {'mountpoint': '/dev', 'opts': 'rw,relatime,size=847192k,nr_inodes=211798,mode=755', 'fstype': 'devtmpfs'}, 'tmpfs': {'mountpoint': '/run', 'opts': 'rw,nosuid,noexec,relatime,size=171896k,mode=755', 'fstype': 'tmpfs'}, 'proc': {'mountpoint': '/proc', 'opts': 'rw,nosuid,nodev,noexec,relatime', 'fstype': 'proc'}, 'rootfs': {'mountpoint': '/', 'opts': 'rw', 'fstype': 'rootfs'}} mounts from /proc/mounts
2013-08-15 14:13:24,146 - util.py[DEBUG]: Running command ['mount', '-o', 'ro,sync', '/dev/sr0', '/tmp/tmpZygdzq'] with allowed return codes [0] (shell=False, capture=True)
2013-08-15 14:13:24,252 - util.py[DEBUG]: Running command ['umount', '-l', '/tmp/tmpZygdzq'] with allowed return codes [0] (shell=False, capture=True)
2013-08-15 14:13:24,260 - util.py[DEBUG]: Recursively deleting /tmp/tmpZygdzq
2013-08-15 14:13:24,261 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:24,261 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:24,261 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:24,261 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:24,261 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:24,261 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:24,261 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:24,261 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:24,261 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:24,262 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:24,262 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:24,262 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:24,262 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:24,262 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:24,262 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:24,262 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:24,262 - DataSourceAzure.py[DEBUG]: found datasource in /dev/sr0
2013-08-15 14:13:24,262 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:24,262 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:24,262 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:24,263 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:24,263 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:24,263 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:24,263 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:24,263 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:24,263 - util.py[DEBUG]: Writing to /var/lib/waagent/ovf-env.xml - wb: [384] 1165 bytes
2013-08-15 14:13:24,264 - util.py[DEBUG]: Running command hostname with allowed return codes [0] (shell=False, capture=True)
2013-08-15 14:13:24,268 - util.py[DEBUG]: Running command ['hostname', u'utl-0815-s14a'] with allowed return codes [0] (shell=False, capture=True)
2013-08-15 14:13:24,272 - DataSourceAzure.py[DEBUG]: pubhname: publishing hostname [phostname=ubuntu hostname=utl-0815-s14a policy=True interface=eth0]
2013-08-15 14:13:24,272 - util.py[DEBUG]: Running command ['sh', '-xc', 'i=$interface; x=0; ifdown $i || x=$?; ifup $i || x=$?; exit $x'] with allowed return codes [0] (shell=False, capture=False)
2013-08-15 14:13:25,147 - DataSourceAzure.py[DEBUG]: publishing hostname took 0.875 seconds
2013-08-15 14:13:25,147 - DataSourceAzure.py[DEBUG]: invoking agent: ['service', 'walinuxagent', 'start']
2013-08-15 14:13:25,148 - util.py[DEBUG]: Running command ['service', 'walinuxagent', 'start'] with allowed return codes [0] (shell=False, capture=True)
2013-08-15 14:13:27,769 - DataSourceAzure.py[DEBUG]: waited 2.537 seconds for 1 files to appear
2013-08-15 14:13:27,770 - stages.py[DEBUG]: Loaded datasource DataSourceAzureNet - DataSourceAzureNet [seed=/dev/sr0]
2013-08-15 14:13:27,771 - util.py[DEBUG]: Reading from /proc/cmdline (quiet=False)
2013-08-15 14:13:27,771 - util.py[DEBUG]: Read 151 bytes from /proc/cmdline
2013-08-15 14:13:27,771 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
2013-08-15 14:13:27,771 - util.py[DEBUG]: Read 2842 bytes from /etc/cloud/cloud.cfg
2013-08-15 14:13:27,771 - util.py[DEBUG]: Attempting to load yaml from string of length 2842 with allowed root types (<type 'dict'>,)
2013-08-15 14:13:27,818 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False)
2013-08-15 14:13:27,819 - util.py[DEBUG]: Read 148 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg
2013-08-15 14:13:27,819 - util.py[DEBUG]: Attempting to load yaml from string of length 148 with allowed root types (<type 'dict'>,)
2013-08-15 14:13:27,825 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90-azure.cfg (quiet=False)
2013-08-15 14:13:27,826 - util.py[DEBUG]: Read 459 bytes from /etc/cloud/cloud.cfg.d/90-azure.cfg
2013-08-15 14:13:27,826 - util.py[DEBUG]: Attempting to load yaml from string of length 459 with allowed root types (<type 'dict'>,)
2013-08-15 14:13:27,855 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
2013-08-15 14:13:27,856 - util.py[DEBUG]: Read 1674 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg
2013-08-15 14:13:27,856 - util.py[DEBUG]: Attempting to load yaml from string of length 1674 with allowed root types (<type 'dict'>,)
2013-08-15 14:13:27,884 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:27,884 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:27,884 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:27,884 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:27,884 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:27,884 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:27,884 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:27,884 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:27,897 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:27,897 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:27,897 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:27,897 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:27,897 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:27,899 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:27,899 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:27,899 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:27,899 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:27,900 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:27,900 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:27,900 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:27,900 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:27,900 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:27,900 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:27,900 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:27,900 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:27,900 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:27,900 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:27,905 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:27,906 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:27,906 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:27,906 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:27,906 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:27,906 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:27,906 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:27,906 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:27,906 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:27,906 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:27,907 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:27,907 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:27,907 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:27,907 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:27,907 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:27,911 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:27,911 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:27,911 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:27,911 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:27,911 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:27,912 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:27,912 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:27,912 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:27,912 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:27,912 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:27,913 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:27,913 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:27,913 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:27,913 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:27,913 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:27,913 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:27,913 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:27,913 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:27,913 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:27,913 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:27,914 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:27,914 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:27,914 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:27,914 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:27,914 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:27,914 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:27,914 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:27,914 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:27,914 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:27,914 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:27,917 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:27,917 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:27,917 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:27,917 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:27,917 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:27,917 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:27,917 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:27,917 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:27,917 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:27,917 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:27,918 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
2013-08-15 14:13:27,918 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b'
2013-08-15 14:13:27,921 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/datasource (quiet=False)
2013-08-15 14:13:27,923 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/datasource - wb: [420] 55 bytes
2013-08-15 14:13:27,923 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [420] 55 bytes
2013-08-15 14:13:27,924 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False)
2013-08-15 14:13:27,924 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [420] 33 bytes
2013-08-15 14:13:27,924 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [420] 33 bytes
2013-08-15 14:13:27,929 - cloud-init[DEBUG]: init will now be targeting instance id: 709fabe8f82c41f2856dc99ba054b50b
2013-08-15 14:13:27,929 - util.py[DEBUG]: Reading from /proc/cmdline (quiet=False)
2013-08-15 14:13:27,929 - util.py[DEBUG]: Read 151 bytes from /proc/cmdline
2013-08-15 14:13:27,929 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
2013-08-15 14:13:27,930 - util.py[DEBUG]: Read 2842 bytes from /etc/cloud/cloud.cfg
2013-08-15 14:13:27,930 - util.py[DEBUG]: Attempting to load yaml from string of length 2842 with allowed root types (<type 'dict'>,)
2013-08-15 14:13:28,001 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False)
2013-08-15 14:13:28,001 - util.py[DEBUG]: Read 148 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg
2013-08-15 14:13:28,001 - util.py[DEBUG]: Attempting to load yaml from string of length 148 with allowed root types (<type 'dict'>,)
2013-08-15 14:13:28,003 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90-azure.cfg (quiet=False)
2013-08-15 14:13:28,003 - util.py[DEBUG]: Read 459 bytes from /etc/cloud/cloud.cfg.d/90-azure.cfg
2013-08-15 14:13:28,003 - util.py[DEBUG]: Attempting to load yaml from string of length 459 with allowed root types (<type 'dict'>,)
2013-08-15 14:13:28,016 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
2013-08-15 14:13:28,025 - util.py[DEBUG]: Read 1674 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg
2013-08-15 14:13:28,025 - util.py[DEBUG]: Attempting to load yaml from string of length 1674 with allowed root types (<type 'dict'>,)
2013-08-15 14:13:28,039 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,039 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,039 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,039 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,039 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,039 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,039 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,039 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,039 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,039 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,040 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,040 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,040 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,040 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,040 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,040 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,040 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,040 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,040 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,040 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,048 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,049 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,049 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,049 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,049 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,049 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,049 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,049 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,049 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,049 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,049 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,050 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,050 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,050 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,050 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,050 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,050 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,050 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,050 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,050 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,050 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,050 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,050 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,051 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,051 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,051 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,051 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,051 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,051 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,051 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,051 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,051 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,051 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,051 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,052 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,052 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,052 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,052 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,052 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,052 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,052 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,052 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,052 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,060 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,061 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,061 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,061 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,061 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,061 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,061 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,061 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,061 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,061 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,061 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,062 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,062 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,062 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,062 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,062 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,062 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,062 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,062 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,064 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [256] 5539 bytes
2013-08-15 14:13:28,069 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/user-data.txt - wb: [384] 0 bytes
2013-08-15 14:13:28,075 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/user-data.txt.i - wb: [384] 344 bytes
2013-08-15 14:13:28,076 - importer.py[DEBUG]: Looking for modules ['ubuntu', 'cloudinit.distros.ubuntu'] that have attributes ['Distro']
2013-08-15 14:13:28,076 - importer.py[DEBUG]: Found ubuntu with attributes ['Distro'] in ['cloudinit.distros.ubuntu']
2013-08-15 14:13:28,076 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.ubuntu.Distro'>
2013-08-15 14:13:28,076 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/consume_userdata - wb: [420] 19 bytes
2013-08-15 14:13:28,081 - helpers.py[DEBUG]: Running consume_userdata using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/consume_userdata'>)
2013-08-15 14:13:28,081 - stages.py[DEBUG]: Registered default handlers: set(['text/cloud-boothook', 'text/upstart-job', 'text/cloud-config-jsonp', 'text/x-shellscript', 'text/cloud-config'])
2013-08-15 14:13:28,082 - init.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (begin, None, 2) with frequency once-per-instance
2013-08-15 14:13:28,082 - init.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (begin, None, 2) with frequency once-per-instance
2013-08-15 14:13:28,082 - init.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (begin, None, 3) with frequency once-per-instance
2013-08-15 14:13:28,082 - init.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (begin, None, 2) with frequency once-per-instance
2013-08-15 14:13:28,082 - init.py[DEBUG]: {'Content-Type': 'text/x-not-multipart', 'Content-Disposition': 'attachment; filename="part-001"', 'MIME-Version': '1.0'}
2013-08-15 14:13:28,083 - init.py[DEBUG]: Empty payload of type text/x-not-multipart
2013-08-15 14:13:28,083 - init.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (end, None, 2) with frequency once-per-instance
2013-08-15 14:13:28,083 - init.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (end, None, 2) with frequency once-per-instance
2013-08-15 14:13:28,083 - init.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (end, None, 3) with frequency once-per-instance
2013-08-15 14:13:28,083 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/cloud-config.txt - wb: [384] 0 bytes
2013-08-15 14:13:28,083 - init.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (end, None, 2) with frequency once-per-instance
2013-08-15 14:13:28,084 - util.py[DEBUG]: Reading from /proc/cmdline (quiet=False)
2013-08-15 14:13:28,084 - util.py[DEBUG]: Read 151 bytes from /proc/cmdline
2013-08-15 14:13:28,084 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
2013-08-15 14:13:28,084 - util.py[DEBUG]: Read 2842 bytes from /etc/cloud/cloud.cfg
2013-08-15 14:13:28,084 - util.py[DEBUG]: Attempting to load yaml from string of length 2842 with allowed root types (<type 'dict'>,)
2013-08-15 14:13:28,153 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False)
2013-08-15 14:13:28,153 - util.py[DEBUG]: Read 148 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg
2013-08-15 14:13:28,153 - util.py[DEBUG]: Attempting to load yaml from string of length 148 with allowed root types (<type 'dict'>,)
2013-08-15 14:13:28,155 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90-azure.cfg (quiet=False)
2013-08-15 14:13:28,155 - util.py[DEBUG]: Read 459 bytes from /etc/cloud/cloud.cfg.d/90-azure.cfg
2013-08-15 14:13:28,155 - util.py[DEBUG]: Attempting to load yaml from string of length 459 with allowed root types (<type 'dict'>,)
2013-08-15 14:13:28,175 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
2013-08-15 14:13:28,175 - util.py[DEBUG]: Read 1674 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg
2013-08-15 14:13:28,175 - util.py[DEBUG]: Attempting to load yaml from string of length 1674 with allowed root types (<type 'dict'>,)
2013-08-15 14:13:28,188 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,197 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,197 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,197 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,197 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,197 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,197 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,197 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,197 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,197 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,197 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,198 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,198 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,198 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,198 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,198 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,198 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,198 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,198 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,198 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,198 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,198 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,198 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,199 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,199 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,199 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,199 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,199 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,199 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,199 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,199 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,199 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,199 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,199 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,200 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,200 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,200 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,200 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,200 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,200 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,200 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,200 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,200 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,200 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,210 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,210 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,210 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,210 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,210 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,210 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,211 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,211 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,211 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,211 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,211 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,211 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,211 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,211 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,211 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,211 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,211 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,211 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,212 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
2013-08-15 14:13:28,212 - util.py[DEBUG]: Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt
2013-08-15 14:13:28,212 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<type 'dict'>,)
2013-08-15 14:13:28,212 - util.py[DEBUG]: load_yaml given empty string, returning default
2013-08-15 14:13:28,212 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,212 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,212 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,217 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,217 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,217 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,217 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,217 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,217 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,217 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,217 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,217 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,217 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,217 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,218 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,218 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,218 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,218 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,218 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,218 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,219 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
2013-08-15 14:13:28,227 - util.py[DEBUG]: Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt
2013-08-15 14:13:28,227 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<type 'dict'>,)
2013-08-15 14:13:28,227 - util.py[DEBUG]: load_yaml given empty string, returning default
2013-08-15 14:13:28,227 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,227 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,227 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,227 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,228 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,228 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,228 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,228 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,228 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:28,228 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:28,228 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:28,228 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:28,228 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:28,228 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:28,229 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:28,229 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:28,245 - importer.py[DEBUG]: Looking for modules ['cc_migrator', 'cloudinit.config.cc_migrator'] that have attributes ['handle']
2013-08-15 14:13:28,251 - importer.py[DEBUG]: Found cc_migrator with attributes ['handle'] in ['cloudinit.config.cc_migrator']
2013-08-15 14:13:28,251 - importer.py[DEBUG]: Looking for modules ['cc_bootcmd', 'cloudinit.config.cc_bootcmd'] that have attributes ['handle']
2013-08-15 14:13:28,257 - importer.py[DEBUG]: Found cc_bootcmd with attributes ['handle'] in ['cloudinit.config.cc_bootcmd']
2013-08-15 14:13:28,257 - importer.py[DEBUG]: Looking for modules ['cc_write_files', 'cloudinit.config.cc_write_files'] that have attributes ['handle']
2013-08-15 14:13:28,266 - importer.py[DEBUG]: Found cc_write_files with attributes ['handle'] in ['cloudinit.config.cc_write_files']
2013-08-15 14:13:28,267 - importer.py[DEBUG]: Looking for modules ['cc_growpart', 'cloudinit.config.cc_growpart'] that have attributes ['handle']
2013-08-15 14:13:28,271 - importer.py[DEBUG]: Found cc_growpart with attributes ['handle'] in ['cloudinit.config.cc_growpart']
2013-08-15 14:13:28,271 - importer.py[DEBUG]: Looking for modules ['cc_resizefs', 'cloudinit.config.cc_resizefs'] that have attributes ['handle']
2013-08-15 14:13:28,282 - importer.py[DEBUG]: Found cc_resizefs with attributes ['handle'] in ['cloudinit.config.cc_resizefs']
2013-08-15 14:13:28,282 - importer.py[DEBUG]: Looking for modules ['cc_set_hostname', 'cloudinit.config.cc_set_hostname'] that have attributes ['handle']
2013-08-15 14:13:28,290 - importer.py[DEBUG]: Found cc_set_hostname with attributes ['handle'] in ['cloudinit.config.cc_set_hostname']
2013-08-15 14:13:28,290 - importer.py[DEBUG]: Looking for modules ['cc_update_hostname', 'cloudinit.config.cc_update_hostname'] that have attributes ['handle']
2013-08-15 14:13:28,301 - importer.py[DEBUG]: Found cc_update_hostname with attributes ['handle'] in ['cloudinit.config.cc_update_hostname']
2013-08-15 14:13:28,301 - importer.py[DEBUG]: Looking for modules ['cc_update_etc_hosts', 'cloudinit.config.cc_update_etc_hosts'] that have attributes ['handle']
2013-08-15 14:13:28,302 - importer.py[DEBUG]: Found cc_update_etc_hosts with attributes ['handle'] in ['cloudinit.config.cc_update_etc_hosts']
2013-08-15 14:13:28,302 - importer.py[DEBUG]: Looking for modules ['cc_ca_certs', 'cloudinit.config.cc_ca_certs'] that have attributes ['handle']
2013-08-15 14:13:28,313 - importer.py[DEBUG]: Found cc_ca_certs with attributes ['handle'] in ['cloudinit.config.cc_ca_certs']
2013-08-15 14:13:28,313 - importer.py[DEBUG]: Looking for modules ['cc_rsyslog', 'cloudinit.config.cc_rsyslog'] that have attributes ['handle']
2013-08-15 14:13:28,317 - importer.py[DEBUG]: Found cc_rsyslog with attributes ['handle'] in ['cloudinit.config.cc_rsyslog']
2013-08-15 14:13:28,317 - importer.py[DEBUG]: Looking for modules ['cc_users_groups', 'cloudinit.config.cc_users_groups'] that have attributes ['handle']
2013-08-15 14:13:28,329 - importer.py[DEBUG]: Found cc_users_groups with attributes ['handle'] in ['cloudinit.config.cc_users_groups']
2013-08-15 14:13:28,329 - importer.py[DEBUG]: Looking for modules ['cc_ssh', 'cloudinit.config.cc_ssh'] that have attributes ['handle']
2013-08-15 14:13:28,336 - importer.py[DEBUG]: Found cc_ssh with attributes ['handle'] in ['cloudinit.config.cc_ssh']
2013-08-15 14:13:28,336 - importer.py[DEBUG]: Looking for modules ['ubuntu', 'cloudinit.distros.ubuntu'] that have attributes ['Distro']
2013-08-15 14:13:28,337 - importer.py[DEBUG]: Found ubuntu with attributes ['Distro'] in ['cloudinit.distros.ubuntu']
2013-08-15 14:13:28,337 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.ubuntu.Distro'>
2013-08-15 14:13:28,338 - helpers.py[DEBUG]: Running config-migrator using lock (<cloudinit.helpers.DummyLock object at 0x242b790>)
2013-08-15 14:13:28,338 - cc_migrator.py[DEBUG]: Migrated 0 semaphore files to there canonicalized names
2013-08-15 14:13:28,338 - helpers.py[DEBUG]: Running config-bootcmd using lock (<cloudinit.helpers.DummyLock object at 0x242b790>)
2013-08-15 14:13:28,338 - cc_bootcmd.py[DEBUG]: Skipping module named bootcmd, no 'bootcmd' key in configuration
2013-08-15 14:13:28,339 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_write_files - wb: [420] 19 bytes
2013-08-15 14:13:28,339 - helpers.py[DEBUG]: Running config-write-files using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_write_files'>)
2013-08-15 14:13:28,339 - cc_write_files.py[DEBUG]: Skipping module named write-files, no/empty 'write_files' key in configuration
2013-08-15 14:13:28,340 - helpers.py[DEBUG]: Running config-growpart using lock (<cloudinit.helpers.DummyLock object at 0x242b5d0>)
2013-08-15 14:13:28,340 - cc_growpart.py[DEBUG]: No 'growpart' entry in cfg. Using default: {'mode': 'auto', 'devices': ['/']}
2013-08-15 14:13:28,340 - util.py[DEBUG]: Running command ['parted', '--help'] with allowed return codes [0] (shell=False, capture=True)
2013-08-15 14:13:28,404 - util.py[DEBUG]: Reading from /proc/774/mountinfo (quiet=False)
2013-08-15 14:13:28,404 - util.py[DEBUG]: Read 1014 bytes from /proc/774/mountinfo
2013-08-15 14:13:28,405 - util.py[DEBUG]: Reading from /sys/class/block/sda1/partition (quiet=False)
2013-08-15 14:13:28,405 - util.py[DEBUG]: Read 2 bytes from /sys/class/block/sda1/partition
2013-08-15 14:13:28,405 - util.py[DEBUG]: Reading from /sys/devices/LNXSYSTM:00/device:00/PNP0A03:00/device:08/VMBUS:00/vmbus_0_1/host2/target2:0:0/2:0:0:0/block/sda/dev (quiet=False)
2013-08-15 14:13:28,405 - util.py[DEBUG]: Read 4 bytes from /sys/devices/LNXSYSTM:00/device:00/PNP0A03:00/device:08/VMBUS:00/vmbus_0_1/host2/target2:0:0/2:0:0:0/block/sda/dev
2013-08-15 14:13:28,408 - util.py[DEBUG]: Running command ['parted', 'resizepart', '/dev/sda', '1'] with allowed return codes [0] (shell=False, capture=True)
2013-08-15 14:13:28,466 - cc_growpart.py[DEBUG]: '/' FAILED: failed to resize: disk=/dev/sda, ptnum=1: Unexpected error while running command.
Command: ['parted', 'resizepart', '/dev/sda', '1']
Exit code: 1
Reason: -
Stdout: '\r \rError: Could not stat device resizepart - No such file or directory.\n'
Stderr: ''
2013-08-15 14:13:28,467 - helpers.py[DEBUG]: Running config-resizefs using lock (<cloudinit.helpers.DummyLock object at 0x242b150>)
2013-08-15 14:13:28,467 - util.py[DEBUG]: Reading from /proc/774/mountinfo (quiet=False)
2013-08-15 14:13:28,468 - util.py[DEBUG]: Read 1014 bytes from /proc/774/mountinfo
2013-08-15 14:13:28,468 - cc_resizefs.py[DEBUG]: resize_info: dev=/dev/disk/by-uuid/4ae33cd0-c2df-4cf2-9b3e-89f52fda8e16 mnt_point=/ path=/
2013-08-15 14:13:28,468 - cc_resizefs.py[DEBUG]: Resizing / (ext4) using resize2fs /dev/disk/by-uuid/4ae33cd0-c2df-4cf2-9b3e-89f52fda8e16
2013-08-15 14:13:28,468 - util.py[DEBUG]: Running command ('resize2fs', '/dev/disk/by-uuid/4ae33cd0-c2df-4cf2-9b3e-89f52fda8e16') with allowed return codes [0] (shell=False, capture=True)
2013-08-15 14:13:29,311 - cc_resizefs.py[DEBUG]: Resizing took 0.843 seconds
2013-08-15 14:13:29,311 - cc_resizefs.py[DEBUG]: Resized root filesystem (type=ext4, val=True)
2013-08-15 14:13:29,312 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_set_hostname - wb: [420] 19 bytes
2013-08-15 14:13:29,312 - helpers.py[DEBUG]: Running config-set_hostname using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_set_hostname'>)
2013-08-15 14:13:29,312 - cc_set_hostname.py[DEBUG]: Setting the hostname to utl-0815-s14a.localdomain (utl-0815-s14a)
2013-08-15 14:13:29,312 - util.py[DEBUG]: Reading from /etc/hostname (quiet=False)
2013-08-15 14:13:29,313 - util.py[DEBUG]: Read 7 bytes from /etc/hostname
2013-08-15 14:13:29,313 - util.py[DEBUG]: Writing to /etc/hostname - wb: [420] 14 bytes
2013-08-15 14:13:29,317 - init.py[DEBUG]: Non-persistently setting the system hostname to utl-0815-s14a
2013-08-15 14:13:29,317 - util.py[DEBUG]: Running command ['hostname', u'utl-0815-s14a'] with allowed return codes [0] (shell=False, capture=True)
2013-08-15 14:13:29,326 - helpers.py[DEBUG]: Running config-update_hostname using lock (<cloudinit.helpers.DummyLock object at 0x242b5d0>)
2013-08-15 14:13:29,326 - cc_update_hostname.py[DEBUG]: Updating hostname to utl-0815-s14a.localdomain (utl-0815-s14a)
2013-08-15 14:13:29,326 - util.py[DEBUG]: Reading from /etc/hostname (quiet=False)
2013-08-15 14:13:29,327 - util.py[DEBUG]: Read 14 bytes from /etc/hostname
2013-08-15 14:13:29,327 - init.py[DEBUG]: Attempting to update hostname to utl-0815-s14a in 1 files
2013-08-15 14:13:29,327 - util.py[DEBUG]: Reading from /var/lib/cloud/data/previous-hostname (quiet=False)
2013-08-15 14:13:29,327 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-hostname - wb: [420] 14 bytes
2013-08-15 14:13:29,328 - helpers.py[DEBUG]: Running config-update_etc_hosts using lock (<cloudinit.helpers.DummyLock object at 0x242b850>)
2013-08-15 14:13:29,328 - cc_update_etc_hosts.py[DEBUG]: Configuration option 'manage_etc_hosts' is not set, not managing /etc/hosts in module update_etc_hosts
2013-08-15 14:13:29,328 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_ca_certs - wb: [420] 19 bytes
2013-08-15 14:13:29,333 - helpers.py[DEBUG]: Running config-ca-certs using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_ca_certs'>)
2013-08-15 14:13:29,333 - cc_ca_certs.py[DEBUG]: Skipping module named ca-certs, no 'ca-certs' key in configuration
2013-08-15 14:13:29,333 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_rsyslog - wb: [420] 19 bytes
2013-08-15 14:13:29,334 - helpers.py[DEBUG]: Running config-rsyslog using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_rsyslog'>)
2013-08-15 14:13:29,334 - cc_rsyslog.py[DEBUG]: Skipping module named rsyslog, no 'rsyslog' key in configuration
2013-08-15 14:13:29,334 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_users_groups - wb: [420] 19 bytes
2013-08-15 14:13:29,335 - helpers.py[DEBUG]: Running config-users-groups using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_users_groups'>)
2013-08-15 14:13:29,335 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:29,335 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:29,335 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:29,335 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:29,335 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:29,335 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:29,336 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:29,336 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:29,336 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:29,336 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:29,336 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:29,336 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:29,336 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:29,336 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:29,336 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:29,336 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:29,341 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:29,341 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:29,341 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:29,341 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:29,341 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:29,341 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:29,341 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:29,341 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:29,342 - init.py[DEBUG]: Adding user utlemming
2013-08-15 14:13:29,342 - util.py[DEBUG]: Running hidden command to protect sensitive input/output logstring: ['useradd', u'utlemming', '--shell', '/bin/bash', '--comment', 'Ubuntu', '--groups', 'adm,audio,cdrom,dialout,dip,floppy,netdev,plugdev,video', '-m']
2013-08-15 14:13:30,466 - util.py[DEBUG]: Reading from /etc/sudoers (quiet=False)
2013-08-15 14:13:30,467 - util.py[DEBUG]: Read 745 bytes from /etc/sudoers
2013-08-15 14:13:30,468 - util.py[DEBUG]: Writing to /etc/sudoers.d/90-cloud-init-users - wb: [288] 129 bytes
2013-08-15 14:13:30,469 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_ssh - wb: [420] 19 bytes
2013-08-15 14:13:30,470 - helpers.py[DEBUG]: Running config-ssh using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_ssh'>)
2013-08-15 14:13:30,471 - util.py[DEBUG]: Running command ['ssh-keygen', '-t', 'rsa', '-N', '', '-f', '/etc/ssh/ssh_host_rsa_key'] with allowed return codes [0] (shell=False, capture=False)
2013-08-15 14:13:31,058 - util.py[DEBUG]: Running command ['ssh-keygen', '-t', 'dsa', '-N', '', '-f', '/etc/ssh/ssh_host_dsa_key'] with allowed return codes [0] (shell=False, capture=False)
2013-08-15 14:13:31,324 - util.py[DEBUG]: Running command ['ssh-keygen', '-t', 'ecdsa', '-N', '', '-f', '/etc/ssh/ssh_host_ecdsa_key'] with allowed return codes [0] (shell=False, capture=False)
2013-08-15 14:13:31,393 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:31,394 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:31,394 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:31,394 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:31,399 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:31,399 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:31,399 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:31,400 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:31,400 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:31,400 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:31,401 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:31,401 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:31,401 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:31,401 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:31,402 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:31,402 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:31,402 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
2013-08-15 14:13:31,407 - importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
2013-08-15 14:13:31,407 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
2013-08-15 14:13:31,407 - importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
2013-08-15 14:13:31,407 - importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
2013-08-15 14:13:31,408 - importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
2013-08-15 14:13:31,408 - init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
2013-08-15 14:13:31,408 - init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
2013-08-15 14:13:31,412 - util.py[DEBUG]: Changing the ownership of /home/utlemming/.ssh to 1000:1000
2013-08-15 14:13:31,412 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False)
2013-08-15 14:13:31,415 - util.py[DEBUG]: Read 2511 bytes from /etc/ssh/sshd_config
2013-08-15 14:13:31,418 - util.py[DEBUG]: Writing to /home/utlemming/.ssh/authorized_keys - wb: [384] 0 bytes
2013-08-15 14:13:31,421 - util.py[DEBUG]: Changing the ownership of /home/utlemming/.ssh/authorized_keys to 1000:1000
2013-08-15 14:13:31,423 - util.py[DEBUG]: Changing the ownership of /root/.ssh to 0:0
2013-08-15 14:13:31,423 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False)
2013-08-15 14:13:31,424 - util.py[DEBUG]: Read 2511 bytes from /etc/ssh/sshd_config
2013-08-15 14:13:31,426 - util.py[DEBUG]: Writing to /root/.ssh/authorized_keys - wb: [384] 0 bytes
2013-08-15 14:13:31,427 - util.py[DEBUG]: Changing the ownership of /root/.ssh/authorized_keys to 0:0
2013-08-15 14:13:31,427 - cloud-init[DEBUG]: Ran 12 modules with 0 failures
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Cloud-init v. 0.7.3 running 'modules:config' at Thu, 15 Aug 2013 14:13:32 +0000. Up 34.86 seconds.
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_emit_upstart', 'cloudinit.config.cc_emit_upstart'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_emit_upstart with attributes ['handle'] in ['cloudinit.config.cc_emit_upstart']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_mounts', 'cloudinit.config.cc_mounts'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_mounts with attributes ['handle'] in ['cloudinit.config.cc_mounts']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_ssh_import_id', 'cloudinit.config.cc_ssh_import_id'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_ssh_import_id with attributes ['handle'] in ['cloudinit.config.cc_ssh_import_id']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_locale', 'cloudinit.config.cc_locale'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_locale with attributes ['handle'] in ['cloudinit.config.cc_locale']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_set_passwords', 'cloudinit.config.cc_set_passwords'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_set_passwords with attributes ['handle'] in ['cloudinit.config.cc_set_passwords']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_grub_dpkg', 'cloudinit.config.cc_grub_dpkg'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_grub_dpkg with attributes ['handle'] in ['cloudinit.config.cc_grub_dpkg']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_apt_pipelining', 'cloudinit.config.cc_apt_pipelining'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_apt_pipelining with attributes ['handle'] in ['cloudinit.config.cc_apt_pipelining']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_apt_configure', 'cloudinit.config.cc_apt_configure'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_apt_configure with attributes ['handle'] in ['cloudinit.config.cc_apt_configure']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_package_update_upgrade_install', 'cloudinit.config.cc_package_update_upgrade_install'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_package_update_upgrade_install with attributes ['handle'] in ['cloudinit.config.cc_package_update_upgrade_install']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_landscape', 'cloudinit.config.cc_landscape'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_landscape with attributes ['handle'] in ['cloudinit.config.cc_landscape']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_timezone', 'cloudinit.config.cc_timezone'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_timezone with attributes ['handle'] in ['cloudinit.config.cc_timezone']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_puppet', 'cloudinit.config.cc_puppet'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_puppet with attributes ['handle'] in ['cloudinit.config.cc_puppet']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_chef', 'cloudinit.config.cc_chef'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_chef with attributes ['handle'] in ['cloudinit.config.cc_chef']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_salt_minion', 'cloudinit.config.cc_salt_minion'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_salt_minion with attributes ['handle'] in ['cloudinit.config.cc_salt_minion']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_mcollective', 'cloudinit.config.cc_mcollective'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_mcollective with attributes ['handle'] in ['cloudinit.config.cc_mcollective']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_disable_ec2_metadata', 'cloudinit.config.cc_disable_ec2_metadata'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_disable_ec2_metadata with attributes ['handle'] in ['cloudinit.config.cc_disable_ec2_metadata']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_runcmd', 'cloudinit.config.cc_runcmd'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_runcmd with attributes ['handle'] in ['cloudinit.config.cc_runcmd']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_byobu', 'cloudinit.config.cc_byobu'] that have attributes ['handle']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_byobu with attributes ['handle'] in ['cloudinit.config.cc_byobu']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['ubuntu', 'cloudinit.distros.ubuntu'] that have attributes ['Distro']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found ubuntu with attributes ['Distro'] in ['cloudinit.distros.ubuntu']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.ubuntu.Distro'>
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-emit_upstart using lock (<cloudinit.helpers.DummyLock object at 0x2927310>)
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Running command ['initctl', 'emit', 'cloud-config', 'CLOUD_CFG=/var/lib/cloud/instance/cloud-config.txt'] with allowed return codes [0] (shell=False, capture=True)
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_mounts - wb: [420] 20 bytes
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-mounts using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_mounts'>)
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] cc_mounts.py[DEBUG]: Ignoring nonexistant named default mount ephemeral0
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] cc_mounts.py[DEBUG]: Ignoring nonexistant named default mount swap
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] cc_mounts.py[DEBUG]: No modifications to fstab needed.
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_ssh_import_id - wb: [420] 20 bytes
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-ssh-import-id using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_ssh_import_id'>)
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_locale - wb: [420] 20 bytes
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-locale using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_locale'>)
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] cc_locale.py[DEBUG]: Setting locale to en_US.UTF-8
Aug 15 14:13:33 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Running command ['locale-gen', 'en_US.UTF-8'] with allowed return codes [0] (shell=False, capture=False)
Aug 15 14:13:36 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Running command ['update-locale', 'en_US.UTF-8'] with allowed return codes [0] (shell=False, capture=False)
Aug 15 14:13:37 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /etc/default/locale - wb: [420] 87 bytes
Aug 15 14:13:37 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_set_passwords - wb: [420] 20 bytes
Aug 15 14:13:37 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-set-passwords using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_set_passwords'>)
Aug 15 14:13:37 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False)
Aug 15 14:13:37 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Read 2511 bytes from /etc/ssh/sshd_config
Aug 15 14:13:37 utl-0815-s14a [CLOUDINIT] cc_set_passwords.py[DEBUG]: Replacing auth line 51 with yes
Aug 15 14:13:37 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /etc/ssh/sshd_config - wb: [420] 2511 bytes
Aug 15 14:13:37 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Running command ['service', 'ssh', 'restart'] with allowed return codes [0] (shell=False, capture=True)
Aug 15 14:13:37 utl-0815-s14a [CLOUDINIT] cc_set_passwords.py[DEBUG]: Restarted the ssh daemon
Aug 15 14:13:37 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_grub_dpkg - wb: [420] 20 bytes
Aug 15 14:13:37 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-grub-dpkg using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_grub_dpkg'>)
Aug 15 14:13:37 utl-0815-s14a [CLOUDINIT] cc_grub_dpkg.py[DEBUG]: Setting grub debconf-set-selections with '/dev/sda','false'
Aug 15 14:13:37 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Running command ['debconf-set-selections'] with allowed return codes [0] (shell=False, capture=True)
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_apt_pipelining - wb: [420] 19 bytes
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-apt-pipelining using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_apt_pipelining'>)
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /etc/apt/apt.conf.d/90cloud-init-pipelining - wb: [420] 80 bytes
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] cc_apt_pipelining.py[DEBUG]: Wrote /etc/apt/apt.conf.d/90cloud-init-pipelining with apt pipeline depth setting 0
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_apt_configure - wb: [420] 20 bytes
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-apt-configure using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_apt_configure'>)
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Running command ['lsb_release', '-cs'] with allowed return codes [0] (shell=False, capture=True)
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Running command ['dpkg', '--print-architecture'] with allowed return codes [0] (shell=False, capture=True)
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] init.py[DEBUG]: filtered distro mirror info: {'security': 'http://security.ubuntu.com/ubuntu', 'primary': 'http://azure.archive.ubuntu.com/ubuntu/'}
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] cc_apt_configure.py[DEBUG]: Mirror info: {'security': 'http://security.ubuntu.com/ubuntu', 'primary': 'http://azure.archive.ubuntu.com/ubuntu/', 'mirror': 'http://azure.archive.ubuntu.com/ubuntu/'}
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/templates/sources.list.ubuntu.tmpl (quiet=False)
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Read 2768 bytes from /etc/cloud/templates/sources.list.ubuntu.tmpl
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /etc/apt/sources.list - wb: [420] 3241 bytes
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy-updates_Release.gpg to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy-updates_Release.gpg
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy_restricted_i18n_Translation-en to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy_restricted_i18n_Translation-en
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy_Release to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy_Release
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy_Release.gpg to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy_Release.gpg
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy-updates_universe_binary-amd64_Packages to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy-updates_universe_binary-amd64_Packages
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy_main_i18n_Translation-en to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy_main_i18n_Translation-en
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy_main_binary-amd64_Packages to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy_main_binary-amd64_Packages
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy-updates_multiverse_i18n_Translation-en to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy-updates_multiverse_i18n_Translation-en
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy-updates_multiverse_binary-amd64_Packages to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy-updates_multiverse_binary-amd64_Packages
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy-updates_main_binary-amd64_Packages to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy-updates_main_binary-amd64_Packages
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy_multiverse_binary-amd64_Packages to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy_multiverse_binary-amd64_Packages
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy_universe_i18n_Translation-en to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy_universe_i18n_Translation-en
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy_multiverse_i18n_Translation-en to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy_multiverse_i18n_Translation-en
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy-updates_universe_i18n_Translation-en to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy-updates_universe_i18n_Translation-en
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy_universe_binary-amd64_Packages to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy_universe_binary-amd64_Packages
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy-updates_main_i18n_Translation-en to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy-updates_main_i18n_Translation-en
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy-updates_restricted_binary-amd64_Packages to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy-updates_restricted_binary-amd64_Packages
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy-updates_restricted_i18n_Translation-en to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy-updates_restricted_i18n_Translation-en
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy_restricted_binary-amd64_Packages to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy_restricted_binary-amd64_Packages
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Renaming /var/lib/apt/lists/archive.ubuntu.com_ubuntu_dists_saucy-updates_Release to /var/lib/apt/lists/azure.archive.ubuntu.com_ubuntu_dists_saucy-updates_Release
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_package_update_upgrade_install - wb: [420] 20 bytes
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-package-update-upgrade-install using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_package_update_upgrade_install'>)
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_landscape - wb: [420] 20 bytes
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-landscape using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_landscape'>)
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_timezone - wb: [420] 20 bytes
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-timezone using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_timezone'>)
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] cc_timezone.py[DEBUG]: Skipping module named timezone, no 'timezone' specified
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_puppet - wb: [420] 20 bytes
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-puppet using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_puppet'>)
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] cc_puppet.py[DEBUG]: Skipping module named puppet, no 'puppet' configuration found
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_chef - wb: [420] 20 bytes
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-chef using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_chef'>)
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] cc_chef.py[DEBUG]: Skipping module named chef, no 'chef' key in configuration
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_salt_minion - wb: [420] 20 bytes
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-salt-minion using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_salt_minion'>)
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] cc_salt_minion.py[DEBUG]: Skipping module named salt-minion, no 'salt_minion' key in configuration
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_mcollective - wb: [420] 20 bytes
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-mcollective using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_mcollective'>)
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] cc_mcollective.py[DEBUG]: Skipping module named mcollective, no 'mcollective' key in configuration
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-disable-ec2-metadata using lock (<cloudinit.helpers.DummyLock object at 0x29b6510>)
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] cc_disable_ec2_metadata.py[DEBUG]: Skipping module named disable-ec2-metadata, disabling the ec2 route not enabled
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_runcmd - wb: [420] 20 bytes
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-runcmd using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_runcmd'>)
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] cc_runcmd.py[DEBUG]: Skipping module named runcmd, no 'runcmd' key in configuration
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_byobu - wb: [420] 20 bytes
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-byobu using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_byobu'>)
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] cc_byobu.py[DEBUG]: Skipping module named byobu, no 'byobu' values found
Aug 15 14:13:42 utl-0815-s14a [CLOUDINIT] cloud-init[DEBUG]: Ran 18 modules with 0 failures
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Cloud-init v. 0.7.3 running 'modules:final' at Thu, 15 Aug 2013 14:13:43 +0000. Up 45.21 seconds.
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_rightscale_userdata', 'cloudinit.config.cc_rightscale_userdata'] that have attributes ['handle']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_rightscale_userdata with attributes ['handle'] in ['cloudinit.config.cc_rightscale_userdata']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_scripts_per_once', 'cloudinit.config.cc_scripts_per_once'] that have attributes ['handle']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_scripts_per_once with attributes ['handle'] in ['cloudinit.config.cc_scripts_per_once']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_scripts_per_boot', 'cloudinit.config.cc_scripts_per_boot'] that have attributes ['handle']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_scripts_per_boot with attributes ['handle'] in ['cloudinit.config.cc_scripts_per_boot']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_scripts_per_instance', 'cloudinit.config.cc_scripts_per_instance'] that have attributes ['handle']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_scripts_per_instance with attributes ['handle'] in ['cloudinit.config.cc_scripts_per_instance']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_scripts_user', 'cloudinit.config.cc_scripts_user'] that have attributes ['handle']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_scripts_user with attributes ['handle'] in ['cloudinit.config.cc_scripts_user']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_ssh_authkey_fingerprints', 'cloudinit.config.cc_ssh_authkey_fingerprints'] that have attributes ['handle']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_ssh_authkey_fingerprints with attributes ['handle'] in ['cloudinit.config.cc_ssh_authkey_fingerprints']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_keys_to_console', 'cloudinit.config.cc_keys_to_console'] that have attributes ['handle']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_keys_to_console with attributes ['handle'] in ['cloudinit.config.cc_keys_to_console']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_phone_home', 'cloudinit.config.cc_phone_home'] that have attributes ['handle']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_phone_home with attributes ['handle'] in ['cloudinit.config.cc_phone_home']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_final_message', 'cloudinit.config.cc_final_message'] that have attributes ['handle']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_final_message with attributes ['handle'] in ['cloudinit.config.cc_final_message']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cc_power_state_change', 'cloudinit.config.cc_power_state_change'] that have attributes ['handle']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found cc_power_state_change with attributes ['handle'] in ['cloudinit.config.cc_power_state_change']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['ubuntu', 'cloudinit.distros.ubuntu'] that have attributes ['Distro']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found ubuntu with attributes ['Distro'] in ['cloudinit.distros.ubuntu']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.ubuntu.Distro'>
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_rightscale_userdata - wb: [420] 20 bytes
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-rightscale_userdata using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_rightscale_userdata'>)
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] cc_rightscale_userdata.py[DEBUG]: Failed to get raw userdata in module rightscale_userdata
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/sem/config_scripts_per_once.once - wb: [420] 20 bytes
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-scripts-per-once using lock (<FileLock using file '/var/lib/cloud/sem/config_scripts_per_once.once'>)
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-scripts-per-boot using lock (<cloudinit.helpers.DummyLock object at 0x2b421d0>)
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_scripts_per_instance - wb: [420] 20 bytes
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-scripts-per-instance using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_scripts_per_instance'>)
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_scripts_user - wb: [420] 20 bytes
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-scripts-user using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_scripts_user'>)
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_ssh_authkey_fingerprints - wb: [420] 20 bytes
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-ssh-authkey-fingerprints using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_ssh_authkey_fingerprints'>)
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] init.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] init.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False)
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Read 2511 bytes from /etc/ssh/sshd_config
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Reading from /home/utlemming/.ssh/authorized_keys (quiet=False)
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Read 0 bytes from /home/utlemming/.ssh/authorized_keys
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_keys_to_console - wb: [420] 19 bytes
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-keys-to-console using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_keys_to_console'>)
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Running command ['/usr/lib/cloud-init/write-ssh-key-fingerprints', '', 'ssh-dss'] with allowed return codes [0] (shell=False, capture=True)
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_phone_home - wb: [420] 20 bytes
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-phone-home using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_phone_home'>)
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] cc_phone_home.py[DEBUG]: Skipping module named phone-home, no 'phone_home' configuration found
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-final-message using lock (<cloudinit.helpers.DummyLock object at 0x2b42610>)
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Read 11 bytes from /proc/uptime
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instance/boot-finished - wb: [420] 51 bytes
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_power_state_change - wb: [420] 20 bytes
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] helpers.py[DEBUG]: Running config-power-state-change using lock (<FileLock using file '/var/lib/cloud/instances/709fabe8f82c41f2856dc99ba054b50b/sem/config_power_state_change'>)
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] cc_power_state_change.py[DEBUG]: no power_state provided. doing nothing
Aug 15 14:13:43 utl-0815-s14a [CLOUDINIT] cloud-init[DEBUG]: Ran 10 modules with 0 failures

@ubuntu-server-builder
Copy link
Collaborator Author

Launchpad user Scott Moser(smoser) wrote on 2013-08-15T17:39:42.724569+00:00

fixed in trunk at revno 860.

@ubuntu-server-builder
Copy link
Collaborator Author

Launchpad user Launchpad Janitor(janitor) wrote on 2013-08-15T20:45:32.852249+00:00

This bug was fixed in the package cloud-init - 0.7.3~bzr860-0ubuntu1

cloud-init (0.7.3~bzr860-0ubuntu1) saucy; urgency=low

  • New upstream snapshot.
    • fix setting of password for a user on azure. (LP: #1212723)
      -- Scott Moser smoser@ubuntu.com Thu, 15 Aug 2013 16:01:40 -0400

@ubuntu-server-builder
Copy link
Collaborator Author

Launchpad user Scott Moser(smoser) wrote on 2013-11-19T21:57:14.100048+00:00

fixed in 0.7.3

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
launchpad Migrated from Launchpad priority Fix soon
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@ubuntu-server-builder