root@46-f4:~# tail -f /var/log/cloud-init*.log
==> /var/log/cloud-init-output.log <==
 ==> File on system created by you or by a script.
 ==> File also in package provided by package maintainer.
 ==> Using current old file as you requested.
ERROR: ld.so: object 'libeatmydata.so' from LD_PRELOAD cannot be preloaded (cannot open shared object file): ignored.
Created symlink /etc/systemd/system/network-pre.target.wants/ntp-systemd-netif.path → /lib/systemd/system/ntp-systemd-netif.path.
Created symlink /etc/systemd/system/multi-user.target.wants/ntp.service → /lib/systemd/system/ntp.service.
ntp-systemd-netif.service is a disabled or a static unit, not starting it.
Processing triggers for libc-bin (2.27-0ubuntu2) ...
Processing triggers for ureadahead (0.100.0-20) ...
Processing triggers for systemd (237-3ubuntu4) ...

==> /var/log/cloud-init.log <==
2018-04-03 17:20:39,895 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini
2018-04-03 17:20:39,897 - util.py[DEBUG]: Reading from /etc/cloud/templates/ntp.conf.ubuntu.tmpl (quiet=False)
2018-04-03 17:20:39,906 - util.py[DEBUG]: Read 2509 bytes from /etc/cloud/templates/ntp.conf.ubuntu.tmpl
2018-04-03 17:20:39,906 - templater.py[DEBUG]: Rendering content of '/etc/cloud/templates/ntp.conf.ubuntu.tmpl' using renderer jinja
2018-04-03 17:20:39,914 - util.py[DEBUG]: Writing to /etc/ntp.conf - wb: [644] 2325 bytes
2018-04-03 17:20:39,916 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/update_sources - wb: [644] 24 bytes
2018-04-03 17:20:39,917 - helpers.py[DEBUG]: Running update-sources using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/update_sources'>)
2018-04-03 17:20:39,917 - util.py[DEBUG]: Running command ['eatmydata', 'apt-get', '--option=Dpkg::Options::=--force-confold', '--option=Dpkg::options::=--force-unsafe-io', '--assume-yes', '--quiet', 'update'] with allowed return codes [0] (shell=False, capture=False)
2018-04-03 17:20:50,127 - util.py[DEBUG]: apt-update [eatmydata apt-get --option=Dpkg::Options::=--force-confold --option=Dpkg::options::=--force-unsafe-io --assume-yes --quiet update] took 10.210 seconds
2018-04-03 17:20:50,128 - util.py[DEBUG]: Running command ['eatmydata', 'apt-get', '--option=Dpkg::Options::=--force-confold', '--option=Dpkg::options::=--force-unsafe-io', '--assume-yes', '--quiet', 'install', 'ntp'] with allowed return codes [0] (shell=False, capture=False)

==> /var/log/cloud-init-output.log <==
Cloud-init v. 18.1 running 'modules:config' at Tue, 03 Apr 2018 17:20:37 +0000. Up 30.26 seconds.

==> /var/log/cloud-init.log <==
2018-04-03 17:20:58,251 - util.py[DEBUG]: apt-install [eatmydata apt-get --option=Dpkg::Options::=--force-confold --option=Dpkg::options::=--force-unsafe-io --assume-yes --quiet install ntp] took 8.123 seconds
2018-04-03 17:20:58,252 - util.py[DEBUG]: Running command ['systemctl', 'reload-or-restart', 'ntp'] with allowed return codes [0] (shell=False, capture=True)
2018-04-03 17:20:58,292 - handlers.py[DEBUG]: finish: modules-config/config-ntp: SUCCESS: config-ntp ran successfully
2018-04-03 17:20:58,293 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="10836729409546132331522776058", oauth_timestamp="1522776058", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:58,299 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:58,303 - stages.py[DEBUG]: Running module timezone (<module 'cloudinit.config.cc_timezone' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_timezone.py'>) with frequency once-per-instance
2018-04-03 17:20:58,305 - handlers.py[DEBUG]: start: modules-config/config-timezone: running config-timezone with frequency once-per-instance
2018-04-03 17:20:58,307 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="2025235322638071541522776058", oauth_timestamp="1522776058", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:58,312 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:58,312 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_timezone - wb: [644] 24 bytes
2018-04-03 17:20:58,316 - helpers.py[DEBUG]: Running config-timezone using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_timezone'>)
2018-04-03 17:20:58,316 - cc_timezone.py[DEBUG]: Skipping module named timezone, no 'timezone' specified
2018-04-03 17:20:58,317 - handlers.py[DEBUG]: finish: modules-config/config-timezone: SUCCESS: config-timezone ran successfully
2018-04-03 17:20:58,317 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="167575691052861641061522776058", oauth_timestamp="1522776058", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:58,323 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:58,324 - stages.py[DEBUG]: Running module disable-ec2-metadata (<module 'cloudinit.config.cc_disable_ec2_metadata' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_disable_ec2_metadata.py'>) with frequency always
2018-04-03 17:20:58,324 - handlers.py[DEBUG]: start: modules-config/config-disable-ec2-metadata: running config-disable-ec2-metadata with frequency always
2018-04-03 17:20:58,324 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="18557314662273336851522776058", oauth_timestamp="1522776058", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:58,329 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:58,329 - helpers.py[DEBUG]: Running config-disable-ec2-metadata using lock (<cloudinit.helpers.DummyLock object at 0x7f5de297ec50>)
2018-04-03 17:20:58,329 - cc_disable_ec2_metadata.py[DEBUG]: Skipping module named disable-ec2-metadata, disabling the ec2 route not enabled
2018-04-03 17:20:58,329 - handlers.py[DEBUG]: finish: modules-config/config-disable-ec2-metadata: SUCCESS: config-disable-ec2-metadata ran successfully
2018-04-03 17:20:58,330 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="25409794327276103851522776058", oauth_timestamp="1522776058", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:58,334 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:58,334 - stages.py[DEBUG]: Running module runcmd (<module 'cloudinit.config.cc_runcmd' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_runcmd.py'>) with frequency once-per-instance
2018-04-03 17:20:58,334 - handlers.py[DEBUG]: start: modules-config/config-runcmd: running config-runcmd with frequency once-per-instance
2018-04-03 17:20:58,335 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="162676664833142553731522776058", oauth_timestamp="1522776058", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:58,339 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:58,340 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_runcmd - wb: [644] 23 bytes
2018-04-03 17:20:58,340 - helpers.py[DEBUG]: Running config-runcmd using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_runcmd'>)
2018-04-03 17:20:58,341 - cc_runcmd.py[DEBUG]: Skipping module named runcmd, no 'runcmd' key in configuration
2018-04-03 17:20:58,341 - handlers.py[DEBUG]: finish: modules-config/config-runcmd: SUCCESS: config-runcmd ran successfully
2018-04-03 17:20:58,341 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="31292150916189533231522776058", oauth_timestamp="1522776058", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:58,344 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:58,344 - stages.py[DEBUG]: Running module byobu (<module 'cloudinit.config.cc_byobu' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_byobu.py'>) with frequency once-per-instance
2018-04-03 17:20:58,345 - handlers.py[DEBUG]: start: modules-config/config-byobu: running config-byobu with frequency once-per-instance
2018-04-03 17:20:58,345 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="107502522209895708401522776058", oauth_timestamp="1522776058", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:58,348 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:58,348 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_byobu - wb: [644] 24 bytes
2018-04-03 17:20:58,348 - helpers.py[DEBUG]: Running config-byobu using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_byobu'>)
2018-04-03 17:20:58,349 - cc_byobu.py[DEBUG]: Skipping module named byobu, no 'byobu' values found
2018-04-03 17:20:58,349 - handlers.py[DEBUG]: finish: modules-config/config-byobu: SUCCESS: config-byobu ran successfully
2018-04-03 17:20:58,350 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="13455062872043240301522776058", oauth_timestamp="1522776058", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:58,354 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:58,354 - main.py[DEBUG]: Ran 15 modules with 0 failures
2018-04-03 17:20:58,355 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
2018-04-03 17:20:58,355 - util.py[DEBUG]: Read 12 bytes from /proc/uptime
2018-04-03 17:20:58,355 - util.py[DEBUG]: cloud-init mode 'modules' took 20.893 seconds (20.89)
2018-04-03 17:20:58,355 - handlers.py[DEBUG]: finish: modules-config: SUCCESS: running modules for config
2018-04-03 17:20:58,355 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="165427106057858406661522776058", oauth_timestamp="1522776058", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:58,382 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,081 - util.py[DEBUG]: Cloud-init v. 18.1 running 'modules:final' at Tue, 03 Apr 2018 17:20:58 +0000. Up 51.75 seconds.

==> /var/log/cloud-init-output.log <==
Hit:1 http://br.archive.ubuntu.com/ubuntu bionic InRelease
Hit:2 http://br.archive.ubuntu.com/ubuntu bionic-updates InRelease
Hit:3 http://br.archive.ubuntu.com/ubuntu bionic-backports InRelease
Hit:4 http://br.archive.ubuntu.com/ubuntu bionic-security InRelease

==> /var/log/cloud-init.log <==
2018-04-03 17:20:59,170 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.ubuntu.Distro'>
2018-04-03 17:20:59,171 - stages.py[DEBUG]: Running module snappy (<module 'cloudinit.config.cc_snappy' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_snappy.py'>) with frequency once-per-instance
2018-04-03 17:20:59,171 - handlers.py[DEBUG]: start: modules-final/config-snappy: running config-snappy with frequency once-per-instance
2018-04-03 17:20:59,179 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="92029991053207899881522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,184 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,185 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_snappy - wb: [644] 25 bytes
2018-04-03 17:20:59,186 - helpers.py[DEBUG]: Running config-snappy using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_snappy'>)
2018-04-03 17:20:59,186 - util.py[DEBUG]: Reading from /etc/os-release (quiet=True)
2018-04-03 17:20:59,186 - util.py[DEBUG]: Read 407 bytes from /etc/os-release
2018-04-03 17:20:59,186 - util.py[DEBUG]: Reading from /etc/system-image/channel.ini (quiet=True)
2018-04-03 17:20:59,187 - util.py[DEBUG]: Read 0 bytes from /etc/system-image/channel.ini
2018-04-03 17:20:59,187 - cc_snappy.py[DEBUG]: snappy: 'auto' mode, and system not snappy
2018-04-03 17:20:59,187 - handlers.py[DEBUG]: finish: modules-final/config-snappy: SUCCESS: config-snappy ran successfully
2018-04-03 17:20:59,187 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="51768508429096415961522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,190 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,190 - stages.py[DEBUG]: Running module package-update-upgrade-install (<module 'cloudinit.config.cc_package_update_upgrade_install' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_package_update_upgrade_install.py'>) with frequency once-per-instance
2018-04-03 17:20:59,191 - handlers.py[DEBUG]: start: modules-final/config-package-update-upgrade-install: running config-package-update-upgrade-install with frequency once-per-instance
2018-04-03 17:20:59,191 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="35733419438948249881522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,194 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,194 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_package_update_upgrade_install - wb: [644] 25 bytes
2018-04-03 17:20:59,194 - helpers.py[DEBUG]: Running config-package-update-upgrade-install using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_package_update_upgrade_install'>)
2018-04-03 17:20:59,194 - handlers.py[DEBUG]: finish: modules-final/config-package-update-upgrade-install: SUCCESS: config-package-update-upgrade-install ran successfully
2018-04-03 17:20:59,195 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="51446701545147869761522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,198 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,198 - stages.py[DEBUG]: Running module fan (<module 'cloudinit.config.cc_fan' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_fan.py'>) with frequency once-per-instance
2018-04-03 17:20:59,199 - handlers.py[DEBUG]: start: modules-final/config-fan: running config-fan with frequency once-per-instance
2018-04-03 17:20:59,199 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="135151725950205763831522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,205 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,205 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_fan - wb: [644] 24 bytes
2018-04-03 17:20:59,206 - helpers.py[DEBUG]: Running config-fan using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_fan'>)
2018-04-03 17:20:59,206 - cc_fan.py[DEBUG]: fan: no 'fan' config entry. disabling
2018-04-03 17:20:59,207 - handlers.py[DEBUG]: finish: modules-final/config-fan: SUCCESS: config-fan ran successfully
2018-04-03 17:20:59,207 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="134381060216010632231522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,210 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,210 - stages.py[DEBUG]: Running module landscape (<module 'cloudinit.config.cc_landscape' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_landscape.py'>) with frequency once-per-instance
2018-04-03 17:20:59,211 - handlers.py[DEBUG]: start: modules-final/config-landscape: running config-landscape with frequency once-per-instance
2018-04-03 17:20:59,211 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="71609516247246187651522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,214 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,214 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_landscape - wb: [644] 25 bytes
2018-04-03 17:20:59,215 - helpers.py[DEBUG]: Running config-landscape using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_landscape'>)
2018-04-03 17:20:59,215 - handlers.py[DEBUG]: finish: modules-final/config-landscape: SUCCESS: config-landscape ran successfully
2018-04-03 17:20:59,215 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="57284219844549708281522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,219 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,219 - stages.py[DEBUG]: Running module lxd (<module 'cloudinit.config.cc_lxd' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_lxd.py'>) with frequency once-per-instance
2018-04-03 17:20:59,220 - handlers.py[DEBUG]: start: modules-final/config-lxd: running config-lxd with frequency once-per-instance
2018-04-03 17:20:59,220 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="120617359943820197361522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,223 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,224 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_lxd - wb: [644] 25 bytes
2018-04-03 17:20:59,224 - helpers.py[DEBUG]: Running config-lxd using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_lxd'>)
2018-04-03 17:20:59,224 - cc_lxd.py[DEBUG]: Skipping module named lxd, not present or disabled by cfg
2018-04-03 17:20:59,225 - handlers.py[DEBUG]: finish: modules-final/config-lxd: SUCCESS: config-lxd ran successfully
2018-04-03 17:20:59,225 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="133676489044773639661522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,228 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,228 - stages.py[DEBUG]: Running module puppet (<module 'cloudinit.config.cc_puppet' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_puppet.py'>) with frequency once-per-instance
2018-04-03 17:20:59,229 - handlers.py[DEBUG]: start: modules-final/config-puppet: running config-puppet with frequency once-per-instance
2018-04-03 17:20:59,229 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="175810896596742875411522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,234 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,235 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_puppet - wb: [644] 25 bytes
2018-04-03 17:20:59,235 - helpers.py[DEBUG]: Running config-puppet using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_puppet'>)
2018-04-03 17:20:59,235 - cc_puppet.py[DEBUG]: Skipping module named puppet, no 'puppet' configuration found
2018-04-03 17:20:59,236 - handlers.py[DEBUG]: finish: modules-final/config-puppet: SUCCESS: config-puppet ran successfully
2018-04-03 17:20:59,236 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="99954767532051528441522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,239 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,240 - stages.py[DEBUG]: Running module chef (<module 'cloudinit.config.cc_chef' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_chef.py'>) with frequency once-per-instance
2018-04-03 17:20:59,240 - handlers.py[DEBUG]: start: modules-final/config-chef: running config-chef with frequency once-per-instance
2018-04-03 17:20:59,240 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="35911362605340738701522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,243 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,244 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_chef - wb: [644] 25 bytes
2018-04-03 17:20:59,244 - helpers.py[DEBUG]: Running config-chef using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_chef'>)
2018-04-03 17:20:59,244 - cc_chef.py[DEBUG]: Skipping module named chef, no 'chef' key in configuration
2018-04-03 17:20:59,244 - handlers.py[DEBUG]: finish: modules-final/config-chef: SUCCESS: config-chef ran successfully
2018-04-03 17:20:59,245 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="158198244503929623241522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,248 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,248 - stages.py[DEBUG]: Running module mcollective (<module 'cloudinit.config.cc_mcollective' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_mcollective.py'>) with frequency once-per-instance
2018-04-03 17:20:59,248 - handlers.py[DEBUG]: start: modules-final/config-mcollective: running config-mcollective with frequency once-per-instance
2018-04-03 17:20:59,250 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="179677246534401758291522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,254 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,254 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_mcollective - wb: [644] 25 bytes
2018-04-03 17:20:59,254 - helpers.py[DEBUG]: Running config-mcollective using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_mcollective'>)
2018-04-03 17:20:59,255 - cc_mcollective.py[DEBUG]: Skipping module named mcollective, no 'mcollective' key in configuration
2018-04-03 17:20:59,255 - handlers.py[DEBUG]: finish: modules-final/config-mcollective: SUCCESS: config-mcollective ran successfully
2018-04-03 17:20:59,255 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="71988065791658673771522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,259 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,259 - stages.py[DEBUG]: Running module salt-minion (<module 'cloudinit.config.cc_salt_minion' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_salt_minion.py'>) with frequency once-per-instance
2018-04-03 17:20:59,259 - handlers.py[DEBUG]: start: modules-final/config-salt-minion: running config-salt-minion with frequency once-per-instance
2018-04-03 17:20:59,259 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="173069009852804314881522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,263 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,263 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_salt_minion - wb: [644] 24 bytes
2018-04-03 17:20:59,264 - helpers.py[DEBUG]: Running config-salt-minion using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_salt_minion'>)
2018-04-03 17:20:59,264 - cc_salt_minion.py[DEBUG]: Skipping module named salt-minion, no 'salt_minion' key in configuration
2018-04-03 17:20:59,264 - handlers.py[DEBUG]: finish: modules-final/config-salt-minion: SUCCESS: config-salt-minion ran successfully
2018-04-03 17:20:59,265 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="102757871693080261331522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,270 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,271 - stages.py[DEBUG]: Running module rightscale_userdata (<module 'cloudinit.config.cc_rightscale_userdata' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_rightscale_userdata.py'>) with frequency once-per-instance
2018-04-03 17:20:59,271 - handlers.py[DEBUG]: start: modules-final/config-rightscale_userdata: running config-rightscale_userdata with frequency once-per-instance
2018-04-03 17:20:59,272 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="73473725294039618301522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,276 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,277 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_rightscale_userdata - wb: [644] 25 bytes
2018-04-03 17:20:59,279 - helpers.py[DEBUG]: Running config-rightscale_userdata using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_rightscale_userdata'>)
2018-04-03 17:20:59,279 - cc_rightscale_userdata.py[DEBUG]: Failed to get raw userdata in module rightscale_userdata
2018-04-03 17:20:59,279 - handlers.py[DEBUG]: finish: modules-final/config-rightscale_userdata: SUCCESS: config-rightscale_userdata ran successfully
2018-04-03 17:20:59,279 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="108483151826898618351522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,284 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,284 - stages.py[DEBUG]: Running module scripts-vendor (<module 'cloudinit.config.cc_scripts_vendor' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_vendor.py'>) with frequency once-per-instance
2018-04-03 17:20:59,285 - handlers.py[DEBUG]: start: modules-final/config-scripts-vendor: running config-scripts-vendor with frequency once-per-instance
2018-04-03 17:20:59,285 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="169413890933402264531522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,289 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,290 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_scripts_vendor - wb: [644] 25 bytes
2018-04-03 17:20:59,290 - helpers.py[DEBUG]: Running config-scripts-vendor using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_scripts_vendor'>)
2018-04-03 17:20:59,291 - handlers.py[DEBUG]: finish: modules-final/config-scripts-vendor: SUCCESS: config-scripts-vendor ran successfully
2018-04-03 17:20:59,291 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="176442231898672690081522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,294 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,295 - stages.py[DEBUG]: Running module scripts-per-once (<module 'cloudinit.config.cc_scripts_per_once' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_per_once.py'>) with frequency once
2018-04-03 17:20:59,295 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-once: running config-scripts-per-once with frequency once
2018-04-03 17:20:59,296 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="61453498172944379851522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,300 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,301 - util.py[DEBUG]: Writing to /var/lib/cloud/sem/config_scripts_per_once.once - wb: [644] 25 bytes
2018-04-03 17:20:59,302 - helpers.py[DEBUG]: Running config-scripts-per-once using lock (<FileLock using file '/var/lib/cloud/sem/config_scripts_per_once.once'>)
2018-04-03 17:20:59,302 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-once: SUCCESS: config-scripts-per-once ran successfully
2018-04-03 17:20:59,302 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="162009272366227368881522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,307 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,307 - stages.py[DEBUG]: Running module scripts-per-boot (<module 'cloudinit.config.cc_scripts_per_boot' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_per_boot.py'>) with frequency always
2018-04-03 17:20:59,308 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-boot: running config-scripts-per-boot with frequency always
2018-04-03 17:20:59,308 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="33905978598771980441522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,316 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,316 - helpers.py[DEBUG]: Running config-scripts-per-boot using lock (<cloudinit.helpers.DummyLock object at 0x7f37ffaeb748>)
2018-04-03 17:20:59,317 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-boot: SUCCESS: config-scripts-per-boot ran successfully
2018-04-03 17:20:59,317 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="136380264201196278201522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,321 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,322 - stages.py[DEBUG]: Running module scripts-per-instance (<module 'cloudinit.config.cc_scripts_per_instance' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_per_instance.py'>) with frequency once-per-instance
2018-04-03 17:20:59,322 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-instance: running config-scripts-per-instance with frequency once-per-instance
2018-04-03 17:20:59,322 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="153267256030246982061522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,326 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,326 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_scripts_per_instance - wb: [644] 25 bytes
2018-04-03 17:20:59,326 - helpers.py[DEBUG]: Running config-scripts-per-instance using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_scripts_per_instance'>)
2018-04-03 17:20:59,327 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-instance: SUCCESS: config-scripts-per-instance ran successfully
2018-04-03 17:20:59,327 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="9435928006454176721522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,331 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,331 - stages.py[DEBUG]: Running module scripts-user (<module 'cloudinit.config.cc_scripts_user' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_user.py'>) with frequency once-per-instance
2018-04-03 17:20:59,331 - handlers.py[DEBUG]: start: modules-final/config-scripts-user: running config-scripts-user with frequency once-per-instance
2018-04-03 17:20:59,332 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="21459480908114326361522776059", oauth_timestamp="1522776059", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:20:59,339 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:20:59,339 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_scripts_user - wb: [644] 25 bytes
2018-04-03 17:20:59,340 - helpers.py[DEBUG]: Running config-scripts-user using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_scripts_user'>)
2018-04-03 17:20:59,340 - util.py[DEBUG]: Running command ['/var/lib/cloud/instance/scripts/part-001'] with allowed return codes [0] (shell=False, capture=False)

==> /var/log/cloud-init-output.log <==
Reading package lists...
Reading package lists...
Building dependency tree...
Reading state information...
The following package was automatically installed and is no longer required:
  libfreetype6
Use 'apt autoremove' to remove it.
The following additional packages will be installed:
  libnvpair1linux libuutil1linux libzfs2linux libzpool2linux
Suggested packages:
  nfs-kernel-server samba-common-bin zfs-initramfs | zfs-dracut zfs-modules
  | zfs-dkms
Recommended packages:
  zfs-zed
The following NEW packages will be installed:
  libnvpair1linux libuutil1linux libzfs2linux libzpool2linux zfsutils-linux
0 upgraded, 5 newly installed, 0 to remove and 67 not upgraded.
Need to get 1106 kB of archives.
After this operation, 3963 kB of additional disk space will be used.
Get:1 http://br.archive.ubuntu.com/ubuntu bionic/main amd64 libnvpair1linux amd64 0.7.5-1ubuntu13 [43.8 kB]
Get:2 http://br.archive.ubuntu.com/ubuntu bionic/main amd64 libuutil1linux amd64 0.7.5-1ubuntu13 [46.5 kB]
Get:3 http://br.archive.ubuntu.com/ubuntu bionic/main amd64 libzpool2linux amd64 0.7.5-1ubuntu13 [547 kB]
Get:4 http://br.archive.ubuntu.com/ubuntu bionic/main amd64 libzfs2linux amd64 0.7.5-1ubuntu13 [128 kB]
Get:5 http://br.archive.ubuntu.com/ubuntu bionic/main amd64 zfsutils-linux amd64 0.7.5-1ubuntu13 [342 kB]
Fetched 1106 kB in 0s (21.8 MB/s)
Selecting previously unselected package libnvpair1linux.
(Reading database ... 28716 files and directories currently installed.)
Preparing to unpack .../libnvpair1linux_0.7.5-1ubuntu13_amd64.deb ...
Unpacking libnvpair1linux (0.7.5-1ubuntu13) ...
Selecting previously unselected package libuutil1linux.
Preparing to unpack .../libuutil1linux_0.7.5-1ubuntu13_amd64.deb ...
Unpacking libuutil1linux (0.7.5-1ubuntu13) ...
Selecting previously unselected package libzpool2linux.
Preparing to unpack .../libzpool2linux_0.7.5-1ubuntu13_amd64.deb ...
Unpacking libzpool2linux (0.7.5-1ubuntu13) ...
Selecting previously unselected package libzfs2linux.
Preparing to unpack .../libzfs2linux_0.7.5-1ubuntu13_amd64.deb ...
Unpacking libzfs2linux (0.7.5-1ubuntu13) ...
Selecting previously unselected package zfsutils-linux.
Preparing to unpack .../zfsutils-linux_0.7.5-1ubuntu13_amd64.deb ...
Unpacking zfsutils-linux (0.7.5-1ubuntu13) ...
Setting up libuutil1linux (0.7.5-1ubuntu13) ...
Setting up libnvpair1linux (0.7.5-1ubuntu13) ...
Processing triggers for libc-bin (2.27-0ubuntu2) ...
Processing triggers for man-db (2.8.2-1) ...
Setting up libzpool2linux (0.7.5-1ubuntu13) ...
Setting up libzfs2linux (0.7.5-1ubuntu13) ...
Setting up zfsutils-linux (0.7.5-1ubuntu13) ...
Created symlink /etc/systemd/system/zfs-import.target.wants/zfs-import-cache.service → /lib/systemd/system/zfs-import-cache.service.
Created symlink /etc/systemd/system/zfs-mount.service.wants/zfs-import.target → /lib/systemd/system/zfs-import.target.
Created symlink /etc/systemd/system/zfs.target.wants/zfs-import.target → /lib/systemd/system/zfs-import.target.
Created symlink /etc/systemd/system/zfs-mount.service.wants/zfs-load-module.service → /lib/systemd/system/zfs-load-module.service.
Created symlink /etc/systemd/system/zfs.target.wants/zfs-load-module.service → /lib/systemd/system/zfs-load-module.service.
Created symlink /etc/systemd/system/zfs-share.service.wants/zfs-mount.service → /lib/systemd/system/zfs-mount.service.
Created symlink /etc/systemd/system/zfs.target.wants/zfs-mount.service → /lib/systemd/system/zfs-mount.service.
Created symlink /etc/systemd/system/zfs.target.wants/zfs-share.service → /lib/systemd/system/zfs-share.service.
Created symlink /etc/systemd/system/multi-user.target.wants/zfs.target → /lib/systemd/system/zfs.target.
zfs-import-scan.service is a disabled or a static unit, not starting it.
Processing triggers for libc-bin (2.27-0ubuntu2) ...
curtin: Installation started. (18.1-1-g45564eef-0ubuntu1)
third party drivers not installed or necessary.
An error occured handling 'vda-part1_format_zfsroot_pool': FileNotFoundError - [Errno 2] No such file or directory: '/dev/disk/by-id'
[Errno 2] No such file or directory: '/dev/disk/by-id'
curtin: Installation failed with exception: Unexpected error while running command.
Command: ['curtin', 'block-meta', 'custom']
Exit code: 3
Reason: -
Stdout: An error occured handling 'vda-part1_format_zfsroot_pool': FileNotFoundError - [Errno 2] No such file or directory: '/dev/disk/by-id'
        [Errno 2] No such file or directory: '/dev/disk/by-id'
        
Stderr: ''
Wrote: /var/log/curtin/curtin-error-logs.tar
Unexpected error while running command.
Command: ['curtin', 'block-meta', 'custom']
Exit code: 3
Reason: -
Stdout: An error occured handling 'vda-part1_format_zfsroot_pool': FileNotFoundError - [Errno 2] No such file or directory: '/dev/disk/by-id'
        [Errno 2] No such file or directory: '/dev/disk/by-id'
        
Stderr: ''
Cloud-init v. 18.1 running 'modules:final' at Tue, 03 Apr 2018 17:20:58 +0000. Up 51.75 seconds.
2018-04-03 17:21:13,111 - util.py[WARNING]: Failed running /var/lib/cloud/instance/scripts/part-001 [3]
2018-04-03 17:21:13,119 - cc_scripts_user.py[WARNING]: Failed to run module scripts-user (scripts in /var/lib/cloud/instance/scripts)
2018-04-03 17:21:13,127 - util.py[WARNING]: Running module scripts-user (<module 'cloudinit.config.cc_scripts_user' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_user.py'>) failed
Cloud-init v. 18.1 finished at Tue, 03 Apr 2018 17:21:13 +0000. Datasource DataSourceMAAS [http://10.0.5.5:5240/MAAS/metadata/curtin].  Up 66.02 seconds

==> /var/log/cloud-init.log <==
2018-04-03 17:21:13,111 - util.py[WARNING]: Failed running /var/lib/cloud/instance/scripts/part-001 [3]
2018-04-03 17:21:13,114 - util.py[DEBUG]: Failed running /var/lib/cloud/instance/scripts/part-001 [3]
Traceback (most recent call last):
  File "/usr/lib/python3/dist-packages/cloudinit/util.py", line 802, in runparts
    subp(prefix + [exe_path], capture=False)
  File "/usr/lib/python3/dist-packages/cloudinit/util.py", line 1957, in subp
    cmd=args)
cloudinit.util.ProcessExecutionError: Unexpected error while running command.
Command: ['/var/lib/cloud/instance/scripts/part-001']
Exit code: 3
Reason: -
Stdout: -
Stderr: -
2018-04-03 17:21:13,119 - cc_scripts_user.py[WARNING]: Failed to run module scripts-user (scripts in /var/lib/cloud/instance/scripts)
2018-04-03 17:21:13,121 - handlers.py[DEBUG]: finish: modules-final/config-scripts-user: FAIL: running config-scripts-user with frequency once-per-instance
2018-04-03 17:21:13,121 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="115145188976214673091522776073", oauth_timestamp="1522776073", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:21:13,127 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:21:13,127 - util.py[WARNING]: Running module scripts-user (<module 'cloudinit.config.cc_scripts_user' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_user.py'>) failed
2018-04-03 17:21:13,129 - util.py[DEBUG]: Running module scripts-user (<module 'cloudinit.config.cc_scripts_user' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_user.py'>) failed
Traceback (most recent call last):
  File "/usr/lib/python3/dist-packages/cloudinit/stages.py", line 792, in _run_modules
    freq=freq)
  File "/usr/lib/python3/dist-packages/cloudinit/cloud.py", line 54, in run
    return self._runners.run(name, functor, args, freq, clear_on_fail)
  File "/usr/lib/python3/dist-packages/cloudinit/helpers.py", line 187, in run
    results = functor(*args)
  File "/usr/lib/python3/dist-packages/cloudinit/config/cc_scripts_user.py", line 45, in handle
    util.runparts(runparts_path)
  File "/usr/lib/python3/dist-packages/cloudinit/util.py", line 809, in runparts
    % (len(failed), len(attempted)))
RuntimeError: Runparts: 1 failures in 1 attempted commands
2018-04-03 17:21:13,144 - stages.py[DEBUG]: Running module ssh-authkey-fingerprints (<module 'cloudinit.config.cc_ssh_authkey_fingerprints' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_ssh_authkey_fingerprints.py'>) with frequency once-per-instance
2018-04-03 17:21:13,144 - handlers.py[DEBUG]: start: modules-final/config-ssh-authkey-fingerprints: running config-ssh-authkey-fingerprints with frequency once-per-instance
2018-04-03 17:21:13,145 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="104125679255655886161522776073", oauth_timestamp="1522776073", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:21:13,148 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:21:13,148 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_ssh_authkey_fingerprints - wb: [644] 25 bytes
2018-04-03 17:21:13,152 - helpers.py[DEBUG]: Running config-ssh-authkey-fingerprints using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_ssh_authkey_fingerprints'>)
2018-04-03 17:21:13,153 - util.py[DEBUG]: Reading from /etc/ssh/sshd_config (quiet=False)
2018-04-03 17:21:13,154 - util.py[DEBUG]: Read 3262 bytes from /etc/ssh/sshd_config
2018-04-03 17:21:13,154 - util.py[DEBUG]: Reading from /home/ubuntu/.ssh/authorized_keys (quiet=False)
2018-04-03 17:21:13,154 - util.py[DEBUG]: Read 393 bytes from /home/ubuntu/.ssh/authorized_keys
2018-04-03 17:21:13,158 - handlers.py[DEBUG]: finish: modules-final/config-ssh-authkey-fingerprints: SUCCESS: config-ssh-authkey-fingerprints ran successfully
2018-04-03 17:21:13,159 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="1315064105559942941522776073", oauth_timestamp="1522776073", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:21:13,163 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:21:13,163 - stages.py[DEBUG]: Running module keys-to-console (<module 'cloudinit.config.cc_keys_to_console' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_keys_to_console.py'>) with frequency once-per-instance
2018-04-03 17:21:13,163 - handlers.py[DEBUG]: start: modules-final/config-keys-to-console: running config-keys-to-console with frequency once-per-instance
2018-04-03 17:21:13,164 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="19097822665919308521522776073", oauth_timestamp="1522776073", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:21:13,168 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:21:13,169 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_keys_to_console - wb: [644] 25 bytes
2018-04-03 17:21:13,169 - helpers.py[DEBUG]: Running config-keys-to-console using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_keys_to_console'>)
2018-04-03 17:21:13,170 - util.py[DEBUG]: Running command ['/usr/lib/cloud-init/write-ssh-key-fingerprints', '', 'ssh-dss'] with allowed return codes [0] (shell=False, capture=True)
2018-04-03 17:21:13,203 - handlers.py[DEBUG]: finish: modules-final/config-keys-to-console: SUCCESS: config-keys-to-console ran successfully
2018-04-03 17:21:13,204 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="7927985120692312051522776073", oauth_timestamp="1522776073", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:21:13,210 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:21:13,210 - stages.py[DEBUG]: Running module phone-home (<module 'cloudinit.config.cc_phone_home' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_phone_home.py'>) with frequency once-per-instance
2018-04-03 17:21:13,211 - handlers.py[DEBUG]: start: modules-final/config-phone-home: running config-phone-home with frequency once-per-instance
2018-04-03 17:21:13,211 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="120677092313297857451522776073", oauth_timestamp="1522776073", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:21:13,217 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:21:13,218 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_phone_home - wb: [644] 24 bytes
2018-04-03 17:21:13,218 - helpers.py[DEBUG]: Running config-phone-home using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_phone_home'>)
2018-04-03 17:21:13,218 - cc_phone_home.py[DEBUG]: Skipping module named phone-home, no 'phone_home' configuration found
2018-04-03 17:21:13,219 - handlers.py[DEBUG]: finish: modules-final/config-phone-home: SUCCESS: config-phone-home ran successfully
2018-04-03 17:21:13,219 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="78333924911652356831522776073", oauth_timestamp="1522776073", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:21:13,223 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:21:13,223 - stages.py[DEBUG]: Running module final-message (<module 'cloudinit.config.cc_final_message' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_final_message.py'>) with frequency always
2018-04-03 17:21:13,223 - handlers.py[DEBUG]: start: modules-final/config-final-message: running config-final-message with frequency always
2018-04-03 17:21:13,223 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="73074772978531409851522776073", oauth_timestamp="1522776073", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:21:13,227 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:21:13,227 - helpers.py[DEBUG]: Running config-final-message using lock (<cloudinit.helpers.DummyLock object at 0x7f38040de128>)
2018-04-03 17:21:13,227 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
2018-04-03 17:21:13,227 - util.py[DEBUG]: Read 12 bytes from /proc/uptime
2018-04-03 17:21:13,232 - util.py[DEBUG]: Cloud-init v. 18.1 finished at Tue, 03 Apr 2018 17:21:13 +0000. Datasource DataSourceMAAS [http://10.0.5.5:5240/MAAS/metadata/curtin].  Up 66.02 seconds
2018-04-03 17:21:13,232 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/boot-finished - wb: [644] 50 bytes
2018-04-03 17:21:13,232 - handlers.py[DEBUG]: finish: modules-final/config-final-message: SUCCESS: config-final-message ran successfully
2018-04-03 17:21:13,233 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="131694256411608252281522776073", oauth_timestamp="1522776073", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:21:13,242 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:21:13,242 - stages.py[DEBUG]: Running module power-state-change (<module 'cloudinit.config.cc_power_state_change' from '/usr/lib/python3/dist-packages/cloudinit/config/cc_power_state_change.py'>) with frequency once-per-instance
2018-04-03 17:21:13,243 - handlers.py[DEBUG]: start: modules-final/config-power-state-change: running config-power-state-change with frequency once-per-instance
2018-04-03 17:21:13,243 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="45025377869947771911522776073", oauth_timestamp="1522776073", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:21:13,247 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:21:13,247 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/parbbf/sem/config_power_state_change - wb: [644] 25 bytes
2018-04-03 17:21:13,247 - helpers.py[DEBUG]: Running config-power-state-change using lock (<FileLock using file '/var/lib/cloud/instances/parbbf/sem/config_power_state_change'>)
2018-04-03 17:21:13,248 - util.py[DEBUG]: Reading from /proc/1858/cmdline (quiet=False)
2018-04-03 17:21:13,248 - util.py[DEBUG]: Read 58 bytes from /proc/1858/cmdline
2018-04-03 17:21:13,248 - cc_power_state_change.py[DEBUG]: After pid 1858 ends, will execute: shutdown -r now
2018-04-03 17:21:13,249 - util.py[DEBUG]: Forked child 3031 who will run callback run_after_pid_gone
2018-04-03 17:21:13,251 - handlers.py[DEBUG]: finish: modules-final/config-power-state-change: SUCCESS: config-power-state-change ran successfully
2018-04-03 17:21:13,252 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="102244796209199355261522776073", oauth_timestamp="1522776073", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:21:13,254 - util.py[DEBUG]: Reading from /proc/1858/cmdline (quiet=False)
2018-04-03 17:21:13,255 - util.py[DEBUG]: Read 58 bytes from /proc/1858/cmdline
2018-04-03 17:21:13,258 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
2018-04-03 17:21:13,259 - main.py[DEBUG]: Ran 20 modules with 1 failures
2018-04-03 17:21:13,259 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/result.json' => '../../var/lib/cloud/data/result.json'
2018-04-03 17:21:13,260 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
2018-04-03 17:21:13,260 - util.py[DEBUG]: Read 12 bytes from /proc/uptime
2018-04-03 17:21:13,260 - util.py[DEBUG]: cloud-init mode 'modules' took 14.307 seconds (14.31)
2018-04-03 17:21:13,260 - handlers.py[DEBUG]: finish: modules-final: FAIL: running modules for final
2018-04-03 17:21:13,260 - url_helper.py[DEBUG]: [0/1] open 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf' with {'url': 'http://10.0.5.5:5240/MAAS/metadata/status/parbbf', 'allow_redirects': True, 'method': 'POST', 'headers': {'Authorization': 'OAuth oauth_nonce="15397751751769297041522776073", oauth_timestamp="1522776073", oauth_version="1.0", oauth_signature_method="PLAINTEXT", oauth_consumer_key="XHtSq32HwqRKcpSBs6", oauth_token="SfDSuSMNcda5Hqg6mF", oauth_signature="%26SD6wmgq2EAVHdEuGW5y4wnfEVL7CbtxB"'}} configuration
2018-04-03 17:21:13,289 - url_helper.py[DEBUG]: Read from http://10.0.5.5:5240/MAAS/metadata/status/parbbf (204, 0b) after 1 attempts
Connection to 10.0.5.191 closed by remote host.
Connection to 10.0.5.191 closed.