Skip to content

Instantly share code, notes, and snippets.

@colemickens
Created March 28, 2019 03:55
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save colemickens/98dee80a8f5d08188d0e932cdc1004a1 to your computer and use it in GitHub Desktop.
Save colemickens/98dee80a8f5d08188d0e932cdc1004a1 to your computer and use it in GitHub Desktop.
-- Logs begin at Thu 2019-03-28 03:35:18 UTC, end at Thu 2019-03-28 03:54:42 UTC. --
Mar 28 03:35:25 localhost systemd[1]: Starting Initial cloud-init job (metadata service crawler)...
Mar 28 03:35:25 localhost cloud-init[802]: WARN: no logging configured! (tried 0 configs)
Mar 28 03:35:25 localhost cloud-init[802]: Setting up basic logging...
Mar 28 03:35:25 localhost cloud-init[802]: Cloud-init v. 0.7.9 running 'init' at Thu, 28 Mar 2019 03:35:25 +0000. Up 18.61 seconds.
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,587 - util.py[DEBUG]: Cloud-init v. 0.7.9 running 'init' at Thu, 28 Mar 2019 03:35:25 +0000. Up 18.61 seconds.
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,590 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [420] 0 bytes
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,606 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:55
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,606 - util.py[DEBUG]: Running command ['ifconfig', '-a'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,620 - util.py[DEBUG]: Running command ['netstat', '-rn'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,626 - util.py[DEBUG]: Running command ['netstat', '-A', 'inet6', '-n'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 03:35:25 localhost cloud-init[802]: ci-info: +++++++++++++++++++++++++++++++Net device info++++++++++++++++++++++++++++++++
Mar 28 03:35:25 localhost cloud-init[802]: ci-info: +--------+------+----------------+---------------+-------+-------------------+
Mar 28 03:35:25 localhost cloud-init[802]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address |
Mar 28 03:35:25 localhost cloud-init[802]: ci-info: +--------+------+----------------+---------------+-------+-------------------+
Mar 28 03:35:25 localhost cloud-init[802]: ci-info: | lo: | True | 127.0.0.1 | 255.0.0.0 | . | . |
Mar 28 03:35:25 localhost cloud-init[802]: ci-info: | lo: | True | . | . | d | . |
Mar 28 03:35:25 localhost cloud-init[802]: ci-info: | eth0: | True | 68.183.173.230 | 255.255.240.0 | . | 76:9b:7a:8c:94:9e |
Mar 28 03:35:25 localhost cloud-init[802]: ci-info: | eth0: | True | . | . | d | 76:9b:7a:8c:94:9e |
Mar 28 03:35:25 localhost cloud-init[802]: ci-info: +--------+------+----------------+---------------+-------+-------------------+
Mar 28 03:35:25 localhost cloud-init[802]: ci-info: ++++++++++++++++++++++++++++++Route IPv4 info++++++++++++++++++++++++++++++
Mar 28 03:35:25 localhost cloud-init[802]: ci-info: +-------+--------------+--------------+---------------+-----------+-------+
Mar 28 03:35:25 localhost cloud-init[802]: ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags |
Mar 28 03:35:25 localhost cloud-init[802]: ci-info: +-------+--------------+--------------+---------------+-----------+-------+
Mar 28 03:35:25 localhost cloud-init[802]: ci-info: | 0 | 0.0.0.0 | 68.183.160.1 | 0.0.0.0 | eth0 | UG |
Mar 28 03:35:25 localhost cloud-init[802]: ci-info: | 1 | 68.183.160.0 | 0.0.0.0 | 255.255.240.0 | eth0 | U |
Mar 28 03:35:25 localhost cloud-init[802]: ci-info: +-------+--------------+--------------+---------------+-----------+-------+
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,631 - 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 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,631 - main.py[DEBUG]: Execution continuing, no previous run detected that would allow us to stop early.
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,631 - handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust]
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,631 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,631 - util.py[DEBUG]: Read 17117 bytes from /var/lib/cloud/instance/obj.pkl
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,634 - util.py[DEBUG]: Reading from /run/cloud-init/.instance-id (quiet=False)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,634 - util.py[DEBUG]: Read 10 bytes from /run/cloud-init/.instance-id
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,634 - stages.py[DEBUG]: restored from cache with run check: DataSourceConfigDrive [net,ver=2][source=/dev/vdb]
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,634 - handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: restored from cache with run check: DataSourceConfigDrive [net,ver=2][source=/dev/vdb]
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,634 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,634 - util.py[DEBUG]: Read 810 bytes from /etc/cloud/cloud.cfg
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,634 - util.py[DEBUG]: Attempting to load yaml from string of length 810 with allowed root types (<type 'dict'>,)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,640 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<type 'dict'>,)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,640 - util.py[DEBUG]: load_yaml given empty string, returning default
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,640 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,640 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/138012245'
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,642 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/138012245/datasource (quiet=False)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,642 - util.py[DEBUG]: Read 74 bytes from /var/lib/cloud/instances/138012245/datasource
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,642 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/datasource - wb: [420] 74 bytes
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,642 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [420] 74 bytes
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,643 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,643 - util.py[DEBUG]: Read 10 bytes from /var/lib/cloud/data/instance-id
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,643 - stages.py[DEBUG]: previous iid found to be 138012245
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,643 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [420] 10 bytes
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,644 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [420] 10 bytes
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,644 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [420] 10 bytes
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,645 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [256] 17206 bytes
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,646 - main.py[DEBUG]: [net] init will now be targeting instance id: 138012245. new=False
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,646 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,646 - util.py[DEBUG]: Read 810 bytes from /etc/cloud/cloud.cfg
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,646 - util.py[DEBUG]: Attempting to load yaml from string of length 810 with allowed root types (<type 'dict'>,)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,650 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<type 'dict'>,)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,650 - util.py[DEBUG]: load_yaml given empty string, returning default
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,651 - DataSourceConfigDrive.py[DEBUG]: network config provided via network_json
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,651 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,651 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,651 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,651 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,651 - stages.py[DEBUG]: applying net config names for {'version': 1, 'config': [{'subnets': [{u'type': 'dhcp4'}], 'mac_address': u'76:9b:7a:8c:94:9e', u'type': 'physical', 'name': 'eth0', u'mtu': 1500}]}
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,651 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.nixos.Distro'>
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,652 - util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,652 - util.py[DEBUG]: Read 8 bytes from /sys/class/net/lo/operstate
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,652 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,652 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,652 - util.py[DEBUG]: Reading from /sys/class/net/eth0/operstate (quiet=False)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,652 - util.py[DEBUG]: Read 3 bytes from /sys/class/net/eth0/operstate
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,652 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,652 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,652 - util.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,657 - util.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,662 - __init__.py[DEBUG]: no work necessary for renaming of [[u'76:9b:7a:8c:94:9e', 'eth0']]
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,662 - stages.py[DEBUG]: not a new instance. network config is not applied.
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,663 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/user-data.txt - wb: [384] 173 bytes
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,671 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/user-data.txt.i - wb: [384] 518 bytes
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,671 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/vendor-data.txt - wb: [384] 11143 bytes
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,673 - util.py[DEBUG]: Attempting to load yaml from string of length 108 with allowed root types (<type 'dict'>,)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,675 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/vendor-data.txt.i - wb: [384] 11201 bytes
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,676 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/sem/consume_data - wb: [420] 19 bytes
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,676 - helpers.py[DEBUG]: Running consume_data using lock (<FileLock using file '/var/lib/cloud/instances/138012245/sem/consume_data'>)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,677 - handlers.py[DEBUG]: start: init-network/consume-user-data: reading and applying user-data
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,677 - 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 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,677 - stages.py[DEBUG]: Added default handler for set(['text/x-shellscript']) from ShellScriptPartHandler: [['text/x-shellscript']]
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,677 - stages.py[DEBUG]: Added default handler for set(['text/cloud-boothook']) from BootHookPartHandler: [['text/cloud-boothook']]
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,677 - stages.py[DEBUG]: Added default handler for set(['text/upstart-job']) from UpstartJobPartHandler: [['text/upstart-job']]
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,677 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency once-per-instance
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,677 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__begin__, None, 2) with frequency once-per-instance
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,677 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency once-per-instance
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,677 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency once-per-instance
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,678 - __init__.py[DEBUG]: {'Content-Type': 'text/x-not-multipart', 'Content-Disposition': 'attachment; filename="part-001"', 'MIME-Version': '1.0'}
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,678 - __init__.py[WARNING]: Unhandled non-multipart (text/x-not-multipart) userdata: 'write_files:...'
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,678 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency once-per-instance
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,678 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__end__, None, 2) with frequency once-per-instance
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,678 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency once-per-instance
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,678 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/cloud-config.txt - wb: [384] 0 bytes
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,678 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency once-per-instance
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,679 - handlers.py[DEBUG]: finish: init-network/consume-user-data: SUCCESS: reading and applying user-data
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,679 - handlers.py[DEBUG]: start: init-network/consume-vendor-data: reading and applying vendor-data
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,679 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,679 - util.py[DEBUG]: Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,679 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<type 'dict'>,)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,679 - util.py[DEBUG]: load_yaml given empty string, returning default
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,679 - stages.py[DEBUG]: vendor data will be consumed. disabled_handlers=None
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,679 - 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 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,679 - stages.py[DEBUG]: Added default handler for set(['text/x-shellscript']) from ShellScriptPartHandler: [['text/x-shellscript']]
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,679 - stages.py[DEBUG]: Added default handler for set(['text/cloud-boothook']) from BootHookPartHandler: [['text/cloud-boothook']]
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,680 - stages.py[DEBUG]: Added default handler for set(['text/upstart-job']) from UpstartJobPartHandler: [['text/upstart-job']]
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,680 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__begin__, None, 2) with frequency once-per-instance
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,680 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__begin__, None, 2) with frequency once-per-instance
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,680 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__begin__, None, 3) with frequency once-per-instance
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,680 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__begin__, None, 2) with frequency once-per-instance
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,680 - __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 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,680 - __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 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,680 - util.py[DEBUG]: Attempting to load yaml from string of length 108 with allowed root types (<type 'dict'>,)
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,682 - cloud_config.py[DEBUG]: Merging by applying [('dict', ['replace']), ('list', []), ('str', [])]
Mar 28 03:35:25 localhost cloud-init[802]: 2019-03-28 03:35:25,682 - __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 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,682 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (text/cloud-boothook, entropy-seed, 2) with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,683 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/boothooks/entropy-seed - wb: [448] 4299 bytes
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,684 - util.py[DEBUG]: Running command ['/var/lib/cloud/instances/138012245/boothooks/entropy-seed'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,880 - __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 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,881 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (text/cloud-boothook, digitalocean_fixups, 2) with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,881 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/boothooks/digitalocean_fixups - wb: [448] 5985 bytes
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,883 - util.py[DEBUG]: Running command ['/var/lib/cloud/instances/138012245/boothooks/digitalocean_fixups'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,974 - __init__.py[DEBUG]: Calling handler BootHookPartHandler: [['text/cloud-boothook']] (__end__, None, 2) with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,974 - __init__.py[DEBUG]: Calling handler UpstartJobPartHandler: [['text/upstart-job']] (__end__, None, 2) with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,974 - __init__.py[DEBUG]: Calling handler CloudConfigPartHandler: [['text/cloud-config', 'text/cloud-config-jsonp']] (__end__, None, 3) with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,976 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/vendor-cloud-config.txt - wb: [384] 145 bytes
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,976 - __init__.py[DEBUG]: Calling handler ShellScriptPartHandler: [['text/x-shellscript']] (__end__, None, 2) with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,977 - handlers.py[DEBUG]: finish: init-network/consume-vendor-data: SUCCESS: reading and applying vendor-data
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,977 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,977 - util.py[DEBUG]: Read 810 bytes from /etc/cloud/cloud.cfg
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,977 - util.py[DEBUG]: Attempting to load yaml from string of length 810 with allowed root types (<type 'dict'>,)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,981 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg.d/90-digitalocean.cfg (quiet=False)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,981 - util.py[DEBUG]: Read 1329 bytes from /etc/cloud/cloud.cfg.d/90-digitalocean.cfg
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,981 - util.py[DEBUG]: Attempting to load yaml from string of length 1329 with allowed root types (<type 'dict'>,)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,987 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<type 'dict'>,)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,987 - util.py[DEBUG]: load_yaml given empty string, returning default
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,987 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,987 - util.py[DEBUG]: Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,987 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<type 'dict'>,)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,987 - util.py[DEBUG]: load_yaml given empty string, returning default
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,987 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/vendor-cloud-config.txt (quiet=False)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,987 - util.py[DEBUG]: Read 145 bytes from /var/lib/cloud/instance/vendor-cloud-config.txt
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,987 - util.py[DEBUG]: Attempting to load yaml from string of length 145 with allowed root types (<type 'dict'>,)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,989 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/cloud-config.txt (quiet=False)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,989 - util.py[DEBUG]: Read 0 bytes from /var/lib/cloud/instance/cloud-config.txt
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,989 - util.py[DEBUG]: Attempting to load yaml from string of length 0 with allowed root types (<type 'dict'>,)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,989 - util.py[DEBUG]: load_yaml given empty string, returning default
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,989 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/vendor-cloud-config.txt (quiet=False)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,989 - util.py[DEBUG]: Read 145 bytes from /var/lib/cloud/instance/vendor-cloud-config.txt
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,989 - util.py[DEBUG]: Attempting to load yaml from string of length 145 with allowed root types (<type 'dict'>,)
Mar 28 03:35:26 localhost cloud-init[802]: WARN: no logging configured! (tried 0 configs)
Mar 28 03:35:26 localhost cloud-init[802]: Setting up basic logging...
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:25,991 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [256] 29529 bytes
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,042 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.nixos.Distro'>
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,042 - 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 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,042 - stages.py[DEBUG]: Running module migrator (<module 'cloudinit.config.cc_migrator' from '/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_migrator.pyc'>) with frequency always
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,043 - handlers.py[DEBUG]: start: init-network/config-migrator: running config-migrator with frequency always
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,043 - helpers.py[DEBUG]: Running config-migrator using lock (<cloudinit.helpers.DummyLock object at 0x7f68f39dc0d0>)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,043 - cc_migrator.py[DEBUG]: Migrated 0 semaphore files to there canonicalized names
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,044 - handlers.py[DEBUG]: finish: init-network/config-migrator: SUCCESS: config-migrator ran successfully
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,044 - stages.py[DEBUG]: Running module seed_random (<module 'cloudinit.config.cc_seed_random' from '/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_seed_random.pyc'>) with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,044 - handlers.py[DEBUG]: start: init-network/config-seed_random: running config-seed_random with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,044 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/sem/config_seed_random - wb: [420] 19 bytes
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,044 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/sem/config_seed_random - wb: [420] 19 bytes
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,046 - helpers.py[DEBUG]: Running config-seed_random using lock (<FileLock using file '/var/lib/cloud/instances/138012245/sem/config_seed_random'>)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,046 - cc_seed_random.py[DEBUG]: no command provided
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,046 - handlers.py[DEBUG]: finish: init-network/config-seed_random: SUCCESS: config-seed_random ran successfully
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,046 - stages.py[DEBUG]: Running module bootcmd (<module 'cloudinit.config.cc_bootcmd' from '/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_bootcmd.pyc'>) with frequency always
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,046 - handlers.py[DEBUG]: start: init-network/config-bootcmd: running config-bootcmd with frequency always
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,046 - helpers.py[DEBUG]: Running config-bootcmd using lock (<cloudinit.helpers.DummyLock object at 0x7f68f39dc250>)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,046 - cc_bootcmd.py[DEBUG]: Skipping module named bootcmd, no 'bootcmd' key in configuration
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,046 - cc_bootcmd.py[DEBUG]: Skipping module named bootcmd, no 'bootcmd' key in configuration
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,047 - handlers.py[DEBUG]: finish: init-network/config-bootcmd: SUCCESS: config-bootcmd ran successfully
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,047 - stages.py[DEBUG]: Running module write-files (<module 'cloudinit.config.cc_write_files' from '/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_write_files.pyc'>) with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,047 - handlers.py[DEBUG]: start: init-network/config-write-files: running config-write-files with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,047 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/sem/config_write_files - wb: [420] 19 bytes
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,048 - helpers.py[DEBUG]: Running config-write-files using lock (<FileLock using file '/var/lib/cloud/instances/138012245/sem/config_write_files'>)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,048 - cc_write_files.py[DEBUG]: Skipping module named write-files, no/empty 'write_files' key in configuration
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,048 - cc_write_files.py[DEBUG]: Skipping module named write-files, no/empty 'write_files' key in configuration
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,048 - handlers.py[DEBUG]: finish: init-network/config-write-files: SUCCESS: config-write-files ran successfully
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,048 - stages.py[DEBUG]: Running module growpart (<module 'cloudinit.config.cc_growpart' from '/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_growpart.pyc'>) with frequency always
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,048 - handlers.py[DEBUG]: start: init-network/config-growpart: running config-growpart with frequency always
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,049 - helpers.py[DEBUG]: Running config-growpart using lock (<cloudinit.helpers.DummyLock object at 0x7f68f39dc310>)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,049 - cc_growpart.py[DEBUG]: No 'growpart' entry in cfg. Using default: {'ignore_growroot_disabled': False, 'mode': 'auto', 'devices': ['/']}
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,049 - util.py[DEBUG]: Running command ['/nix/store/kdbgjhgbw3mhiv4x24l8kmiacaawqlzs-cloud-utils-0.30/bin/growpart', '--help'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,049 - util.py[DEBUG]: Running command ['/nix/store/kdbgjhgbw3mhiv4x24l8kmiacaawqlzs-cloud-utils-0.30/bin/growpart', '--help'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,118 - util.py[DEBUG]: Reading from /proc/802/mountinfo (quiet=False)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,119 - util.py[DEBUG]: Read 2713 bytes from /proc/802/mountinfo
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,331 - util.py[DEBUG]: Reading from /sys/class/block/vda1/partition (quiet=False)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,332 - util.py[DEBUG]: Read 2 bytes from /sys/class/block/vda1/partition
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,332 - util.py[DEBUG]: Reading from /sys/devices/pci0000:00/0000:00:05.0/virtio2/block/vda/dev (quiet=False)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,332 - util.py[DEBUG]: Read 6 bytes from /sys/devices/pci0000:00/0000:00:05.0/virtio2/block/vda/dev
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,332 - util.py[DEBUG]: Running command ['/nix/store/kdbgjhgbw3mhiv4x24l8kmiacaawqlzs-cloud-utils-0.30/bin/growpart', '--dry-run', '/dev/vda', '1'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,510 - util.py[DEBUG]: resize_devices took 0.393 seconds
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,511 - cc_growpart.py[DEBUG]: '/' NOCHANGE: no change necessary (/dev/vda, 1)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,511 - handlers.py[DEBUG]: finish: init-network/config-growpart: SUCCESS: config-growpart ran successfully
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,511 - stages.py[DEBUG]: Running module resizefs (<module 'cloudinit.config.cc_resizefs' from '/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_resizefs.pyc'>) with frequency always
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,511 - handlers.py[DEBUG]: start: init-network/config-resizefs: running config-resizefs with frequency always
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,511 - helpers.py[DEBUG]: Running config-resizefs using lock (<cloudinit.helpers.DummyLock object at 0x7f68f39dc210>)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,511 - util.py[DEBUG]: Reading from /proc/802/mountinfo (quiet=False)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,512 - util.py[DEBUG]: Read 2713 bytes from /proc/802/mountinfo
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,512 - cc_resizefs.py[DEBUG]: resize_info: dev=/dev/disk/by-label/nixos mnt_point=/ path=/
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,512 - cc_resizefs.py[DEBUG]: resize_info: dev=/dev/disk/by-label/nixos mnt_point=/ path=/
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,512 - util.py[DEBUG]: Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,518 - util.py[DEBUG]: Running command ['running-in-container'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,596 - util.py[DEBUG]: Running command ['lxc-is-container'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,596 - util.py[DEBUG]: Running command ['lxc-is-container'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,606 - util.py[DEBUG]: Reading from /proc/1/environ (quiet=False)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,606 - util.py[DEBUG]: Reading from /proc/1/environ (quiet=False)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,606 - util.py[DEBUG]: Read 266 bytes from /proc/1/environ
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,606 - util.py[DEBUG]: Reading from /proc/self/status (quiet=False)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,607 - util.py[DEBUG]: Read 1020 bytes from /proc/self/status
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,607 - cc_resizefs.py[DEBUG]: Resizing / (ext4) using resize2fs /dev/disk/by-label/nixos
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,607 - util.py[DEBUG]: Running command ('resize2fs', '/dev/disk/by-label/nixos') with allowed return codes [0] (shell=False, capture=True)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,650 - util.py[DEBUG]: Resizing took 0.043 seconds
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,650 - cc_resizefs.py[DEBUG]: Resized root filesystem (type=ext4, val=True)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,657 - handlers.py[DEBUG]: finish: init-network/config-resizefs: SUCCESS: config-resizefs ran successfully
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,657 - stages.py[DEBUG]: Running module update_etc_hosts (<module 'cloudinit.config.cc_update_etc_hosts' from '/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_update_etc_hosts.pyc'>) with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,657 - handlers.py[DEBUG]: start: init-network/config-update_etc_hosts: running config-update_etc_hosts with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,664 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/sem/config_update_etc_hosts - wb: [420] 19 bytes
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,698 - helpers.py[DEBUG]: Running config-update_etc_hosts using lock (<FileLock using file '/var/lib/cloud/instances/138012245/sem/config_update_etc_hosts'>)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,698 - helpers.py[DEBUG]: Running config-update_etc_hosts using lock (<FileLock using file '/var/lib/cloud/instances/138012245/sem/config_update_etc_hosts'>)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,708 - cloud.py[WARNING]: No template found at /etc/cloud/templates/hosts.nixos.tmpl for template named hosts.nixos
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,708 - cloud.py[WARNING]: No template found at /etc/cloud/templates/hosts.nixos.tmpl for template named hosts.nixos
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,715 - handlers.py[DEBUG]: finish: init-network/config-update_etc_hosts: FAIL: running config-update_etc_hosts with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,715 - handlers.py[DEBUG]: finish: init-network/config-update_etc_hosts: FAIL: running config-update_etc_hosts with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,715 - util.py[WARNING]: Running module update_etc_hosts (<module 'cloudinit.config.cc_update_etc_hosts' from '/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_update_etc_hosts.pyc'>) failed
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,723 - util.py[DEBUG]: Running module update_etc_hosts (<module 'cloudinit.config.cc_update_etc_hosts' from '/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_update_etc_hosts.pyc'>) failed
Mar 28 03:35:26 localhost cloud-init[802]: Traceback (most recent call last):
Mar 28 03:35:26 localhost cloud-init[802]: File "/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/stages.py", line 776, in _run_modules
Mar 28 03:35:26 localhost cloud-init[802]: freq=freq)
Mar 28 03:35:26 localhost cloud-init[802]: File "/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/cloud.py", line 54, in run
Mar 28 03:35:26 localhost cloud-init[802]: return self._runners.run(name, functor, args, freq, clear_on_fail)
Mar 28 03:35:26 localhost cloud-init[802]: File "/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/helpers.py", line 187, in run
Mar 28 03:35:26 localhost cloud-init[802]: results = functor(*args)
Mar 28 03:35:26 localhost cloud-init[802]: File "/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_update_etc_hosts.py", line 75, in handle
Mar 28 03:35:26 localhost cloud-init[802]: (cloud.distro.osfamily))
Mar 28 03:35:26 localhost cloud-init[802]: RuntimeError: No hosts template could be found for distro nixos
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,723 - util.py[DEBUG]: Running module update_etc_hosts (<module 'cloudinit.config.cc_update_etc_hosts' from '/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_update_etc_hosts.pyc'>) failed
Mar 28 03:35:26 localhost cloud-init[802]: Traceback (most recent call last):
Mar 28 03:35:26 localhost cloud-init[802]: File "/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/stages.py", line 776, in _run_modules
Mar 28 03:35:26 localhost cloud-init[802]: freq=freq)
Mar 28 03:35:26 localhost cloud-init[802]: File "/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/cloud.py", line 54, in run
Mar 28 03:35:26 localhost cloud-init[802]: return self._runners.run(name, functor, args, freq, clear_on_fail)
Mar 28 03:35:26 localhost cloud-init[802]: File "/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/helpers.py", line 187, in run
Mar 28 03:35:26 localhost cloud-init[802]: results = functor(*args)
Mar 28 03:35:26 localhost cloud-init[802]: File "/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_update_etc_hosts.py", line 75, in handle
Mar 28 03:35:26 localhost cloud-init[802]: (cloud.distro.osfamily))
Mar 28 03:35:26 localhost cloud-init[802]: RuntimeError: No hosts template could be found for distro nixos
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,752 - stages.py[DEBUG]: Running module ca-certs (<module 'cloudinit.config.cc_ca_certs' from '/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_ca_certs.pyc'>) with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,797 - handlers.py[DEBUG]: start: init-network/config-ca-certs: running config-ca-certs with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,813 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/sem/config_ca_certs - wb: [420] 19 bytes
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,845 - helpers.py[DEBUG]: Running config-ca-certs using lock (<FileLock using file '/var/lib/cloud/instances/138012245/sem/config_ca_certs'>)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,845 - helpers.py[DEBUG]: Running config-ca-certs using lock (<FileLock using file '/var/lib/cloud/instances/138012245/sem/config_ca_certs'>)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,848 - cc_ca_certs.py[DEBUG]: Skipping module named ca-certs, no 'ca-certs' key in configuration
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,848 - cc_ca_certs.py[DEBUG]: Skipping module named ca-certs, no 'ca-certs' key in configuration
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,858 - handlers.py[DEBUG]: finish: init-network/config-ca-certs: SUCCESS: config-ca-certs ran successfully
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,858 - handlers.py[DEBUG]: finish: init-network/config-ca-certs: SUCCESS: config-ca-certs ran successfully
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,858 - stages.py[DEBUG]: Running module rsyslog (<module 'cloudinit.config.cc_rsyslog' from '/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_rsyslog.pyc'>) with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,858 - stages.py[DEBUG]: Running module rsyslog (<module 'cloudinit.config.cc_rsyslog' from '/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_rsyslog.pyc'>) with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,875 - handlers.py[DEBUG]: start: init-network/config-rsyslog: running config-rsyslog with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,875 - handlers.py[DEBUG]: start: init-network/config-rsyslog: running config-rsyslog with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,882 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/sem/config_rsyslog - wb: [420] 19 bytes
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,882 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/sem/config_rsyslog - wb: [420] 19 bytes
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,889 - helpers.py[DEBUG]: Running config-rsyslog using lock (<FileLock using file '/var/lib/cloud/instances/138012245/sem/config_rsyslog'>)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,889 - helpers.py[DEBUG]: Running config-rsyslog using lock (<FileLock using file '/var/lib/cloud/instances/138012245/sem/config_rsyslog'>)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,889 - cc_rsyslog.py[DEBUG]: Skipping module named rsyslog, no 'rsyslog' key in configuration
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,889 - cc_rsyslog.py[DEBUG]: Skipping module named rsyslog, no 'rsyslog' key in configuration
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,903 - handlers.py[DEBUG]: finish: init-network/config-rsyslog: SUCCESS: config-rsyslog ran successfully
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,903 - stages.py[DEBUG]: Running module users-groups (<module 'cloudinit.config.cc_users_groups' from '/nix/store/b7wc1y7ksx3zggb2m3kkm27j7r1gb05k-cloud-init-0.7.9/lib/python2.7/site-packages/cloudinit/config/cc_users_groups.pyc'>) with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,915 - handlers.py[DEBUG]: start: init-network/config-users-groups: running config-users-groups with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,915 - handlers.py[DEBUG]: start: init-network/config-users-groups: running config-users-groups with frequency once-per-instance
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,929 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/sem/config_users_groups - wb: [420] 19 bytes
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,929 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/138012245/sem/config_users_groups - wb: [420] 19 bytes
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,937 - helpers.py[DEBUG]: Running config-users-groups using lock (<FileLock using file '/var/lib/cloud/instances/138012245/sem/config_users_groups'>)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,937 - helpers.py[DEBUG]: Running config-users-groups using lock (<FileLock using file '/var/lib/cloud/instances/138012245/sem/config_users_groups'>)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,943 - __init__.py[INFO]: User root already exists, skipping.
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,943 - __init__.py[INFO]: User root already exists, skipping.
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,947 - util.py[DEBUG]: Running command ['passwd', '-l', 'root'] with allowed return codes [0] (shell=False, capture=True)
Mar 28 03:35:26 localhost passwd[860]: password for 'root' changed by 'root'
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,973 - handlers.py[DEBUG]: finish: init-network/config-users-groups: SUCCESS: config-users-groups ran successfully
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,973 - handlers.py[DEBUG]: finish: init-network/config-users-groups: SUCCESS: config-users-groups ran successfully
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,978 - main.py[DEBUG]: Ran 10 modules with 1 failures
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,987 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,987 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,990 - util.py[DEBUG]: Read 12 bytes from /proc/uptime
Mar 28 03:35:26 localhost cloud-init[802]: 2019-03-28 03:35:26,992 - util.py[DEBUG]: cloud-init mode 'init' took 1.432 seconds (1.44)
Mar 28 03:35:27 localhost cloud-init[802]: 2019-03-28 03:35:26,999 - handlers.py[DEBUG]: finish: init-network: FAIL: searching for network datasources
Mar 28 03:35:27 localhost systemd[1]: cloud-init.service: Main process exited, code=exited, status=1/FAILURE
Mar 28 03:35:27 localhost systemd[1]: cloud-init.service: Failed with result 'exit-code'.
Mar 28 03:35:27 localhost systemd[1]: Failed to start Initial cloud-init job (metadata service crawler).
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment