Attachment 1459370 Details for Bug 1601793 – /var/log/cloud-init.log

/var/log/cloud-init.log

sudo_cat__var_log_cloud_init_log_.log (text/plain), 35.87 KB, created by Chen Shi on 2018-07-17 09:13:45 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Chen Shi

Created: 2018-07-17 09:13:45 UTC

Size: 35.87 KB

patch

obsolete

>2018-07-16 14:19:19,967 - util.py[DEBUG]: Cloud-init v. 0.7.9 running 'init-local' at Mon, 16 Jul 2018 06:19:19 +0000. Up 38.71 seconds.
>2018-07-16 14:19:19,968 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud (recursive=False)
>2018-07-16 14:19:19,969 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts (recursive=False)
>2018-07-16 14:19:19,971 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-instance (recursive=False)
>2018-07-16 14:19:19,972 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-once (recursive=False)
>2018-07-16 14:19:19,973 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-boot (recursive=False)
>2018-07-16 14:19:19,973 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/vendor (recursive=False)
>2018-07-16 14:19:19,974 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/seed (recursive=False)
>2018-07-16 14:19:19,974 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances (recursive=False)
>2018-07-16 14:19:19,974 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/handlers (recursive=False)
>2018-07-16 14:19:19,975 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/sem (recursive=False)
>2018-07-16 14:19:19,975 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data (recursive=False)
>2018-07-16 14:19:19,976 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [384] 0 bytes
>2018-07-16 14:19:19,976 - util.py[DEBUG]: Restoring selinux mode for /var/log/cloud-init.log (recursive=False)
>2018-07-16 14:19:19,976 - util.py[DEBUG]: Restoring selinux mode for /var/log/cloud-init.log (recursive=False)
>2018-07-16 14:19:19,977 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:0
>2018-07-16 14:19:19,977 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance/boot-finished
>2018-07-16 14:19:19,977 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/data/no-net
>2018-07-16 14:19:19,977 - handlers.py[DEBUG]: start: init-local/check-cache: attempting to read from cache [check]
>2018-07-16 14:19:19,977 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
>2018-07-16 14:19:19,977 - stages.py[DEBUG]: no cache found
>2018-07-16 14:19:19,977 - handlers.py[DEBUG]: finish: init-local/check-cache: SUCCESS: no cache found
>2018-07-16 14:19:19,978 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
>2018-07-16 14:19:19,996 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.rhel.Distro'>
>2018-07-16 14:19:19,997 - __init__.py[DEBUG]: Looking for for data source in: ['AliYun'], via packages ['', u'cloudinit.sources'] that matches dependencies ['FILESYSTEM']
>2018-07-16 14:19:20,000 - __init__.py[DEBUG]: Searching for local data source in: []
>2018-07-16 14:19:20,000 - main.py[DEBUG]: No local datasource found
>2018-07-16 14:19:20,001 - util.py[DEBUG]: Reading from /sys/class/net/eth0/carrier (quiet=False)
>2018-07-16 14:19:20,001 - util.py[DEBUG]: Reading from /sys/class/net/eth0/dormant (quiet=False)
>2018-07-16 14:19:20,001 - util.py[DEBUG]: Reading from /sys/class/net/eth0/operstate (quiet=False)
>2018-07-16 14:19:20,001 - util.py[DEBUG]: Read 5 bytes from /sys/class/net/eth0/operstate
>2018-07-16 14:19:20,001 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)
>2018-07-16 14:19:20,001 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address
>2018-07-16 14:19:20,001 - stages.py[DEBUG]: applying net config names for {'version': 1, 'config': [{'subnets': [{'type': 'dhcp'}], 'type': 'physical', 'name': 'eth0', 'mac_address': '00:16:3e:08:96:b3'}]}
>2018-07-16 14:19:20,001 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False)
>2018-07-16 14:19:20,002 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type
>2018-07-16 14:19:20,002 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)
>2018-07-16 14:19:20,002 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address
>2018-07-16 14:19:20,002 - util.py[DEBUG]: Reading from /sys/class/net/eth0/addr_assign_type (quiet=False)
>2018-07-16 14:19:20,002 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/addr_assign_type
>2018-07-16 14:19:20,002 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)
>2018-07-16 14:19:20,002 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address
>2018-07-16 14:19:20,002 - util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False)
>2018-07-16 14:19:20,002 - util.py[DEBUG]: Read 8 bytes from /sys/class/net/lo/operstate
>2018-07-16 14:19:20,002 - util.py[DEBUG]: Reading from /sys/class/net/eth0/operstate (quiet=False)
>2018-07-16 14:19:20,002 - util.py[DEBUG]: Read 5 bytes from /sys/class/net/eth0/operstate
>2018-07-16 14:19:20,003 - util.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True)
>2018-07-16 14:19:20,021 - util.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True)
>2018-07-16 14:19:20,030 - __init__.py[DEBUG]: no work necessary for renaming of [['00:16:3e:08:96:b3', 'eth0']]
>2018-07-16 14:19:20,031 - stages.py[INFO]: Applying network configuration from fallback bringup=False: {'version': 1, 'config': [{'subnets': [{'type': 'dhcp'}], 'type': 'physical', 'name': 'eth0', 'mac_address': '00:16:3e:08:96:b3'}]}
>2018-07-16 14:19:20,036 - util.py[DEBUG]: Writing to /etc/sysconfig/network-scripts/ifcfg-eth0 - wb: [420] 159 bytes
>2018-07-16 14:19:20,038 - util.py[DEBUG]: Restoring selinux mode for /etc/sysconfig/network-scripts/ifcfg-eth0 (recursive=False)
>2018-07-16 14:19:20,039 - util.py[DEBUG]: Restoring selinux mode for /etc/sysconfig/network-scripts/ifcfg-eth0 (recursive=False)
>2018-07-16 14:19:20,039 - util.py[DEBUG]: Reading from /etc/resolv.conf (quiet=False)
>2018-07-16 14:19:20,040 - util.py[DEBUG]: Read 137 bytes from /etc/resolv.conf
>2018-07-16 14:19:20,040 - util.py[DEBUG]: Writing to /etc/resolv.conf - wb: [420] 208 bytes
>2018-07-16 14:19:20,042 - util.py[DEBUG]: Restoring selinux mode for /etc/resolv.conf (recursive=False)
>2018-07-16 14:19:20,043 - util.py[DEBUG]: Restoring selinux mode for /etc/resolv.conf (recursive=False)
>2018-07-16 14:19:20,044 - util.py[DEBUG]: Writing to /etc/udev/rules.d/70-persistent-net.rules - wb: [420] 96 bytes
>2018-07-16 14:19:20,046 - util.py[DEBUG]: Restoring selinux mode for /etc/udev/rules.d/70-persistent-net.rules (recursive=False)
>2018-07-16 14:19:20,047 - util.py[DEBUG]: Restoring selinux mode for /etc/udev/rules.d/70-persistent-net.rules (recursive=False)
>2018-07-16 14:19:20,047 - main.py[DEBUG]: [local] Exiting without datasource in local mode
>2018-07-16 14:19:20,048 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
>2018-07-16 14:19:20,048 - util.py[DEBUG]: Read 14 bytes from /proc/uptime
>2018-07-16 14:19:20,048 - util.py[DEBUG]: cloud-init mode 'init' took 0.158 seconds (0.15)
>2018-07-16 14:19:20,048 - handlers.py[DEBUG]: finish: init-local: SUCCESS: searching for local datasources
>2018-07-16 14:19:26,389 - util.py[DEBUG]: Cloud-init v. 0.7.9 running 'init' at Mon, 16 Jul 2018 06:19:26 +0000. Up 45.17 seconds.
>2018-07-16 14:19:26,390 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud (recursive=False)
>2018-07-16 14:19:26,390 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts (recursive=False)
>2018-07-16 14:19:26,391 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-instance (recursive=False)
>2018-07-16 14:19:26,391 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-once (recursive=False)
>2018-07-16 14:19:26,391 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-boot (recursive=False)
>2018-07-16 14:19:26,392 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/vendor (recursive=False)
>2018-07-16 14:19:26,392 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/seed (recursive=False)
>2018-07-16 14:19:26,393 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances (recursive=False)
>2018-07-16 14:19:26,393 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/handlers (recursive=False)
>2018-07-16 14:19:26,393 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/sem (recursive=False)
>2018-07-16 14:19:26,394 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data (recursive=False)
>2018-07-16 14:19:26,394 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [384] 0 bytes
>2018-07-16 14:19:26,395 - util.py[DEBUG]: Restoring selinux mode for /var/log/cloud-init.log (recursive=False)
>2018-07-16 14:19:26,395 - util.py[DEBUG]: Restoring selinux mode for /var/log/cloud-init.log (recursive=False)
>2018-07-16 14:19:26,395 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:0
>2018-07-16 14:19:26,395 - util.py[DEBUG]: Running command ['ifconfig', '-a'] with allowed return codes [0] (shell=False, capture=True)
>2018-07-16 14:19:26,415 - util.py[DEBUG]: Running command ['netstat', '-rn'] with allowed return codes [0] (shell=False, capture=True)
>2018-07-16 14:19:26,423 - util.py[DEBUG]: Running command ['netstat', '-A', 'inet6', '-n'] with allowed return codes [0] (shell=False, capture=True)
>2018-07-16 14:19:26,431 - main.py[DEBUG]: Checking to see if files that we need already exist from a previous run that would allow us to stop early.
>2018-07-16 14:19:26,431 - main.py[DEBUG]: Execution continuing, no previous run detected that would allow us to stop early.
>2018-07-16 14:19:26,432 - handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust]
>2018-07-16 14:19:26,432 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
>2018-07-16 14:19:26,432 - stages.py[DEBUG]: no cache found
>2018-07-16 14:19:26,432 - handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: no cache found
>2018-07-16 14:19:26,432 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
>2018-07-16 14:19:26,445 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.rhel.Distro'>
>2018-07-16 14:19:26,445 - __init__.py[DEBUG]: Looking for for data source in: ['AliYun'], via packages ['', u'cloudinit.sources'] that matches dependencies ['FILESYSTEM', 'NETWORK']
>2018-07-16 14:19:26,446 - __init__.py[DEBUG]: Searching for network data source in: [u'DataSourceAliYun']
>2018-07-16 14:19:26,446 - handlers.py[DEBUG]: start: init-network/search-AliYun: searching for network data from DataSourceAliYun
>2018-07-16 14:19:26,446 - __init__.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceAliYun.DataSourceAliYun'>
>2018-07-16 14:19:26,446 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/AliYun/meta-data (quiet=False)
>2018-07-16 14:19:26,447 - util.py[DEBUG]: querying dmi data /sys/class/dmi/id/product_name
>2018-07-16 14:19:26,447 - util.py[DEBUG]: Reading from /sys/class/dmi/id/product_name (quiet=False)
>2018-07-16 14:19:26,448 - util.py[DEBUG]: Read 20 bytes from /sys/class/dmi/id/product_name
>2018-07-16 14:19:26,448 - util.py[DEBUG]: dmi data /sys/class/dmi/id/product_name returned AliServer Thor02-2U
>2018-07-16 14:19:26,448 - DataSourceEc2.py[DEBUG]: strict_mode: warn, cloud_platform=No-EC2-Metadata
>2018-07-16 14:19:26,448 - handlers.py[DEBUG]: finish: init-network/search-AliYun: SUCCESS: no network data found from DataSourceAliYun
>2018-07-16 14:19:26,448 - util.py[WARNING]: No instance datasource found! Likely bad things to come!
>2018-07-16 14:19:26,448 - util.py[DEBUG]: No instance datasource found! Likely bad things to come!
>Traceback (most recent call last):
>  File "/usr/lib/python2.7/site-packages/cloudinit/cmd/main.py", line 236, in main_init
>    init.fetch(existing=existing)
>  File "/usr/lib/python2.7/site-packages/cloudinit/stages.py", line 343, in fetch
>    return self._get_data_source(existing=existing)
>  File "/usr/lib/python2.7/site-packages/cloudinit/stages.py", line 253, in _get_data_source
>    pkg_list, self.reporter)
>  File "/usr/lib/python2.7/site-packages/cloudinit/sources/__init__.py", line 320, in find_source
>    raise DataSourceNotFoundException(msg)
>DataSourceNotFoundException: Did not find any data source, searched classes: (DataSourceAliYun)
>2018-07-16 14:19:26,465 - util.py[DEBUG]: Reading from /sys/class/net/eth0/carrier (quiet=False)
>2018-07-16 14:19:26,465 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/carrier
>2018-07-16 14:19:26,465 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)
>2018-07-16 14:19:26,465 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address
>2018-07-16 14:19:26,465 - stages.py[DEBUG]: applying net config names for {'version': 1, 'config': [{'subnets': [{'type': 'dhcp'}], 'type': 'physical', 'name': 'eth0', 'mac_address': '00:16:3e:08:96:b3'}]}
>2018-07-16 14:19:26,465 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False)
>2018-07-16 14:19:26,465 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type
>2018-07-16 14:19:26,465 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)
>2018-07-16 14:19:26,466 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address
>2018-07-16 14:19:26,466 - util.py[DEBUG]: Reading from /sys/class/net/eth0/addr_assign_type (quiet=False)
>2018-07-16 14:19:26,466 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/addr_assign_type
>2018-07-16 14:19:26,466 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)
>2018-07-16 14:19:26,466 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address
>2018-07-16 14:19:26,466 - util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False)
>2018-07-16 14:19:26,466 - util.py[DEBUG]: Read 8 bytes from /sys/class/net/lo/operstate
>2018-07-16 14:19:26,466 - util.py[DEBUG]: Reading from /sys/class/net/eth0/operstate (quiet=False)
>2018-07-16 14:19:26,466 - util.py[DEBUG]: Read 3 bytes from /sys/class/net/eth0/operstate
>2018-07-16 14:19:26,466 - util.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True)
>2018-07-16 14:19:26,474 - util.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True)
>2018-07-16 14:19:26,478 - __init__.py[DEBUG]: no work necessary for renaming of [['00:16:3e:08:96:b3', 'eth0']]
>2018-07-16 14:19:26,478 - stages.py[INFO]: Applying network configuration from fallback bringup=True: {'version': 1, 'config': [{'subnets': [{'type': 'dhcp'}], 'type': 'physical', 'name': 'eth0', 'mac_address': '00:16:3e:08:96:b3'}]}
>2018-07-16 14:19:26,482 - util.py[DEBUG]: Writing to /etc/sysconfig/network-scripts/ifcfg-eth0 - wb: [420] 159 bytes
>2018-07-16 14:19:26,482 - util.py[DEBUG]: Restoring selinux mode for /etc/sysconfig/network-scripts/ifcfg-eth0 (recursive=False)
>2018-07-16 14:19:26,483 - util.py[DEBUG]: Restoring selinux mode for /etc/sysconfig/network-scripts/ifcfg-eth0 (recursive=False)
>2018-07-16 14:19:26,483 - util.py[DEBUG]: Reading from /etc/resolv.conf (quiet=False)
>2018-07-16 14:19:26,483 - util.py[DEBUG]: Read 91 bytes from /etc/resolv.conf
>2018-07-16 14:19:26,484 - util.py[DEBUG]: Writing to /etc/resolv.conf - wb: [420] 162 bytes
>2018-07-16 14:19:26,484 - util.py[DEBUG]: Restoring selinux mode for /etc/resolv.conf (recursive=False)
>2018-07-16 14:19:26,484 - util.py[DEBUG]: Restoring selinux mode for /etc/resolv.conf (recursive=False)
>2018-07-16 14:19:26,485 - util.py[DEBUG]: Writing to /etc/udev/rules.d/70-persistent-net.rules - wb: [420] 96 bytes
>2018-07-16 14:19:26,485 - util.py[DEBUG]: Restoring selinux mode for /etc/udev/rules.d/70-persistent-net.rules (recursive=False)
>2018-07-16 14:19:26,486 - util.py[DEBUG]: Restoring selinux mode for /etc/udev/rules.d/70-persistent-net.rules (recursive=False)
>2018-07-16 14:19:26,486 - main.py[DEBUG]: [net] Exiting without datasource in local mode
>2018-07-16 14:19:26,487 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
>2018-07-16 14:19:26,487 - util.py[DEBUG]: Read 14 bytes from /proc/uptime
>2018-07-16 14:19:26,487 - util.py[DEBUG]: cloud-init mode 'init' took 0.143 seconds (0.14)
>2018-07-16 14:19:26,487 - handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources
>2018-07-16 06:27:52,924 - util.py[DEBUG]: Cloud-init v. 18.2 running 'init-local' at Mon, 16 Jul 2018 06:27:52 +0000. Up 37.69 seconds.
>2018-07-16 06:27:52,925 - main.py[DEBUG]: No kernel command line url found.
>2018-07-16 06:27:52,925 - main.py[DEBUG]: Closing stdin.
>2018-07-16 06:27:52,925 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud (recursive=False)
>2018-07-16 06:27:52,926 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts (recursive=False)
>2018-07-16 06:27:52,927 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-instance (recursive=False)
>2018-07-16 06:27:52,928 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-once (recursive=False)
>2018-07-16 06:27:52,928 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-boot (recursive=False)
>2018-07-16 06:27:52,929 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/vendor (recursive=False)
>2018-07-16 06:27:52,929 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/seed (recursive=False)
>2018-07-16 06:27:52,930 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances (recursive=False)
>2018-07-16 06:27:52,930 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/handlers (recursive=False)
>2018-07-16 06:27:52,930 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/sem (recursive=False)
>2018-07-16 06:27:52,931 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data (recursive=False)
>2018-07-16 06:27:52,931 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [600] 0 bytes
>2018-07-16 06:27:52,932 - util.py[DEBUG]: Restoring selinux mode for /var/log/cloud-init.log (recursive=False)
>2018-07-16 06:27:52,932 - util.py[DEBUG]: Restoring selinux mode for /var/log/cloud-init.log (recursive=False)
>2018-07-16 06:27:52,933 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:0
>2018-07-16 06:27:52,933 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance/boot-finished
>2018-07-16 06:27:52,933 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/data/no-net
>2018-07-16 06:27:52,933 - handlers.py[DEBUG]: start: init-local/check-cache: attempting to read from cache [check]
>2018-07-16 06:27:52,933 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
>2018-07-16 06:27:52,933 - stages.py[DEBUG]: no cache found
>2018-07-16 06:27:52,933 - handlers.py[DEBUG]: finish: init-local/check-cache: SUCCESS: no cache found
>2018-07-16 06:27:52,933 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
>2018-07-16 06:27:52,939 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.rhel.Distro'>
>2018-07-16 06:27:52,939 - __init__.py[DEBUG]: Looking for data source in: ['AliYun'], via packages ['', u'cloudinit.sources'] that matches dependencies ['FILESYSTEM']
>2018-07-16 06:27:52,944 - __init__.py[DEBUG]: Searching for local data source in: []
>2018-07-16 06:27:52,944 - main.py[DEBUG]: No local datasource found
>2018-07-16 06:27:52,945 - util.py[DEBUG]: Reading from /sys/class/net/eth0/carrier (quiet=False)
>2018-07-16 06:27:52,945 - util.py[DEBUG]: Reading from /sys/class/net/eth0/dormant (quiet=False)
>2018-07-16 06:27:52,946 - util.py[DEBUG]: Reading from /sys/class/net/eth0/operstate (quiet=False)
>2018-07-16 06:27:52,946 - util.py[DEBUG]: Read 5 bytes from /sys/class/net/eth0/operstate
>2018-07-16 06:27:52,946 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)
>2018-07-16 06:27:52,946 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address
>2018-07-16 06:27:52,946 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)
>2018-07-16 06:27:52,946 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address
>2018-07-16 06:27:52,946 - stages.py[DEBUG]: applying net config names for {'version': 1, 'config': [{'subnets': [{'type': 'dhcp'}], 'type': 'physical', 'name': 'eth0', 'mac_address': '00:16:3e:08:96:b3'}]}
>2018-07-16 06:27:52,946 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False)
>2018-07-16 06:27:52,946 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/eth0/device/device
>2018-07-16 06:27:52,946 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False)
>2018-07-16 06:27:52,946 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type
>2018-07-16 06:27:52,946 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False)
>2018-07-16 06:27:52,946 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent
>2018-07-16 06:27:52,947 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)
>2018-07-16 06:27:52,947 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address
>2018-07-16 06:27:52,947 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False)
>2018-07-16 06:27:52,947 - util.py[DEBUG]: Reading from /sys/class/net/eth0/addr_assign_type (quiet=False)
>2018-07-16 06:27:52,947 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/addr_assign_type
>2018-07-16 06:27:52,947 - util.py[DEBUG]: Reading from /sys/class/net/eth0/uevent (quiet=False)
>2018-07-16 06:27:52,947 - util.py[DEBUG]: Read 25 bytes from /sys/class/net/eth0/uevent
>2018-07-16 06:27:52,947 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)
>2018-07-16 06:27:52,947 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address
>2018-07-16 06:27:52,947 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False)
>2018-07-16 06:27:52,947 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/eth0/device/device
>2018-07-16 06:27:52,947 - util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False)
>2018-07-16 06:27:52,947 - util.py[DEBUG]: Read 8 bytes from /sys/class/net/lo/operstate
>2018-07-16 06:27:52,947 - util.py[DEBUG]: Reading from /sys/class/net/eth0/operstate (quiet=False)
>2018-07-16 06:27:52,947 - util.py[DEBUG]: Read 5 bytes from /sys/class/net/eth0/operstate
>2018-07-16 06:27:52,948 - util.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True)
>2018-07-16 06:27:53,012 - util.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True)
>2018-07-16 06:27:53,028 - __init__.py[DEBUG]: no work necessary for renaming of [['00:16:3e:08:96:b3', 'eth0', 'virtio_net', '0x0001']]
>2018-07-16 06:27:53,029 - stages.py[INFO]: Applying network configuration from fallback bringup=False: {'version': 1, 'config': [{'subnets': [{'type': 'dhcp'}], 'type': 'physical', 'name': 'eth0', 'mac_address': '00:16:3e:08:96:b3'}]}
>2018-07-16 06:27:53,030 - __init__.py[DEBUG]: Selected renderer 'sysconfig' from priority list: None
>2018-07-16 06:27:53,037 - util.py[DEBUG]: Writing to /etc/sysconfig/network-scripts/ifcfg-eth0 - wb: [644] 159 bytes
>2018-07-16 06:27:53,039 - util.py[DEBUG]: Restoring selinux mode for /etc/sysconfig/network-scripts/ifcfg-eth0 (recursive=False)
>2018-07-16 06:27:53,040 - util.py[DEBUG]: Restoring selinux mode for /etc/sysconfig/network-scripts/ifcfg-eth0 (recursive=False)
>2018-07-16 06:27:53,040 - util.py[DEBUG]: Reading from /etc/resolv.conf (quiet=False)
>2018-07-16 06:27:53,040 - util.py[DEBUG]: Read 162 bytes from /etc/resolv.conf
>2018-07-16 06:27:53,040 - util.py[DEBUG]: Writing to /etc/resolv.conf - wb: [644] 162 bytes
>2018-07-16 06:27:53,040 - util.py[DEBUG]: Restoring selinux mode for /etc/resolv.conf (recursive=False)
>2018-07-16 06:27:53,041 - util.py[DEBUG]: Restoring selinux mode for /etc/resolv.conf (recursive=False)
>2018-07-16 06:27:53,041 - util.py[DEBUG]: Writing to /etc/udev/rules.d/70-persistent-net.rules - wb: [644] 96 bytes
>2018-07-16 06:27:53,042 - util.py[DEBUG]: Restoring selinux mode for /etc/udev/rules.d/70-persistent-net.rules (recursive=False)
>2018-07-16 06:27:53,042 - util.py[DEBUG]: Restoring selinux mode for /etc/udev/rules.d/70-persistent-net.rules (recursive=False)
>2018-07-16 06:27:53,042 - util.py[DEBUG]: Writing to /etc/sysconfig/network - wb: [644] 86 bytes
>2018-07-16 06:27:53,043 - util.py[DEBUG]: Restoring selinux mode for /etc/sysconfig/network (recursive=False)
>2018-07-16 06:27:53,043 - util.py[DEBUG]: Restoring selinux mode for /etc/sysconfig/network (recursive=False)
>2018-07-16 06:27:53,044 - main.py[DEBUG]: [local] Exiting without datasource in local mode
>2018-07-16 06:27:53,044 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
>2018-07-16 06:27:53,044 - util.py[DEBUG]: Read 14 bytes from /proc/uptime
>2018-07-16 06:27:53,044 - util.py[DEBUG]: cloud-init mode 'init' took 0.226 seconds (0.23)
>2018-07-16 06:27:53,045 - handlers.py[DEBUG]: finish: init-local: SUCCESS: searching for local datasources
>2018-07-16 06:27:56,479 - util.py[DEBUG]: Cloud-init v. 18.2 running 'init' at Mon, 16 Jul 2018 06:27:56 +0000. Up 41.26 seconds.
>2018-07-16 06:27:56,479 - main.py[DEBUG]: No kernel command line url found.
>2018-07-16 06:27:56,479 - main.py[DEBUG]: Closing stdin.
>2018-07-16 06:27:56,480 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud (recursive=False)
>2018-07-16 06:27:56,480 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts (recursive=False)
>2018-07-16 06:27:56,481 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-instance (recursive=False)
>2018-07-16 06:27:56,481 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-once (recursive=False)
>2018-07-16 06:27:56,481 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/per-boot (recursive=False)
>2018-07-16 06:27:56,482 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/scripts/vendor (recursive=False)
>2018-07-16 06:27:56,482 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/seed (recursive=False)
>2018-07-16 06:27:56,483 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/instances (recursive=False)
>2018-07-16 06:27:56,483 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/handlers (recursive=False)
>2018-07-16 06:27:56,483 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/sem (recursive=False)
>2018-07-16 06:27:56,484 - util.py[DEBUG]: Restoring selinux mode for /var/lib/cloud/data (recursive=False)
>2018-07-16 06:27:56,484 - util.py[DEBUG]: Writing to /var/log/cloud-init.log - ab: [600] 0 bytes
>2018-07-16 06:27:56,484 - util.py[DEBUG]: Restoring selinux mode for /var/log/cloud-init.log (recursive=False)
>2018-07-16 06:27:56,485 - util.py[DEBUG]: Restoring selinux mode for /var/log/cloud-init.log (recursive=False)
>2018-07-16 06:27:56,485 - util.py[DEBUG]: Changing the ownership of /var/log/cloud-init.log to 0:0
>2018-07-16 06:27:56,485 - util.py[DEBUG]: Running command ['ifconfig', '-a'] with allowed return codes [0, 1] (shell=False, capture=True)
>2018-07-16 06:27:56,494 - util.py[DEBUG]: Running command ['netstat', '-rn'] with allowed return codes [0, 1] (shell=False, capture=True)
>2018-07-16 06:27:56,503 - util.py[DEBUG]: Running command ['netstat', '-A', 'inet6', '-n'] with allowed return codes [0, 1] (shell=False, capture=True)
>2018-07-16 06:27:56,510 - main.py[DEBUG]: Checking to see if files that we need already exist from a previous run that would allow us to stop early.
>2018-07-16 06:27:56,511 - main.py[DEBUG]: Execution continuing, no previous run detected that would allow us to stop early.
>2018-07-16 06:27:56,511 - handlers.py[DEBUG]: start: init-network/check-cache: attempting to read from cache [trust]
>2018-07-16 06:27:56,512 - util.py[DEBUG]: Reading from /var/lib/cloud/instance/obj.pkl (quiet=False)
>2018-07-16 06:27:56,512 - stages.py[DEBUG]: no cache found
>2018-07-16 06:27:56,512 - handlers.py[DEBUG]: finish: init-network/check-cache: SUCCESS: no cache found
>2018-07-16 06:27:56,512 - util.py[DEBUG]: Attempting to remove /var/lib/cloud/instance
>2018-07-16 06:27:56,517 - stages.py[DEBUG]: Using distro class <class 'cloudinit.distros.rhel.Distro'>
>2018-07-16 06:27:56,517 - __init__.py[DEBUG]: Looking for data source in: ['AliYun'], via packages ['', u'cloudinit.sources'] that matches dependencies ['FILESYSTEM', 'NETWORK']
>2018-07-16 06:27:56,519 - __init__.py[DEBUG]: Searching for network data source in: [u'DataSourceAliYun']
>2018-07-16 06:27:56,519 - handlers.py[DEBUG]: start: init-network/search-AliYun: searching for network data from DataSourceAliYun
>2018-07-16 06:27:56,519 - __init__.py[DEBUG]: Seeing if we can get any data from <class 'cloudinit.sources.DataSourceAliYun.DataSourceAliYun'>
>2018-07-16 06:27:56,519 - util.py[DEBUG]: Reading from /var/lib/cloud/seed/AliYun/meta-data (quiet=False)
>2018-07-16 06:27:56,532 - util.py[DEBUG]: Running command ['systemd-detect-virt', '--quiet', '--container'] with allowed return codes [0] (shell=False, capture=True)
>2018-07-16 06:27:56,539 - util.py[DEBUG]: Running command ['running-in-container'] with allowed return codes [0] (shell=False, capture=True)
>2018-07-16 06:27:56,544 - util.py[DEBUG]: Running command ['lxc-is-container'] with allowed return codes [0] (shell=False, capture=True)
>2018-07-16 06:27:56,551 - util.py[DEBUG]: Reading from /proc/1/environ (quiet=False)
>2018-07-16 06:27:56,552 - util.py[DEBUG]: Read 0 bytes from /proc/1/environ
>2018-07-16 06:27:56,552 - util.py[DEBUG]: Reading from /proc/self/status (quiet=False)
>2018-07-16 06:27:56,552 - util.py[DEBUG]: Read 1214 bytes from /proc/self/status
>2018-07-16 06:27:56,553 - util.py[DEBUG]: querying dmi data /sys/class/dmi/id/product_name
>2018-07-16 06:27:56,553 - util.py[DEBUG]: Reading from /sys/class/dmi/id/product_name (quiet=False)
>2018-07-16 06:27:56,553 - util.py[DEBUG]: Read 20 bytes from /sys/class/dmi/id/product_name
>2018-07-16 06:27:56,554 - util.py[DEBUG]: dmi data /sys/class/dmi/id/product_name returned AliServer Thor02-2U
>2018-07-16 06:27:56,554 - DataSourceEc2.py[DEBUG]: strict_mode: warn, cloud_platform=No-EC2-Metadata
>2018-07-16 06:27:56,554 - handlers.py[DEBUG]: finish: init-network/search-AliYun: SUCCESS: no network data found from DataSourceAliYun
>2018-07-16 06:27:56,554 - util.py[WARNING]: No instance datasource found! Likely bad things to come!
>2018-07-16 06:27:56,554 - util.py[DEBUG]: No instance datasource found! Likely bad things to come!
>Traceback (most recent call last):
>  File "/usr/lib/python2.7/site-packages/cloudinit/cmd/main.py", line 323, in main_init
>    init.fetch(existing=existing)
>  File "/usr/lib/python2.7/site-packages/cloudinit/stages.py", line 349, in fetch
>    return self._get_data_source(existing=existing)
>  File "/usr/lib/python2.7/site-packages/cloudinit/stages.py", line 259, in _get_data_source
>    pkg_list, self.reporter)
>  File "/usr/lib/python2.7/site-packages/cloudinit/sources/__init__.py", line 455, in find_source
>    raise DataSourceNotFoundException(msg)
>DataSourceNotFoundException: Did not find any data source, searched classes: (DataSourceAliYun)
>2018-07-16 06:27:56,605 - util.py[DEBUG]: Reading from /sys/class/net/eth0/carrier (quiet=False)
>2018-07-16 06:27:56,605 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/carrier
>2018-07-16 06:27:56,605 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)
>2018-07-16 06:27:56,605 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address
>2018-07-16 06:27:56,606 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)
>2018-07-16 06:27:56,606 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address
>2018-07-16 06:27:56,606 - stages.py[DEBUG]: applying net config names for {'version': 1, 'config': [{'subnets': [{'type': 'dhcp'}], 'type': 'physical', 'name': 'eth0', 'mac_address': '00:16:3e:08:96:b3'}]}
>2018-07-16 06:27:56,606 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False)
>2018-07-16 06:27:56,606 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/eth0/device/device
>2018-07-16 06:27:56,606 - util.py[DEBUG]: Reading from /sys/class/net/lo/addr_assign_type (quiet=False)
>2018-07-16 06:27:56,606 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/lo/addr_assign_type
>2018-07-16 06:27:56,606 - util.py[DEBUG]: Reading from /sys/class/net/lo/uevent (quiet=False)
>2018-07-16 06:27:56,606 - util.py[DEBUG]: Read 23 bytes from /sys/class/net/lo/uevent
>2018-07-16 06:27:56,606 - util.py[DEBUG]: Reading from /sys/class/net/lo/address (quiet=False)
>2018-07-16 06:27:56,606 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/lo/address
>2018-07-16 06:27:56,606 - util.py[DEBUG]: Reading from /sys/class/net/lo/device/device (quiet=False)
>2018-07-16 06:27:56,606 - util.py[DEBUG]: Reading from /sys/class/net/eth0/addr_assign_type (quiet=False)
>2018-07-16 06:27:56,606 - util.py[DEBUG]: Read 2 bytes from /sys/class/net/eth0/addr_assign_type
>2018-07-16 06:27:56,607 - util.py[DEBUG]: Reading from /sys/class/net/eth0/uevent (quiet=False)
>2018-07-16 06:27:56,607 - util.py[DEBUG]: Read 25 bytes from /sys/class/net/eth0/uevent
>2018-07-16 06:27:56,607 - util.py[DEBUG]: Reading from /sys/class/net/eth0/address (quiet=False)
>2018-07-16 06:27:56,607 - util.py[DEBUG]: Read 18 bytes from /sys/class/net/eth0/address
>2018-07-16 06:27:56,607 - util.py[DEBUG]: Reading from /sys/class/net/eth0/device/device (quiet=False)
>2018-07-16 06:27:56,607 - util.py[DEBUG]: Read 7 bytes from /sys/class/net/eth0/device/device
>2018-07-16 06:27:56,607 - util.py[DEBUG]: Reading from /sys/class/net/lo/operstate (quiet=False)
>2018-07-16 06:27:56,607 - util.py[DEBUG]: Read 8 bytes from /sys/class/net/lo/operstate
>2018-07-16 06:27:56,607 - util.py[DEBUG]: Reading from /sys/class/net/eth0/operstate (quiet=False)
>2018-07-16 06:27:56,607 - util.py[DEBUG]: Read 3 bytes from /sys/class/net/eth0/operstate
>2018-07-16 06:27:56,607 - util.py[DEBUG]: Running command ['ip', '-6', 'addr', 'show', 'permanent', 'scope', 'global'] with allowed return codes [0] (shell=False, capture=True)
>2018-07-16 06:27:56,613 - util.py[DEBUG]: Running command ['ip', '-4', 'addr', 'show'] with allowed return codes [0] (shell=False, capture=True)
>2018-07-16 06:27:56,617 - __init__.py[DEBUG]: no work necessary for renaming of [['00:16:3e:08:96:b3', 'eth0', 'virtio_net', '0x0001']]
>2018-07-16 06:27:56,618 - stages.py[INFO]: Applying network configuration from fallback bringup=True: {'version': 1, 'config': [{'subnets': [{'type': 'dhcp'}], 'type': 'physical', 'name': 'eth0', 'mac_address': '00:16:3e:08:96:b3'}]}
>2018-07-16 06:27:56,618 - __init__.py[DEBUG]: Selected renderer 'sysconfig' from priority list: None
>2018-07-16 06:27:56,623 - util.py[DEBUG]: Writing to /etc/sysconfig/network-scripts/ifcfg-eth0 - wb: [644] 159 bytes
>2018-07-16 06:27:56,624 - util.py[DEBUG]: Restoring selinux mode for /etc/sysconfig/network-scripts/ifcfg-eth0 (recursive=False)
>2018-07-16 06:27:56,624 - util.py[DEBUG]: Restoring selinux mode for /etc/sysconfig/network-scripts/ifcfg-eth0 (recursive=False)
>2018-07-16 06:27:56,624 - util.py[DEBUG]: Reading from /etc/resolv.conf (quiet=False)
>2018-07-16 06:27:56,625 - util.py[DEBUG]: Read 91 bytes from /etc/resolv.conf
>2018-07-16 06:27:56,625 - util.py[DEBUG]: Writing to /etc/resolv.conf - wb: [644] 162 bytes
>2018-07-16 06:27:56,625 - util.py[DEBUG]: Restoring selinux mode for /etc/resolv.conf (recursive=False)
>2018-07-16 06:27:56,626 - util.py[DEBUG]: Restoring selinux mode for /etc/resolv.conf (recursive=False)
>2018-07-16 06:27:56,626 - util.py[DEBUG]: Writing to /etc/udev/rules.d/70-persistent-net.rules - wb: [644] 96 bytes
>2018-07-16 06:27:56,627 - util.py[DEBUG]: Restoring selinux mode for /etc/udev/rules.d/70-persistent-net.rules (recursive=False)
>2018-07-16 06:27:56,627 - util.py[DEBUG]: Restoring selinux mode for /etc/udev/rules.d/70-persistent-net.rules (recursive=False)
>2018-07-16 06:27:56,627 - util.py[DEBUG]: Writing to /etc/sysconfig/network - wb: [644] 86 bytes
>2018-07-16 06:27:56,628 - util.py[DEBUG]: Restoring selinux mode for /etc/sysconfig/network (recursive=False)
>2018-07-16 06:27:56,628 - util.py[DEBUG]: Restoring selinux mode for /etc/sysconfig/network (recursive=False)
>2018-07-16 06:27:56,629 - main.py[DEBUG]: [net] Exiting without datasource in local mode
>2018-07-16 06:27:56,629 - util.py[DEBUG]: Reading from /proc/uptime (quiet=False)
>2018-07-16 06:27:56,629 - util.py[DEBUG]: Read 14 bytes from /proc/uptime
>2018-07-16 06:27:56,629 - util.py[DEBUG]: cloud-init mode 'init' took 0.194 seconds (0.19)
>2018-07-16 06:27:56,630 - handlers.py[DEBUG]: finish: init-network: SUCCESS: searching for network datasources

Actions: View

Attachments on bug 1601793: 1459368 | 1459369 | 1459370