yahoo-eng-team team mailing list archive

[Remy van Elst <1454185@xxxxxxxxxxxxxxxxxx>]

Public bug reported:

On a minimal debian 8 install, with cloud-init installed from the debian
jessie repositories, it does not work as expected. The hostname does not
change, the SSH key is not set and the root password is not set.

This is on a cloud provider using Openstack with the config drive
(cloudvps).

The same config file works on Debian 7 (with cloud-init installed via
backports).

Without a custom config file, it also fails with the same error messages
(with a default debian 8 package provided config file).

When running manually:

    cloud-init modules --mode init
    Traceback (most recent call last):
      File "/usr/bin/cloud-init", line 618, in <module>
        sys.exit(main())
      File "/usr/bin/cloud-init", line 614, in main
        get_uptime=True, func=functor, args=(name, args))
      File "/usr/lib/python2.7/dist-packages/cloudinit/util.py", line 1875, in log_time
        ret = func(*args, **kwargs)
      File "/usr/bin/cloud-init", line 487, in status_wrapper
        v1[mode]['start'] = time.time()
    KeyError: 'modules-init'


The config or final part also fail, both give this error message:

    cloud-init modules --mode config
    Can not apply stage config, no datasource found! Likely bad things to come!
    ------------------------------------------------------------
    Traceback (most recent call last):
      File "/usr/bin/cloud-init", line 318, in main_modules
        init.fetch()
      File "/usr/lib/python2.7/dist-packages/cloudinit/stages.py", line 308, in fetch
        return self._get_data_source()
      File "/usr/lib/python2.7/dist-packages/cloudinit/stages.py", line 236, in _get_data_source
        pkg_list)
      File "/usr/lib/python2.7/dist-packages/cloudinit/sources/__init__.py", line 260, in find_source
        raise DataSourceNotFoundException(msg)
    DataSourceNotFoundException: Did not find any data source, searched classes: ()
    ------------------------------------------------------------


The config drive is available:

    root@debian:~# lsblk /dev/sr0
    NAME MAJ:MIN RM  SIZE RO TYPE MOUNTPOINT
    sr0   11:0    1  410K  0 rom  
    root@debian:~# blkid /dev/sr0
    /dev/sr0: UUID="2015-05-11-15-45-45-00" LABEL="config-2" TYPE="iso9660"

Including the metadata:

    # cat /mnt/openstack/latest/meta_data.json
    {"admin_pass": "[...]", "random_seed": "l4Zr[...]=", "uuid": "3bc8[...]370b4", "availability_zone": "NL1", "hostname": "jessie", "launch_index": 0, "public_keys": {"User": "ssh-rsa AAA[...]DIl"}, "name": "jessie"}


This is the custom config file:

    user: root
    disable_root: 0
    preserve_hostname: False

    datasource_list: [ ConfigDrive ]

    system_info:
       distro: debian

    cloud_init_modules:
     - bootcmd
     - resizefs
     - set_hostname
     - update_hostname
     - update_etc_hosts
     - ca-certs
     - ssh

    cloud_config_modules:
     - disk-setup
     - ssh-import-id
     - locale
     - set-passwords
     - runcmd

    cloud_final_modules:
     - scripts-per-once
     - scripts-per-boot
     - scripts-per-instance
     - scripts-user
     - keys-to-console
     - final-message

As said, it also fails with a default debian 8 config file.

This is the debug log:

     cat /var/log/cloud-init.log 
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Cloud-init v. 0.7.6 running 'init-local' at Mon, 11 May 2015 13:46:29 +0000. Up 8.10 seconds.
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [420] 0 bytes
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:4
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance/boot-finished
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 11 bytes from /proc/uptime
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: cloud-init mode 'init' took 0.024 seconds (0.02)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Cloud-init v. 0.7.6 running 'init' at Mon, 11 May 2015 13:46:29 +0000. Up 8.26 seconds.
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [420] 0 bytes
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:4
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['ifconfig', '-a'] with allowed return codes [0] (shell=False, capture=True)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['netstat', '-rn'] with allowed return codes [0] (shell=False, capture=True)
    May 11 15:46:29 debian [CLOUDINIT] cloud-init[DEBUG]: Checking to see if files that we need already exist from a previous run that would allow us to stop early.
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/data/no-net (quiet=False)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['debian', 'cloudinit.distros.debian'] that have attributes ['Distro']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Failed at attempted import of 'debian' due to: No module named debian
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found debian with attributes ['Distro'] in ['cloudinit.distros.debian']
    May 11 15:46:29 debian [CLOUDINIT] stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'>
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Looking for for data source in: ['ConfigDrive'], via packages ['', 'cloudinit.sources'] that matches dependencies ['FILESYSTEM', 'NETWORK']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['DataSourceConfigDrive', 'cloudinit.sources.DataSourceConfigDrive'] that have attributes ['get_datasource_list']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Failed at attempted import of 'DataSourceConfigDrive' due to: No module named DataSourceConfigDrive
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found DataSourceConfigDrive with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceConfigDrive']
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Searching for data source in: ['DataSourceConfigDriveNet']
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceConfigDrive.DataSourceConfigDriveNet'>
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['/sbin/blkid', '-odevice', '/dev/sr0'] with allowed return codes [0, 2] (shell=False, capture=True)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['/sbin/blkid', '-odevice', '/dev/sr1'] with allowed return codes [0, 2] (shell=False, capture=True)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['/sbin/blkid', '-tTYPE=vfat', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['/sbin/blkid', '-tTYPE=iso9660', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['/sbin/blkid', '-tLABEL=config-2', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /proc/mounts (quiet=False)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 1780 bytes from /proc/mounts
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Fetched {'devpts': {'mountpoint': '/dev/pts', 'opts': 'rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000', 'fstype': 'devpts'}, 'debugfs': {'mountpoint': '/sys/kernel/debug', 'opts': 'rw,relatime', 'fstype': 'debugfs'}, 'securityfs': {'mountpoint': '/sys/kernel/security', 'opts': 'rw,nosuid,nodev,noexec,relatime', 'fstype': 'securityfs'}, 'sysfs': {'mountpoint': '/sys', 'opts': 'rw,nosuid,nodev,noexec,relatime', 'fstype': 'sysfs'}, 'mqueue': {'mountpoint': '/dev/mqueue', 'opts': 'rw,relatime', 'fstype': 'mqueue'}, 'pstore': {'mountpoint': '/sys/fs/pstore', 'opts': 'rw,nosuid,nodev,noexec,relatime', 'fstype': 'pstore'}, 'udev': {'mountpoint': '/dev', 'opts': 'rw,relatime,size=10240k,nr_inodes=505425,mode=755', 'fstype': 'devtmpfs'}, 'hugetlbfs': {'mountpoint': '/dev/hugepages', 'opts': 'rw,relatime', 'fstype': 'hugetlbfs'}, '/dev/vda1': {'mountpoint': '/boot', 'opts': 'rw,noatime,errors=remount-ro,data=ordered', 'fstype': 'ext4'}, 'cgroup': {'mountpoint': '/sys/fs/cgroup/perf_event', 'opts': 'rw,nosuid,nodev,noexec,relatime,perf_event', 'fstype': 'cgroup'}, 'tmpfs': {'mountpoint': '/sys/fs/cgroup', 'opts': 'ro,nosuid,nodev,noexec,mode=755', 'fstype': 'tmpfs'}, 'proc': {'mountpoint': '/proc', 'opts': 'rw,nosuid,nodev,noexec,relatime', 'fstype': 'proc'}, '/dev/vda2': {'mountpoint': '/', 'opts': 'rw,noatime,errors=remount-ro,data=ordered', 'fstype': 'ext4'}, 'systemd-1': {'mountpoint': '/proc/sys/fs/binfmt_misc', 'opts': 'rw,relatime,fd=23,pgrp=1,timeout=300,minproto=5,maxproto=5,direct', 'fstype': 'autofs'}, 'rootfs': {'mountpoint': '/', 'opts': 'rw', 'fstype': 'rootfs'}} mounts from proc
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['mount', '-o', 'ro,sync', '/dev/sr0', '/tmp/tmp8GBPpv'] with allowed return codes [0] (shell=False, capture=True)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /tmp/tmp8GBPpv/openstack/2012-08-10/meta_data.json (quiet=False)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 581 bytes from /tmp/tmp8GBPpv/openstack/2012-08-10/meta_data.json
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /tmp/tmp8GBPpv/ec2/latest/meta-data.json (quiet=False)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 961 bytes from /tmp/tmp8GBPpv/ec2/latest/meta-data.json
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['umount', '-l', '/tmp/tmp8GBPpv'] with allowed return codes [0] (shell=False, capture=True)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Recursively deleting /tmp/tmp8GBPpv
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False)
    May 11 15:46:29 debian [CLOUDINIT] stages.py[INFO]: Loaded datasource DataSourceConfigDriveNet - DataSourceConfigDriveNet [net,ver=2][source=/dev/sr0]
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /proc/cmdline (quiet=False)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 128 bytes from /proc/cmdline
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 476 bytes from /etc/cloud/cloud.cfg
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 476 with allowed root types (<type 'dict'>,)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 88 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 88 with allowed root types (<type 'dict'>,)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 1910 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 1910 with allowed root types (<type 'dict'>,)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_debian.cfg (quiet=False)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 26 bytes from /etc/cloud/cloud.cfg.d/00_debian.cfg
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 26 with allowed root types (<type 'dict'>,)
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
    May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
    May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 11 bytes from /proc/uptime
    May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: cloud-init mode 'init' took 0.714 seconds (0.71)
    root@debian:~#

** Affects: cloud-init
     Importance: Undecided
         Status: New

-- 
You received this bug notification because you are a member of Yahoo!
Engineering Team, which is subscribed to cloud-init.
https://bugs.launchpad.net/bugs/1454185

Title:
  cloud-init fails on debian 8

Status in Init scripts for use on cloud images:
  New

Bug description:
  On a minimal debian 8 install, with cloud-init installed from the
  debian jessie repositories, it does not work as expected. The hostname
  does not change, the SSH key is not set and the root password is not
  set.

  This is on a cloud provider using Openstack with the config drive
  (cloudvps).

  The same config file works on Debian 7 (with cloud-init installed via
  backports).

  Without a custom config file, it also fails with the same error
  messages (with a default debian 8 package provided config file).

  When running manually:

      cloud-init modules --mode init
      Traceback (most recent call last):
        File "/usr/bin/cloud-init", line 618, in <module>
          sys.exit(main())
        File "/usr/bin/cloud-init", line 614, in main
          get_uptime=True, func=functor, args=(name, args))
        File "/usr/lib/python2.7/dist-packages/cloudinit/util.py", line 1875, in log_time
          ret = func(*args, **kwargs)
        File "/usr/bin/cloud-init", line 487, in status_wrapper
          v1[mode]['start'] = time.time()
      KeyError: 'modules-init'

  
  The config or final part also fail, both give this error message:

      cloud-init modules --mode config
      Can not apply stage config, no datasource found! Likely bad things to come!
      ------------------------------------------------------------
      Traceback (most recent call last):
        File "/usr/bin/cloud-init", line 318, in main_modules
          init.fetch()
        File "/usr/lib/python2.7/dist-packages/cloudinit/stages.py", line 308, in fetch
          return self._get_data_source()
        File "/usr/lib/python2.7/dist-packages/cloudinit/stages.py", line 236, in _get_data_source
          pkg_list)
        File "/usr/lib/python2.7/dist-packages/cloudinit/sources/__init__.py", line 260, in find_source
          raise DataSourceNotFoundException(msg)
      DataSourceNotFoundException: Did not find any data source, searched classes: ()
      ------------------------------------------------------------

  
  The config drive is available:

      root@debian:~# lsblk /dev/sr0
      NAME MAJ:MIN RM  SIZE RO TYPE MOUNTPOINT
      sr0   11:0    1  410K  0 rom  
      root@debian:~# blkid /dev/sr0
      /dev/sr0: UUID="2015-05-11-15-45-45-00" LABEL="config-2" TYPE="iso9660"

  Including the metadata:

      # cat /mnt/openstack/latest/meta_data.json
      {"admin_pass": "[...]", "random_seed": "l4Zr[...]=", "uuid": "3bc8[...]370b4", "availability_zone": "NL1", "hostname": "jessie", "launch_index": 0, "public_keys": {"User": "ssh-rsa AAA[...]DIl"}, "name": "jessie"}

  
  This is the custom config file:

      user: root
      disable_root: 0
      preserve_hostname: False

      datasource_list: [ ConfigDrive ]

      system_info:
         distro: debian

      cloud_init_modules:
       - bootcmd
       - resizefs
       - set_hostname
       - update_hostname
       - update_etc_hosts
       - ca-certs
       - ssh

      cloud_config_modules:
       - disk-setup
       - ssh-import-id
       - locale
       - set-passwords
       - runcmd

      cloud_final_modules:
       - scripts-per-once
       - scripts-per-boot
       - scripts-per-instance
       - scripts-user
       - keys-to-console
       - final-message

  As said, it also fails with a default debian 8 config file.

  This is the debug log:

       cat /var/log/cloud-init.log 
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Cloud-init v. 0.7.6 running 'init-local' at Mon, 11 May 2015 13:46:29 +0000. Up 8.10 seconds.
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [420] 0 bytes
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:4
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance/boot-finished
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 11 bytes from /proc/uptime
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: cloud-init mode 'init' took 0.024 seconds (0.02)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Cloud-init v. 0.7.6 running 'init' at Mon, 11 May 2015 13:46:29 +0000. Up 8.26 seconds.
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [420] 0 bytes
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:4
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['ifconfig', '-a'] with allowed return codes [0] (shell=False, capture=True)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['netstat', '-rn'] with allowed return codes [0] (shell=False, capture=True)
      May 11 15:46:29 debian [CLOUDINIT] cloud-init[DEBUG]: Checking to see if files that we need already exist from a previous run that would allow us to stop early.
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/data/no-net (quiet=False)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['debian', 'cloudinit.distros.debian'] that have attributes ['Distro']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Failed at attempted import of 'debian' due to: No module named debian
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found debian with attributes ['Distro'] in ['cloudinit.distros.debian']
      May 11 15:46:29 debian [CLOUDINIT] stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.debian.Distro'>
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Looking for for data source in: ['ConfigDrive'], via packages ['', 'cloudinit.sources'] that matches dependencies ['FILESYSTEM', 'NETWORK']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['DataSourceConfigDrive', 'cloudinit.sources.DataSourceConfigDrive'] that have attributes ['get_datasource_list']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Failed at attempted import of 'DataSourceConfigDrive' due to: No module named DataSourceConfigDrive
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found DataSourceConfigDrive with attributes ['get_datasource_list'] in ['cloudinit.sources.DataSourceConfigDrive']
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Searching for data source in: ['DataSourceConfigDriveNet']
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceConfigDrive.DataSourceConfigDriveNet'>
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['/sbin/blkid', '-odevice', '/dev/sr0'] with allowed return codes [0, 2] (shell=False, capture=True)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['/sbin/blkid', '-odevice', '/dev/sr1'] with allowed return codes [0, 2] (shell=False, capture=True)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['/sbin/blkid', '-tTYPE=vfat', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['/sbin/blkid', '-tTYPE=iso9660', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['/sbin/blkid', '-tLABEL=config-2', '-odevice'] with allowed return codes [0, 2] (shell=False, capture=True)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /proc/mounts (quiet=False)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 1780 bytes from /proc/mounts
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Fetched {'devpts': {'mountpoint': '/dev/pts', 'opts': 'rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000', 'fstype': 'devpts'}, 'debugfs': {'mountpoint': '/sys/kernel/debug', 'opts': 'rw,relatime', 'fstype': 'debugfs'}, 'securityfs': {'mountpoint': '/sys/kernel/security', 'opts': 'rw,nosuid,nodev,noexec,relatime', 'fstype': 'securityfs'}, 'sysfs': {'mountpoint': '/sys', 'opts': 'rw,nosuid,nodev,noexec,relatime', 'fstype': 'sysfs'}, 'mqueue': {'mountpoint': '/dev/mqueue', 'opts': 'rw,relatime', 'fstype': 'mqueue'}, 'pstore': {'mountpoint': '/sys/fs/pstore', 'opts': 'rw,nosuid,nodev,noexec,relatime', 'fstype': 'pstore'}, 'udev': {'mountpoint': '/dev', 'opts': 'rw,relatime,size=10240k,nr_inodes=505425,mode=755', 'fstype': 'devtmpfs'}, 'hugetlbfs': {'mountpoint': '/dev/hugepages', 'opts': 'rw,relatime', 'fstype': 'hugetlbfs'}, '/dev/vda1': {'mountpoint': '/boot', 'opts': 'rw,noatime,errors=remount-ro,data=ordered', 'fstype': 'ext4'}, 'cgroup': {'mountpoint': '/sys/fs/cgroup/perf_event', 'opts': 'rw,nosuid,nodev,noexec,relatime,perf_event', 'fstype': 'cgroup'}, 'tmpfs': {'mountpoint': '/sys/fs/cgroup', 'opts': 'ro,nosuid,nodev,noexec,mode=755', 'fstype': 'tmpfs'}, 'proc': {'mountpoint': '/proc', 'opts': 'rw,nosuid,nodev,noexec,relatime', 'fstype': 'proc'}, '/dev/vda2': {'mountpoint': '/', 'opts': 'rw,noatime,errors=remount-ro,data=ordered', 'fstype': 'ext4'}, 'systemd-1': {'mountpoint': '/proc/sys/fs/binfmt_misc', 'opts': 'rw,relatime,fd=23,pgrp=1,timeout=300,minproto=5,maxproto=5,direct', 'fstype': 'autofs'}, 'rootfs': {'mountpoint': '/', 'opts': 'rw', 'fstype': 'rootfs'}} mounts from proc
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['mount', '-o', 'ro,sync', '/dev/sr0', '/tmp/tmp8GBPpv'] with allowed return codes [0] (shell=False, capture=True)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /tmp/tmp8GBPpv/openstack/2012-08-10/meta_data.json (quiet=False)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 581 bytes from /tmp/tmp8GBPpv/openstack/2012-08-10/meta_data.json
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /tmp/tmp8GBPpv/ec2/latest/meta-data.json (quiet=False)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 961 bytes from /tmp/tmp8GBPpv/ec2/latest/meta-data.json
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Running command ['umount', '-l', '/tmp/tmp8GBPpv'] with allowed return codes [0] (shell=False, capture=True)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Recursively deleting /tmp/tmp8GBPpv
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False)
      May 11 15:46:29 debian [CLOUDINIT] stages.py[INFO]: Loaded datasource DataSourceConfigDriveNet - DataSourceConfigDriveNet [net,ver=2][source=/dev/sr0]
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /proc/cmdline (quiet=False)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 128 bytes from /proc/cmdline
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 476 bytes from /etc/cloud/cloud.cfg
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 476 with allowed root types (<type 'dict'>,)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 88 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 88 with allowed root types (<type 'dict'>,)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 1910 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 1910 with allowed root types (<type 'dict'>,)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_debian.cfg (quiet=False)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 26 bytes from /etc/cloud/cloud.cfg.d/00_debian.cfg
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Attempting to load yaml from string of length 26 with allowed root types (<type 'dict'>,)
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_list'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_list with attributes ['Merger'] in ['cloudinit.mergers.m_list']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_dict'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_dict with attributes ['Merger'] in ['cloudinit.mergers.m_dict']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Looking for modules ['cloudinit.mergers.m_str'] that have attributes ['Merger']
      May 11 15:46:29 debian [CLOUDINIT] importer.py[DEBUG]: Found m_str with attributes ['Merger'] in ['cloudinit.mergers.m_str']
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging 'dict' into 'dict' using method '_handle_unknown' of 'LookupMerger: (3)'
      May 11 15:46:29 debian [CLOUDINIT] __init__.py[DEBUG]: Merging using located merger 'DictMerger: (method=no_replace,recurse_str=False,recurse_dict=True,recurse_array=False,allow_delete=False)' since it had method '_on_dict'
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: Read 11 bytes from /proc/uptime
      May 11 15:46:29 debian [CLOUDINIT] util.py[DEBUG]: cloud-init mode 'init' took 0.714 seconds (0.71)
      root@debian:~#

To manage notifications about this bug go to:
https://bugs.launchpad.net/cloud-init/+bug/1454185/+subscriptions