I have removed ConfigDrive and restarted, however even using the OpenStack provider it will fail with networking, AND then fail when setting the SSH key for the freebsd user (default user in cloud.cfg)


/usr/local/bin/cloud-init startingWARN: no logging configured! (tried 0 configs)
Setting up basic logging...
Cloud-init v. 0.7.8 running 'init' at Tue, 20 Sep 2016 20:41:00 +0000. Up 9.48849606514 seconds.
2016-09-20 20:41:00,513 - util.py[DEBUG]: Cloud-init v. 0.7.8 running 'init' at Tue, 20 Sep 2016 20:41:00 +0000. Up 9.48849606514 seconds.
2016-09-20 20:41:00,516 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [420] 0 bytes
2016-09-20 20:41:00,518 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:0
2016-09-20 20:41:00,519 - util.py[DEBUG]: Running command ['ifconfig', '-a'] with allowed return codes [0] (shell=False, capture=True)
2016-09-20 20:41:00,527 - util.py[DEBUG]: Running command ['netstat', '-rn'] with allowed return codes [0] (shell=False, capture=True)
2016-09-20 20:41:00,547 - util.py[DEBUG]: Running command ['netstat', '-A', 'inet6', '-n'] with allowed return codes [0] (shell=False, capture=True)
ci-info: ++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++
ci-info: +---------+-------+---------------+------------+-------+-------------------+
ci-info: |  Device |   Up  |    Address    |    Mask    | Scope |     Hw-Address    |
ci-info: +---------+-------+---------------+------------+-------+-------------------+
ci-info: | vtnet1: | False |       .       |     .      |   .   | fa:16:3e:9e:b0:af |
ci-info: | vtnet0: |  True | 172.17.63.153 | 0xffffff80 |   .   | fa:16:3e:f3:97:a7 |
ci-info: |   lo0:  |  True |   127.0.0.1   | 0xff000000 |   .   |         .         |
ci-info: |   lo0:  |  True |       .       |     .      |   d   |         .         |
ci-info: +---------+-------+---------------+------------+-------+-------------------+
2016-09-20 20:41:00,558 - main.py[DEBUG]: Checking to see if files that we need already exist from a previous run that would allow us to stop early.
2016-09-20 20:41:00,559 - main.py[DEBUG]: Execution continuing, no previous run detected that would allow us to stop early.
2016-09-20 20:41:00,560 - handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust]
2016-09-20 20:41:00,561 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
2016-09-20 20:41:00,562 - stages.py[DEBUG]: no cache found
2016-09-20 20:41:00,563 - handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: no cache found
2016-09-20 20:41:00,564 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
2016-09-20 20:41:00,567 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.freebsd.Distro'>
2016-09-20 20:41:00,568 - __init__.py[DEBUG]: Looking for for data source in: ['OpenStack', 'Ec2'], via packages ['', u'cloudinit.sources'] that matches dependencies ['FILESYSTEM', 'NETWORK']
2016-09-20 20:41:00,572 - __init__.py[DEBUG]: Searching for network data source in: [u'DataSourceOpenStack', u'DataSourceEc2']
2016-09-20 20:41:00,573 - handlers.py[DEBUG]: start: init-network/search-OpenStack: searching for network data from DataSourceOpenStack
2016-09-20 20:41:00,574 - __init__.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceOpenStack.DataSourceOpenStack'>
2016-09-20 20:41:01,282 - url_helper.py[DEBUG]: [0/1] open 'http://169.254.169.254/openstack' with {'url': 'http://169.254.169.254/openstack', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 10.0} configuration
2016-09-20 20:41:01,301 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:02,035 - connectionpool.py[DEBUG]: "GET /openstack HTTP/1.1" 200 50
2016-09-20 20:41:02,039 - url_helper.py[DEBUG]: Read from http://169.254.169.254/openstack (200, 50b) after 1 attempts
2016-09-20 20:41:02,041 - DataSourceOpenStack.py[DEBUG]: Using metadata source: 'http://169.254.169.254'
2016-09-20 20:41:02,043 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/openstack' with {'url': 'http://169.254.169.254/openstack', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:02,051 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:02,721 - connectionpool.py[DEBUG]: "GET /openstack HTTP/1.1" 200 50
2016-09-20 20:41:02,724 - url_helper.py[DEBUG]: Read from http://169.254.169.254/openstack (200, 50b) after 1 attempts
2016-09-20 20:41:02,726 - openstack.py[DEBUG]: Selected version '2015-10-15' from ['2012-08-10', '2013-04-04', '2013-10-17', '2015-10-15', 'latest']
2016-09-20 20:41:02,728 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/openstack/2015-10-15/vendor_data.json' with {'url': 'http://169.254.169.254/openstack/2015-10-15/vendor_data.json', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:02,735 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:02,741 - connectionpool.py[DEBUG]: "GET /openstack/2015-10-15/vendor_data.json HTTP/1.1" 200 2
2016-09-20 20:41:02,744 - url_helper.py[DEBUG]: Read from http://169.254.169.254/openstack/2015-10-15/vendor_data.json (200, 2b) after 1 attempts
2016-09-20 20:41:02,746 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/openstack/2015-10-15/user_data' with {'url': 'http://169.254.169.254/openstack/2015-10-15/user_data', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:02,752 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:03,372 - connectionpool.py[DEBUG]: "GET /openstack/2015-10-15/user_data HTTP/1.1" 404 154
2016-09-20 20:41:03,375 - url_helper.py[DEBUG]: Please wait 1 seconds while we wait to try again
2016-09-20 20:41:04,410 - url_helper.py[DEBUG]: [1/6] open 'http://169.254.169.254/openstack/2015-10-15/user_data' with {'url': 'http://169.254.169.254/openstack/2015-10-15/user_data', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:04,416 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:04,422 - connectionpool.py[DEBUG]: "GET /openstack/2015-10-15/user_data HTTP/1.1" 404 154
2016-09-20 20:41:04,425 - url_helper.py[DEBUG]: Please wait 1 seconds while we wait to try again
2016-09-20 20:41:05,472 - url_helper.py[DEBUG]: [2/6] open 'http://169.254.169.254/openstack/2015-10-15/user_data' with {'url': 'http://169.254.169.254/openstack/2015-10-15/user_data', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:05,478 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:05,485 - connectionpool.py[DEBUG]: "GET /openstack/2015-10-15/user_data HTTP/1.1" 404 154
2016-09-20 20:41:05,488 - url_helper.py[DEBUG]: Please wait 1 seconds while we wait to try again
2016-09-20 20:41:06,530 - url_helper.py[DEBUG]: [3/6] open 'http://169.254.169.254/openstack/2015-10-15/user_data' with {'url': 'http://169.254.169.254/openstack/2015-10-15/user_data', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:06,536 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:07,261 - connectionpool.py[DEBUG]: "GET /openstack/2015-10-15/user_data HTTP/1.1" 404 154
2016-09-20 20:41:07,264 - url_helper.py[DEBUG]: Please wait 1 seconds while we wait to try again
2016-09-20 20:41:08,315 - url_helper.py[DEBUG]: [4/6] open 'http://169.254.169.254/openstack/2015-10-15/user_data' with {'url': 'http://169.254.169.254/openstack/2015-10-15/user_data', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:08,321 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:08,958 - connectionpool.py[DEBUG]: "GET /openstack/2015-10-15/user_data HTTP/1.1" 404 154
2016-09-20 20:41:08,963 - url_helper.py[DEBUG]: Please wait 1 seconds while we wait to try again
2016-09-20 20:41:09,967 - url_helper.py[DEBUG]: [5/6] open 'http://169.254.169.254/openstack/2015-10-15/user_data' with {'url': 'http://169.254.169.254/openstack/2015-10-15/user_data', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:09,974 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:10,752 - connectionpool.py[DEBUG]: "GET /openstack/2015-10-15/user_data HTTP/1.1" 404 154
2016-09-20 20:41:10,757 - openstack.py[DEBUG]: Failed reading optional path http://169.254.169.254/openstack/2015-10-15/user_data due to: 404 Client Error: Not Found for url: http://169.254.169.254/openstack/2015-10-15/user_data
2016-09-20 20:41:10,761 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/openstack/2015-10-15/network_data.json' with {'url': 'http://169.254.169.254/openstack/2015-10-15/network_data.json', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:10,768 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:11,423 - connectionpool.py[DEBUG]: "GET /openstack/2015-10-15/network_data.json HTTP/1.1" 200 1118
2016-09-20 20:41:11,427 - url_helper.py[DEBUG]: Read from http://169.254.169.254/openstack/2015-10-15/network_data.json (200, 1118b) after 1 attempts
2016-09-20 20:41:11,429 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/openstack/2015-10-15/meta_data.json' with {'url': 'http://169.254.169.254/openstack/2015-10-15/meta_data.json', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:11,436 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:12,104 - connectionpool.py[DEBUG]: "GET /openstack/2015-10-15/meta_data.json HTTP/1.1" 200 1791
2016-09-20 20:41:12,107 - url_helper.py[DEBUG]: Read from http://169.254.169.254/openstack/2015-10-15/meta_data.json (200, 1791b) after 1 attempts
2016-09-20 20:41:12,110 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/' with {'url': 'http://169.254.169.254/latest/meta-data/', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:12,116 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:12,122 - connectionpool.py[DEBUG]: "GET /latest/meta-data/ HTTP/1.1" 200 223
2016-09-20 20:41:12,125 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/ (200, 223b) after 1 attempts
2016-09-20 20:41:12,127 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/block-device-mapping/' with {'url': 'http://169.254.169.254/latest/meta-data/block-device-mapping/', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:12,134 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:12,751 - connectionpool.py[DEBUG]: "GET /latest/meta-data/block-device-mapping/ HTTP/1.1" 200 8
2016-09-20 20:41:12,754 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/block-device-mapping/ (200, 8b) after 1 attempts
2016-09-20 20:41:12,756 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/block-device-mapping/ami' with {'url': 'http://169.254.169.254/latest/meta-data/block-device-mapping/ami', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:12,763 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:13,378 - connectionpool.py[DEBUG]: "GET /latest/meta-data/block-device-mapping/ami HTTP/1.1" 200 3
2016-09-20 20:41:13,382 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/block-device-mapping/ami (200, 3b) after 1 attempts
2016-09-20 20:41:13,384 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/block-device-mapping/root' with {'url': 'http://169.254.169.254/latest/meta-data/block-device-mapping/root', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:13,390 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:13,395 - connectionpool.py[DEBUG]: "GET /latest/meta-data/block-device-mapping/root HTTP/1.1" 200 8
2016-09-20 20:41:13,399 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/block-device-mapping/root (200, 8b) after 1 attempts
2016-09-20 20:41:13,402 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/placement/' with {'url': 'http://169.254.169.254/latest/meta-data/placement/', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:13,408 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:14,041 - connectionpool.py[DEBUG]: "GET /latest/meta-data/placement/ HTTP/1.1" 200 17
2016-09-20 20:41:14,045 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/placement/ (200, 17b) after 1 attempts
2016-09-20 20:41:14,047 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/placement/availability-zone' with {'url': 'http://169.254.169.254/latest/meta-data/placement/availability-zone', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:14,054 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:14,059 - connectionpool.py[DEBUG]: "GET /latest/meta-data/placement/availability-zone HTTP/1.1" 200 4
2016-09-20 20:41:14,062 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/placement/availability-zone (200, 4b) after 1 attempts
2016-09-20 20:41:14,065 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/public-keys/' with {'url': 'http://169.254.169.254/latest/meta-data/public-keys/', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:14,072 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:14,684 - connectionpool.py[DEBUG]: "GET /latest/meta-data/public-keys/ HTTP/1.1" 200 7
2016-09-20 20:41:14,687 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/public-keys/ (200, 7b) after 1 attempts
2016-09-20 20:41:14,689 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/public-keys/0/openssh-key' with {'url': 'http://169.254.169.254/latest/meta-data/public-keys/0/openssh-key', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:14,696 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:15,356 - connectionpool.py[DEBUG]: "GET /latest/meta-data/public-keys/0/openssh-key HTTP/1.1" 200 400
2016-09-20 20:41:15,359 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/public-keys/0/openssh-key (200, 400b) after 1 attempts
2016-09-20 20:41:15,362 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/local-ipv4' with {'url': 'http://169.254.169.254/latest/meta-data/local-ipv4', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:15,368 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:15,374 - connectionpool.py[DEBUG]: "GET /latest/meta-data/local-ipv4 HTTP/1.1" 200 13
2016-09-20 20:41:15,376 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/local-ipv4 (200, 13b) after 1 attempts
2016-09-20 20:41:15,379 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/reservation-id' with {'url': 'http://169.254.169.254/latest/meta-data/reservation-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:15,385 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:16,062 - connectionpool.py[DEBUG]: "GET /latest/meta-data/reservation-id HTTP/1.1" 200 10
2016-09-20 20:41:16,065 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/reservation-id (200, 10b) after 1 attempts
2016-09-20 20:41:16,068 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/local-hostname' with {'url': 'http://169.254.169.254/latest/meta-data/local-hostname', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:16,074 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:16,692 - connectionpool.py[DEBUG]: "GET /latest/meta-data/local-hostname HTTP/1.1" 200 19
2016-09-20 20:41:16,695 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/local-hostname (200, 19b) after 1 attempts
2016-09-20 20:41:16,698 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/security-groups' with {'url': 'http://169.254.169.254/latest/meta-data/security-groups', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:16,704 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:16,710 - connectionpool.py[DEBUG]: "GET /latest/meta-data/security-groups HTTP/1.1" 200 15
2016-09-20 20:41:16,713 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/security-groups (200, 15b) after 1 attempts
2016-09-20 20:41:16,715 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/ami-launch-index' with {'url': 'http://169.254.169.254/latest/meta-data/ami-launch-index', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:16,723 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:16,731 - connectionpool.py[DEBUG]: "GET /latest/meta-data/ami-launch-index HTTP/1.1" 200 1
2016-09-20 20:41:16,734 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/ami-launch-index (200, 1b) after 1 attempts
2016-09-20 20:41:16,736 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/public-hostname' with {'url': 'http://169.254.169.254/latest/meta-data/public-hostname', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:16,742 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:16,746 - connectionpool.py[DEBUG]: "GET /latest/meta-data/public-hostname HTTP/1.1" 200 19
2016-09-20 20:41:16,749 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/public-hostname (200, 19b) after 1 attempts
2016-09-20 20:41:16,751 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/hostname' with {'url': 'http://169.254.169.254/latest/meta-data/hostname', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:16,756 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:17,405 - connectionpool.py[DEBUG]: "GET /latest/meta-data/hostname HTTP/1.1" 200 19
2016-09-20 20:41:17,408 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/hostname (200, 19b) after 1 attempts
2016-09-20 20:41:17,410 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/ami-id' with {'url': 'http://169.254.169.254/latest/meta-data/ami-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:17,416 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:18,229 - connectionpool.py[DEBUG]: "GET /latest/meta-data/ami-id HTTP/1.1" 200 12
2016-09-20 20:41:18,232 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/ami-id (200, 12b) after 1 attempts
2016-09-20 20:41:18,234 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/instance-action' with {'url': 'http://169.254.169.254/latest/meta-data/instance-action', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:18,241 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:18,873 - connectionpool.py[DEBUG]: "GET /latest/meta-data/instance-action HTTP/1.1" 200 4
2016-09-20 20:41:18,877 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/instance-action (200, 4b) after 1 attempts
2016-09-20 20:41:18,879 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/public-ipv4' with {'url': 'http://169.254.169.254/latest/meta-data/public-ipv4', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:18,885 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:19,581 - connectionpool.py[DEBUG]: "GET /latest/meta-data/public-ipv4 HTTP/1.1" 200 0
2016-09-20 20:41:19,584 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/public-ipv4 (200, 0b) after 1 attempts
2016-09-20 20:41:19,586 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/ami-manifest-path' with {'url': 'http://169.254.169.254/latest/meta-data/ami-manifest-path', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:19,593 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:20,231 - connectionpool.py[DEBUG]: "GET /latest/meta-data/ami-manifest-path HTTP/1.1" 200 5
2016-09-20 20:41:20,234 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/ami-manifest-path (200, 5b) after 1 attempts
2016-09-20 20:41:20,237 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/instance-type' with {'url': 'http://169.254.169.254/latest/meta-data/instance-type', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:20,243 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:20,247 - connectionpool.py[DEBUG]: "GET /latest/meta-data/instance-type HTTP/1.1" 200 8
2016-09-20 20:41:20,252 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/instance-type (200, 8b) after 1 attempts
2016-09-20 20:41:20,255 - url_helper.py[DEBUG]: [0/6] open 'http://169.254.169.254/latest/meta-data/instance-id' with {'url': 'http://169.254.169.254/latest/meta-data/instance-id', 'headers': {'User-Agent': 'Cloud-Init/0.7.8'}, 'allow_redirects': True, 'method': 'GET', 'timeout': 5.0} configuration
2016-09-20 20:41:20,261 - connectionpool.py[INFO]: Starting new HTTP connection (1): 169.254.169.254
2016-09-20 20:41:20,891 - connectionpool.py[DEBUG]: "GET /latest/meta-data/instance-id HTTP/1.1" 200 10
2016-09-20 20:41:20,894 - url_helper.py[DEBUG]: Read from http://169.254.169.254/latest/meta-data/instance-id (200, 10b) after 1 attempts
2016-09-20 20:41:20,897 - util.py[DEBUG]: Crawl of openstack metadata service took 18.854 seconds
2016-09-20 20:41:20,899 - handlers.py[DEBUG]: finish: init-network/search-OpenStack: SUCCESS: found network data from DataSourceOpenStack
2016-09-20 20:41:20,901 - stages.py[INFO]: Loaded datasource DataSourceOpenStack - DataSourceOpenStack [net,ver=2]
2016-09-20 20:41:20,904 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
2016-09-20 20:41:20,905 - util.py[DEBUG]: Reading from /usr/local/etc/cloud/cloud.cfg (quiet=False)
2016-09-20 20:41:20,907 - util.py[DEBUG]: Read 2022 bytes from /usr/local/etc/cloud/cloud.cfg
2016-09-20 20:41:20,908 - util.py[DEBUG]: Attempting to load yaml from string of length 2022 with allowed root types (<type 'dict'>,)
2016-09-20 20:41:20,929 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
2016-09-20 20:41:20,931 - util.py[DEBUG]: Creating symbolic link from '/var/lib/cloud/instance' => '/var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b'
2016-09-20 20:41:20,935 - util.py[DEBUG]: Reading from /var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/datasource (quiet=False)
2016-09-20 20:41:20,937 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/datasource - wb: [420] 53 bytes
2016-09-20 20:41:20,939 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-datasource - wb: [420] 53 bytes
2016-09-20 20:41:20,941 - util.py[DEBUG]: Reading from /var/lib/cloud/data/instance-id (quiet=False)
2016-09-20 20:41:20,942 - stages.py[DEBUG]: previous iid found to be NO_PREVIOUS_INSTANCE_ID
2016-09-20 20:41:20,943 - util.py[DEBUG]: Writing to /var/lib/cloud/data/instance-id - wb: [420] 37 bytes
2016-09-20 20:41:20,945 - util.py[DEBUG]: Writing to /run/cloud-init/.instance-id - wb: [420] 37 bytes
2016-09-20 20:41:20,947 - util.py[DEBUG]: Writing to /var/lib/cloud/data/previous-instance-id - wb: [420] 24 bytes
2016-09-20 20:41:20,950 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/obj.pkl - wb: [256] 6899 bytes
2016-09-20 20:41:20,951 - main.py[DEBUG]: [net] init will now be targeting instance id: aba344ee-5536-4f23-a035-721f4060807b. new=True
2016-09-20 20:41:20,953 - util.py[DEBUG]: Reading from /etc/cloud/cloud.cfg (quiet=False)
2016-09-20 20:41:20,954 - util.py[DEBUG]: Reading from /usr/local/etc/cloud/cloud.cfg (quiet=False)
2016-09-20 20:41:20,956 - util.py[DEBUG]: Read 2022 bytes from /usr/local/etc/cloud/cloud.cfg
2016-09-20 20:41:20,957 - util.py[DEBUG]: Attempting to load yaml from string of length 2022 with allowed root types (<type 'dict'>,)
2016-09-20 20:41:20,977 - util.py[WARNING]: failed stage init
2016-09-20 20:41:20,977 - util.py[DEBUG]: failed stage init
Traceback (most recent call last):
  File "/usr/local/lib/python2.7/site-packages/cloudinit/cmd/main.py", line 521, in status_wrapper
    ret = functor(name, args)
  File "/usr/local/lib/python2.7/site-packages/cloudinit/cmd/main.py", line 280, in main_init
    init.apply_network_config(bring_up=bool(mode != sources.DSMODE_LOCAL))
  File "/usr/local/lib/python2.7/site-packages/cloudinit/stages.py", line 631, in apply_network_config
    netcfg, src = self._find_networking_config()
  File "/usr/local/lib/python2.7/site-packages/cloudinit/stages.py", line 628, in _find_networking_config
    return (net.generate_fallback_config(), "fallback")
  File "/usr/local/lib/python2.7/site-packages/cloudinit/net/__init__.py", line 133, in generate_fallback_config
    potential_interfaces = set(get_devicelist())
  File "/usr/local/lib/python2.7/site-packages/cloudinit/net/__init__.py", line 99, in get_devicelist
    return os.listdir(SYS_CLASS_NET)
OSError: [Errno 2] No such file or directory: '/sys/class/net/'
failed run of stage init
------------------------------------------------------------
Traceback (most recent call last):
  File "/usr/local/lib/python2.7/site-packages/cloudinit/cmd/main.py", line 521, in status_wrapper
    ret = functor(name, args)
  File "/usr/local/lib/python2.7/site-packages/cloudinit/cmd/main.py", line 280, in main_init
    init.apply_network_config(bring_up=bool(mode != sources.DSMODE_LOCAL))
  File "/usr/local/lib/python2.7/site-packages/cloudinit/stages.py", line 631, in apply_network_config
    netcfg, src = self._find_networking_config()
  File "/usr/local/lib/python2.7/site-packages/cloudinit/stages.py", line 628, in _find_networking_config
    return (net.generate_fallback_config(), "fallback")
  File "/usr/local/lib/python2.7/site-packages/cloudinit/net/__init__.py", line 133, in generate_fallback_config
    potential_interfaces = set(get_devicelist())
  File "/usr/local/lib/python2.7/site-packages/cloudinit/net/__init__.py", line 99, in get_devicelist
    return os.listdir(SYS_CLASS_NET)
OSError: [Errno 2] No such file or directory: '/sys/class/net/'
------------------------------------------------------------
2016-09-20 20:41:21,002 - util.py[DEBUG]: cloud-init mode 'init' took 20.548 seconds (20.55)
2016-09-20 20:41:21,003 - handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources
/usr/local/bin/cloud-init starting
WARN: no logging configured! (tried 0 configs)
Setting up basic logging...
Cloud-init v. 0.7.8 running 'modules:config' at Tue, 20 Sep 2016 20:41:21 +0000. Up 30.3876640797 seconds.
2016-09-20 20:41:21,458 - util.py[DEBUG]: Cloud-init v. 0.7.8 running 'modules:config' at Tue, 20 Sep 2016 20:41:21 +0000. Up 30.3876640797 seconds.
2016-09-20 20:41:21,474 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.freebsd.Distro'>
2016-09-20 20:41:21,475 - stages.py[INFO]: Skipping modules ['ssh-import-id'] because they are not verified on distro 'freebsd'.  To run anyway, add them to 'unverified_modules' in config.
2016-09-20 20:41:21,477 - stages.py[DEBUG]: Running module ssh-import-id (<module 'cloudinit.config.cc_ssh_import_id' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_ssh_import_id.pyc'>) with frequency once-per-instance
2016-09-20 20:41:21,479 - handlers.py[DEBUG]: start: modules-config/config-ssh-import-id: running config-ssh-import-id with frequency once-per-instance
2016-09-20 20:41:21,480 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_ssh_import_id - wb: [420] 19 bytes
2016-09-20 20:41:21,482 - helpers.py[DEBUG]: Running config-ssh-import-id using lock (<FileLock using file '/var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_ssh_import_id'>)
2016-09-20 20:41:21,484 - handlers.py[DEBUG]: finish: modules-config/config-ssh-import-id: SUCCESS: config-ssh-import-id ran successfully
2016-09-20 20:41:21,485 - stages.py[DEBUG]: Running module locale (<module 'cloudinit.config.cc_locale' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_locale.pyc'>) with frequency once-per-instance
2016-09-20 20:41:21,487 - handlers.py[DEBUG]: start: modules-config/config-locale: running config-locale with frequency once-per-instance
2016-09-20 20:41:21,488 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_locale - wb: [420] 19 bytes
2016-09-20 20:41:21,490 - helpers.py[DEBUG]: Running config-locale using lock (<FileLock using file '/var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_locale'>)
2016-09-20 20:41:21,491 - cc_locale.py[DEBUG]: Setting locale to en_US.UTF-8
2016-09-20 20:41:21,492 - util.py[DEBUG]: Reading from /etc/login.conf (quiet=False)
2016-09-20 20:41:21,494 - util.py[DEBUG]: Read 6779 bytes from /etc/login.conf
2016-09-20 20:41:21,497 - util.py[DEBUG]: Copying /etc/login.conf to /etc/login.conf.orig
2016-09-20 20:41:21,499 - util.py[DEBUG]: Writing to /etc/login.conf - wb: [420] 6796 bytes
2016-09-20 20:41:21,500 - freebsd.py[DEBUG]: Running cap_mkdb for en_US.UTF-8
2016-09-20 20:41:21,500 - util.py[DEBUG]: Running command ['cap_mkdb', '/etc/login.conf'] with allowed return codes [0] (shell=False, capture=True)
2016-09-20 20:41:21,526 - handlers.py[DEBUG]: finish: modules-config/config-locale: SUCCESS: config-locale ran successfully
2016-09-20 20:41:21,528 - stages.py[DEBUG]: Running module set-passwords (<module 'cloudinit.config.cc_set_passwords' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_set_passwords.pyc'>) with frequency once-per-instance
2016-09-20 20:41:21,530 - handlers.py[DEBUG]: start: modules-config/config-set-passwords: running config-set-passwords with frequency once-per-instance
2016-09-20 20:41:21,531 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_set_passwords - wb: [420] 19 bytes
2016-09-20 20:41:21,533 - helpers.py[DEBUG]: Running config-set-passwords using lock (<FileLock using file '/var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_set_passwords'>)
2016-09-20 20:41:21,535 - handlers.py[DEBUG]: finish: modules-config/config-set-passwords: SUCCESS: config-set-passwords ran successfully
2016-09-20 20:41:21,536 - stages.py[DEBUG]: Running module package-update-upgrade-install (<module 'cloudinit.config.cc_package_update_upgrade_install' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_package_update_upgrade_install.pyc'>) with frequency once-per-instance
2016-09-20 20:41:21,538 - handlers.py[DEBUG]: start: modules-config/config-package-update-upgrade-install: running config-package-update-upgrade-install with frequency once-per-instance
2016-09-20 20:41:21,540 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_package_update_upgrade_install - wb: [420] 19 bytes
2016-09-20 20:41:21,542 - helpers.py[DEBUG]: Running config-package-update-upgrade-install using lock (<FileLock using file '/var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_package_update_upgrade_install'>)
2016-09-20 20:41:21,544 - handlers.py[DEBUG]: finish: modules-config/config-package-update-upgrade-install: SUCCESS: config-package-update-upgrade-install ran successfully
2016-09-20 20:41:21,545 - stages.py[DEBUG]: Running module timezone (<module 'cloudinit.config.cc_timezone' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_timezone.pyc'>) with frequency once-per-instance
2016-09-20 20:41:21,547 - handlers.py[DEBUG]: start: modules-config/config-timezone: running config-timezone with frequency once-per-instance
2016-09-20 20:41:21,548 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_timezone - wb: [420] 19 bytes
2016-09-20 20:41:21,550 - helpers.py[DEBUG]: Running config-timezone using lock (<FileLock using file '/var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_timezone'>)
2016-09-20 20:41:21,551 - cc_timezone.py[DEBUG]: Skipping module named timezone, no 'timezone' specified
2016-09-20 20:41:21,552 - handlers.py[DEBUG]: finish: modules-config/config-timezone: SUCCESS: config-timezone ran successfully
2016-09-20 20:41:21,553 - stages.py[DEBUG]: Running module disable-ec2-metadata (<module 'cloudinit.config.cc_disable_ec2_metadata' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_disable_ec2_metadata.pyc'>) with frequency always
2016-09-20 20:41:21,555 - handlers.py[DEBUG]: start: modules-config/config-disable-ec2-metadata: running config-disable-ec2-metadata with frequency always
2016-09-20 20:41:21,557 - helpers.py[DEBUG]: Running config-disable-ec2-metadata using lock (<cloudinit.helpers.DummyLock object at 0x807d72410>)
2016-09-20 20:41:21,558 - cc_disable_ec2_metadata.py[DEBUG]: Skipping module named disable-ec2-metadata, disabling the ec2 route not enabled
2016-09-20 20:41:21,559 - handlers.py[DEBUG]: finish: modules-config/config-disable-ec2-metadata: SUCCESS: config-disable-ec2-metadata ran successfully
2016-09-20 20:41:21,560 - stages.py[DEBUG]: Running module runcmd (<module 'cloudinit.config.cc_runcmd' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_runcmd.pyc'>) with frequency once-per-instance
2016-09-20 20:41:21,562 - handlers.py[DEBUG]: start: modules-config/config-runcmd: running config-runcmd with frequency once-per-instance
2016-09-20 20:41:21,563 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_runcmd - wb: [420] 19 bytes
2016-09-20 20:41:21,565 - helpers.py[DEBUG]: Running config-runcmd using lock (<FileLock using file '/var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_runcmd'>)
2016-09-20 20:41:21,567 - cc_runcmd.py[DEBUG]: Skipping module named runcmd, no 'runcmd' key in configuration
2016-09-20 20:41:21,568 - handlers.py[DEBUG]: finish: modules-config/config-runcmd: SUCCESS: config-runcmd ran successfully
2016-09-20 20:41:21,569 - main.py[DEBUG]: Ran 7 modules with 0 failures
2016-09-20 20:41:21,570 - util.py[DEBUG]: cloud-init mode 'modules' took 0.197 seconds (0.20)
2016-09-20 20:41:21,571 - handlers.py[DEBUG]: finish: modules-config: SUCCESS: running modules for config
ELF ldconfig path: /lib /usr/lib /usr/lib/compat /usr/local/lib
32-bit compatibility ldconfig path: /usr/lib32
Creating and/or trimming log files.
Starting syslogd.
Clearing /tmp (X related).
Mounting late file systems:.
Updating motd:.
Performing sanity check on sshd configuration.
Starting sshd.
Starting cron.
/usr/local/bin/cloud-init startingWARN: no logging configured! (tried 0 configs)
Setting up basic logging...
Cloud-init v. 0.7.8 running 'modules:final' at Tue, 20 Sep 2016 20:41:22 +0000. Up 31.2931408882 seconds.
2016-09-20 20:41:22,343 - util.py[DEBUG]: Cloud-init v. 0.7.8 running 'modules:final' at Tue, 20 Sep 2016 20:41:22 +0000. Up 31.2931408882 seconds.
2016-09-20 20:41:22,362 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.freebsd.Distro'>
2016-09-20 20:41:22,363 - stages.py[DEBUG]: Running module rightscale_userdata (<module 'cloudinit.config.cc_rightscale_userdata' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_rightscale_userdata.pyc'>) with frequency once-per-instance
2016-09-20 20:41:22,364 - handlers.py[DEBUG]: start: modules-final/config-rightscale_userdata: running config-rightscale_userdata with frequency once-per-instance
2016-09-20 20:41:22,365 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_rightscale_userdata - wb: [420] 19 bytes
2016-09-20 20:41:22,367 - helpers.py[DEBUG]: Running config-rightscale_userdata using lock (<FileLock using file '/var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_rightscale_userdata'>)
2016-09-20 20:41:22,368 - cc_rightscale_userdata.py[DEBUG]: Failed to get raw userdata in module rightscale_userdata
2016-09-20 20:41:22,368 - handlers.py[DEBUG]: finish: modules-final/config-rightscale_userdata: SUCCESS: config-rightscale_userdata ran successfully
2016-09-20 20:41:22,369 - stages.py[DEBUG]: Running module scripts-vendor (<module 'cloudinit.config.cc_scripts_vendor' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_scripts_vendor.pyc'>) with frequency once-per-instance
2016-09-20 20:41:22,371 - handlers.py[DEBUG]: start: modules-final/config-scripts-vendor: running config-scripts-vendor with frequency once-per-instance
2016-09-20 20:41:22,372 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_scripts_vendor - wb: [420] 19 bytes
2016-09-20 20:41:22,373 - helpers.py[DEBUG]: Running config-scripts-vendor using lock (<FileLock using file '/var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_scripts_vendor'>)
2016-09-20 20:41:22,374 - handlers.py[DEBUG]: finish: modules-final/config-scripts-vendor: SUCCESS: config-scripts-vendor ran successfully
2016-09-20 20:41:22,375 - stages.py[DEBUG]: Running module scripts-per-once (<module 'cloudinit.config.cc_scripts_per_once' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_scripts_per_once.pyc'>) with frequency once
2016-09-20 20:41:22,376 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-once: running config-scripts-per-once with frequency once
2016-09-20 20:41:22,377 - util.py[DEBUG]: Writing to /var/lib/cloud/sem/config_scripts_per_once.once - wb: [420] 19 bytes
2016-09-20 20:41:22,378 - helpers.py[DEBUG]: Running config-scripts-per-once using lock (<FileLock using file '/var/lib/cloud/sem/config_scripts_per_once.once'>)
2016-09-20 20:41:22,379 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-once: SUCCESS: config-scripts-per-once ran successfully
2016-09-20 20:41:22,380 - stages.py[DEBUG]: Running module scripts-per-boot (<module 'cloudinit.config.cc_scripts_per_boot' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_scripts_per_boot.pyc'>) with frequency always
2016-09-20 20:41:22,381 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-boot: running config-scripts-per-boot with frequency always
2016-09-20 20:41:22,382 - helpers.py[DEBUG]: Running config-scripts-per-boot using lock (<cloudinit.helpers.DummyLock object at 0x807d6bd50>)
2016-09-20 20:41:22,383 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-boot: SUCCESS: config-scripts-per-boot ran successfully
2016-09-20 20:41:22,384 - stages.py[DEBUG]: Running module scripts-per-instance (<module 'cloudinit.config.cc_scripts_per_instance' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_scripts_per_instance.pyc'>) with frequency once-per-instance
2016-09-20 20:41:22,385 - handlers.py[DEBUG]: start: modules-final/config-scripts-per-instance: running config-scripts-per-instance with frequency once-per-instance
2016-09-20 20:41:22,386 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_scripts_per_instance - wb: [420] 19 bytes
2016-09-20 20:41:22,387 - helpers.py[DEBUG]: Running config-scripts-per-instance using lock (<FileLock using file '/var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_scripts_per_instance'>)
2016-09-20 20:41:22,389 - handlers.py[DEBUG]: finish: modules-final/config-scripts-per-instance: SUCCESS: config-scripts-per-instance ran successfully
2016-09-20 20:41:22,389 - stages.py[DEBUG]: Running module scripts-user (<module 'cloudinit.config.cc_scripts_user' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_scripts_user.pyc'>) with frequency once-per-instance
2016-09-20 20:41:22,391 - handlers.py[DEBUG]: start: modules-final/config-scripts-user: running config-scripts-user with frequency once-per-instance
2016-09-20 20:41:22,392 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_scripts_user - wb: [420] 19 bytes
2016-09-20 20:41:22,393 - helpers.py[DEBUG]: Running config-scripts-user using lock (<FileLock using file '/var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_scripts_user'>)
2016-09-20 20:41:22,394 - handlers.py[DEBUG]: finish: modules-final/config-scripts-user: SUCCESS: config-scripts-user ran successfully
2016-09-20 20:41:22,395 - stages.py[DEBUG]: Running module ssh-authkey-fingerprints (<module 'cloudinit.config.cc_ssh_authkey_fingerprints' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_ssh_authkey_fingerprints.pyc'>) with frequency once-per-instance
2016-09-20 20:41:22,396 - handlers.py[DEBUG]: start: modules-final/config-ssh-authkey-fingerprints: running config-ssh-authkey-fingerprints with frequency once-per-instance
2016-09-20 20:41:22,397 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_ssh_authkey_fingerprints - wb: [420] 18 bytes
2016-09-20 20:41:22,398 - helpers.py[DEBUG]: Running config-ssh-authkey-fingerprints using lock (<FileLock using file '/var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_ssh_authkey_fingerprints'>)
2016-09-20 20:41:22,400 - handlers.py[DEBUG]: finish: modules-final/config-ssh-authkey-fingerprints: FAIL: running config-ssh-authkey-fingerprints with frequency once-per-instance
2016-09-20 20:41:22,401 - util.py[WARNING]: Running module ssh-authkey-fingerprints (<module 'cloudinit.config.cc_ssh_authkey_fingerprints' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_ssh_authkey_fingerprints.pyc'>) failed
2016-09-20 20:41:22,402 - util.py[DEBUG]: Running module ssh-authkey-fingerprints (<module 'cloudinit.config.cc_ssh_authkey_fingerprints' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_ssh_authkey_fingerprints.pyc'>) failed
Traceback (most recent call last):
  File "/usr/local/lib/python2.7/site-packages/cloudinit/stages.py", line 785, in _run_modules
    freq=freq)
  File "/usr/local/lib/python2.7/site-packages/cloudinit/cloud.py", line 70, in run
    return self._runners.run(name, functor, args, freq, clear_on_fail)
  File "/usr/local/lib/python2.7/site-packages/cloudinit/helpers.py", line 199, in run
    results = functor(*args)
  File "/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_ssh_authkey_fingerprints.py", line 103, in handle
    (key_fn, key_entries) = ssh_util.extract_authorized_keys(user_name)
  File "/usr/local/lib/python2.7/site-packages/cloudinit/ssh_util.py", line 212, in extract_authorized_keys
    (ssh_dir, pw_ent) = users_ssh_info(username)
  File "/usr/local/lib/python2.7/site-packages/cloudinit/ssh_util.py", line 205, in users_ssh_info
    pw_ent = pwd.getpwnam(username)
KeyError: 'getpwnam(): name not found: freebsd'
2016-09-20 20:41:22,413 - stages.py[DEBUG]: Running module keys-to-console (<module 'cloudinit.config.cc_keys_to_console' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_keys_to_console.pyc'>) with frequency once-per-instance
2016-09-20 20:41:22,415 - handlers.py[DEBUG]: start: modules-final/config-keys-to-console: running config-keys-to-console with frequency once-per-instance
2016-09-20 20:41:22,416 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_keys_to_console - wb: [420] 19 bytes
2016-09-20 20:41:22,417 - helpers.py[DEBUG]: Running config-keys-to-console using lock (<FileLock using file '/var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_keys_to_console'>)
2016-09-20 20:41:22,418 - cc_keys_to_console.py[WARNING]: Unable to activate module keys-to-console, helper tool not found at /usr/lib/cloud-init/write-ssh-key-fingerprints
2016-09-20 20:41:22,419 - handlers.py[DEBUG]: finish: modules-final/config-keys-to-console: SUCCESS: config-keys-to-console ran successfully
2016-09-20 20:41:22,419 - stages.py[DEBUG]: Running module phone-home (<module 'cloudinit.config.cc_phone_home' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_phone_home.pyc'>) with frequency once-per-instance
2016-09-20 20:41:22,421 - handlers.py[DEBUG]: start: modules-final/config-phone-home: running config-phone-home with frequency once-per-instance
2016-09-20 20:41:22,422 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_phone_home - wb: [420] 19 bytes
2016-09-20 20:41:22,423 - helpers.py[DEBUG]: Running config-phone-home using lock (<FileLock using file '/var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_phone_home'>)
2016-09-20 20:41:22,424 - cc_phone_home.py[DEBUG]: Skipping module named phone-home, no 'phone_home' configuration found
2016-09-20 20:41:22,424 - handlers.py[DEBUG]: finish: modules-final/config-phone-home: SUCCESS: config-phone-home ran successfully
2016-09-20 20:41:22,425 - stages.py[DEBUG]: Running module final-message (<module 'cloudinit.config.cc_final_message' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_final_message.pyc'>) with frequency always
2016-09-20 20:41:22,427 - handlers.py[DEBUG]: start: modules-final/config-final-message: running config-final-message with frequency always
2016-09-20 20:41:22,427 - helpers.py[DEBUG]: Running config-final-message using lock (<cloudinit.helpers.DummyLock object at 0x807da1350>)
Cloud-init v. 0.7.8 finished at Tue, 20 Sep 2016 20:41:22 +0000. Datasource DataSourceOpenStack [net,ver=2].  Up 31.4285519123 seconds
2016-09-20 20:41:22,432 - util.py[DEBUG]: Cloud-init v. 0.7.8 finished at Tue, 20 Sep 2016 20:41:22 +0000. Datasource DataSourceOpenStack [net,ver=2].  Up 31.4285519123 seconds
2016-09-20 20:41:22,433 - util.py[DEBUG]: Writing to /var/lib/cloud/instance/boot-finished - wb: [420] 59 bytes
2016-09-20 20:41:22,434 - handlers.py[DEBUG]: finish: modules-final/config-final-message: SUCCESS: config-final-message ran successfully
2016-09-20 20:41:22,435 - stages.py[DEBUG]: Running module power-state-change (<module 'cloudinit.config.cc_power_state_change' from '/usr/local/lib/python2.7/site-packages/cloudinit/config/cc_power_state_change.pyc'>) with frequency once-per-instance
2016-09-20 20:41:22,436 - handlers.py[DEBUG]: start: modules-final/config-power-state-change: running config-power-state-change with frequency once-per-instance
2016-09-20 20:41:22,437 - util.py[DEBUG]: Writing to /var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_power_state_change - wb: [420] 19 bytes
2016-09-20 20:41:22,438 - helpers.py[DEBUG]: Running config-power-state-change using lock (<FileLock using file '/var/lib/cloud/instances/aba344ee-5536-4f23-a035-721f4060807b/sem/config_power_state_change'>)
2016-09-20 20:41:22,439 - cc_power_state_change.py[DEBUG]: no power_state provided. doing nothing
2016-09-20 20:41:22,440 - handlers.py[DEBUG]: finish: modules-final/config-power-state-change: SUCCESS: config-power-state-change ran successfully
2016-09-20 20:41:22,441 - main.py[DEBUG]: Ran 11 modules with 1 failures
2016-09-20 20:41:22,442 - util.py[DEBUG]: Creating symbolic link from '/run/cloud-init/result.json' => '../../var/lib/cloud/data/result.json'
2016-09-20 20:41:22,443 - util.py[DEBUG]: cloud-init mode 'modules' took 0.162 seconds (0.16)
2016-09-20 20:41:22,443 - handlers.py[DEBUG]: finish: modules-final: FAIL: running modules for final
Configuring syscons: blanktime.
Starting background file system checks in 60 seconds.