hartwork / image-bootstrap Goto Github PK

:partly_sunny: Creates Linux chroots and bootable virtual machine images; command line tool (Python 3)

Makefile 0.42% Python 98.86% Shell 0.73%

alpine-linux arch-linux archlinux archlinux-images block-device bootstrap centos chroot chroot-environment cli fedora gentoo install-script installer installers linux python virtual-machine virtualization void-linux

image-bootstrap's Introduction

Table of Contents

About
History
Example run
Speeding things up
Debian package
Usage (--help output)
Hints on using image-bootstrap within a pipe
Known limitations
- Installing to partition block devices

About

Welcome to the home of image-bootstrap (and its little brother directory-bootstrap).

image-bootstrap is a command line tool to generate bootable virtual machine images and write them to a given block device.
Linux distributions supported by image-bootstrap currently include: Arch, Debian, Gentoo, Ubuntu.
When passing the --openstack parameter, images are prepared for use with OpenStack.

directory-bootstrap is a command line tool to install non-Debian Linux distributions into a given directory (similar to debootstrap for Debian/Ubuntu).
Distributions supported by directory-bootstrap currently include: Alpine Linux, Arch Linux, Gentoo, and Void Linux.

History

image-bootstrap started out as a re-write of grml-debootstrap. Primarily, it can be used to create Debian/Ubuntu or Arch images ready to be launched as a virtual machine.

In comparison to grml-debootstrap, by now image-bootstrap

installs to block devices only,
supports several approaches to installing GRUB 2.x, or extlinux, or no bootloader at all,
does not leak the host's hostname into the resulting image,
supports passing the root password off the command-line (and the eyes of other users and shell history),
supports using a custom /etc/resolv.conf file (e.g. to not leak your home router model name from /etc/resolv.conf into the image),
has support for installing Arch Linux, Gentoo and Ubuntu (besides Debian),
is able to create OpenStack images,
is written in Python rather than Bash/mksh, and
has more friendly terminal output.

directory-bootstrap came into life with the arrival of support for Arch Linux. Support for Gentoo followed, after. Support for Void Linux ~~and CentOS~~ chroots came into live during 34c3, December 2017. Support for Alpine Linux chroots came into in March 2018.

Example run

The following is a complete demo of installing Debian stretch to LVM volume /dev/vg/lv and launching the resulting image using KVM.

# ${EDITOR} root_password.txt

# sudo image-bootstrap --hostname stretch debian \
                                  --password-file root_password.txt /dev/vg/lv
     _                          __             __      __
    (_)_ _  ___ ____ ____  ___ / /  ___  ___  / /____ / /________ ____
   / /  ' \/ _ `/ _ `/ -_)/__// _ \/ _ \/ _ \/ __(_-</ __/ __/ _ `/ _ \
  /_/_/_/_/\_,_/\_, /\__/    /_.__/\___/\___/\__/___/\__/_/  \_,_/ .__/
               /___/                      v0.9.1 :: 2015-07-11  /_/

Software libre licensed under AGPL v3 or later.
Brought to you by Sebastian Pipping <[email protected]>.
Please report bugs at https://github.com/hartwork/image-bootstrap.  Thank you!

Selected approach "chroot-grub2-drive" for bootloader installation.
Checking for blkid... /sbin/blkid
Checking for chmod... /bin/chmod
Checking for chroot... /usr/sbin/chroot
Checking for cp... /bin/cp
Checking for debootstrap... /usr/sbin/debootstrap
Checking for find... /usr/bin/find
Checking for kpartx... /sbin/kpartx
Checking for mkdir... /bin/mkdir
Checking for mkfs.ext4... /sbin/mkfs.ext4
Checking for mount... /bin/mount
Checking for parted... /sbin/parted
Checking for partprobe... /sbin/partprobe
Checking for rm... /bin/rm
Checking for rmdir... /bin/rmdir
Checking for sed... /bin/sed
Checking for tune2fs... /sbin/tune2fs
Checking for umount... /bin/umount
Checking for uname... /bin/uname
Checking for unshare... /usr/bin/unshare

Checking for known unsupported architecture/machine combination...
Checking if "/dev/vg/lv" is a block device...
Reading root password from file "/home/user1/root_password.txt"...
Unsharing Linux namespaces (mount, UTS/hostname)...
Partitioning "/dev/vg/lv"...
Activating partition devices...
Creating file system on "/dev/mapper/vg-lvp1"...
Creating directory "/mnt/tmpFczeFl"...
Mounting partitions...
Creating directory "/mnt/tmpFczeFl/etc"...
Writing file "/mnt/tmpFczeFl/etc/hostname"...
Writing file "/mnt/tmpFczeFl/etc/resolv.conf" (based on file "/etc/resolv.conf")...
Bootstrapping Debian "stretch" into "/mnt/tmpFczeFl"...
Writing file "/mnt/tmpFczeFl/etc/hostname"...
Writing file "/mnt/tmpFczeFl/etc/resolv.conf" (based on file "/etc/resolv.conf")...
Writing file "/mnt/tmpFczeFl/etc/fstab"...
Writing file "/mnt/tmpFczeFl/etc/network/interfaces"...
Running pre-chroot scripts...
Mounting non-disk file systems...
Setting root password...
Writing device map to "/mnt/tmpFczeFl/boot/grub/device.map" (mapping "(hd9999)" to "/dev/dm-8")...
Installing bootloader to device "/dev/vg/lv" (actually "/dev/dm-8", approach "chroot-grub2-drive")...
Generating GRUB configuration...
Post-processing GRUB config...
Generating initramfs...
Unmounting non-disk file systems...
Cleaning chroot apt cache...
Running post-chroot scripts...
Unmounting partitions...
Removing directory "/mnt/tmpFczeFl"...
Deactivating partition devices...
Done.

# sudo kvm -hda /dev/vg/lv

Without --color never, the output above is actually in color.

Speeding things up

Using RAM instead of HDD/SSD

If you run image-bootstrap repeatedly and have enough RAM, you may want to create images on RAM storage rather than on disk. I use a setup with

a loop device (to have a block device)
over a sparse file (to save space)
in a tmpfs mount (to use RAM).

For example (assuming you have /tmp in RAM already):

# sudo mount -o remount,size=6g /tmp
# truncate --size 3g /tmp/disk3g
# LOOP_DEVICE="$(losetup --show -f /tmp/disk3g)"

# image-bootstrap .... arch ... "${LOOP_DEVICE}"
# qemu-img convert -p -f raw -O qcow2 "${LOOP_DEVICE}" /var/lib/arch-$(date -I).qcow2

# losetup -d "${LOOP_DEVICE}"
# rm /tmp/disk3g

Apt-Cacher NG -- a cache specific to Debian/Ubuntu

When creating multiple images, a local instance of Apt-Cacher NG and passing --mirror http://localhost:3142/debian to image-bootstrap may come in handy.

Polipo -- a generic HTTP cache

For a distribution-agnostic cache, using Polipo can greatly speed up consecutive runs. Invoke image-bootstrap with

# http_proxy=http://127.0.0.1:8123/ image-bootstrap ...

when using Polipo with default port configuration.

haveged -- an entropy generator

During image creation, cryptographic keys may need to be generated, e.g. for the OpenSSH server, at least temporarily. As key generation relies on availability of entropy, image creation may take longer in environments that are slow at adding to the entropy pool. To speed things up, running haveged at the host system could be an option, especially since all keys should be deleted from images, eventually. Otherwise, there is a risk of ending up with multiple systems having the same key allowing for attacks. I am unsure of the quality of entropy that haveged produces. Use is at your own risk.

Debian package

As long as image-bootstrap as not available in Debian, you can make an image-bootstrap Debian package yourself easily from Git as follows:

# git clone https://github.com/hartwork/image-bootstrap.git
Cloning into 'image-bootstrap'...
[..]

# make -C image-bootstrap/ deb
[..]

# ls *.deb
image-bootstrap_0.9.1_all.deb

# sudo dpkg -i image-bootstrap_0.9.1_all.deb
[..]

Usage (`--help` output)

In general, the usage is:

image-bootstrap [..] DISTRIBUTION [..] DEVICE

image-bootstrap --hostname NAME [DISTRO_AGNOSTIC] DISTRIBUTION [DISTRO_SPECIFIC] DEVICE

in a bit more detail.

A dump of the current --help output would be:

# image-bootstrap --help
usage: image-bootstrap [-h] [--version] [--color {never,always,auto}]
                       [--debug] [--quiet] [--verbose] [--arch ARCHITECTURE]
                       [--bootloader {auto,chroot-grub2-device,chroot-grub2-drive,host-extlinux,host-grub2-device,host-grub2-drive,none}]
                       [--bootloader-force] [--hostname NAME] [--openstack]
                       [--password PASSWORD | --password-file FILE]
                       [--resolv-conf FILE] [--disk-id ID]
                       [--first-partition-uuid UUID] [--machine-id ID]
                       [--scripts-pre DIRECTORY] [--scripts-chroot DIRECTORY]
                       [--scripts-post DIRECTORY] [--grub2-install COMMAND]
                       [--cache-dir DIRECTORY]
                       DISTRIBUTION ... DEVICE

Command line tool for creating bootable virtual machine images

positional arguments:
  DEVICE                block device to install to

optional arguments:
  -h, --help            show this help message and exit
  --version             show program's version number and exit

text output configuration:
  --color {never,always,auto}
                        toggle output color (default: auto)
  --debug               enable debugging
  --quiet               limit output to error messages
  --verbose             increase verbosity

machine configuration:
  --arch ARCHITECTURE   architecture (e.g. amd64)
  --bootloader {auto,chroot-grub2-device,chroot-grub2-drive,host-extlinux,host-grub2-device,host-grub2-drive,none}
                        approach to take during bootloader installation
                        (default: auto)
  --bootloader-force    apply more force when installing bootloader (default:
                        disabled)
  --hostname NAME       hostname to set (default: "machine")
  --openstack           prepare for use with OpenStack (default: disabled)
  --password PASSWORD   root password to set (default: password log-in
                        disabled)
  --password-file FILE  file to read root password from (default: password
                        log-in disabled)
  --resolv-conf FILE    file to copy nameserver entries from (default:
                        /etc/resolv.conf)
  --disk-id ID          specific disk identifier to apply, e.g. 0x12345678
  --first-partition-uuid UUID
                        specific UUID to apply to first partition, e.g.
                        c1b9d5a2-f162-11cf-9ece-0020afc76f16
  --machine-id ID       specific machine identifier to apply, e.g.
                        c1b9d5a2f16211cf9ece0020afc76f16

script integration:
  --scripts-pre DIRECTORY
                        scripts to run prior to chrooting phase, in
                        alphabetical order
  --scripts-chroot DIRECTORY
                        scripts to run during chrooting phase, in alphabetical
                        order
  --scripts-post DIRECTORY
                        scripts to run after chrooting phase, in alphabetical
                        order

command names:
  --grub2-install COMMAND
                        override grub2-install command

general configuration:
  --cache-dir DIRECTORY
                        directory to use for downloads (default:
                        /var/cache/directory-bootstrap/)

subcommands (choice of distribution):
  Run "image-bootstrap DISTRIBUTION --help" for details on options specific to that distribution.

  DISTRIBUTION          choice of distribution, pick from:
    arch                Arch Linux
    debian              Debian GNU/Linux
    gentoo              Gentoo
    ubuntu              Ubuntu

     _                          __             __      __
    (_)_ _  ___ ____ ____  ___ / /  ___  ___  / /____ / /________ ____
   / /  ' \/ _ `/ _ `/ -_)/__// _ \/ _ \/ _ \/ __(_-</ __/ __/ _ `/ _ \
  /_/_/_/_/\_,_/\_, /\__/    /_.__/\___/\___/\__/___/\__/_/  \_,_/ .__/
               /___/                      v2.0.0 :: 2020-02-28  /_/

Software libre licensed under AGPL v3 or later.
Brought to you by Sebastian Pipping <[email protected]>.
Please report bugs at https://github.com/hartwork/image-bootstrap.  Thank you!

To show options specific to Debian, run ..

# image-bootstrap debian --help
usage: image-bootstrap debian [-h] [--debootstrap COMMAND] [--release RELEASE]
                              [--mirror URL] [--debootstrap-opt OPTION]

optional arguments:
  -h, --help            show this help message and exit
  --release RELEASE     specify Debian release (default: stretch)
  --mirror URL          specify Debian mirror to use (e.g.
                        http://localhost:3142/debian for a local instance of
                        apt-cacher-ng; default:
                        http://httpredir.debian.org/debian)
  --debootstrap-opt OPTION
                        option to pass to debootstrap, in addition; can be
                        passed several times; use with --debootstrap-opt=...
                        syntax, i.e. with "="

command names:
  --debootstrap COMMAND
                        override debootstrap command

Hints on using image-bootstrap within a pipe

If you want to run image-bootstrap in a pipe to capture its output to both stdout/stderr to a single log file, be sure to run image-bootstrap in unbuffered mode, e.g.:

python3 -u image-bootstrap [OPTIONS] 2>&1 | tee my.log

The default shebang generated by python setuptools does not use -u. It's also not easily possible to have it pass -u since the env command does allow for for passing command parameters only in fairly recent versions. As a consequence, stdout/stderr won't be synchronized and error output you see in a log file will not exactly correspond to preceding/succeeding output on stdout. (See issue #71 for more details.)

Moreover, if you're using Bash and you need to keep track of image-bootstrap's exit code, be sure to run set -o pipefail prior to invoking image-bootstrap. (Please see the Pipelines section of the GNU Bash Reference Manual for more details.)

Known limitations

Installing to partition block devices

Linux does not like partitions in partitions much. It can be tricked using device mapper, though.

This is how to install to a partition using another partition as a temporary target. The temporary target must

be 2 GiB in space or more (to hold the whole distribution) and
smaller or equal than the actually target (for the later copy to work).

# dmsetup create dm-linear-vda4 --table "0 $(blockdev --getsz /dev/vda4) linear /dev/vda4 0"
# image-bootstrap --openstack arch /dev/mapper/dm-linear-vda4
# partprobe /dev/mapper/dm-linear-vda4
# pv /dev/mapper/dm-linear-vda4p1 > /dev/vda2
# dmsetup remove dm-linear-vda4p1
# dmsetup remove dm-linear-vda4

(/dev/vda2 is the real target, /dev/vda4 the temporary one.)

There are other ways to achieve the same.

image-bootstrap's People

Contributors

Stargazers

Watchers

image-bootstrap's Issues

Cache images after initial bootstrap

When generating many images, it is kind of sad to have to go through the full bootstrapping process multiple times. I believe it should be possible to cache the image that results from doing the initial distro bootstrap, and then generate new images from that subsequently. This should save significant time when building more than one image.

Consider "console=tty0 console=ttyS0,115200" or so kernel parameters

What build-openstack-debian-image does:

echo "default linux
timeout 1
label linux
kernel /vmlinuz
append initrd=/initrd.img root=/dev/vda1 console=tty0 console=ttyS0,115200 ro" > ${MOUNT_DIR}/boot/extlinux/extlinux.conf
extlinux --install ${MOUNT_DIR}/boot/extlinux

Bootloader `host-extlinux` images not bootable (due to 64bit ext4)

Host OS: Arch Linux
Guest OS: Arch Linux
Boot loader: host-extlinux
(Additional flags: --openstack)

During boot (from #42 (comment)):

Boot from hard disk...
MBR

SYSLINUX 6.03 EDD ...
Failed to load ldlinux.c32

Enable DHCP client option 26/interface-mtu for Arch to support GRE environments with MTU 1454

Debian has it enabled:

debian@...:~$ sudo fgrep -RiI mtu /etc/ | fgrep -v certs                                                                                                                                                    
/etc/dhcp/dhclient.conf:        netbios-name-servers, netbios-scope, interface-mtu,

Arch does not:

[arch@... ~]$ sudo fgrep -Ri mtu /etc/ | fgrep -v cert
/etc/dhcpcd.conf:# Respect the network MTU.
/etc/dhcpcd.conf:# Some interface drivers reset when changing the MTU so disabled by default.
/etc/dhcpcd.conf:#option interface_mtu

grub install fails on EFI systems

I'm trying to construct an Arch Linux image from an Arch Linux host, and am running into some issue on the step that installs the bootloader. I first tried host-extlinux, which completed successfully, but resulted in an unbootable image. I then tried auto, which failed with a suspicious-looking:

Error: Command "chroot /mnt/tmp5bDk0h grub-install \(hd9999\)" returned non-zero exit status 1

I then finally went with host-grub2-device, which also errors, though with a slightly different error:

$ sudo image-bootstrap --hostname foobar --openstack --bootloader host-grub2-device arch $LOOP_DEVICE
     _                          __             __      __
    (_)_ _  ___ ____ ____  ___ / /  ___  ___  / /____ / /________ ____
   / /  ' \/ _ `/ _ `/ -_)/__// _ \/ _ \/ _ \/ __(_-</ __/ __/ _ `/ _ \
  /_/_/_/_/\_,_/\_, /\__/    /_.__/\___/\___/\__/___/\__/_/  \_,_/ .__/
               /___/                    v0.9.2.1 :: 2017-01-10  /_/

...

Installing bootloader to device "/dev/loop0" (approach "host-grub2-device")...
Unmounting partitions...
Removing directory "/mnt/tmpzijoWx"...
Deactivating partition devices...
Error: Command "grub-install --boot-directory /mnt/tmpzijoWx/boot /dev/loop0" returned non-zero exit status 1

I then decided to try this command manually, and ran:

$ sudo mkdir /mnt/tmpzijoWx
$ sudo mount /dev/loop0p1 /mnt/tmpzijoWx
$ sudo grub-install --boot-directory /mnt/tmpzijoWx/boot /dev/loop0
Installing for x86_64-efi platform.
grub-install: error: cannot find EFI directory.

So, it looks like GRUB (2.02) isn't super happy about trying to install itself onto a loop-mounted file-system, since it can't get at the necessary EFI variables. host-grub2-drive doesn't do much better:

...

Writing device map to "/mnt/tmpmGKytg/boot/grub/device.map" (mapping "(hd9999)" to "/dev/loop0")...
Installing bootloader to device "/dev/loop0" (approach "host-grub2-drive")...
Unmounting partitions...
Removing directory "/mnt/tmpmGKytg"...
Deactivating partition devices...
Error: Command "grub-install --boot-directory /mnt/tmpmGKytg/boot \(hd9999\)" returned non-zero exit status 1

Inspect why /etc/udev/rules.d/80-net-setup-link.rules is re-generated (e.g. with Arch) and if that's a problem

The current symlink from /etc/udev/rules.d/80-net-setup-link.rules to /dev/null is overritten, it seems.
Find out why and if that's a problem.

GPG of Debian jessie does not support --no-autostart

gpg: Invalid option "--no-autostart"

Better resolv.conf defaults

My server could not resolve anything from 5.102.144.102 which is the default entry in /etc/resolv.conf in the images you produce. Making the default something more accessible like 8.8.8.8 and 8.8.4.4 would help a lot of users.

Arch: gpg: keyserver option 'ca-cert-file' is obsolete; please use 'hkp-cacert' in dirmngr.conf

[..]
# unshare --fork --pid gpg --home /tmp/tmpTVhh3u/gpg_home --keyid-format 0xlong --batch --keyserver hkps://hkps.pool.sks-keyservers.net --keyserver-options ca-cert-file=/tmp/tmpTVhh3u/gpg_home/sks-keyservers.netCA.pem --receive-keys F3691687D867B81B51CE07D9BBE43771487328A9
gpg: keyserver option 'ca-cert-file' is obsolete; please use 'hkp-cacert' in dirmngr.conf
[..]

cloud-init seems to not configure the network nameserver

/etc/resolv.conf still have the static configuration from the image after boot.

Tested with latest Arch Linux image 02032016

Have image-bootstrap delete /etc/resolv.conf, eventually

Crashes when no Colorama and/or no Beautiful Soup installed

# ./image-bootstrap .....................
Traceback (most recent call last):
  File "./image-bootstrap", line 5, in <module>
    from image_bootstrap.cli import main
  File "/root/image-bootstrap/image_bootstrap/cli.py", line 10, in <module>
    from directory_bootstrap.shared.messenger import Messenger, BANNER, \
  File "/root/image-bootstrap/directory_bootstrap/shared/messenger.py", line 9, in <module>
    from colorama import Fore, Style
ImportError: No module named colorama

Delete generated SSH keys

Gentoo shutdown seen take quite a while to start

Maybe sys-power/acpid can help?

cloud-init >=0.7.7: Arch cloud-init fails to set hostname

In reference to issue #34. Hostname is not set on the latest arch image.

-- Logs begin at Tue 2016-09-20 11:05:40 UTC, end at Tue 2016-09-20 11:41:08 UTC. --
Sep 20 11:05:42 machine systemd[1]: Starting Initial cloud-init job (metadata service crawler)...
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Cloud-init v. 0.7.7 running 'init' at Tue, 20 Sep 2016 11:05:43 +0000. Up 4.46 seconds.
Sep 20 11:05:43 machine cloud-init[283]: Cloud-init v. 0.7.7 running 'init' at Tue, 20 Sep 2016 11:05:43 +0000. Up 4.46 seconds.
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [420] 0 bytes
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ['ifconfig', '-a'] with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ['netstat', '-rn'] with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ['netstat', '-A', 'inet6', '-n'] with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] main.py[DEBUG]: Checking to see if files that we need already exist from a previous run that would allow us to stop early.
Sep 20 11:05:43 machine cloud-init[283]: ci-info: +++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++
Sep 20 11:05:43 machine cloud-init[283]: ci-info: +--------+------+-----------+-----------+-------+-------------------+
Sep 20 11:05:43 machine cloud-init[283]: ci-info: | Device |  Up  |  Address  |    Mask   | Scope |     Hw-Address    |
Sep 20 11:05:43 machine cloud-init[283]: ci-info: +--------+------+-----------+-----------+-------+-------------------+
Sep 20 11:05:43 machine cloud-init[283]: ci-info: |  lo:   | True | 127.0.0.1 | 255.0.0.0 |   .   |         .         |
Sep 20 11:05:43 machine cloud-init[283]: ci-info: |  lo:   | True |     .     |     .     |   d   |         .         |
Sep 20 11:05:43 machine cloud-init[283]: ci-info: | eth0:  | True |     .     |     .     |   .   | fa:16:3e:cc:dd:4c |
Sep 20 11:05:43 machine cloud-init[283]: ci-info: | eth0:  | True |     .     |     .     |   d   | fa:16:3e:cc:dd:4c |
Sep 20 11:05:43 machine cloud-init[283]: ci-info: +--------+------+-----------+-----------+-------+-------------------+
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] main.py[DEBUG]: Execution continuing, no previous run detected that would allow us to stop early.
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust]
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: no cache found
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: no cache found
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.arch.Distro'>
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] __init__.py[DEBUG]: Looking for for data source in: ['ConfigDrive', 'Openstack', 'Ec2'], via packages ['', u'cloudinit.sources'] that matches dependencies ['FILESYSTEM', 'NETWORK']
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] __init__.py[DEBUG]: Searching for network data source in: [u'DataSourceEc2']
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/search-Ec2: searching for network data from DataSourceEc2
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] __init__.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceEc2.DataSourceEc2'>
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/seed/ec2/meta-data (quiet=False)
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] DataSourceEc2.py[DEBUG]: Removed the following from metadata urls: ['http://instance-data.:8773']
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/1] open 'http://169.254.169.254/2009-04-04/meta-data/instance-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/instance-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 50.0} configuration
Sep 20 11:05:43 machine cloud-init[283]: 2016-09-20 11:05:43,200 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [0/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63910>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [0/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63910>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:43 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Please wait 1 seconds while we wait to try again
Sep 20 11:05:44 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/1] open 'http://169.254.169.254/2009-04-04/meta-data/instance-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/instance-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 50.0} configuration
Sep 20 11:05:44 machine cloud-init[283]: 2016-09-20 11:05:44,208 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [1/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63c90>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:44 machine cloud-init[283]: [CLOUDINIT] url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [1/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63c90>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:44 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Please wait 1 seconds while we wait to try again
Sep 20 11:05:45 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/1] open 'http://169.254.169.254/2009-04-04/meta-data/instance-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/instance-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 50.0} configuration
Sep 20 11:05:45 machine cloud-init[283]: 2016-09-20 11:05:45,211 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [2/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63b90>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:45 machine cloud-init[283]: [CLOUDINIT] url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [2/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63b90>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:45 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Please wait 1 seconds while we wait to try again
Sep 20 11:05:46 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/1] open 'http://169.254.169.254/2009-04-04/meta-data/instance-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/instance-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 50.0} configuration
Sep 20 11:05:46 machine cloud-init[283]: 2016-09-20 11:05:46,215 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [3/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63c50>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:46 machine cloud-init[283]: [CLOUDINIT] url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [3/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63c50>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:46 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Please wait 1 seconds while we wait to try again
Sep 20 11:05:47 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/1] open 'http://169.254.169.254/2009-04-04/meta-data/instance-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/instance-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 50.0} configuration
Sep 20 11:05:47 machine cloud-init[283]: 2016-09-20 11:05:47,218 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [4/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63b10>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:47 machine cloud-init[283]: [CLOUDINIT] url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [4/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63b10>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:47 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Please wait 1 seconds while we wait to try again
Sep 20 11:05:48 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/1] open 'http://169.254.169.254/2009-04-04/meta-data/instance-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/instance-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 50.0} configuration
Sep 20 11:05:48 machine cloud-init[283]: 2016-09-20 11:05:48,221 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [5/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63110>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:48 machine cloud-init[283]: [CLOUDINIT] url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [5/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63110>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:48 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Please wait 2 seconds while we wait to try again
Sep 20 11:05:50 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/1] open 'http://169.254.169.254/2009-04-04/meta-data/instance-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/instance-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 50.0} configuration
Sep 20 11:05:50 machine cloud-init[283]: 2016-09-20 11:05:50,225 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [7/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63b50>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:50 machine cloud-init[283]: [CLOUDINIT] url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [7/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63b50>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:50 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Please wait 2 seconds while we wait to try again
Sep 20 11:05:52 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/1] open 'http://169.254.169.254/2009-04-04/meta-data/instance-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/instance-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 50.0} configuration
Sep 20 11:05:52 machine cloud-init[283]: 2016-09-20 11:05:52,235 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [9/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63bd0>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:52 machine cloud-init[283]: [CLOUDINIT] url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [9/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63bd0>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:52 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Please wait 2 seconds while we wait to try again
Sep 20 11:05:54 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/1] open 'http://169.254.169.254/2009-04-04/meta-data/instance-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/instance-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 50.0} configuration
Sep 20 11:05:54 machine cloud-init[283]: 2016-09-20 11:05:54,245 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [11/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63c10>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:54 machine cloud-init[283]: [CLOUDINIT] url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [11/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63c10>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:54 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Please wait 2 seconds while we wait to try again
Sep 20 11:05:56 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/1] open 'http://169.254.169.254/2009-04-04/meta-data/instance-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/instance-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 50.0} configuration
Sep 20 11:05:56 machine cloud-init[283]: 2016-09-20 11:05:56,254 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [13/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63ad0>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:56 machine cloud-init[283]: [CLOUDINIT] url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [13/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63ad0>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:56 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Please wait 2 seconds while we wait to try again
Sep 20 11:05:58 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/1] open 'http://169.254.169.254/2009-04-04/meta-data/instance-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/instance-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 50.0} configuration
Sep 20 11:05:58 machine cloud-init[283]: 2016-09-20 11:05:58,259 - url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [15/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63890>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:58 machine cloud-init[283]: [CLOUDINIT] url_helper.py[WARNING]: Calling 'http://169.254.169.254/2009-04-04/meta-data/instance-id' failed [15/120s]: request error [HTTPConnectionPool(host='169.254.169.254', port=80): Max retries exceeded with url: /2009-04-04/meta-data/instance-id (Caused by NewConnectionError('<requests.packages.urllib3.connection.HTTPConnection object at 0x7fa474e63890>: Failed to establish a new connection: [Errno 101] Network is unreachable',))]
Sep 20 11:05:58 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Please wait 3 seconds while we wait to try again
Sep 20 11:06:01 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/1] open 'http://169.254.169.254/2009-04-04/meta-data/instance-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/instance-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 50.0} configuration
Sep 20 11:06:02 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/instance-id (200, 10b) after 1 attempts
Sep 20 11:06:02 machine cloud-init[283]: [CLOUDINIT] DataSourceEc2.py[DEBUG]: Using metadata source: 'http://169.254.169.254'
Sep 20 11:06:02 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/user-data' with {'url': 'http://169.254.169.254/2009-04-04/user-data', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:02 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:04 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/ (200, 244b) after 1 attempts
Sep 20 11:06:04 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/block-device-mapping/' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/block-device-mapping/', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/block-device-mapping/ (200, 8b) after 1 attempts
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/block-device-mapping/ami' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/block-device-mapping/ami', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/block-device-mapping/ami (200, 3b) after 1 attempts
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/block-device-mapping/root' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/block-device-mapping/root', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/block-device-mapping/root (200, 8b) after 1 attempts
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/placement/' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/placement/', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/placement/ (200, 17b) after 1 attempts
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/placement/availability-zone' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/placement/availability-zone', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/placement/availability-zone (200, 4b) after 1 attempts
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/public-keys/' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/public-keys/', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/public-keys/ (200, 7b) after 1 attempts
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/public-keys/0/openssh-key' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/public-keys/0/openssh-key', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/public-keys/0/openssh-key (200, 393b) after 1 attempts
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/local-ipv4' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/local-ipv4', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/local-ipv4 (200, 12b) after 1 attempts
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/reservation-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/reservation-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/reservation-id (200, 10b) after 1 attempts
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/local-hostname' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/local-hostname', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/local-hostname (200, 4b) after 1 attempts
Sep 20 11:06:05 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/security-groups' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/security-groups', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/security-groups (200, 0b) after 1 attempts
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/ami-launch-index' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/ami-launch-index', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/ami-launch-index (200, 1b) after 1 attempts
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/ramdisk-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/ramdisk-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/ramdisk-id (200, 4b) after 1 attempts
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/public-hostname' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/public-hostname', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/public-hostname (200, 4b) after 1 attempts
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/hostname' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/hostname', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/hostname (200, 4b) after 1 attempts
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/ami-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/ami-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/ami-id (200, 12b) after 1 attempts
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/kernel-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/kernel-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/kernel-id (200, 4b) after 1 attempts
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/instance-action' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/instance-action', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/instance-action (200, 4b) after 1 attempts
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/public-ipv4' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/public-ipv4', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/public-ipv4 (200, 0b) after 1 attempts
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/ami-manifest-path' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/ami-manifest-path', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/ami-manifest-path (200, 5b) after 1 attempts
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/instance-type' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/instance-type', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/instance-type (200, 9b) after 1 attempts
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/2009-04-04/meta-data/instance-id' with {'url': 'http://169.254.169.254/2009-04-04/meta-data/instance-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.7'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] url_helper.py[DEBUG]: Read from http://169.254.169.254/2009-04-04/meta-data/instance-id (200, 10b) after 1 attempts
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] DataSourceEc2.py[DEBUG]: Crawl of metadata service took 3 seconds
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/search-Ec2: SUCCESS: found network data from DataSourceEc2
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[INFO]: Loaded datasource DataSourceEc2 - DataSourceEc2
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 1849 bytes from /etc/cloud/cloud.cfg
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 1849 with allowed root types (<type 'dict'>,)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_datasource.cfg (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 79 bytes from /etc/cloud/cloud.cfg.d/90_datasource.cfg
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 79 with allowed root types (<type 'dict'>,)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 1910 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 1910 with allowed root types (<type 'dict'>,)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_syslog_fix_perms.cfg (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 23 bytes from /etc/cloud/cloud.cfg.d/00_syslog_fix_perms.cfg
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 23 with allowed root types (<type 'dict'>,)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/i-005e688a'
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instances/i-005e688a/datasource (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-005e688a/datasource - wb: [420] 29 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [420] 29 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: previous iid found to be NO_PREVIOUS_INSTANCE_ID
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [420] 11 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [420] 11 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [420] 24 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [256] 6502 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] main.py[DEBUG]: [net] init will now be targeting instance id: i-005e688a. new=True
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 1849 bytes from /etc/cloud/cloud.cfg
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 1849 with allowed root types (<type 'dict'>,)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_datasource.cfg (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 79 bytes from /etc/cloud/cloud.cfg.d/90_datasource.cfg
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 79 with allowed root types (<type 'dict'>,)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 1910 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 1910 with allowed root types (<type 'dict'>,)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_syslog_fix_perms.cfg (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 23 bytes from /etc/cloud/cloud.cfg.d/00_syslog_fix_perms.cfg
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 23 with allowed root types (<type 'dict'>,)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/net/eth0/carrier (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/carrier
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: applying net config names for {'version': 1, 'config': [{'subnets': [{'type': 'dhcp'}], 'type': 'physical', 'name': 'eth0', 'mac_address': u'fa:16:3e:cc:dd:4c'}]}
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.arch.Distro'>
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 8 bytes from /sys/class/net/lo/operstate
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/net/eth0/operstate (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 3 bytes from /sys/class/net/eth0/operstate
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] __init__.py[DEBUG]: no work necessary for renaming of [[u'fa:16:3e:cc:dd:4c', 'eth0']]
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[INFO]: Applying network configuration from fallback bringup=True: {'version': 1, 'config': [{'subnets': [{'type': 'dhcp'}], 'type': 'physical', 'name': 'eth0', 'mac_address': u'fa:16:3e:cc:dd:4c'}]}
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] __init__.py[WARNING]: apply_network_config is not currently implemented for distribution '<class 'cloudinit.distros.arch.Distro'>'.  Attempting to use apply_network
Sep 20 11:06:06 machine cloud-init[283]: 2016-09-20 11:06:06,268 - __init__.py[WARNING]: apply_network_config is not currently implemented for distribution '<class 'cloudinit.distros.arch.Distro'>'.  Attempting to use apply_network
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] arch.py[DEBUG]: Translated ubuntu style network settings # Converted from network_config for distro <class 'cloudinit.distros.arch.Distro'>
                                         # Implmentation of _write_network_config is needed.
                                         auto lo
                                         iface lo inet loopback

                                         auto eth0
                                         iface eth0 inet dhcp
                                             hwaddress fa:16:3e:cc:dd:4c
                                          into {'lo': {'auto': True, 'ipv6': {}}, 'eth0': {'auto': True, 'bootproto': 'dhcp', 'ipv6': {}}}
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /etc/netctllo - wb: [420] 83 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ['netctl', 'reenable', 'lo'] with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[WARNING]: Running interface command ['netctl', 'reenable', 'lo'] failed
Sep 20 11:06:06 machine cloud-init[283]: 2016-09-20 11:06:06,376 - util.py[WARNING]: Running interface command ['netctl', 'reenable', 'lo'] failed
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running interface command ['netctl', 'reenable', 'lo'] failed
                                         Traceback (most recent call last):
                                           File "/usr/lib/python2.7/site-packages/cloudinit/distros/arch.py", line 96, in _enable_interface
                                             (_out, err) = util.subp(cmd)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/util.py", line 1724, in subp
                                             cmd=args)
                                         ProcessExecutionError: Unexpected error while running command.
                                         Command: ['netctl', 'reenable', 'lo']
                                         Exit code: 1
                                         Reason: -
                                         Stdout: ''
                                         Stderr: "<3>No regular unit file found for profile 'lo'\n<3>Profile 'lo' does not exist or is not readable\n"
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /etc/netctleth0 - wb: [420] 85 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ['netctl', 'reenable', 'eth0'] with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[WARNING]: Running interface command ['netctl', 'reenable', 'eth0'] failed
Sep 20 11:06:06 machine cloud-init[283]: 2016-09-20 11:06:06,465 - util.py[WARNING]: Running interface command ['netctl', 'reenable', 'eth0'] failed
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running interface command ['netctl', 'reenable', 'eth0'] failed
                                         Traceback (most recent call last):
                                           File "/usr/lib/python2.7/site-packages/cloudinit/distros/arch.py", line 96, in _enable_interface
                                             (_out, err) = util.subp(cmd)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/util.py", line 1724, in subp
                                             cmd=args)
                                         ProcessExecutionError: Unexpected error while running command.
                                         Command: ['netctl', 'reenable', 'eth0']
                                         Exit code: 1
                                         Reason: -
                                         Stdout: ''
                                         Stderr: "<3>No regular unit file found for profile 'eth0'\n<3>Profile 'eth0' does not exist or is not readable\n"
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] arch.py[DEBUG]: Attempting to run bring up interface lo using command ['netctl', 'restart', 'lo']
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ['netctl', 'restart', 'lo'] with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[WARNING]: Running interface command ['netctl', 'restart', 'lo'] failed
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running interface command ['netctl', 'restart', 'lo'] failed
                                         Traceback (most recent call last):
                                           File "/usr/lib/python2.7/site-packages/cloudinit/distros/arch.py", line 107, in _bring_up_interface
                                             (_out, err) = util.subp(cmd)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/util.py", line 1724, in subp
                                             cmd=args)
                                         ProcessExecutionError: Unexpected error while running command.
                                         Command: ['netctl', 'restart', 'lo']
                                         Exit code: 1
                                         Reason: -
                                         Stdout: ''
                                         Stderr: 'Job for [email protected] failed because the control process exited with error code.\nSee "systemctl status [email protected]" and "journalctl -xe" for details.\n'
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-005e688a/user-data.txt - wb: [384] 0 bytes
Sep 20 11:06:06 machine cloud-init[283]: 2016-09-20 11:06:06,504 - util.py[WARNING]: Running interface command ['netctl', 'restart', 'lo'] failed
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-005e688a/user-data.txt.i - wb: [384] 345 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-005e688a/vendor-data.txt - wb: [384] 0 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-005e688a/vendor-data.txt.i - wb: [384] 345 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-005e688a/sem/consume_data - wb: [420] 19 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] helpers.py[DEBUG]: Running consume_data using lock (<FileLock using file '/var/lib/cloud/instances/i-005e688a/sem/consume_data'>)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/consume-user-data: reading and applying user-data
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] launch_index.py[DEBUG]: Discarding 0 multipart messages which do not match launch index 0
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Added default handler for set(['text/cloud-config-jsonp', 'text/cloud-config']) from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']]
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Added default handler for set(['text/x-shellscript']) from ShellScriptPartHandler: [['text/x-shellscript']]
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Added default handler for set(['text/cloud-boothook']) from BootHookPartHandler: [['text/cloud-boothook']]
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Added default handler for set(['text/upstart-job']) from UpstartJobPartHandler: [['text/upstart-job']]
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__begin__, None, 2) with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] __init__.py[DEBUG]: {'Content-Type': 'text/x-not-multipart', 'Content-Disposition': 'attachment; filename="part-001"', 'MIME-Version': '1.0'}
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] __init__.py[DEBUG]: Empty payload of type text/x-not-multipart
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__end__, None, 2) with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-005e688a/cloud-config.txt - wb: [384] 0 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/consume-user-data: SUCCESS: reading and applying user-data
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/consume-vendor-data: reading and applying vendor-data
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: no vendordata from datasource
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/consume-vendor-data: SUCCESS: reading and applying vendor-data
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 1849 bytes from /etc/cloud/cloud.cfg
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 1849 with allowed root types (<type 'dict'>,)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_datasource.cfg (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 79 bytes from /etc/cloud/cloud.cfg.d/90_datasource.cfg
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 79 with allowed root types (<type 'dict'>,)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 1910 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 1910 with allowed root types (<type 'dict'>,)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_syslog_fix_perms.cfg (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 23 bytes from /etc/cloud/cloud.cfg.d/00_syslog_fix_perms.cfg
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 23 with allowed root types (<type 'dict'>,)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<type 'dict'>,)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: load_yaml given empty string, returning default
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<type 'dict'>,)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: load_yaml given empty string, returning default
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.arch.Distro'>
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[INFO]: Skipping modules ['ca-certs'] because they are not verified on distro 'arch'.  To run anyway, add them to 'unverified_modules' in config.
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Running module migrator (<module 'cloudinit.config.cc_migrator' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_migrator.pyc'>) with frequency always
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-migrator: running config-migrator with frequency always
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] helpers.py[DEBUG]: Running config-migrator using lock (<cloudinit.helpers.DummyLock object at 0x7fa4745583d0>)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] cc_migrator.py[DEBUG]: Migrated 0 semaphore files to there canonicalized names
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-migrator: SUCCESS: config-migrator ran successfully
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Running module ubuntu-init-switch (<module 'cloudinit.config.cc_ubuntu_init_switch' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_ubuntu_init_switch.pyc'>) with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-ubuntu-init-switch: running config-ubuntu-init-switch with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-005e688a/sem/config_ubuntu_init_switch - wb: [420] 19 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] helpers.py[DEBUG]: Running config-ubuntu-init-switch using lock (<FileLock using file '/var/lib/cloud/instances/i-005e688a/sem/config_ubuntu_init_switch'>)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] cc_ubuntu_init_switch.py[DEBUG]: ubuntu-init-switch: distro is '<class 'cloudinit.distros.arch.Distro'>', not ubuntu. returning
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-ubuntu-init-switch: SUCCESS: config-ubuntu-init-switch ran successfully
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Running module seed_random (<module 'cloudinit.config.cc_seed_random' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_seed_random.pyc'>) with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-seed_random: running config-seed_random with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-005e688a/sem/config_seed_random - wb: [420] 19 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] helpers.py[DEBUG]: Running config-seed_random using lock (<FileLock using file '/var/lib/cloud/instances/i-005e688a/sem/config_seed_random'>)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] cc_seed_random.py[DEBUG]: no command provided
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-seed_random: SUCCESS: config-seed_random ran successfully
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Running module bootcmd (<module 'cloudinit.config.cc_bootcmd' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_bootcmd.pyc'>) with frequency always
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-bootcmd: running config-bootcmd with frequency always
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] helpers.py[DEBUG]: Running config-bootcmd using lock (<cloudinit.helpers.DummyLock object at 0x7fa4745586d0>)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] cc_bootcmd.py[DEBUG]: Skipping module named bootcmd, no 'bootcmd' key in configuration
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-bootcmd: SUCCESS: config-bootcmd ran successfully
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Running module write-files (<module 'cloudinit.config.cc_write_files' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_write_files.pyc'>) with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-write-files: running config-write-files with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-005e688a/sem/config_write_files - wb: [420] 19 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] helpers.py[DEBUG]: Running config-write-files using lock (<FileLock using file '/var/lib/cloud/instances/i-005e688a/sem/config_write_files'>)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] cc_write_files.py[DEBUG]: Skipping module named write-files, no/empty 'write_files' key in configuration
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-write-files: SUCCESS: config-write-files ran successfully
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Running module growpart (<module 'cloudinit.config.cc_growpart' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_growpart.pyc'>) with frequency always
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-growpart: running config-growpart with frequency always
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] helpers.py[DEBUG]: Running config-growpart using lock (<cloudinit.helpers.DummyLock object at 0x7fa474558790>)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] cc_growpart.py[DEBUG]: No 'growpart' entry in cfg.  Using default: {'ignore_growroot_disabled': False, 'mode': 'auto', 'devices': ['/']}
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ['growpart', '--help'] with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/283/mountinfo (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 2329 bytes from /proc/283/mountinfo
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/class/block/vda1/partition (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 2 bytes from /sys/class/block/vda1/partition
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /sys/devices/pci0000:00/0000:00:04.0/virtio1/block/vda/dev (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 6 bytes from /sys/devices/pci0000:00/0000:00:04.0/virtio1/block/vda/dev
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ['growpart', '--dry-run', u'/dev/vda', u'1'] with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ['growpart', u'/dev/vda', u'1'] with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: resize_devices took 0.255 seconds
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] cc_growpart.py[INFO]: '/' resized: changed (/dev/vda, 1) from 2146435072 to 10736352768
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-growpart: SUCCESS: config-growpart ran successfully
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Running module resizefs (<module 'cloudinit.config.cc_resizefs' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_resizefs.pyc'>) with frequency always
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-resizefs: running config-resizefs with frequency always
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] helpers.py[DEBUG]: Running config-resizefs using lock (<cloudinit.helpers.DummyLock object at 0x7fa474558850>)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/283/mountinfo (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 2329 bytes from /proc/283/mountinfo
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] cc_resizefs.py[DEBUG]: resize_info: dev=/dev/vda1 mnt_point=/ path=/
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ['running-in-container'] with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ['lxc-is-container'] with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/1/environ (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 11 bytes from /proc/1/environ
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/self/status (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 965 bytes from /proc/self/status
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] cc_resizefs.py[DEBUG]: Resizing / (ext4) using resize2fs /dev/vda1
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ('resize2fs', u'/dev/vda1') with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Resizing took 0.067 seconds
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] cc_resizefs.py[DEBUG]: Resized root filesystem (type=ext4, val=True)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-resizefs: SUCCESS: config-resizefs ran successfully
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Running module set_hostname (<module 'cloudinit.config.cc_set_hostname' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_set_hostname.pyc'>) with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-set_hostname: running config-set_hostname with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-005e688a/sem/config_set_hostname - wb: [420] 19 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] helpers.py[DEBUG]: Running config-set_hostname using lock (<FileLock using file '/var/lib/cloud/instances/i-005e688a/sem/config_set_hostname'>)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] cc_set_hostname.py[DEBUG]: Setting the hostname to dev1.localdomain (dev1)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/hostname (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 8 bytes from /etc/hostname
Sep 20 11:06:06 machine cloud-init[283]: 2016-09-20 11:06:06,956 - util.py[WARNING]: Failed to set the hostname to dev1.localdomain (dev1)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[WARNING]: Failed to set the hostname to dev1.localdomain (dev1)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Failed to set the hostname to dev1.localdomain (dev1)
                                         Traceback (most recent call last):
                                           File "/usr/lib/python2.7/site-packages/cloudinit/config/cc_set_hostname.py", line 33, in handle
                                             cloud.distro.set_hostname(hostname, fqdn)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/distros/__init__.py", line 96, in set_hostname
                                             self._write_hostname(writeable_hostname, self.hostname_conf_fn)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/distros/arch.py", line 132, in _write_hostname
                                             util.write_file(out_fn, conf, 0o644)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/util.py", line 1662, in write_file
                                             content = encode_text(content)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/util.py", line 97, in encode_text
                                             return text.encode(encoding)
                                         AttributeError: 'HostnameConf' object has no attribute 'encode'
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-set_hostname: FAIL: running config-set_hostname with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: 2016-09-20 11:06:06,960 - util.py[WARNING]: Running module set_hostname (<module 'cloudinit.config.cc_set_hostname' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_set_hostname.pyc'>) failed
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[WARNING]: Running module set_hostname (<module 'cloudinit.config.cc_set_hostname' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_set_hostname.pyc'>) failed
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running module set_hostname (<module 'cloudinit.config.cc_set_hostname' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_set_hostname.pyc'>) failed
                                         Traceback (most recent call last):
                                           File "/usr/lib/python2.7/site-packages/cloudinit/stages.py", line 785, in _run_modules
                                             freq=freq)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/cloud.py", line 70, in run
                                             return self._runners.run(name, functor, args, freq, clear_on_fail)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/helpers.py", line 199, in run
                                             results = functor(*args)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/config/cc_set_hostname.py", line 33, in handle
                                             cloud.distro.set_hostname(hostname, fqdn)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/distros/__init__.py", line 96, in set_hostname
                                             self._write_hostname(writeable_hostname, self.hostname_conf_fn)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/distros/arch.py", line 132, in _write_hostname
                                             util.write_file(out_fn, conf, 0o644)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/util.py", line 1662, in write_file
                                             content = encode_text(content)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/util.py", line 97, in encode_text
                                             return text.encode(encoding)
                                         AttributeError: 'HostnameConf' object has no attribute 'encode'
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Running module update_hostname (<module 'cloudinit.config.cc_update_hostname' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_update_hostname.pyc'>) with frequency always
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-update_hostname: running config-update_hostname with frequency always
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] helpers.py[DEBUG]: Running config-update_hostname using lock (<cloudinit.helpers.DummyLock object at 0x7fa47458d910>)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] cc_update_hostname.py[DEBUG]: Updating hostname to dev1.localdomain (dev1)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/hostname (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 8 bytes from /etc/hostname
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] __init__.py[DEBUG]: Attempting to update hostname to dev1 in 1 files
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/data/previous-hostname (quiet=False)
Sep 20 11:06:06 machine cloud-init[283]: 2016-09-20 11:06:06,965 - util.py[WARNING]: Failed to update the hostname to dev1.localdomain (dev1)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[WARNING]: Failed to update the hostname to dev1.localdomain (dev1)
Sep 20 11:06:06 machine cloud-init[283]: 2016-09-20 11:06:06,970 - util.py[WARNING]: Running module update_hostname (<module 'cloudinit.config.cc_update_hostname' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_update_hostname.pyc'>) failed
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Failed to update the hostname to dev1.localdomain (dev1)
                                         Traceback (most recent call last):
                                           File "/usr/lib/python2.7/site-packages/cloudinit/config/cc_update_hostname.py", line 39, in handle
                                             cloud.distro.update_hostname(hostname, fqdn, prev_fn)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/distros/__init__.py", line 277, in update_hostname
                                             self._write_hostname(hostname, fn)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/distros/arch.py", line 132, in _write_hostname
                                             util.write_file(out_fn, conf, 0o644)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/util.py", line 1662, in write_file
                                             content = encode_text(content)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/util.py", line 97, in encode_text
                                             return text.encode(encoding)
                                         AttributeError: 'HostnameConf' object has no attribute 'encode'
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-update_hostname: FAIL: running config-update_hostname with frequency always
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[WARNING]: Running module update_hostname (<module 'cloudinit.config.cc_update_hostname' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_update_hostname.pyc'>) failed
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running module update_hostname (<module 'cloudinit.config.cc_update_hostname' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_update_hostname.pyc'>) failed
                                         Traceback (most recent call last):
                                           File "/usr/lib/python2.7/site-packages/cloudinit/stages.py", line 785, in _run_modules
                                             freq=freq)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/cloud.py", line 70, in run
                                             return self._runners.run(name, functor, args, freq, clear_on_fail)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/helpers.py", line 199, in run
                                             results = functor(*args)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/config/cc_update_hostname.py", line 39, in handle
                                             cloud.distro.update_hostname(hostname, fqdn, prev_fn)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/distros/__init__.py", line 277, in update_hostname
                                             self._write_hostname(hostname, fn)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/distros/arch.py", line 132, in _write_hostname
                                             util.write_file(out_fn, conf, 0o644)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/util.py", line 1662, in write_file
                                             content = encode_text(content)
                                           File "/usr/lib/python2.7/site-packages/cloudinit/util.py", line 97, in encode_text
                                             return text.encode(encoding)
                                         AttributeError: 'HostnameConf' object has no attribute 'encode'
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Running module update_etc_hosts (<module 'cloudinit.config.cc_update_etc_hosts' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_update_etc_hosts.pyc'>) with frequency always
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-update_etc_hosts: running config-update_etc_hosts with frequency always
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] helpers.py[DEBUG]: Running config-update_etc_hosts using lock (<cloudinit.helpers.DummyLock object at 0x7fa47458db10>)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] cc_update_etc_hosts.py[DEBUG]: Configuration option 'manage_etc_hosts' is not set, not managing /etc/hosts in module update_etc_hosts
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-update_etc_hosts: SUCCESS: config-update_etc_hosts ran successfully
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Running module ca-certs (<module 'cloudinit.config.cc_ca_certs' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_ca_certs.pyc'>) with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-ca-certs: running config-ca-certs with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-005e688a/sem/config_ca_certs - wb: [420] 19 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] helpers.py[DEBUG]: Running config-ca-certs using lock (<FileLock using file '/var/lib/cloud/instances/i-005e688a/sem/config_ca_certs'>)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] cc_ca_certs.py[DEBUG]: Skipping module named ca-certs, no 'ca-certs' key in configuration
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-ca-certs: SUCCESS: config-ca-certs ran successfully
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Running module rsyslog (<module 'cloudinit.config.cc_rsyslog' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_rsyslog.pyc'>) with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-rsyslog: running config-rsyslog with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-005e688a/sem/config_rsyslog - wb: [420] 19 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] helpers.py[DEBUG]: Running config-rsyslog using lock (<FileLock using file '/var/lib/cloud/instances/i-005e688a/sem/config_rsyslog'>)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] cc_rsyslog.py[DEBUG]: Skipping module named rsyslog, no 'rsyslog' key in configuration
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-rsyslog: SUCCESS: config-rsyslog ran successfully
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Running module users-groups (<module 'cloudinit.config.cc_users_groups' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_users_groups.pyc'>) with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-users-groups: running config-users-groups with frequency once-per-instance
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-005e688a/sem/config_users_groups - wb: [420] 19 bytes
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] helpers.py[DEBUG]: Running config-users-groups using lock (<FileLock using file '/var/lib/cloud/instances/i-005e688a/sem/config_users_groups'>)
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] __init__.py[INFO]: User arch already exists, skipping.
Sep 20 11:06:06 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ['passwd', '-l', 'arch'] with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:06:07 machine passwd[410]: password for 'arch' changed by 'root'
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/sudoers (quiet=False)
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 3174 bytes from /etc/sudoers
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /etc/sudoers.d/90-cloud-init-users - wb: [288] 119 bytes
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-users-groups: SUCCESS: config-users-groups ran successfully
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] stages.py[DEBUG]: Running module ssh (<module 'cloudinit.config.cc_ssh' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_ssh.pyc'>) with frequency once-per-instance
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: start: init-network/config-ssh: running config-ssh with frequency once-per-instance
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/i-005e688a/sem/config_ssh - wb: [420] 19 bytes
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] helpers.py[DEBUG]: Running config-ssh using lock (<FileLock using file '/var/lib/cloud/instances/i-005e688a/sem/config_ssh'>)
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to remove /etc/ssh/ssh_host_dsa_key.pub
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to remove /etc/ssh/ssh_host_ecdsa_key.pub
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to remove /etc/ssh/ssh_host_rsa_key
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to remove /etc/ssh/ssh_host_dsa_key
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to remove /etc/ssh/ssh_host_ed25519_key
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to remove /etc/ssh/ssh_host_ed25519_key.pub
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to remove /etc/ssh/ssh_host_rsa_key.pub
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Attempting to remove /etc/ssh/ssh_host_ecdsa_key
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] 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=True)
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] 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=True)
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] 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=True)
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Running command ['ssh-keygen', '-t', 'ed25519', '-N', '', '-f', '/etc/ssh/ssh_host_ed25519_key'] with allowed return codes [0] (shell=False, capture=True)
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Changing the ownership of /home/arch/.ssh to 1000:1000
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False)
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 3594 bytes from /etc/ssh/sshd_config
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /home/arch/.ssh/authorized_keys - wb: [384] 394 bytes
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Changing the ownership of /home/arch/.ssh/authorized_keys to 1000:1000
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Changing the ownership of /root/.ssh to 0:0
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False)
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 3594 bytes from /etc/ssh/sshd_config
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Writing to /root/.ssh/authorized_keys - wb: [384] 547 bytes
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Changing the ownership of /root/.ssh/authorized_keys to 0:0
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network/config-ssh: SUCCESS: config-ssh ran successfully
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] main.py[DEBUG]: Ran 14 modules with 2 failures
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: Read 12 bytes from /proc/uptime
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] util.py[DEBUG]: cloud-init mode 'init' took 24.116 seconds (24.12)
Sep 20 11:06:07 machine cloud-init[283]: [CLOUDINIT] handlers.py[DEBUG]: finish: init-network: FAIL: searching for network datasources
Sep 20 11:06:07 machine cloud-init[283]: Generating public/private rsa key pair.
Sep 20 11:06:07 machine cloud-init[283]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key.
Sep 20 11:06:07 machine cloud-init[283]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub.
Sep 20 11:06:07 machine cloud-init[283]: The key fingerprint is:
...
Sep 20 11:06:07 machine cloud-init[283]: The key's randomart image is:
Sep 20 11:06:07 machine cloud-init[283]: +---[RSA 2048]----+
...
Sep 20 11:06:07 machine cloud-init[283]: +----[SHA256]-----+
Sep 20 11:06:07 machine cloud-init[283]: Generating public/private dsa key pair.
Sep 20 11:06:07 machine cloud-init[283]: Your identification has been saved in /etc/ssh/ssh_host_dsa_key.
Sep 20 11:06:07 machine cloud-init[283]: Your public key has been saved in /etc/ssh/ssh_host_dsa_key.pub.
Sep 20 11:06:07 machine cloud-init[283]: The key fingerprint is:
...
Sep 20 11:06:07 machine cloud-init[283]: The key's randomart image is:
Sep 20 11:06:07 machine cloud-init[283]: +---[DSA 1024]----+
....
Sep 20 11:06:07 machine cloud-init[283]: +----[SHA256]-----+
Sep 20 11:06:07 machine cloud-init[283]: Generating public/private ecdsa key pair.
Sep 20 11:06:07 machine cloud-init[283]: Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key.
Sep 20 11:06:07 machine cloud-init[283]: Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub.
Sep 20 11:06:07 machine cloud-init[283]: The key fingerprint is:
...
Sep 20 11:06:07 machine cloud-init[283]: The key's randomart image is:
Sep 20 11:06:07 machine cloud-init[283]: +---[ECDSA 256]---+
....
Sep 20 11:06:07 machine cloud-init[283]: +----[SHA256]-----+
Sep 20 11:06:07 machine cloud-init[283]: Generating public/private ed25519 key pair.
Sep 20 11:06:07 machine cloud-init[283]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key.
Sep 20 11:06:07 machine cloud-init[283]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub.
Sep 20 11:06:07 machine cloud-init[283]: The key fingerprint is:
...
Sep 20 11:06:07 machine cloud-init[283]: The key's randomart image is:
Sep 20 11:06:07 machine cloud-init[283]: +--[ED25519 256]--+
...
Sep 20 11:06:07 machine cloud-init[283]: +----[SHA256]-----+
Sep 20 11:06:07 machine systemd[1]: cloud-init.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Sep 20 11:06:07 machine systemd[1]: Failed to start Initial cloud-init job (metadata service crawler).
Sep 20 11:06:07 machine systemd[1]: cloud-init.service: Unit entered failed state.
Sep 20 11:06:07 machine systemd[1]: cloud-init.service: Failed with result 'exit-code'.

Find a clean way to resolve need for passing "--grub2-install grub-install" on Debian

Configure default locale

Gentoo: eselect locale set en_US.UTF-8

Add high-level reporting for Gentoo, too

Provide better errors when scripts fail

Try creating an entirely empty file, mark it as executable, and add it to the scripts-pre directory. The resulting error is not particularly helpful:

...
Running pre-chroot scripts...
# /tmp/tmp.5v3QeEfQiI/pre/test.sh
Unmounting partitions...
# umount /mnt/tmpotamTf
Removing directory "/mnt/tmpotamTf"...
Deactivating partition devices...
# kpartx -d /dev/loop0
Error: [Errno 8] Exec format error
If this looks like a bug to you, please file a report at https://github.com/hartwork/image-bootstrap.  Thank you!

It'd be better if the error told you which script failed to execute in addition to the error. In this particular case it might also tell you that the file is missing a #! line at the beginning, but that's less important.

Document script working directory and environment variables

In certain pre/post scripts, it is useful to be able to refer back to files in the directory that houses that script itself (e.g., static assets, configuration files, etc.). It would be great if the working directory at the time when image-bootstrap is invoked was somehow made accessible to scripts (perhaps through an ORIGINAL_WD environment variable? An alternative would be to just chdir back to the original working directory before invoking the scripts; I don't know which is better.

Broken Python in Arch-Linux (no cloud-init possible)

Hi,

I just tried to run:
http://linuximages.de/openstack/arch/arch-openstack-2016-01-13-06-45-image-bootstrap-0.9.1-66-g61de370.qcow2

OpenStack image should not manually create user

This is taken care of by cloud-init. When the user is created through _create_sudo_nopasswd_user, the cloud-init user creation code doesn't kick in because it detects that the user already exists:

[CLOUDINIT] stages.py[DEBUG]: Running module users-groups (<module 'cloudinit.config.cc_users_groups' from '/usr/lib/python2.7/site-packages/cloudinit/config/cc_users_groups.pyc'>) with frequency once-per-instance
[CLOUDINIT] util.py[DEBUG]: Writing to /var/lib/cloud/instances/iid-local01/sem/config_users_groups - wb: [420] 19 bytes
[CLOUDINIT] helpers.py[DEBUG]: Running config-users-groups using lock (<FileLock using file '/var/lib/cloud/instances/iid-local01/sem/config_users_groups'>)
[CLOUDINIT] __init__.py[INFO]: User arch already exists, skipping.

This in turn means that the modifications made by adjust_cloud_cfg_dict and friends do not take effect, and the user does not get added to other groups such as adm.

Add support for generating Alpine Linux images

It'd be really near if image-bootstrap also supported generating images for Alpine Linux, as it is a distro that is specifically designed to be small and secure (and therefore fits well as a cloud image). They also have cloud-init available as a package.

What would be the changes necessary to add another distro to image-bootstrap? I might be able to contribute some time to this if I'm pointed in the right direction.

Seen failing to install Grub on with DRBD device on Debian jessie

..
Installing bootloader to device "/dev/drbd8" (approach "chroot-grub2-drive")...
# chroot /mnt/tmp96s8Lu grub-install \(hd9999\)
/usr/sbin/grub-probe: error: cannot find a GRUB drive for /dev/mapper/drbd8p1.  Check your device.map.
Auto-detection of a filesystem of /dev/mapper/drbd8p1 failed.
Try with --recheck.
..

Arch OpenStack images no longer resize first partition to full disk size

Your current image only mounts a 2gb partition. It make it a pain to boot into rescue mode and resize it. Any reason it's setup like this? Wouldn't it be better to just mount the full available space?

Failure to run on ubuntu 15.04/vivid due to kpartx lacking -u flag

Hi -

I'm trying image-bootstrap out to bootstrap an arch system from Ubuntu 15, with a plain block device.

I'm getting a failure on the kpartx command as follows:

Error: Command "kpartx -u -s /dev/sdd" returned non-zero exit status 1

Having looked into this a little, kpartx doesn't actually have the -u (update partitions) flag on Ubuntu, which I expect is why it's having a failed exit.

Full output below:


root@<server>:/home/<user>/host/image-bootstrap# ./image-bootstrap --bootloader none --password-file root_password.txt arch /dev/sdd
     _                          __             __      __               
    (_)_ _  ___ ____ ____  ___ / /  ___  ___  / /____ / /________ ____  
   / /  ' \/ _ `/ _ `/ -_)/__// _ \/ _ \/ _ \/ __(_-</ __/ __/ _ `/ _ \ 
  /_/_/_/_/\_,_/\_, /\__/    /_.__/\___/\___/\__/___/\__/_/  \_,_/ .__/ 
               /___/                      v0.9.1 :: 2015-07-11  /_/     

Software libre licensed under AGPL v3 or later.
Brought to you by Sebastian Pipping <[email protected]>.
Please report bugs at https://github.com/hartwork/image-bootstrap.  Thank you!

Checking for blkid... /sbin/blkid
Checking for blockdev... /sbin/blockdev
Checking for chmod... /bin/chmod
Checking for chroot... /usr/sbin/chroot
Checking for cp... /bin/cp
Checking for find... /usr/bin/find
Checking for gpg... /usr/bin/gpg
Checking for kpartx... /sbin/kpartx
Checking for mkdir... /bin/mkdir
Checking for mkfs.ext4... /sbin/mkfs.ext4
Checking for mount... /bin/mount
Checking for parted... /sbin/parted
Checking for partprobe... /sbin/partprobe
Checking for rm... /bin/rm
Checking for rmdir... /bin/rmdir
Checking for sed... /bin/sed
Checking for tune2fs... /sbin/tune2fs
Checking for umount... /bin/umount
Checking for wget... /usr/bin/wget

Checking for known unsupported architecture/machine combination...
Checking if "/dev/sdd" is a block device...
Reading root password from file "/home/stone/host/image-bootstrap/root_password.txt"...
Unsharing Linux namespaces (mount, UTS/hostname)...
Checking size of "/dev/sdd"...
Partitioning "/dev/sdd"...
Activating partition devices...
Error: Command "kpartx -u -s /dev/sdd" returned non-zero exit status 1
If this looks like a bug to you, please file a report at https://github.com/hartwork/image-bootstrap.  Thank you!

Mention haveged for speeding up in the docs

LinuxImages no longer creating new Arch images daily

It seems like http://linuximages.de/openstack/arch/ stopped creating new Arch images. I don't know if this is intentional.

I am also having an issue with the latest image that cloud-init is failing to install the ssh key and as such there is no way to get the logs and see what went wrong without access to the host. I am attempting to use the image on OVH public cloud.

For machine-id, allow a custom value and do not include in OpenStack images

To illustrate the problem, for the official Debian 8.1.0 images, I currently get

# ls -al /etc/machine-id /var/lib/dbus/machine-id
-r--r--r-- 1 root root 33 Jun  6 13:03 /etc/machine-id
-rw-r--r-- 1 root root 33 Jun  6 13:05 /var/lib/dbus/machine-id

# cat /etc/machine-id /var/lib/dbus/machine-id 
cdb7f92199c748a7a20d33ab52974cbb
cdb7f92199c748a7a20d33ab52974cbb

anywhere.

Resources:

Related issue in Debian (openstack-debian-images):
https://bugs.debian.org/cgi-bin/bugreport.cgi?bug=789960

Add support for rpm/yum based distros to directory-bootstrap

References:

LVM partions may appear at "lv1" (e.g. Gentoo) or "lvp1" (e.g. Debian wheezy)

Find a way to support both.

# sudo ./image-bootstrap [..] /dev/..../archbox
[..]
Error: [Errno 2] No such block device file: '/dev/mapper/....-archboxp1'
[..]

Try creating /var/cache/directory-bootstrap ourselves

Error: [Errno 2] No such file or directory: '/var/cache/directory-bootstrap'

Arch image keyring errors

Downloading keyring listing...
Downloading "https://sources.archlinux.org/other/archlinux-keyring/archlinux-keyring-20171020.tar.gz.sig"...
# wget -O/var/cache/directory-bootstrap/archlinux-keyring-20171020.tar.gz.sig https://sources.archlinux.org/other/archlinux-keyring/archlinux-keyring-20171020.tar.gz.sig
--2017-10-20 12:17:51--  https://sources.archlinux.org/other/archlinux-keyring/archlinux-keyring-20171020.tar.gz.sig
Loaded CA certificate '/etc/ssl/certs/ca-certificates.crt'
Resolving sources.archlinux.org... 88.198.91.70, 2a01:4f8:160:6087::1
Connecting to sources.archlinux.org|88.198.91.70|:443... connected.
HTTP request sent, awaiting response... 200 OK
Length: 566 [application/octet-stream]
Saving to: ‘/var/cache/directory-bootstrap/archlinux-keyring-20171020.tar.gz.sig’

/var/cache/directory-bootstrap/archli 100%[======================================================================>]     566  --.-KB/s    in 0s

2017-10-20 12:17:52 (9.61 MB/s) - ‘/var/cache/directory-bootstrap/archlinux-keyring-20171020.tar.gz.sig’ saved [566/566]

Downloading "https://sources.archlinux.org/other/archlinux-keyring/archlinux-keyring-20171020.tar.gz"...
# wget -O/var/cache/directory-bootstrap/archlinux-keyring-20171020.tar.gz https://sources.archlinux.org/other/archlinux-keyring/archlinux-keyring-20171020.tar.gz
--2017-10-20 12:17:52--  https://sources.archlinux.org/other/archlinux-keyring/archlinux-keyring-20171020.tar.gz
Loaded CA certificate '/etc/ssl/certs/ca-certificates.crt'
Resolving sources.archlinux.org... 88.198.91.70, 2a01:4f8:160:6087::1
Connecting to sources.archlinux.org|88.198.91.70|:443... connected.
HTTP request sent, awaiting response... 200 OK
Length: 636848 (622K) [application/octet-stream]
Saving to: ‘/var/cache/directory-bootstrap/archlinux-keyring-20171020.tar.gz’

/var/cache/directory-bootstrap/archli 100%[======================================================================>] 621.92K  1.30MB/s    in 0.5s

2017-10-20 12:17:53 (1.30 MB/s) - ‘/var/cache/directory-bootstrap/archlinux-keyring-20171020.tar.gz’ saved [636848/636848]

Initializing temporary GnuPG home at "/tmp/tmpydg4f7/gpg_home"...
# unshare --fork --pid gpg --home /tmp/tmpydg4f7/gpg_home --keyid-format 0xlong --batch --quiet --import /tmp/tmpydg4f7/archlinux-keyring-20171020/archlinux.gpg
key 0xAFF5D95098BC6FF5:
4 signatures not checked due to missing keys
key 0xFC1B547C8D8172C8:
6 signatures not checked due to missing keys
key 0xE62F853100F0D0F0:
14 signatures not checked due to missing keys
key 0xEEEEE2EEEE2EEEEE:
13 signatures not checked due to missing keys
key 0x6D1655C14CE1C13E:
1 signature not checked due to a missing key
key 0xBBE43771487328A9:
17 signatures not checked due to missing keys
key 0x5C2E46A0F53A76ED:
3 signatures not checked due to missing keys
key 0x9C02FF419FECBE16:
8 signatures not checked due to missing keys
key 0xDA6426DD215B37AD:
11 signatures not checked due to missing keys
key 0xA5E9288C4FA415FA:
11 signatures not checked due to missing keys
key 0xC06086337C50773E:
1 signature not checked due to a missing key
key 0x06096A6AD1CEDDAC:
1 signature not checked due to a missing key
key 0x7F2D434B9741E8AC:
2 signatures not checked due to missing keys
key 0x206CBC892D1493D2:
2 signatures not checked due to missing keys
key 0x8DBD63B82072D77A:
2 signatures not checked due to missing keys
key 0x284FC34C8E4B1A25:
1 signature not checked due to a missing key
Verifying integrity of file "/var/cache/directory-bootstrap/archlinux-keyring-20171020.tar.gz"...
# unshare --fork --pid gpg --home /tmp/tmpydg4f7/gpg_home --keyid-format 0xlong --batch --verify /var/cache/directory-bootstrap/archlinux-keyring-20171020.tar.gz.sig /var/cache/directory-bootstrap/archlinux-keyring-20171020.tar.gz
gpg: Signature made Fri 20 Oct 2017 08:58:02 AM EDT
gpg:                using RSA key BD84DE71F493DF6814B0167254EDC91609BC9183
gpg: Can't check signature: No public key

Error: Command "unshare --fork --pid gpg --home /tmp/tmpydg4f7/gpg_home --keyid-format 0xlong --batch --verify /var/cache/directory-bootstrap/archlinux-keyring-20171020.tar.gz.sig /var/cache/directory-bootstrap/archlinux-keyring-20171020.tar.gz" returned non-zero exit status 2

Finish handling of Debian networking udev files

Files to look at:

/etc/udev/rules.d/*net*.rules
/lib/udev/rules.d/*net*.rules
/lib/udev/write_net_rules

cloud-init datasources does not include the "NoCloud" ds, causing issues when using a CDROM drive to provide metadata

When creating a VM using KVM/Libvirt, it is common practice to attach an iso image with configuration data for cloud-init. In order for this to work, the "NoCloud" data source needs to be enabled.

From a (working) ubuntu image, the following cloud-init configuration is configured in /etc/cloud/cloud.cfg.d/90_dpkg.cfg:

datasource_list: [ NoCloud, ConfigDrive, OpenNebula, Azure, AltCloud, OVF, MAAS, GCE, OpenStack, CloudSigma, SmartOS, Ec2, CloudStack, None ]

In this archlinux image, the file is called /etc/cloud/cloud.cfg.d/90_datasource.cfg and should be expanded to include at least the NoCloud directive (potentially the others too).

[Fedora] directory-bootstrap does not support --arch parameter?

Why is it not possible to provide the arch for this script but you can for image-bootstrap?

5G space turned out too little to build Gentoo

dd622b9#diff-c4bba16b92cb21436c8f3d8cfcbbf9f8R460

cloud-init writing /etc/hostname rather than /etc/conf.d/hostname on Gentoo

gentoo@machine ~ $ cat /etc/conf.d/hostname 
# Set to the hostname of this machine
hostname="machine"

gentoo@machine ~ $ cat /etc/hostname 
gentoo-15-07-16

gentoo@machine ~ $ sudo hostname fooo

gentoo@machine ~ $ exec bash

gentoo@fooo ~ $

Pass LC_ALL=C to anything run by chroot(8)

On Debian host: Arch OpenStack started failing during cloud-init package creation

Broken on host OS Debian.
Works on host OS Gentoo.

Is /dev/urandom is missing?:

[..]
# sudo -u nobody makepkg
==> Making package: cloud-init 9999-1 (Wed Jul 19 14:23:21 UTC 2017)
==> Checking runtime dependencies...
==> Checking buildtime dependencies...
==> Retrieving sources...
  -> Downloading cloud-init-0.7.6.tar.gz...
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed
  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0
100  503k  100  503k    0     0   901k      0 --:--:-- --:--:-- --:--:--  901k
==> Validating source files with sha1sums...
    cloud-init-0.7.6.tar.gz ... Passed
==> Extracting sources...
  -> Extracting cloud-init-0.7.6.tar.gz with bsdtar
==> Starting prepare()...
==> Entering fakeroot environment...
==> Starting package()...
Traceback (most recent call last):
  File "./setup.py", line 27, in <module>
    import setuptools
  File "/usr/lib/python2.7/site-packages/setuptools/__init__.py", line 10, in <module>
    from setuptools.extern.six.moves import filter, map
  File "/usr/lib/python2.7/site-packages/setuptools/extern/__init__.py", line 1, in <module>
    from pkg_resources.extern import VendorImporter
  File "/usr/lib/python2.7/site-packages/pkg_resources/__init__.py", line 36, in <module>
    import email.parser
  File "/usr/lib/python2.7/email/parser.py", line 12, in <module>
    from email.feedparser import FeedParser
  File "/usr/lib/python2.7/email/feedparser.py", line 27, in <module>
    from email import message
  File "/usr/lib/python2.7/email/message.py", line 16, in <module>
    import email.charset
  File "/usr/lib/python2.7/email/charset.py", line 13, in <module>
    import email.base64mime
  File "/usr/lib/python2.7/email/base64mime.py", line 40, in <module>
    from email.utils import fix_eols
  File "/usr/lib/python2.7/email/utils.py", line 27, in <module>
    import random
  File "/usr/lib/python2.7/random.py", line 885, in <module>
    _inst = Random()
  File "/usr/lib/python2.7/random.py", line 97, in __init__
    self.seed(x)
  File "/usr/lib/python2.7/random.py", line 113, in seed
    a = long(_hexlify(_urandom(2500)), 16)
OSError: [Errno 38] Function not implemented
==> ERROR: A failure occurred in package().
    Aborting...
[..]

extlinux (default for ubuntu): install-mbr fails without --force

sudo [..]/image-bootstrap --verbose --openstack --password .... ubuntu --release xenial "${LOOP_DEVICE}"
[..]
Writing MBR of "/dev/loop0"...
# install-mbr /dev/loop0
install-mbr:/dev/loop0: Probable detection of modern MBR format which is currently incompatible with install-mbr.  Installing will probably break Windows installations on this system.  Use --force to override.
Unmounting partitions...
# umount /mnt/tmpqHMiCC
Removing directory "/mnt/tmpqHMiCC"...
Deactivating partition devices...
# kpartx -d /dev/loop0
Error: Command "install-mbr /dev/loop0" returned non-zero exit status 1
If this looks like a bug to you, please file a report at https://github.com/hartwork/image-bootstrap.  Thank you!

Varying file sizes for Arch Linux images

Why are Arch Linux daily images varying in size?

Some images are ~500MB, some 1.1GB (with only one day between the builds).

Source: http://linuximages.de/openstack/arch/

chroot scripts are not run in alphabetical order

Specifically, https://github.com/hartwork/image-bootstrap/blob/master/image_bootstrap/engine.py#L659 is missing a call to sorted.

No longer include pacman keys (/etc/pacman.d/gnupg) in Arch OpenStack image

Run "eselect news read --quiet all" during clean-up with Gentoo

Add support for extlinux + (install-)mbr as an alternative to GRUB

Run "rm /usr/portage/distfiles/*" inside chroot during clean-up with Gentoo

Add another script execution phase

Currently, we have pre, chroot, and post. pre gets executed before the chroot is constructed and many utilities (such as sudo) have been installed, chroot gets executed inside $MNTPOINT once all other setup has finished, and post gets executed at the end outside of the $MNTPOINT. This leaves one important hook for scripts: outside the after all other setup has completed (i.e., just before the chroot scripts run). I was expecting pre to be like this, but was surprised when it wasn't. In particular, where pre currently runs, many commands do not work since the various special partitions haven't been mounted, the package manager hasn't been initialized, etc.

Arch image cloud-init generate a wrong locale.gen file

cloud-init generate not correctly /etc/locale.gen, like this :

# Created by cloud-init v. 0.7.6 on Thu, 03 Mar 2016 06:25:03 +0000
LANG=en_US.UTF-8

This should be

en_US.UTF-8 UTF-8

LANG=en_US.UTF-8 is valid only for /etc/locale.conf I think the confusion comes from here.

Fix "Unable to change the ownership of /var/log/cloud-init.log"

gentoo # sudo /etc/init.d/cloud-init-local restart
Cloud-init v. 0.7.6 running 'init-local' at Fri, 17 Jul 2015 19:13:47 +0000. Up 73301.81 seconds.
2015-07-17 19:13:47,875 - util.py[WARNING]: Unable to change the ownership of /var/log/cloud-init.log to user syslog, group adm

gentoo # echo $(cat /etc/group | awk -F : '{print $1}' | sort)
adm audio bin cdrom cdrw console daemon disk floppy gentoo input kmem lp man mem news nobody nogroup portage root sshd sys tape tty usb users utmp uucp video wheel

Recommend Projects

React

A declarative, efficient, and flexible JavaScript library for building user interfaces.
Vue.js

🖖 Vue.js is a progressive, incrementally-adoptable JavaScript framework for building UI on the web.
Typescript

TypeScript is a superset of JavaScript that compiles to clean JavaScript output.
TensorFlow

An Open Source Machine Learning Framework for Everyone
Django

The Web framework for perfectionists with deadlines.
Laravel

A PHP framework for web artisans
D3

Bring data to life with SVG, Canvas and HTML. 📊📈🎉

Recommend Topics

javascript

JavaScript (JS) is a lightweight interpreted programming language with first-class functions.
web

Some thing interesting about web. New door for the world.
server

A server is a program made to process requests and deliver data to clients.
Machine learning

Machine learning is a way of modeling and interpreting data that allows a piece of software to respond intelligently.
Visualization

Some thing interesting about visualization, use data art
Game

Some thing interesting about game, make everyone happy.

Recommend Org

Facebook

We are working to build community through open source technology. NB: members must have two-factor auth.
Microsoft

Open source projects and samples from Microsoft.
Google

Google ❤️ Open Source for everyone.
Alibaba

Alibaba Open Source for everyone
D3

Data-Driven Documents codes.
Tencent

China tencent open source team.