← Back to team overview

yahoo-eng-team team mailing list archive

[Bug 1454185] Re: cloud-init fails on debian 8

 

[Expired for cloud-init because there has been no activity for 60 days.]

** Changed in: cloud-init
       Status: Incomplete => Expired

-- 
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 cloud-init:
  Expired

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


References