← Back to team overview

yahoo-eng-team team mailing list archive

[Bug 1718959] Re: user-data ignored in DataSourceNoCloud

 

[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/1718959

Title:
  user-data ignored in DataSourceNoCloud

Status in cloud-init:
  Expired

Bug description:
  We have some OS-Templates with the official cloud-init packages.
  We use a ISO-Image to push the configuration to the VM. The ISO is always the same expect IP number, SSH-Key etc. (generated from the same template).

  [root@iptest-centos mnt]# cat meta-data
  instance-id: iptest-centos.saas.noris.de
  local-hostname: iptest-centos.saas.noris.de
  fqdn: iptest-centos.saas.noris.de

  network-interfaces: |
    auto eth0
    allow-hotplug eth0
    iface eth0 inet static
      dns-nameservers 62.128.1.42 62.128.1.53
      address 10.4.252.8
      netmask 255.255.0.0
      gateway 10.4.0.1

  manage-resolv-conf: true
  resolv_conf:
    nameservers: ['62.128.1.42', '62.128.1.53'][root@iptest-centos mnt]#
  [root@iptest-centos mnt]# cat user-data
  #cloud-config

  ssh_authorized_keys:
    - ssh-rsa A....

  chpasswd:
    list: |
      root:secret
    expire: False

  #power_state:
    #mode: reboot
    #message: Reboot after first Cloud-Init configuration
    #timeout: 10

  final_message: "$TIMESTAMP: System successfully configured with cloud-
  init from datasource $DATASOURCE."

  runcmd:
    # CD auswerfen, damit vCenter nicht stehen bleibt
    - [ eject, /dev/sr0 ]


  Template        Status  cloud-init Version

  Debian8 	OK 	0.7.6~bzr976-2 	
  Debian9 	ERROR 	0.7.9 (python3) 
  CentOS7 	OK 	0.7.9-9.el7.centos.2 (python2)
  RHEL7 	        OK 	0.7.6              Downgrade 0.7.9->0.7.6 due to other troubles
  Ubuntu1604 	ERROR 	0.7.9-233-ge586fe35-0ubuntu1~16.0 (python3) 
  Ubuntu1704 	ERROR 	0.7.9-233-ge586fe35-0ubuntu1~17.0 (python3) 	 
  Ubuntu1404 	OK 	0.7.5 	 
  SLES12 	        ERROR 	0.7.8 (python2)    Some kind of other trouble. 

  
  Systems with status "OK" are loading meta-data and user-data and execute the config.
  Systems with status "ERRIR" load meta-data only and ignoring the user-data completly.

  Log file from Debian9 (with '--debug'):
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,767 - handlers.py[DEBUG]: start: modules-config: running modules for config
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,768 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,769 - util.py[DEBUG]: Read 11 bytes from /proc/uptime
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,769 - util.py[DEBUG]: Reading from /var/lib/cloud/data/status.json (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,770 - util.py[DEBUG]: Read 465 bytes from /var/lib/cloud/data/status.json
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,770 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/status.json' => '../../var/lib/cloud/data/status.json'
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,771 - util.py[DEBUG]: Attempting to remove /run/cloud-init/status.json
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,772 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,772 - util.py[DEBUG]: Read 11 bytes from /proc/uptime
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,772 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,773 - util.py[DEBUG]: Read 1146 bytes from /etc/cloud/cloud.cfg
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,773 - util.py[DEBUG]: Attempting to load yaml from string of length 1146 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,783 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/99_noris.cfg (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,784 - util.py[DEBUG]: Read 427 bytes from /etc/cloud/cloud.cfg.d/99_noris.cfg
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,784 - util.py[DEBUG]: Attempting to load yaml from string of length 427 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,787 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,788 - util.py[DEBUG]: Read 197 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,788 - util.py[DEBUG]: Attempting to load yaml from string of length 197 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,789 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,789 - util.py[DEBUG]: Read 2057 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,789 - util.py[DEBUG]: Attempting to load yaml from string of length 2057 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,791 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_debian.cfg (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,792 - util.py[DEBUG]: Read 93 bytes from /etc/cloud/cloud.cfg.d/00_debian.cfg
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,792 - util.py[DEBUG]: Attempting to load yaml from string of length 93 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,793 - util.py[DEBUG]: Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,794 - util.py[DEBUG]: Running command ['running-in-container'] with allowed return codes [0] (shell=False, capture=True)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,796 - util.py[DEBUG]: Running command ['lxc-is-container'] with allowed return codes [0] (shell=False, capture=True)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,797 - util.py[DEBUG]: Reading from /proc/1/environ (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,797 - util.py[DEBUG]: Read 156 bytes from /proc/1/environ
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,797 - util.py[DEBUG]: Reading from /proc/self/status (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,797 - util.py[DEBUG]: Read 1003 bytes from /proc/self/status
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,797 - util.py[DEBUG]: Reading from /proc/cmdline (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,797 - util.py[DEBUG]: Read 92 bytes from /proc/cmdline
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,797 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,797 - util.py[DEBUG]: load_yaml given empty string, returning default
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,797 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,797 - util.py[DEBUG]: Read 26 bytes from /var/lib/cloud/instance/cloud-config.txt
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,797 - util.py[DEBUG]: Attempting to load yaml from string of length 26 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,797 - handlers.py[DEBUG]: start: modules-config/check-cache: attempting to read from cache [trust]
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,797 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,797 - util.py[DEBUG]: Read 9853 bytes from /var/lib/cloud/instance/obj.pkl
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,811 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,812 - util.py[DEBUG]: Read 29 bytes from /run/cloud-init/.instance-id
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,812 - stages.py[DEBUG]: restored from cache with run check: DataSourceNoCloud [seed=/dev/sr0][dsmode=net]
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,813 - handlers.py[DEBUG]: finish: modules-config/check-cache: SUCCESS: restored from cache with run check: DataSourceNoCloud [seed=/dev/sr0][dsmode=net]
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,815 - main.py[DEBUG]: Closing stdin
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,816 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,816 - util.py[DEBUG]: Read 1146 bytes from /etc/cloud/cloud.cfg
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,816 - util.py[DEBUG]: Attempting to load yaml from string of length 1146 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,823 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/99_noris.cfg (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,824 - util.py[DEBUG]: Read 427 bytes from /etc/cloud/cloud.cfg.d/99_noris.cfg
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,824 - util.py[DEBUG]: Attempting to load yaml from string of length 427 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,825 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,825 - util.py[DEBUG]: Read 197 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,825 - util.py[DEBUG]: Attempting to load yaml from string of length 197 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,827 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,828 - util.py[DEBUG]: Read 2057 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,828 - util.py[DEBUG]: Attempting to load yaml from string of length 2057 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,831 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_debian.cfg (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,832 - util.py[DEBUG]: Read 93 bytes from /etc/cloud/cloud.cfg.d/00_debian.cfg
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,832 - util.py[DEBUG]: Attempting to load yaml from string of length 93 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,833 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,833 - util.py[DEBUG]: load_yaml given empty string, returning default
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,834 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,836 - util.py[DEBUG]: Read 26 bytes from /var/lib/cloud/instance/cloud-config.txt
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,836 - util.py[DEBUG]: Attempting to load yaml from string of length 26 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,836 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,836 - util.py[DEBUG]: Read 26 bytes from /var/lib/cloud/instance/cloud-config.txt
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,836 - util.py[DEBUG]: Attempting to load yaml from string of length 26 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,836 - util.py[DEBUG]: Redirecting <_io.TextIOWrapper name='<stdout>' mode='w' encoding='UTF-8'> to | tee -a /var/log/cloud-init-output.log
  Sep 22 13:53:52 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,838 - util.py[DEBUG]: Redirecting <_io.TextIOWrapper name='<stderr>' mode='w' encoding='UTF-8'> to | tee -a /var/log/cloud-init-output.log
  Sep 22 13:53:53 iptest-debian9 cloud-init[767]: 2017-09-22 13:53:52,838 - main.py[DEBUG]: Logging being reset, this logger may no longer be active shortly
  Sep 22 13:53:53 iptest-debian9 cloud-init[767]: Cloud-init v. 0.7.9 running 'modules:config' at Fri, 22 Sep 2017 11:53:52 +0000. Up 10.60 seconds.
  Sep 22 13:53:53 iptest-debian9 systemd[1]: Started Apply the settings specified in cloud-config.
  Sep 22 13:53:53 iptest-debian9 systemd[1]: Starting Execute cloud user/final scripts...
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,754 - handlers.py[DEBUG]: start: modules-final: running modules for final
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,790 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,790 - util.py[DEBUG]: Read 11 bytes from /proc/uptime
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,790 - util.py[DEBUG]: Reading from /var/lib/cloud/data/status.json (quiet=False)
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,790 - util.py[DEBUG]: Read 493 bytes from /var/lib/cloud/data/status.json
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,791 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/status.json' => '../../var/lib/cloud/data/status.json'
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,791 - util.py[DEBUG]: Attempting to remove /run/cloud-init/status.json
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,792 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,792 - util.py[DEBUG]: Read 11 bytes from /proc/uptime
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,792 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,792 - util.py[DEBUG]: Read 1146 bytes from /etc/cloud/cloud.cfg
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,792 - util.py[DEBUG]: Attempting to load yaml from string of length 1146 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,800 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/99_noris.cfg (quiet=False)
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,801 - util.py[DEBUG]: Read 427 bytes from /etc/cloud/cloud.cfg.d/99_noris.cfg
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,801 - util.py[DEBUG]: Attempting to load yaml from string of length 427 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,803 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False)
  Sep 22 13:53:54 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,803 - util.py[DEBUG]: Read 197 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,803 - util.py[DEBUG]: Attempting to load yaml from string of length 197 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,805 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,805 - util.py[DEBUG]: Read 2057 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,805 - util.py[DEBUG]: Attempting to load yaml from string of length 2057 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,891 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_debian.cfg (quiet=False)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,891 - util.py[DEBUG]: Read 93 bytes from /etc/cloud/cloud.cfg.d/00_debian.cfg
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,891 - util.py[DEBUG]: Attempting to load yaml from string of length 93 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,894 - util.py[DEBUG]: Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,898 - util.py[DEBUG]: Running command ['running-in-container'] with allowed return codes [0] (shell=False, capture=True)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,900 - util.py[DEBUG]: Running command ['lxc-is-container'] with allowed return codes [0] (shell=False, capture=True)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,901 - util.py[DEBUG]: Reading from /proc/1/environ (quiet=False)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,902 - util.py[DEBUG]: Read 156 bytes from /proc/1/environ
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,902 - util.py[DEBUG]: Reading from /proc/self/status (quiet=False)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,902 - util.py[DEBUG]: Read 1003 bytes from /proc/self/status
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,902 - util.py[DEBUG]: Reading from /proc/cmdline (quiet=False)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,902 - util.py[DEBUG]: Read 92 bytes from /proc/cmdline
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,902 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,902 - util.py[DEBUG]: load_yaml given empty string, returning default
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,903 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,903 - util.py[DEBUG]: Read 26 bytes from /var/lib/cloud/instance/cloud-config.txt
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,903 - util.py[DEBUG]: Attempting to load yaml from string of length 26 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,904 - handlers.py[DEBUG]: start: modules-final/check-cache: attempting to read from cache [trust]
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,904 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,904 - util.py[DEBUG]: Read 9853 bytes from /var/lib/cloud/instance/obj.pkl
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,998 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,999 - util.py[DEBUG]: Read 29 bytes from /run/cloud-init/.instance-id
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,999 - stages.py[DEBUG]: restored from cache with run check: DataSourceNoCloud [seed=/dev/sr0][dsmode=net]
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,999 - handlers.py[DEBUG]: finish: modules-final/check-cache: SUCCESS: restored from cache with run check: DataSourceNoCloud [seed=/dev/sr0][dsmode=net]
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,999 - main.py[DEBUG]: Closing stdin
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,999 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,999 - util.py[DEBUG]: Read 1146 bytes from /etc/cloud/cloud.cfg
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:54,999 - util.py[DEBUG]: Attempting to load yaml from string of length 1146 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,090 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/99_noris.cfg (quiet=False)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,091 - util.py[DEBUG]: Read 427 bytes from /etc/cloud/cloud.cfg.d/99_noris.cfg
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,091 - util.py[DEBUG]: Attempting to load yaml from string of length 427 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,094 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90_dpkg.cfg (quiet=False)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,094 - util.py[DEBUG]: Read 197 bytes from /etc/cloud/cloud.cfg.d/90_dpkg.cfg
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,094 - util.py[DEBUG]: Attempting to load yaml from string of length 197 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,096 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/05_logging.cfg (quiet=False)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,096 - util.py[DEBUG]: Read 2057 bytes from /etc/cloud/cloud.cfg.d/05_logging.cfg
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,096 - util.py[DEBUG]: Attempting to load yaml from string of length 2057 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,100 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/00_debian.cfg (quiet=False)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,100 - util.py[DEBUG]: Read 93 bytes from /etc/cloud/cloud.cfg.d/00_debian.cfg
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,100 - util.py[DEBUG]: Attempting to load yaml from string of length 93 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,102 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,102 - util.py[DEBUG]: load_yaml given empty string, returning default
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,102 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,102 - util.py[DEBUG]: Read 26 bytes from /var/lib/cloud/instance/cloud-config.txt
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,102 - util.py[DEBUG]: Attempting to load yaml from string of length 26 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,103 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,103 - util.py[DEBUG]: Read 26 bytes from /var/lib/cloud/instance/cloud-config.txt
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,103 - util.py[DEBUG]: Attempting to load yaml from string of length 26 with allowed root types (<class 'dict'>,)
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,104 - util.py[DEBUG]: Redirecting <_io.TextIOWrapper name='<stdout>' mode='w' encoding='UTF-8'> to | tee -a /var/log/cloud-init-output.log
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,105 - util.py[DEBUG]: Redirecting <_io.TextIOWrapper name='<stderr>' mode='w' encoding='UTF-8'> to | tee -a /var/log/cloud-init-output.log
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: 2017-09-22 13:53:55,105 - main.py[DEBUG]: Logging being reset, this logger may no longer be active shortly
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: Cloud-init v. 0.7.9 running 'modules:final' at Fri, 22 Sep 2017 11:53:54 +0000. Up 12.62 seconds.
  Sep 22 13:53:55 iptest-debian9 cloud-init[789]: Cloud-init v. 0.7.9 finished at Fri, 22 Sep 2017 11:53:55 +0000. Datasource DataSourceNoCloud [seed=/dev/sr0][dsmode=net].  Up 13.12 seconds
  Sep 22 13:53:55 iptest-debian9 systemd[1]: Started Execute cloud user/final scripts.
  Sep 22 13:53:55 iptest-debian9 systemd[1]: Reached target Cloud-init target.
  Sep 22 13:53:55 iptest-debian9 systemd[1]: Startup finished in 5.090s (kernel) + 8.342s (userspace) = 13.433s.

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


References