cloud-init.log

-- Logs begin at Thu 2019-03-28 13:10:39 UTC, end at Thu 2019-03-28 13:21:32 UTC. --
Mar 28 13:10:47 localhost systemd[1]: Starting Initial cloud-init job (metadata service crawler)...
Mar 28 13:10:47 localhost cloud-init[743]: WARN: no logging configured! (tried 0 configs)
Mar 28 13:10:47 localhost cloud-init[743]: Setting up basic logging...
Mar 28 13:10:47 localhost cloud-init[743]: Cloud-init v. 0.7.9 running 'init' at Thu, 28 Mar 2019 13:10:47 +0000. Up 12.66 seconds.
Mar 28 13:10:47 localhost cloud-init[743]: 2019-03-28 13:10:47,975 - util.py[DEBUG]: Cloud-init v. 0.7.9 running 'init' at Thu, 28 Mar 2019 13:10:47 +0000. Up 12.66 seconds.
Mar 28 13:10:47 localhost cloud-init[743]: 2019-03-28 13:10:47,981 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [420] 0 bytes
Mar 28 13:10:47 localhost cloud-init[743]: 2019-03-28 13:10:47,985 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:55
Mar 28 13:10:47 localhost cloud-init[743]: 2019-03-28 13:10:47,985 - util.py[DEBUG]: Running command ['ifconfig', '-a'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 13:10:47 localhost cloud-init[743]: 2019-03-28 13:10:47,995 - util.py[DEBUG]: Running command ['netstat', '-rn'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:47,999 - util.py[DEBUG]: Running command ['netstat', '-A', 'inet6', '-n'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 13:10:48 localhost cloud-init[743]: ci-info: +++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++
Mar 28 13:10:48 localhost cloud-init[743]: ci-info: +--------+------+---------------+---------------+-------+-------------------+
Mar 28 13:10:48 localhost cloud-init[743]: ci-info: | Device |  Up  |    Address    |      Mask     | Scope |     Hw-Address    |
Mar 28 13:10:48 localhost cloud-init[743]: ci-info: +--------+------+---------------+---------------+-------+-------------------+
Mar 28 13:10:48 localhost cloud-init[743]: ci-info: |  lo:   | True |   127.0.0.1   |   255.0.0.0   |   .   |         .         |
Mar 28 13:10:48 localhost cloud-init[743]: ci-info: |  lo:   | True |       .       |       .       |   d   |         .         |
Mar 28 13:10:48 localhost cloud-init[743]: ci-info: | eth0:  | True | 134.209.45.23 | 255.255.240.0 |   .   | 8e:a9:a8:33:d0:7d |
Mar 28 13:10:48 localhost cloud-init[743]: ci-info: | eth0:  | True |       .       |       .       |   d   | 8e:a9:a8:33:d0:7d |
Mar 28 13:10:48 localhost cloud-init[743]: ci-info: +--------+------+---------------+---------------+-------+-------------------+
Mar 28 13:10:48 localhost cloud-init[743]: ci-info: ++++++++++++++++++++++++++++++Route IPv4 info++++++++++++++++++++++++++++++
Mar 28 13:10:48 localhost cloud-init[743]: ci-info: +-------+--------------+--------------+---------------+-----------+-------+
Mar 28 13:10:48 localhost cloud-init[743]: ci-info: | Route | Destination  |   Gateway    |    Genmask    | Interface | Flags |
Mar 28 13:10:48 localhost cloud-init[743]: ci-info: +-------+--------------+--------------+---------------+-----------+-------+
Mar 28 13:10:48 localhost cloud-init[743]: ci-info: |   0   |   0.0.0.0    | 134.209.32.1 |    0.0.0.0    |    eth0   |   UG  |
Mar 28 13:10:48 localhost cloud-init[743]: ci-info: |   1   | 134.209.32.0 |   0.0.0.0    | 255.255.240.0 |    eth0   |   U   |
Mar 28 13:10:48 localhost cloud-init[743]: ci-info: +-------+--------------+--------------+---------------+-----------+-------+
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,004 - main.py[DEBUG]: Checking to see if files that we need already exist from a previous run that would allow us to stop early.
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,005 - main.py[DEBUG]: Execution continuing, no previous run detected that would allow us to stop early.
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,005 - handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust]
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,005 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,005 - util.py[DEBUG]: Read 16572 bytes from /var/lib/cloud/instance/obj.pkl
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,008 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,008 - util.py[DEBUG]: Read 10 bytes from /run/cloud-init/.instance-id
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,008 - stages.py[DEBUG]: restored from cache with run check: DataSourceConfigDrive [net,ver=2][source=/dev/vdb]
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,008 - handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: restored from cache with run check: DataSourceConfigDrive [net,ver=2][source=/dev/vdb]
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,011 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,011 - util.py[DEBUG]: Read 810 bytes from /etc/cloud/cloud.cfg
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,011 - util.py[DEBUG]: Attempting to load yaml from string of length 810 with allowed root types (<type 'dict'>,)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,019 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<type 'dict'>,)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,020 - util.py[DEBUG]: load_yaml given empty string, returning default
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,020 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,020 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/138061459'
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,021 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/138061459/datasource (quiet=False)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,021 - util.py[DEBUG]: Read 74 bytes from /var/lib/cloud/instances/138061459/datasource
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,021 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/datasource - wb: [420] 74 bytes
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,021 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [420] 74 bytes
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,022 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,022 - util.py[DEBUG]: Read 10 bytes from /var/lib/cloud/data/instance-id
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,022 - stages.py[DEBUG]: previous iid found to be 138061459
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,022 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [420] 10 bytes
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,024 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [420] 10 bytes
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,028 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [420] 10 bytes
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,029 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [256] 16661 bytes
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,030 - main.py[DEBUG]: [net] init will now be targeting instance id: 138061459. new=False
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,030 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,030 - util.py[DEBUG]: Read 810 bytes from /etc/cloud/cloud.cfg
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,030 - util.py[DEBUG]: Attempting to load yaml from string of length 810 with allowed root types (<type 'dict'>,)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,034 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<type 'dict'>,)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,034 - util.py[DEBUG]: load_yaml given empty string, returning default
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,040 - DataSourceConfigDrive.py[DEBUG]: network config provided via network_json
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,040 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,040 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,040 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,040 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,041 - stages.py[DEBUG]: applying net config names for {'version': 1, 'config': [{'subnets': [{u'type': 'dhcp4'}], 'mac_address': u'8e:a9:a8:33:d0:7d', u'type': 'physical', 'name': 'eth0', u'mtu': 1500}]}
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,041 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.nixos.Distro'>
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,041 - util.py[DEBUG]: Reading from /sys/class/net/eth0/operstate (quiet=False)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,041 - util.py[DEBUG]: Read 3 bytes from /sys/class/net/eth0/operstate
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,041 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,041 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,041 - util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,041 - util.py[DEBUG]: Read 8 bytes from /sys/class/net/lo/operstate
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,041 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,042 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,042 - util.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,048 - util.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,052 - __init__.py[DEBUG]: no work necessary for renaming of [[u'8e:a9:a8:33:d0:7d', 'eth0']]
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,052 - stages.py[DEBUG]: not a new instance. network config is not applied.
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,056 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/user-data.txt - wb: [384] 0 bytes
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,059 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/user-data.txt.i - wb: [384] 345 bytes
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,060 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/vendor-data.txt - wb: [384] 11143 bytes
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,065 - util.py[DEBUG]: Attempting to load yaml from string of length 108 with allowed root types (<type 'dict'>,)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,067 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/vendor-data.txt.i - wb: [384] 11201 bytes
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,069 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/sem/consume_data - wb: [420] 19 bytes
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,072 - helpers.py[DEBUG]: Running consume_data using lock (<FileLock using file '/var/lib/cloud/instances/138061459/sem/consume_data'>)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,072 - handlers.py[DEBUG]: start: init-network/consume-user-data: reading and applying user-data
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,073 - stages.py[DEBUG]: Added default handler for set(['text/cloud-config-jsonp', 'text/cloud-config']) from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']]
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,073 - stages.py[DEBUG]: Added default handler for set(['text/x-shellscript']) from ShellScriptPartHandler: [['text/x-shellscript']]
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,073 - stages.py[DEBUG]: Added default handler for set(['text/cloud-boothook']) from BootHookPartHandler: [['text/cloud-boothook']]
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,073 - stages.py[DEBUG]: Added default handler for set(['text/upstart-job']) from UpstartJobPartHandler: [['text/upstart-job']]
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,073 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency once-per-instance
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,073 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__begin__, None, 2) with frequency once-per-instance
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,073 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency once-per-instance
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,073 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency once-per-instance
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,073 - __init__.py[DEBUG]: {'Content-Type': 'text/x-not-multipart', 'Content-Disposition': 'attachment; filename="part-001"', 'MIME-Version': '1.0'}
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,073 - __init__.py[DEBUG]: Empty payload of type text/x-not-multipart
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,073 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency once-per-instance
Mar 28 13:10:48 localhost systemd[1]: cloud-init.service: Main process exited, code=exited, status=1/FAILURE
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,074 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__end__, None, 2) with frequency once-per-instance
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,074 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency once-per-instance
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,074 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/cloud-config.txt - wb: [384] 0 bytes
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,074 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency once-per-instance
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,076 - handlers.py[DEBUG]: finish: init-network/consume-user-data: SUCCESS: reading and applying user-data
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,076 - handlers.py[DEBUG]: start: init-network/consume-vendor-data: reading and applying vendor-data
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,076 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,076 - util.py[DEBUG]: Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,076 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<type 'dict'>,)
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,076 - util.py[DEBUG]: load_yaml given empty string, returning default
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,076 - stages.py[DEBUG]: vendor data will be consumed. disabled_handlers=None
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,079 - stages.py[DEBUG]: Added default handler for set(['text/cloud-config-jsonp', 'text/cloud-config']) from CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']]
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,079 - stages.py[DEBUG]: Added default handler for set(['text/x-shellscript']) from ShellScriptPartHandler: [['text/x-shellscript']]
Mar 28 13:10:48 localhost cloud-init[743]: 2019-03-28 13:10:48,079 - stages.py[DEBUG]: Added default handler for set(['text/cloud-boothook']) from BootHookPartHandler: [['text/cloud-boothook']]
Mar 28 13:10:48 localhost systemd[1]: cloud-init.service: Failed with result 'exit-code'.
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,079 - stages.py[DEBUG]: Added default handler for set(['text/upstart-job']) from UpstartJobPartHandler: [['text/upstart-job']]
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,079 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency once-per-instance
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,079 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__begin__, None, 2) with frequency once-per-instance
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,079 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency once-per-instance
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,079 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency once-per-instance
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,079 - __init__.py[DEBUG]: {'Content-Transfer-Encoding': '7bit', 'Content-Type': 'text/cloud-config; charset="us-ascii"', 'Content-Disposition': 'attachment; filename="cloud-config"', 'MIME-Version': '1.0'}
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,079 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (text/cloud-config, cloud-config, 3) with frequency once-per-instance
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,080 - util.py[DEBUG]: Attempting to load yaml from string of length 108 with allowed root types (<type 'dict'>,)
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,083 - cloud_config.py[DEBUG]: Merging by applying [('dict', ['replace']), ('list', []), ('str', [])]
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,083 - __init__.py[DEBUG]: {'Content-Transfer-Encoding': '7bit', 'Content-Type': 'text/cloud-boothook; charset="us-ascii"', 'Content-Disposition': 'attachment; filename="entropy-seed"', 'MIME-Version': '1.0'}
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,083 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (text/cloud-boothook, entropy-seed, 2) with frequency once-per-instance
Mar 28 13:10:48 localhost systemd[1]: Failed to start Initial cloud-init job (metadata service crawler).
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,086 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/boothooks/entropy-seed - wb: [448] 4299 bytes
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,087 - util.py[DEBUG]: Running command ['/var/lib/cloud/instances/138061459/boothooks/entropy-seed'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,103 - __init__.py[DEBUG]: {'Content-Transfer-Encoding': '7bit', 'Content-Type': 'text/cloud-boothook; charset="us-ascii"', 'Content-Disposition': 'attachment; filename="digitalocean_fixups"', 'MIME-Version': '1.0'}
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,103 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (text/cloud-boothook, digitalocean_fixups, 2) with frequency once-per-instance
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,103 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/boothooks/digitalocean_fixups - wb: [448] 5985 bytes
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,105 - util.py[DEBUG]: Running command ['/var/lib/cloud/instances/138061459/boothooks/digitalocean_fixups'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,136 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency once-per-instance
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,137 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__end__, None, 2) with frequency once-per-instance
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,137 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency once-per-instance
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,138 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/vendor-cloud-config.txt - wb: [384] 145 bytes
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,140 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency once-per-instance
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,140 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data: SUCCESS: reading and applying vendor-data
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,143 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,143 - util.py[DEBUG]: Read 810 bytes from /etc/cloud/cloud.cfg
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,143 - util.py[DEBUG]: Attempting to load yaml from string of length 810 with allowed root types (<type 'dict'>,)
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,150 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90-digitalocean.cfg (quiet=False)
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,151 - util.py[DEBUG]: Read 1329 bytes from /etc/cloud/cloud.cfg.d/90-digitalocean.cfg
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,151 - util.py[DEBUG]: Attempting to load yaml from string of length 1329 with allowed root types (<type 'dict'>,)
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,159 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<type 'dict'>,)
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,159 - util.py[DEBUG]: load_yaml given empty string, returning default
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,165 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,165 - util.py[DEBUG]: Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,165 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<type 'dict'>,)
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,165 - util.py[DEBUG]: load_yaml given empty string, returning default
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,165 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/vendor-cloud-config.txt (quiet=False)
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,165 - util.py[DEBUG]: Read 145 bytes from /var/lib/cloud/instance/vendor-cloud-config.txt
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,165 - util.py[DEBUG]: Attempting to load yaml from string of length 145 with allowed root types (<type 'dict'>,)
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,166 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,166 - util.py[DEBUG]: Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,166 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<type 'dict'>,)
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,166 - util.py[DEBUG]: load_yaml given empty string, returning default
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,167 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/vendor-cloud-config.txt (quiet=False)
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,167 - util.py[DEBUG]: Read 145 bytes from /var/lib/cloud/instance/vendor-cloud-config.txt
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,167 - util.py[DEBUG]: Attempting to load yaml from string of length 145 with allowed root types (<type 'dict'>,)
Mar 28 13:10:49 localhost cloud-init[743]: WARN: no logging configured! (tried 0 configs)
Mar 28 13:10:49 localhost cloud-init[743]: Setting up basic logging...
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,169 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [256] 28983 bytes
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,191 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.nixos.Distro'>
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,191 - stages.py[INFO]: Skipping modules ['ca-certs'] because they are not verified on distro 'nixos'.  To run anyway, add them to 'unverified_modules' in config.
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,191 - stages.py[DEBUG]: Running module migrator (<module 'cloudinit.config.cc_migrator' from '/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_migrator.pyc'>) with frequency always
Mar 28 13:10:49 localhost cloud-init[743]: 2019-03-28 13:10:48,192 - handlers.py[DEBUG]: start: init-network/config-migrator: running config-migrator with frequency always
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,192 - handlers.py[DEBUG]: start: init-network/config-migrator: running config-migrator with frequency always
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,192 - helpers.py[DEBUG]: Running config-migrator using lock (<cloudinit.helpers.DummyLock object at 0x7f1c5e4170d0>)
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,192 - cc_migrator.py[DEBUG]: Migrated 0 semaphore files to there canonicalized names
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,192 - handlers.py[DEBUG]: finish: init-network/config-migrator: SUCCESS: config-migrator ran successfully
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,192 - stages.py[DEBUG]: Running module seed_random (<module 'cloudinit.config.cc_seed_random' from '/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_seed_random.pyc'>) with frequency once-per-instance
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,192 - handlers.py[DEBUG]: start: init-network/config-seed_random: running config-seed_random with frequency once-per-instance
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,192 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/sem/config_seed_random - wb: [420] 19 bytes
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,193 - helpers.py[DEBUG]: Running config-seed_random using lock (<FileLock using file '/var/lib/cloud/instances/138061459/sem/config_seed_random'>)
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,193 - cc_seed_random.py[DEBUG]: no command provided
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,193 - handlers.py[DEBUG]: finish: init-network/config-seed_random: SUCCESS: config-seed_random ran successfully
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,193 - stages.py[DEBUG]: Running module bootcmd (<module 'cloudinit.config.cc_bootcmd' from '/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_bootcmd.pyc'>) with frequency always
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,194 - handlers.py[DEBUG]: start: init-network/config-bootcmd: running config-bootcmd with frequency always
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,194 - helpers.py[DEBUG]: Running config-bootcmd using lock (<cloudinit.helpers.DummyLock object at 0x7f1c5e417250>)
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,194 - cc_bootcmd.py[DEBUG]: Skipping module named bootcmd, no 'bootcmd' key in configuration
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,194 - handlers.py[DEBUG]: finish: init-network/config-bootcmd: SUCCESS: config-bootcmd ran successfully
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,194 - stages.py[DEBUG]: Running module write-files (<module 'cloudinit.config.cc_write_files' from '/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_write_files.pyc'>) with frequency once-per-instance
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,194 - handlers.py[DEBUG]: start: init-network/config-write-files: running config-write-files with frequency once-per-instance
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,194 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/sem/config_write_files - wb: [420] 19 bytes
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,196 - helpers.py[DEBUG]: Running config-write-files using lock (<FileLock using file '/var/lib/cloud/instances/138061459/sem/config_write_files'>)
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,196 - cc_write_files.py[DEBUG]: Skipping module named write-files, no/empty 'write_files' key in configuration
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,196 - handlers.py[DEBUG]: finish: init-network/config-write-files: SUCCESS: config-write-files ran successfully
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,196 - stages.py[DEBUG]: Running module growpart (<module 'cloudinit.config.cc_growpart' from '/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_growpart.pyc'>) with frequency always
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,196 - handlers.py[DEBUG]: start: init-network/config-growpart: running config-growpart with frequency always
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,196 - helpers.py[DEBUG]: Running config-growpart using lock (<cloudinit.helpers.DummyLock object at 0x7f1c5e417310>)
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,196 - cc_growpart.py[DEBUG]: No 'growpart' entry in cfg.  Using default: {'ignore_growroot_disabled': False, 'mode': 'auto', 'devices': ['/']}
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,196 - util.py[DEBUG]: Running command ['/nix/store/hbzsppz29w50qb5f9663fzqqrr6k54gq-cloud-utils-0.30/bin/growpart', '--help'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,196 - util.py[DEBUG]: Running command ['/nix/store/hbzsppz29w50qb5f9663fzqqrr6k54gq-cloud-utils-0.30/bin/growpart', '--help'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,224 - util.py[DEBUG]: Reading from /proc/743/mountinfo (quiet=False)
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,225 - util.py[DEBUG]: Read 2723 bytes from /proc/743/mountinfo
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,236 - util.py[DEBUG]: Reading from /sys/class/block/vda1/partition (quiet=False)
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,237 - util.py[DEBUG]: Read 2 bytes from /sys/class/block/vda1/partition
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,237 - util.py[DEBUG]: Reading from /sys/devices/pci0000:00/0000:00:05.0/virtio2/block/vda/dev (quiet=False)
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,237 - util.py[DEBUG]: Read 6 bytes from /sys/devices/pci0000:00/0000:00:05.0/virtio2/block/vda/dev
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,237 - util.py[DEBUG]: Running command ['/nix/store/hbzsppz29w50qb5f9663fzqqrr6k54gq-cloud-utils-0.30/bin/growpart', '--dry-run', '/dev/vda', '1'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 13:10:50 localhost cloud-init[743]: 2019-03-28 13:10:48,337 - util.py[DEBUG]: resize_devices took 0.113 seconds
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,337 - cc_growpart.py[DEBUG]: '/' NOCHANGE: no change necessary (/dev/vda, 1)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,337 - handlers.py[DEBUG]: finish: init-network/config-growpart: SUCCESS: config-growpart ran successfully
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,338 - stages.py[DEBUG]: Running module resizefs (<module 'cloudinit.config.cc_resizefs' from '/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_resizefs.pyc'>) with frequency always
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,338 - handlers.py[DEBUG]: start: init-network/config-resizefs: running config-resizefs with frequency always
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,338 - helpers.py[DEBUG]: Running config-resizefs using lock (<cloudinit.helpers.DummyLock object at 0x7f1c5e417210>)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,338 - util.py[DEBUG]: Reading from /proc/743/mountinfo (quiet=False)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,338 - util.py[DEBUG]: Read 2723 bytes from /proc/743/mountinfo
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,338 - cc_resizefs.py[DEBUG]: resize_info: dev=/dev/disk/by-label/nixos mnt_point=/ path=/
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,338 - cc_resizefs.py[DEBUG]: resize_info: dev=/dev/disk/by-label/nixos mnt_point=/ path=/
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,338 - util.py[DEBUG]: Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,348 - util.py[DEBUG]: Running command ['running-in-container'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,354 - util.py[DEBUG]: Running command ['lxc-is-container'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,360 - util.py[DEBUG]: Reading from /proc/1/environ (quiet=False)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,360 - util.py[DEBUG]: Read 266 bytes from /proc/1/environ
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,360 - util.py[DEBUG]: Reading from /proc/self/status (quiet=False)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,361 - util.py[DEBUG]: Read 1018 bytes from /proc/self/status
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,361 - cc_resizefs.py[DEBUG]: Resizing / (ext4) using resize2fs /dev/disk/by-label/nixos
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,361 - util.py[DEBUG]: Running command ('resize2fs', '/dev/disk/by-label/nixos') with allowed return codes [0] (shell=False, capture=True)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,361 - util.py[DEBUG]: Running command ('resize2fs', '/dev/disk/by-label/nixos') with allowed return codes [0] (shell=False, capture=True)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,377 - util.py[DEBUG]: Resizing took 0.016 seconds
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,377 - cc_resizefs.py[DEBUG]: Resized root filesystem (type=ext4, val=True)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,378 - handlers.py[DEBUG]: finish: init-network/config-resizefs: SUCCESS: config-resizefs ran successfully
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,378 - stages.py[DEBUG]: Running module update_etc_hosts (<module 'cloudinit.config.cc_update_etc_hosts' from '/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_update_etc_hosts.pyc'>) with frequency once-per-instance
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,378 - handlers.py[DEBUG]: start: init-network/config-update_etc_hosts: running config-update_etc_hosts with frequency once-per-instance
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,378 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/sem/config_update_etc_hosts - wb: [420] 19 bytes
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,380 - helpers.py[DEBUG]: Running config-update_etc_hosts using lock (<FileLock using file '/var/lib/cloud/instances/138061459/sem/config_update_etc_hosts'>)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,380 - util.py[DEBUG]: Reading from /etc/cloud/templates/hosts.nixos.tmpl (quiet=False)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,382 - util.py[DEBUG]: Read 66 bytes from /etc/cloud/templates/hosts.nixos.tmpl
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,382 - templater.py[DEBUG]: Using Cheetah as the renderer for unknown template.
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,382 - templater.py[DEBUG]: Rendering content of '/etc/cloud/templates/hosts.nixos.tmpl' using renderer cheetah
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,392 - util.py[DEBUG]: Writing to /etc/hosts - wb: [420] 90 bytes
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,392 - handlers.py[DEBUG]: finish: init-network/config-update_etc_hosts: FAIL: running config-update_etc_hosts with frequency once-per-instance
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,392 - util.py[WARNING]: Running module update_etc_hosts (<module 'cloudinit.config.cc_update_etc_hosts' from '/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_update_etc_hosts.pyc'>) failed
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,392 - util.py[WARNING]: Running module update_etc_hosts (<module 'cloudinit.config.cc_update_etc_hosts' from '/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_update_etc_hosts.pyc'>) failed
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,392 - util.py[DEBUG]: Running module update_etc_hosts (<module 'cloudinit.config.cc_update_etc_hosts' from '/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_update_etc_hosts.pyc'>) failed
Mar 28 13:10:51 localhost cloud-init[743]: Traceback (most recent call last):
Mar 28 13:10:51 localhost cloud-init[743]:   File "/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/stages.py", line 776, in _run_modules
Mar 28 13:10:51 localhost cloud-init[743]:     freq=freq)
Mar 28 13:10:51 localhost cloud-init[743]:   File "/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/cloud.py", line 54, in run
Mar 28 13:10:51 localhost cloud-init[743]:     return self._runners.run(name, functor, args, freq, clear_on_fail)
Mar 28 13:10:51 localhost cloud-init[743]:   File "/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/helpers.py", line 187, in run
Mar 28 13:10:51 localhost cloud-init[743]:     results = functor(*args)
Mar 28 13:10:51 localhost cloud-init[743]:   File "/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_update_etc_hosts.py", line 78, in handle
Mar 28 13:10:51 localhost cloud-init[743]:     {'hostname': hostname, 'fqdn': fqdn})
Mar 28 13:10:51 localhost cloud-init[743]:   File "/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/templater.py", line 131, in render_to_file
Mar 28 13:10:51 localhost cloud-init[743]:     util.write_file(outfn, contents, mode=mode)
Mar 28 13:10:51 localhost cloud-init[743]:   File "/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/util.py", line 1755, in write_file
Mar 28 13:10:51 localhost cloud-init[743]:     with open(filename, omode) as fh:
Mar 28 13:10:51 localhost cloud-init[743]: IOError: [Errno 30] Read-only file system: '/etc/hosts'
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,392 - util.py[DEBUG]: Running module update_etc_hosts (<module 'cloudinit.config.cc_update_etc_hosts' from '/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_update_etc_hosts.pyc'>) failed
Mar 28 13:10:51 localhost cloud-init[743]: Traceback (most recent call last):
Mar 28 13:10:51 localhost cloud-init[743]:   File "/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/stages.py", line 776, in _run_modules
Mar 28 13:10:51 localhost cloud-init[743]:     freq=freq)
Mar 28 13:10:51 localhost cloud-init[743]:   File "/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/cloud.py", line 54, in run
Mar 28 13:10:51 localhost cloud-init[743]:     return self._runners.run(name, functor, args, freq, clear_on_fail)
Mar 28 13:10:51 localhost cloud-init[743]:   File "/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/helpers.py", line 187, in run
Mar 28 13:10:51 localhost cloud-init[743]:     results = functor(*args)
Mar 28 13:10:51 localhost cloud-init[743]:   File "/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_update_etc_hosts.py", line 78, in handle
Mar 28 13:10:51 localhost cloud-init[743]:     {'hostname': hostname, 'fqdn': fqdn})
Mar 28 13:10:51 localhost cloud-init[743]:   File "/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/templater.py", line 131, in render_to_file
Mar 28 13:10:51 localhost cloud-init[743]:     util.write_file(outfn, contents, mode=mode)
Mar 28 13:10:51 localhost cloud-init[743]:   File "/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/util.py", line 1755, in write_file
Mar 28 13:10:51 localhost cloud-init[743]:     with open(filename, omode) as fh:
Mar 28 13:10:51 localhost cloud-init[743]: IOError: [Errno 30] Read-only file system: '/etc/hosts'
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,399 - stages.py[DEBUG]: Running module ca-certs (<module 'cloudinit.config.cc_ca_certs' from '/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_ca_certs.pyc'>) with frequency once-per-instance
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,400 - handlers.py[DEBUG]: start: init-network/config-ca-certs: running config-ca-certs with frequency once-per-instance
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,400 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/sem/config_ca_certs - wb: [420] 18 bytes
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,400 - helpers.py[DEBUG]: Running config-ca-certs using lock (<FileLock using file '/var/lib/cloud/instances/138061459/sem/config_ca_certs'>)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,401 - cc_ca_certs.py[DEBUG]: Skipping module named ca-certs, no 'ca-certs' key in configuration
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,401 - handlers.py[DEBUG]: finish: init-network/config-ca-certs: SUCCESS: config-ca-certs ran successfully
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,401 - stages.py[DEBUG]: Running module rsyslog (<module 'cloudinit.config.cc_rsyslog' from '/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_rsyslog.pyc'>) with frequency once-per-instance
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,401 - handlers.py[DEBUG]: start: init-network/config-rsyslog: running config-rsyslog with frequency once-per-instance
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,401 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/sem/config_rsyslog - wb: [420] 18 bytes
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,401 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/sem/config_rsyslog - wb: [420] 18 bytes
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,402 - helpers.py[DEBUG]: Running config-rsyslog using lock (<FileLock using file '/var/lib/cloud/instances/138061459/sem/config_rsyslog'>)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,402 - cc_rsyslog.py[DEBUG]: Skipping module named rsyslog, no 'rsyslog' key in configuration
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,402 - handlers.py[DEBUG]: finish: init-network/config-rsyslog: SUCCESS: config-rsyslog ran successfully
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,402 - stages.py[DEBUG]: Running module users-groups (<module 'cloudinit.config.cc_users_groups' from '/nix/store/lfb4vx0fl1v2n30hs79bsy5pwfk622ip-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_users_groups.pyc'>) with frequency once-per-instance
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,402 - handlers.py[DEBUG]: start: init-network/config-users-groups: running config-users-groups with frequency once-per-instance
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,402 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/sem/config_users_groups - wb: [420] 18 bytes
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,402 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138061459/sem/config_users_groups - wb: [420] 18 bytes
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,406 - helpers.py[DEBUG]: Running config-users-groups using lock (<FileLock using file '/var/lib/cloud/instances/138061459/sem/config_users_groups'>)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,406 - __init__.py[INFO]: User root already exists, skipping.
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,406 - util.py[DEBUG]: Running command ['passwd', '-l', 'root'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,429 - handlers.py[DEBUG]: finish: init-network/config-users-groups: SUCCESS: config-users-groups ran successfully
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,429 - main.py[DEBUG]: Ran 10 modules with 1 failures
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,430 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,430 - util.py[DEBUG]: Read 11 bytes from /proc/uptime
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,430 - util.py[DEBUG]: cloud-init mode 'init' took 0.481 seconds (0.48)
Mar 28 13:10:51 localhost cloud-init[743]: 2019-03-28 13:10:48,430 - handlers.py[DEBUG]: finish: init-network: FAIL: searching for network datasources