2019-06-06 05:07:55,955 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-06 05:08:45,990 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-06 05:09:36,037 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-06 05:10:26,081 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-06 05:11:16,122 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-06 05:13:23,792 [salt.utils.decorators:613 ][WARNING ][2454] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 05:13:24,337 [salt.utils.decorators:613 ][WARNING ][2454] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 05:13:26,343 [salt.loaded.int.states.file:2298][WARNING ][2596] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-06 05:13:31,026 [salt.state       :2022][WARNING ][2703] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-06 05:13:33,554 [salt.utils.decorators:613 ][WARNING ][2703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 05:15:45,744 [salt.utils.decorators:613 ][WARNING ][2703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 05:31:16,552 [salt.utils.decorators:613 ][WARNING ][2703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 05:42:41,115 [salt.utils.decorators:613 ][WARNING ][2703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 05:42:49,043 [salt.loaded.ext.module.maasng:1008][WARNING ][2703] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-06-06 05:42:49,043 [salt.loaded.ext.module.maasng:1011][WARNING ][2703] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-06-06 05:42:49,719 [salt.loaded.ext.module.maasng:1235][WARNING ][2703] Ignoring parameter vlan:0
2019-06-06 05:42:51,662 [salt.utils.decorators:613 ][WARNING ][2703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 05:42:53,855 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15233] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-06 05:42:53,890 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15233] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-06 05:42:53,915 [salt.utils.parsers:1051][WARNING ][364] Minion received a SIGTERM. Exiting.
2019-06-06 05:42:54,896 [salt.cli.daemons :293 ][INFO    ][15300] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-06-06 05:42:54,977 [salt.cli.daemons :82  ][INFO    ][15300] Starting up the Salt Minion
2019-06-06 05:42:54,977 [salt.utils.event :1017][INFO    ][15300] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-06-06 05:42:55,816 [salt.minion      :976 ][INFO    ][15300] Creating minion process manager
2019-06-06 05:42:57,360 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15300] Executing command ['date', '+%z'] in directory '/root'
2019-06-06 05:42:57,383 [salt.utils.schedule:568 ][INFO    ][15300] Updating job settings for scheduled job: __mine_interval
2019-06-06 05:42:57,385 [salt.minion      :1108][INFO    ][15300] Added mine.update to scheduler
2019-06-06 05:42:57,387 [salt.utils.decorators:613 ][WARNING ][15235] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 05:42:57,390 [salt.minion      :1975][INFO    ][15300] Minion is starting as user 'root'
2019-06-06 05:42:57,401 [salt.minion      :2336][INFO    ][15300] Minion is ready to receive requests!
2019-06-06 05:42:57,451 [salt.loaded.ext.module.maas:412 ][WARNING ][15235] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-06 05:42:58,866 [salt.loaded.ext.module.maas:412 ][WARNING ][15235] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-06 05:43:00,246 [salt.loaded.ext.module.maas:412 ][WARNING ][15235] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-06 05:43:01,528 [salt.loaded.ext.module.maas:412 ][WARNING ][15235] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-06 05:43:02,702 [salt.loaded.ext.module.maas:412 ][WARNING ][15235] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-06 05:43:35,701 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command state.apply with jid 20190606054335688103
2019-06-06 05:43:35,722 [salt.minion      :1432][INFO    ][15722] Starting a new job with PID 15722
2019-06-06 05:43:39,501 [salt.state       :915 ][INFO    ][15722] Loading fresh modules for state activity
2019-06-06 05:43:39,552 [salt.fileclient  :1219][INFO    ][15722] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-06 05:43:39,596 [salt.state       :1780][INFO    ][15722] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:43:39.596808
2019-06-06 05:43:39,597 [salt.state       :1813][INFO    ][15722] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-06 05:43:39,599 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15722] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-06 05:43:41,053 [salt.state       :300 ][INFO    ][15722] {'pid': 15733, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-06 05:43:41,054 [salt.state       :1951][INFO    ][15722] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:43:41.053995 duration_in_ms=1457.188
2019-06-06 05:43:41,055 [salt.state       :1780][INFO    ][15722] Running state [maas.wait_for_machine_status] at time 05:43:41.055409
2019-06-06 05:43:41,055 [salt.state       :1813][INFO    ][15722] Executing state module.run for [maas.wait_for_machine_status]
2019-06-06 05:43:41,055 [salt.utils.decorators:613 ][WARNING ][15722] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 05:43:42,027 [salt.loaded.ext.module.maas:1023][INFO    ][15722] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.03305292s left)
2019-06-06 05:43:50,782 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606054350769691
2019-06-06 05:43:50,805 [salt.minion      :1432][INFO    ][15752] Starting a new job with PID 15752
2019-06-06 05:43:50,828 [salt.minion      :1711][INFO    ][15752] Returning information for job: 20190606054350769691
2019-06-06 05:44:13,008 [salt.loaded.ext.module.maas:1023][INFO    ][15722] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.051337s left)
2019-06-06 05:44:20,849 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606054420799531
2019-06-06 05:44:20,869 [salt.minion      :1432][INFO    ][15795] Starting a new job with PID 15795
2019-06-06 05:44:20,890 [salt.minion      :1711][INFO    ][15795] Returning information for job: 20190606054420799531
2019-06-06 05:44:44,291 [salt.loaded.ext.module.maas:1023][INFO    ][15722] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.76878095s left)
2019-06-06 05:44:50,883 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606054450871575
2019-06-06 05:44:50,903 [salt.minion      :1432][INFO    ][15940] Starting a new job with PID 15940
2019-06-06 05:44:50,926 [salt.minion      :1711][INFO    ][15940] Returning information for job: 20190606054450871575
2019-06-06 05:45:15,603 [salt.loaded.ext.module.maas:1023][INFO    ][15722] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.45685101s left)
2019-06-06 05:45:20,931 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606054520918755
2019-06-06 05:45:20,955 [salt.minion      :1432][INFO    ][16068] Starting a new job with PID 16068
2019-06-06 05:45:20,980 [salt.minion      :1711][INFO    ][16068] Returning information for job: 20190606054520918755
2019-06-06 05:45:47,178 [salt.loaded.ext.module.maas:1023][INFO    ][15722] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.88146901s left)
2019-06-06 05:45:51,005 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606054550991513
2019-06-06 05:45:51,027 [salt.minion      :1432][INFO    ][16647] Starting a new job with PID 16647
2019-06-06 05:45:51,051 [salt.minion      :1711][INFO    ][16647] Returning information for job: 20190606054550991513
2019-06-06 05:46:19,053 [salt.loaded.ext.module.maas:1023][INFO    ][15722] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.00715303s left)
2019-06-06 05:46:21,061 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606054621048963
2019-06-06 05:46:21,084 [salt.minion      :1432][INFO    ][16814] Starting a new job with PID 16814
2019-06-06 05:46:21,108 [salt.minion      :1711][INFO    ][16814] Returning information for job: 20190606054621048963
2019-06-06 05:46:51,122 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606054651108915
2019-06-06 05:46:51,143 [salt.minion      :1432][INFO    ][17026] Starting a new job with PID 17026
2019-06-06 05:46:51,159 [salt.minion      :1711][INFO    ][17026] Returning information for job: 20190606054651108915
2019-06-06 05:46:52,846 [salt.state       :300 ][INFO    ][15722] {'ret': True}
2019-06-06 05:46:52,847 [salt.state       :1951][INFO    ][15722] Completed state [maas.wait_for_machine_status] at time 05:46:52.846938 duration_in_ms=191791.526
2019-06-06 05:46:52,851 [salt.minion      :1711][INFO    ][15722] Returning information for job: 20190606054335688103
2019-06-06 05:46:53,521 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command state.apply with jid 20190606054653508243
2019-06-06 05:46:53,543 [salt.minion      :1432][INFO    ][17035] Starting a new job with PID 17035
2019-06-06 05:46:57,278 [salt.state       :915 ][INFO    ][17035] Loading fresh modules for state activity
2019-06-06 05:46:57,331 [salt.fileclient  :1219][INFO    ][17035] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-06 05:46:57,427 [salt.state       :1780][INFO    ][17035] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:46:57.427083
2019-06-06 05:46:57,427 [salt.state       :1813][INFO    ][17035] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-06 05:46:57,429 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17035] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-06 05:46:58,874 [salt.state       :300 ][INFO    ][17035] {'pid': 17042, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-06 05:46:58,874 [salt.state       :1951][INFO    ][17035] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:46:58.874502 duration_in_ms=1447.42
2019-06-06 05:46:58,875 [salt.state       :1780][INFO    ][17035] Running state [maas_machines_storage_cmp002_lvm] at time 05:46:58.875963
2019-06-06 05:46:58,876 [salt.state       :1813][INFO    ][17035] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-06 05:47:00,217 [salt.loaded.ext.module.maasng:610 ][INFO    ][17035] bp74s6
2019-06-06 05:47:00,218 [salt.loaded.ext.module.maasng:626 ][INFO    ][17035] sda
2019-06-06 05:47:00,944 [salt.loaded.ext.module.maasng:361 ][INFO    ][17035] bp74s6
2019-06-06 05:47:01,078 [salt.loaded.ext.module.maasng:367 ][INFO    ][17035] [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'3a2ba4a9-c4da-4e01-971f-fa2eefdba980', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bp74s6', u'filesystem': {u'uuid': u'7fb6572f-0f8a-453c-a2a0-1a523521d8a2', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/bp74s6/blockdevices/5/partition/7', u'type': u'partition', u'id': 7, u'device_id': 5}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'bp74s6', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/bp74s6/blockdevices/5/'}, {u'block_size': 4096, u'uuid': u'245ea3f3-5b51-4ac3-8ae5-6ac3a508c98e', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'bp74s6', u'partition_table_type': None, u'filesystem': {u'uuid': u'5c63d8c3-d0b3-4ca0-9117-f2a6f79ad9ea', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 2397988454400, u'used_size': 2397988454400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/bp74s6/blockdevices/12/'}]
2019-06-06 05:47:01,079 [salt.loaded.ext.module.maasng:632 ][INFO    ][17035] vgroot
2019-06-06 05:47:01,079 [salt.loaded.ext.module.maasng:635 ][INFO    ][17035] lvroot
2019-06-06 05:47:01,079 [salt.loaded.ext.module.maasng:639 ][INFO    ][17035] 107374182400
2019-06-06 05:47:01,580 [salt.loaded.ext.module.maasng:645 ][INFO    ][17035] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'status_action': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.42'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'c35c1dc2-0bbd-4ba0-8ea8-af33ec43cdd3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bp74s6', u'device_id': 5, u'filesystem': {u'uuid': u'c66c93cd-54b5-4ca6-b39d-44a58e9ea961', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/bp74s6/blockdevices/5/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'bp74s6', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/bp74s6/blockdevices/5/', u'type': u'physical', u'id': 5, u'size': 2397998940160}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'bp74s6', u'power_state': u'on', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'4dcf2986-b3bb-4dcd-b533-fffef4849300', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'bp74s6', u'partition_table_type': None, u'filesystem': {u'uuid': u'a779b098-cb1e-4674-8b78-58612f5f2642', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/bp74s6/blockdevices/13/', u'type': u'virtual', u'id': 13, u'size': 107374182400}], u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'c35c1dc2-0bbd-4ba0-8ea8-af33ec43cdd3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bp74s6', u'device_id': 5, u'filesystem': {u'uuid': u'c66c93cd-54b5-4ca6-b39d-44a58e9ea961', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/bp74s6/blockdevices/5/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'bp74s6', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/bp74s6/blockdevices/5/', u'used_size': 2397998940160, u'id': 5, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'4dcf2986-b3bb-4dcd-b533-fffef4849300', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'bp74s6', u'partition_table_type': None, u'filesystem': {u'uuid': u'a779b098-cb1e-4674-8b78-58612f5f2642', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/bp74s6/blockdevices/13/', u'used_size': 107374182400, u'id': 13, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'bp74s6', u'id': 8}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xf86x3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xf86x3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 43, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xf86x3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'system_id': u'bp74s6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/bp74s6/interfaces/4/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'c35c1dc2-0bbd-4ba0-8ea8-af33ec43cdd3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bp74s6', u'device_id': 5, u'filesystem': {u'uuid': u'c66c93cd-54b5-4ca6-b39d-44a58e9ea961', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/bp74s6/blockdevices/5/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'bp74s6', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/bp74s6/blockdevices/5/', u'type': u'physical', u'id': 5, u'size': 2397998940160}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xf86x3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xf86x3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 43, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xf86x3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'system_id': u'bp74s6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/bp74s6/interfaces/4/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp9s0', u'links': [{u'id': 44, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6d', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'bp74s6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/bp74s6/interfaces/21/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp7s0', u'links': [{u'id': 45, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6b', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'bp74s6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/bp74s6/interfaces/22/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp8s0', u'links': [{u'id': 46, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6c', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'bp74s6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/bp74s6/interfaces/23/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/bp74s6/'}
2019-06-06 05:47:01,582 [salt.state       :300 ][INFO    ][17035] {'new': {'storage_layout': 'lvm'}}
2019-06-06 05:47:01,582 [salt.state       :1951][INFO    ][17035] Completed state [maas_machines_storage_cmp002_lvm] at time 05:47:01.582645 duration_in_ms=2706.679
2019-06-06 05:47:01,583 [salt.state       :1780][INFO    ][17035] Running state [maas_machines_storage_cmp001_lvm] at time 05:47:01.583213
2019-06-06 05:47:01,583 [salt.state       :1813][INFO    ][17035] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-06 05:47:03,114 [salt.loaded.ext.module.maasng:610 ][INFO    ][17035] 4n7ar4
2019-06-06 05:47:03,114 [salt.loaded.ext.module.maasng:626 ][INFO    ][17035] sda
2019-06-06 05:47:03,838 [salt.loaded.ext.module.maasng:361 ][INFO    ][17035] 4n7ar4
2019-06-06 05:47:03,953 [salt.loaded.ext.module.maasng:367 ][INFO    ][17035] [{u'size': 2397998940160, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'89ff75a2-32d7-48f3-833e-5536eb8aa7a2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4n7ar4', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'f063c470-96ec-4770-933d-96b510adbe15', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/4n7ar4/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 2397992648704}], u'filesystem': None, u'id': 1, u'system_id': u'4n7ar4', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'used_for': u'GPT partitioned with 1 partition', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/4n7ar4/blockdevices/1/'}, {u'size': 2397988454400, u'available_size': 0, u'uuid': u'f7fc7d4d-fad1-40b7-a4cb-39aaa2032388', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'52acf40e-2375-4796-bb35-772d2edb32c3', u'fstype': u'ext4', u'label': u'root'}, u'id': 6, u'system_id': u'4n7ar4', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/4n7ar4/blockdevices/6/'}]
2019-06-06 05:47:03,953 [salt.loaded.ext.module.maasng:632 ][INFO    ][17035] vgroot
2019-06-06 05:47:03,953 [salt.loaded.ext.module.maasng:635 ][INFO    ][17035] lvroot
2019-06-06 05:47:03,954 [salt.loaded.ext.module.maasng:639 ][INFO    ][17035] 107374182400
2019-06-06 05:47:04,691 [salt.loaded.ext.module.maasng:645 ][INFO    ][17035] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xf86x3', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 17, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xf86x3', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xf86x3', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'system_id': u'4n7ar4', u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4n7ar4/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'07abe597-fad6-425d-869e-63ba389fbaf1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4n7ar4', u'filesystem': {u'uuid': u'584aa7ac-52ee-4893-8700-78707798204d', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/4n7ar4/blockdevices/1/partition/9', u'type': u'partition', u'id': 9, u'device_id': 1}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'4n7ar4', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/4n7ar4/blockdevices/1/'}, u'fqdn': u'cmp001.maas', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/4n7ar4/', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'owner_data': {}, u'system_id': u'4n7ar4', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.38'], u'blockdevice_set': [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'07abe597-fad6-425d-869e-63ba389fbaf1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4n7ar4', u'filesystem': {u'uuid': u'584aa7ac-52ee-4893-8700-78707798204d', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/4n7ar4/blockdevices/1/partition/9', u'type': u'partition', u'id': 9, u'device_id': 1}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'4n7ar4', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/4n7ar4/blockdevices/1/'}, {u'block_size': 4096, u'uuid': u'e4c58414-d2c7-4bdb-a12f-b90c1be4e9d4', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'4n7ar4', u'partition_table_type': None, u'filesystem': {u'uuid': u'5e036f34-0b3c-4d37-ac72-efa00572f05b', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 14, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/4n7ar4/blockdevices/14/'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'power_state': u'off', u'physicalblockdevice_set': [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'07abe597-fad6-425d-869e-63ba389fbaf1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4n7ar4', u'filesystem': {u'uuid': u'584aa7ac-52ee-4893-8700-78707798204d', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/4n7ar4/blockdevices/1/partition/9', u'type': u'partition', u'id': 9, u'device_id': 1}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'4n7ar4', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/4n7ar4/blockdevices/1/'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'4n7ar4', u'id': 9}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'block_size': 4096, u'uuid': u'e4c58414-d2c7-4bdb-a12f-b90c1be4e9d4', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'4n7ar4', u'partition_table_type': None, u'filesystem': {u'uuid': u'5e036f34-0b3c-4d37-ac72-efa00572f05b', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 14, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/4n7ar4/blockdevices/14/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xf86x3', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 17, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xf86x3', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xf86x3', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'system_id': u'4n7ar4', u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4n7ar4/interfaces/5/'}, {u'name': u'enp9s0', u'links': [{u'id': 18, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 9, u'discovered': None, u'parents': [], u'system_id': u'4n7ar4', u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4n7ar4/interfaces/9/'}, {u'name': u'enp7s0', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 10, u'discovered': None, u'parents': [], u'system_id': u'4n7ar4', u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4n7ar4/interfaces/10/'}, {u'name': u'enp8s0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'parents': [], u'system_id': u'4n7ar4', u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4n7ar4/interfaces/11/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-06-06 05:47:04,693 [salt.state       :300 ][INFO    ][17035] {'new': {'storage_layout': 'lvm'}}
2019-06-06 05:47:04,694 [salt.state       :1951][INFO    ][17035] Completed state [maas_machines_storage_cmp001_lvm] at time 05:47:04.694145 duration_in_ms=3110.931
2019-06-06 05:47:04,697 [salt.minion      :1711][INFO    ][17035] Returning information for job: 20190606054653508243
2019-06-06 05:47:05,339 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command state.apply with jid 20190606054705326175
2019-06-06 05:47:05,361 [salt.minion      :1432][INFO    ][17110] Starting a new job with PID 17110
2019-06-06 05:47:06,122 [salt.state       :915 ][INFO    ][17110] Loading fresh modules for state activity
2019-06-06 05:47:06,174 [salt.fileclient  :1219][INFO    ][17110] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-06 05:47:06,214 [salt.state       :1780][INFO    ][17110] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:47:06.214918
2019-06-06 05:47:06,215 [salt.state       :1813][INFO    ][17110] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-06 05:47:06,217 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17110] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-06 05:47:07,497 [salt.state       :300 ][INFO    ][17110] {'pid': 17117, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-06 05:47:07,498 [salt.state       :1951][INFO    ][17110] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:47:07.498649 duration_in_ms=1283.731
2019-06-06 05:47:07,501 [salt.state       :1780][INFO    ][17110] Running state [maas.deploy_machines] at time 05:47:07.501188
2019-06-06 05:47:07,501 [salt.state       :1813][INFO    ][17110] Executing state module.run for [maas.deploy_machines]
2019-06-06 05:47:07,503 [salt.utils.decorators:613 ][WARNING ][17110] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 05:47:08,224 [salt.loaded.ext.module.maas:684 ][INFO    ][17110] deploymachines hwe_kernel=hwe-16.04 system_id=bp74s6 distro_series=xenial
2019-06-06 05:47:11,223 [salt.loaded.ext.module.maas:684 ][INFO    ][17110] deploymachines hwe_kernel=hwe-16.04 system_id=4n7ar4 distro_series=xenial
2019-06-06 05:47:14,038 [salt.loaded.ext.module.maas:684 ][INFO    ][17110] deploymachines hwe_kernel=hwe-16.04 system_id=kn7mtd distro_series=xenial
2019-06-06 05:47:16,570 [salt.loaded.ext.module.maas:684 ][INFO    ][17110] deploymachines hwe_kernel=hwe-16.04 system_id=gy8ed3 distro_series=xenial
2019-06-06 05:47:19,053 [salt.loaded.ext.module.maas:684 ][INFO    ][17110] deploymachines hwe_kernel=hwe-16.04 system_id=gf8rs6 distro_series=xenial
2019-06-06 05:47:20,393 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606054720380818
2019-06-06 05:47:20,414 [salt.minion      :1432][INFO    ][17383] Starting a new job with PID 17383
2019-06-06 05:47:20,438 [salt.minion      :1711][INFO    ][17383] Returning information for job: 20190606054720380818
2019-06-06 05:47:21,341 [salt.state       :300 ][INFO    ][17110] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-06 05:47:21,341 [salt.state       :1951][INFO    ][17110] Completed state [maas.deploy_machines] at time 05:47:21.341867 duration_in_ms=13840.678
2019-06-06 05:47:21,345 [salt.minion      :1711][INFO    ][17110] Returning information for job: 20190606054705326175
2019-06-06 05:47:21,927 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command state.apply with jid 20190606054721914872
2019-06-06 05:47:21,949 [salt.minion      :1432][INFO    ][17401] Starting a new job with PID 17401
2019-06-06 05:47:25,864 [salt.state       :915 ][INFO    ][17401] Loading fresh modules for state activity
2019-06-06 05:47:25,916 [salt.fileclient  :1219][INFO    ][17401] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-06 05:47:25,958 [salt.state       :1780][INFO    ][17401] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:47:25.958740
2019-06-06 05:47:25,959 [salt.state       :1813][INFO    ][17401] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-06 05:47:25,961 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17401] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-06 05:47:27,600 [salt.state       :300 ][INFO    ][17401] {'pid': 17414, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-06 05:47:27,601 [salt.state       :1951][INFO    ][17401] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:47:27.601390 duration_in_ms=1642.649
2019-06-06 05:47:27,604 [salt.state       :1780][INFO    ][17401] Running state [maas.wait_for_machine_status] at time 05:47:27.604821
2019-06-06 05:47:27,605 [salt.state       :1813][INFO    ][17401] Executing state module.run for [maas.wait_for_machine_status]
2019-06-06 05:47:27,606 [salt.utils.decorators:613 ][WARNING ][17401] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 05:47:30,393 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.2239759s left)
2019-06-06 05:47:37,038 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606054737024725
2019-06-06 05:47:37,060 [salt.minion      :1432][INFO    ][17455] Starting a new job with PID 17455
2019-06-06 05:47:37,082 [salt.minion      :1711][INFO    ][17455] Returning information for job: 20190606054737024725
2019-06-06 05:48:03,839 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.77834988s left)
2019-06-06 05:48:07,075 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606054807065324
2019-06-06 05:48:07,098 [salt.minion      :1432][INFO    ][17483] Starting a new job with PID 17483
2019-06-06 05:48:07,123 [salt.minion      :1711][INFO    ][17483] Returning information for job: 20190606054807065324
2019-06-06 05:48:37,124 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606054837112631
2019-06-06 05:48:37,144 [salt.minion      :1432][INFO    ][17526] Starting a new job with PID 17526
2019-06-06 05:48:37,168 [salt.minion      :1711][INFO    ][17526] Returning information for job: 20190606054837112631
2019-06-06 05:48:37,426 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.19048095s left)
2019-06-06 05:49:07,167 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606054907154969
2019-06-06 05:49:07,188 [salt.minion      :1432][INFO    ][17640] Starting a new job with PID 17640
2019-06-06 05:49:07,211 [salt.minion      :1711][INFO    ][17640] Returning information for job: 20190606054907154969
2019-06-06 05:49:11,043 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.57421684s left)
2019-06-06 05:49:37,222 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606054937209799
2019-06-06 05:49:37,245 [salt.minion      :1432][INFO    ][18068] Starting a new job with PID 18068
2019-06-06 05:49:37,269 [salt.minion      :1711][INFO    ][18068] Returning information for job: 20190606054937209799
2019-06-06 05:49:44,516 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.10065794s left)
2019-06-06 05:50:07,282 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055007269368
2019-06-06 05:50:07,303 [salt.minion      :1432][INFO    ][18245] Starting a new job with PID 18245
2019-06-06 05:50:07,330 [salt.minion      :1711][INFO    ][18245] Returning information for job: 20190606055007269368
2019-06-06 05:50:17,385 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.23141694s left)
2019-06-06 05:50:37,352 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055037340459
2019-06-06 05:50:37,371 [salt.minion      :1432][INFO    ][18669] Starting a new job with PID 18669
2019-06-06 05:50:37,394 [salt.minion      :1711][INFO    ][18669] Returning information for job: 20190606055037340459
2019-06-06 05:50:51,093 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.52414393s left)
2019-06-06 05:51:07,408 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055107394760
2019-06-06 05:51:07,430 [salt.minion      :1432][INFO    ][18721] Starting a new job with PID 18721
2019-06-06 05:51:07,454 [salt.minion      :1711][INFO    ][18721] Returning information for job: 20190606055107394760
2019-06-06 05:51:24,560 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.05675793s left)
2019-06-06 05:51:37,469 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055137456905
2019-06-06 05:51:37,493 [salt.minion      :1432][INFO    ][18906] Starting a new job with PID 18906
2019-06-06 05:51:37,517 [salt.minion      :1711][INFO    ][18906] Returning information for job: 20190606055137456905
2019-06-06 05:51:58,070 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.54710984s left)
2019-06-06 05:52:07,541 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055207529351
2019-06-06 05:52:07,563 [salt.minion      :1432][INFO    ][19071] Starting a new job with PID 19071
2019-06-06 05:52:07,587 [salt.minion      :1711][INFO    ][19071] Returning information for job: 20190606055207529351
2019-06-06 05:52:31,518 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.09861302s left)
2019-06-06 05:52:37,605 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055237597667
2019-06-06 05:52:37,625 [salt.minion      :1432][INFO    ][19465] Starting a new job with PID 19465
2019-06-06 05:52:37,648 [salt.minion      :1711][INFO    ][19465] Returning information for job: 20190606055237597667
2019-06-06 05:53:04,975 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.64227104s left)
2019-06-06 05:53:07,679 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055307666323
2019-06-06 05:53:07,702 [salt.minion      :1432][INFO    ][19598] Starting a new job with PID 19598
2019-06-06 05:53:07,725 [salt.minion      :1711][INFO    ][19598] Returning information for job: 20190606055307666323
2019-06-06 05:53:37,759 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055337746474
2019-06-06 05:53:37,781 [salt.minion      :1432][INFO    ][19865] Starting a new job with PID 19865
2019-06-06 05:53:37,804 [salt.minion      :1711][INFO    ][19865] Returning information for job: 20190606055337746474
2019-06-06 05:53:38,382 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.23462582s left)
2019-06-06 05:54:07,840 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055407827391
2019-06-06 05:54:07,862 [salt.minion      :1432][INFO    ][19907] Starting a new job with PID 19907
2019-06-06 05:54:07,884 [salt.minion      :1711][INFO    ][19907] Returning information for job: 20190606055407827391
2019-06-06 05:54:11,823 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.79354596s left)
2019-06-06 05:54:37,935 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055437922720
2019-06-06 05:54:37,958 [salt.minion      :1432][INFO    ][20042] Starting a new job with PID 20042
2019-06-06 05:54:37,983 [salt.minion      :1711][INFO    ][20042] Returning information for job: 20190606055437922720
2019-06-06 05:54:45,323 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.29353404s left)
2019-06-06 05:55:08,078 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055508064892
2019-06-06 05:55:08,099 [salt.minion      :1432][INFO    ][20246] Starting a new job with PID 20246
2019-06-06 05:55:08,121 [salt.minion      :1711][INFO    ][20246] Returning information for job: 20190606055508064892
2019-06-06 05:55:18,682 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1778.93508792s left)
2019-06-06 05:55:38,183 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055538170593
2019-06-06 05:55:38,206 [salt.minion      :1432][INFO    ][20579] Starting a new job with PID 20579
2019-06-06 05:55:38,231 [salt.minion      :1711][INFO    ][20579] Returning information for job: 20190606055538170593
2019-06-06 05:55:51,310 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1746.30733895s left)
2019-06-06 05:56:08,290 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055608277350
2019-06-06 05:56:08,313 [salt.minion      :1432][INFO    ][20661] Starting a new job with PID 20661
2019-06-06 05:56:08,335 [salt.minion      :1711][INFO    ][20661] Returning information for job: 20190606055608277350
2019-06-06 05:56:25,089 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1712.52804589s left)
2019-06-06 05:56:38,410 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055638397148
2019-06-06 05:56:38,433 [salt.minion      :1432][INFO    ][20703] Starting a new job with PID 20703
2019-06-06 05:56:38,456 [salt.minion      :1711][INFO    ][20703] Returning information for job: 20190606055638397148
2019-06-06 05:56:57,801 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1679.81570792s left)
2019-06-06 05:57:08,541 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055708528837
2019-06-06 05:57:08,563 [salt.minion      :1432][INFO    ][20734] Starting a new job with PID 20734
2019-06-06 05:57:08,587 [salt.minion      :1711][INFO    ][20734] Returning information for job: 20190606055708528837
2019-06-06 05:57:31,340 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1646.27696991s left)
2019-06-06 05:57:38,675 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055738662289
2019-06-06 05:57:38,698 [salt.minion      :1432][INFO    ][20779] Starting a new job with PID 20779
2019-06-06 05:57:38,722 [salt.minion      :1711][INFO    ][20779] Returning information for job: 20190606055738662289
2019-06-06 05:58:04,612 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1613.00541091s left)
2019-06-06 05:58:08,816 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055808804633
2019-06-06 05:58:08,839 [salt.minion      :1432][INFO    ][20811] Starting a new job with PID 20811
2019-06-06 05:58:08,863 [salt.minion      :1711][INFO    ][20811] Returning information for job: 20190606055808804633
2019-06-06 05:58:38,186 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1579.43129897s left)
2019-06-06 05:58:38,967 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055838957749
2019-06-06 05:58:38,988 [salt.minion      :1432][INFO    ][20983] Starting a new job with PID 20983
2019-06-06 05:58:39,012 [salt.minion      :1711][INFO    ][20983] Returning information for job: 20190606055838957749
2019-06-06 05:59:09,132 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055909113522
2019-06-06 05:59:09,154 [salt.minion      :1432][INFO    ][21025] Starting a new job with PID 21025
2019-06-06 05:59:09,178 [salt.minion      :1711][INFO    ][21025] Returning information for job: 20190606055909113522
2019-06-06 05:59:11,461 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1546.1554749s left)
2019-06-06 05:59:39,300 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606055939287413
2019-06-06 05:59:39,323 [salt.minion      :1432][INFO    ][21065] Starting a new job with PID 21065
2019-06-06 05:59:39,348 [salt.minion      :1711][INFO    ][21065] Returning information for job: 20190606055939287413
2019-06-06 05:59:44,520 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1513.09635901s left)
2019-06-06 06:00:09,484 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060009471228
2019-06-06 06:00:09,507 [salt.minion      :1432][INFO    ][21104] Starting a new job with PID 21104
2019-06-06 06:00:09,529 [salt.minion      :1711][INFO    ][21104] Returning information for job: 20190606060009471228
2019-06-06 06:00:18,221 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1479.39623189s left)
2019-06-06 06:00:39,672 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060039659379
2019-06-06 06:00:39,694 [salt.minion      :1432][INFO    ][21143] Starting a new job with PID 21143
2019-06-06 06:00:39,718 [salt.minion      :1711][INFO    ][21143] Returning information for job: 20190606060039659379
2019-06-06 06:00:51,580 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1446.03653884s left)
2019-06-06 06:01:09,869 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060109861420
2019-06-06 06:01:09,889 [salt.minion      :1432][INFO    ][21178] Starting a new job with PID 21178
2019-06-06 06:01:09,914 [salt.minion      :1711][INFO    ][21178] Returning information for job: 20190606060109861420
2019-06-06 06:01:25,191 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1412.42538404s left)
2019-06-06 06:01:40,079 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060140065199
2019-06-06 06:01:40,101 [salt.minion      :1432][INFO    ][21219] Starting a new job with PID 21219
2019-06-06 06:01:40,125 [salt.minion      :1711][INFO    ][21219] Returning information for job: 20190606060140065199
2019-06-06 06:01:58,473 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1379.14429402s left)
2019-06-06 06:02:10,304 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060210290604
2019-06-06 06:02:10,327 [salt.minion      :1432][INFO    ][21255] Starting a new job with PID 21255
2019-06-06 06:02:10,350 [salt.minion      :1711][INFO    ][21255] Returning information for job: 20190606060210290604
2019-06-06 06:02:31,953 [salt.loaded.ext.module.maas:1023][INFO    ][17401] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1345.66381598s left)
2019-06-06 06:02:40,325 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060240313057
2019-06-06 06:02:40,348 [salt.minion      :1432][INFO    ][21297] Starting a new job with PID 21297
2019-06-06 06:02:40,371 [salt.minion      :1711][INFO    ][21297] Returning information for job: 20190606060240313057
2019-06-06 06:03:05,534 [salt.loaded.ext.module.maas:993 ][INFO    ][17401] Machine gf8rs6 mark broken
2019-06-06 06:03:06,250 [salt.loaded.ext.module.maas:996 ][INFO    ][17401] Machine gf8rs6 mark fixed
2019-06-06 06:03:07,491 [salt.loaded.ext.module.maas:684 ][INFO    ][17401] deploymachines hwe_kernel=hwe-16.04 system_id=gf8rs6 distro_series=xenial
2019-06-06 06:03:10,013 [salt.loaded.ext.module.maas:160 ][ERROR   ][17401] Failed for object kvm02 reason Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node.
2019-06-06 06:03:10,015 [salt.state       :302 ][ERROR   ][17401] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {'kvm02': "Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node."}, 'success': []}
2019-06-06 06:03:10,016 [salt.state       :1951][INFO    ][17401] Completed state [maas.wait_for_machine_status] at time 06:03:10.015946 duration_in_ms=942411.12
2019-06-06 06:03:10,025 [salt.minion      :1711][INFO    ][17401] Returning information for job: 20190606054721914872
2019-06-06 06:03:20,700 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command pillar.get with jid 20190606060320688862
2019-06-06 06:03:20,716 [salt.minion      :1432][INFO    ][21406] Starting a new job with PID 21406
2019-06-06 06:03:20,721 [salt.minion      :1711][INFO    ][21406] Returning information for job: 20190606060320688862
2019-06-06 06:03:21,256 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command service.status with jid 20190606060321245295
2019-06-06 06:03:21,271 [salt.minion      :1432][INFO    ][21411] Starting a new job with PID 21411
2019-06-06 06:03:21,646 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][21411] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-06 06:03:21,681 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][21411] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-06 06:03:21,699 [salt.minion      :1711][INFO    ][21411] Returning information for job: 20190606060321245295
2019-06-06 06:03:22,212 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command state.apply with jid 20190606060322198867
2019-06-06 06:03:22,233 [salt.minion      :1432][INFO    ][21422] Starting a new job with PID 21422
2019-06-06 06:03:25,991 [salt.state       :915 ][INFO    ][21422] Loading fresh modules for state activity
2019-06-06 06:03:26,321 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21422] Executing command 'salt-minion --version' in directory '/root'
2019-06-06 06:03:26,690 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21422] Executing command 'salt-minion --version' in directory '/root'
2019-06-06 06:03:27,475 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21422] Executing command 'salt-minion --version' in directory '/root'
2019-06-06 06:03:27,797 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21422] Executing command 'salt-minion --version' in directory '/root'
2019-06-06 06:03:29,115 [salt.state       :1780][INFO    ][21422] Running state [salt-minion] at time 06:03:29.115527
2019-06-06 06:03:29,115 [salt.state       :1813][INFO    ][21422] Executing state pkg.installed for [salt-minion]
2019-06-06 06:03:29,116 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21422] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-06 06:03:29,197 [salt.state       :300 ][INFO    ][21422] All specified packages are already installed
2019-06-06 06:03:29,197 [salt.state       :1951][INFO    ][21422] Completed state [salt-minion] at time 06:03:29.197525 duration_in_ms=81.997
2019-06-06 06:03:29,197 [salt.state       :1780][INFO    ][21422] Running state [salt_minion_dependency_packages] at time 06:03:29.197797
2019-06-06 06:03:29,198 [salt.state       :1813][INFO    ][21422] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-06 06:03:29,203 [salt.state       :300 ][INFO    ][21422] All specified packages are already installed
2019-06-06 06:03:29,203 [salt.state       :1951][INFO    ][21422] Completed state [salt_minion_dependency_packages] at time 06:03:29.203288 duration_in_ms=5.491
2019-06-06 06:03:29,205 [salt.state       :1780][INFO    ][21422] Running state [/etc/salt/minion.d/minion.conf] at time 06:03:29.205893
2019-06-06 06:03:29,206 [salt.state       :1813][INFO    ][21422] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-06 06:03:29,364 [salt.state       :300 ][INFO    ][21422] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-06 06:03:29,364 [salt.state       :1951][INFO    ][21422] Completed state [/etc/salt/minion.d/minion.conf] at time 06:03:29.364366 duration_in_ms=158.474
2019-06-06 06:03:29,364 [salt.state       :1780][INFO    ][21422] Running state [python-netaddr] at time 06:03:29.364570
2019-06-06 06:03:29,364 [salt.state       :1813][INFO    ][21422] Executing state pkg.installed for [python-netaddr]
2019-06-06 06:03:29,369 [salt.state       :300 ][INFO    ][21422] All specified packages are already installed
2019-06-06 06:03:29,369 [salt.state       :1951][INFO    ][21422] Completed state [python-netaddr] at time 06:03:29.369130 duration_in_ms=4.56
2019-06-06 06:03:29,371 [salt.state       :1780][INFO    ][21422] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:03:29.371138
2019-06-06 06:03:29,371 [salt.state       :1813][INFO    ][21422] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-06 06:03:29,380 [salt.state       :300 ][INFO    ][21422] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-06 06:03:29,380 [salt.state       :1951][INFO    ][21422] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:03:29.380686 duration_in_ms=9.549
2019-06-06 06:03:29,381 [salt.state       :1780][INFO    ][21422] Running state [salt-minion] at time 06:03:29.381305
2019-06-06 06:03:29,381 [salt.state       :1813][INFO    ][21422] Executing state service.running for [salt-minion]
2019-06-06 06:03:29,381 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21422] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-06 06:03:29,416 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21422] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-06 06:03:29,431 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21422] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-06 06:03:29,447 [salt.state       :300 ][INFO    ][21422] The service salt-minion is already running
2019-06-06 06:03:29,447 [salt.state       :1951][INFO    ][21422] Completed state [salt-minion] at time 06:03:29.447705 duration_in_ms=66.4
2019-06-06 06:03:29,448 [salt.state       :1780][INFO    ][21422] Running state [/etc/salt/grains.d] at time 06:03:29.448809
2019-06-06 06:03:29,449 [salt.state       :1813][INFO    ][21422] Executing state file.directory for [/etc/salt/grains.d]
2019-06-06 06:03:29,449 [salt.state       :300 ][INFO    ][21422] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-06 06:03:29,449 [salt.state       :1951][INFO    ][21422] Completed state [/etc/salt/grains.d] at time 06:03:29.449944 duration_in_ms=1.136
2019-06-06 06:03:29,450 [salt.state       :1780][INFO    ][21422] Running state [/etc/salt/grains] at time 06:03:29.450426
2019-06-06 06:03:29,450 [salt.state       :1813][INFO    ][21422] Executing state file.managed for [/etc/salt/grains]
2019-06-06 06:03:29,451 [salt.state       :300 ][INFO    ][21422] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-06 06:03:29,451 [salt.state       :1951][INFO    ][21422] Completed state [/etc/salt/grains] at time 06:03:29.451207 duration_in_ms=0.781
2019-06-06 06:03:29,451 [salt.state       :1780][INFO    ][21422] Running state [/etc/salt/grains.d/placeholder] at time 06:03:29.451545
2019-06-06 06:03:29,451 [salt.state       :1813][INFO    ][21422] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-06 06:03:29,452 [salt.state       :300 ][INFO    ][21422] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-06 06:03:29,452 [salt.state       :1951][INFO    ][21422] Completed state [/etc/salt/grains.d/placeholder] at time 06:03:29.452294 duration_in_ms=0.748
2019-06-06 06:03:29,452 [salt.state       :1780][INFO    ][21422] Running state [/etc/salt/grains.d/sphinx] at time 06:03:29.452634
2019-06-06 06:03:29,452 [salt.state       :1813][INFO    ][21422] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-06 06:03:29,453 [salt.state       :300 ][INFO    ][21422] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-06 06:03:29,453 [salt.state       :1951][INFO    ][21422] Completed state [/etc/salt/grains.d/sphinx] at time 06:03:29.453888 duration_in_ms=1.253
2019-06-06 06:03:29,455 [salt.state       :1780][INFO    ][21422] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:03:29.455511
2019-06-06 06:03:29,455 [salt.state       :1813][INFO    ][21422] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-06 06:03:29,456 [salt.state       :300 ][INFO    ][21422] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-06 06:03:29,456 [salt.state       :1951][INFO    ][21422] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:03:29.456136 duration_in_ms=0.625
2019-06-06 06:03:29,456 [salt.state       :1780][INFO    ][21422] Running state [/etc/salt/grains.d/dns_records] at time 06:03:29.456469
2019-06-06 06:03:29,456 [salt.state       :1813][INFO    ][21422] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-06 06:03:29,457 [salt.state       :300 ][INFO    ][21422] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-06 06:03:29,457 [salt.state       :1951][INFO    ][21422] Completed state [/etc/salt/grains.d/dns_records] at time 06:03:29.457544 duration_in_ms=1.075
2019-06-06 06:03:29,458 [salt.state       :1780][INFO    ][21422] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:03:29.458179
2019-06-06 06:03:29,458 [salt.state       :1813][INFO    ][21422] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-06 06:03:29,458 [salt.state       :300 ][INFO    ][21422] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-06 06:03:29,458 [salt.state       :1951][INFO    ][21422] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:03:29.458811 duration_in_ms=0.632
2019-06-06 06:03:29,459 [salt.state       :1780][INFO    ][21422] Running state [/etc/salt/grains.d/salt] at time 06:03:29.459147
2019-06-06 06:03:29,459 [salt.state       :1813][INFO    ][21422] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-06 06:03:29,460 [salt.state       :300 ][INFO    ][21422] File /etc/salt/grains.d/salt is in the correct state
2019-06-06 06:03:29,460 [salt.state       :1951][INFO    ][21422] Completed state [/etc/salt/grains.d/salt] at time 06:03:29.460214 duration_in_ms=1.066
2019-06-06 06:03:29,460 [salt.state       :1780][INFO    ][21422] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:03:29.460837
2019-06-06 06:03:29,461 [salt.state       :1813][INFO    ][21422] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-06 06:03:29,461 [salt.state       :300 ][INFO    ][21422] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-06 06:03:29,461 [salt.state       :1951][INFO    ][21422] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:03:29.461432 duration_in_ms=0.595
2019-06-06 06:03:29,462 [salt.state       :1780][INFO    ][21422] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:03:29.462721
2019-06-06 06:03:29,462 [salt.state       :1813][INFO    ][21422] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-06 06:03:29,463 [salt.state       :300 ][INFO    ][21422] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-06 06:03:29,463 [salt.state       :1951][INFO    ][21422] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:03:29.463321 duration_in_ms=0.6
2019-06-06 06:03:29,463 [salt.state       :1780][INFO    ][21422] Running state [mine.update] at time 06:03:29.463788
2019-06-06 06:03:29,464 [salt.state       :1813][INFO    ][21422] Executing state module.wait for [mine.update]
2019-06-06 06:03:29,464 [salt.state       :300 ][INFO    ][21422] No changes made for mine.update
2019-06-06 06:03:29,464 [salt.state       :1951][INFO    ][21422] Completed state [mine.update] at time 06:03:29.464335 duration_in_ms=0.546
2019-06-06 06:03:29,464 [salt.state       :1780][INFO    ][21422] Running state [ca-certificates] at time 06:03:29.464514
2019-06-06 06:03:29,464 [salt.state       :1813][INFO    ][21422] Executing state pkg.installed for [ca-certificates]
2019-06-06 06:03:29,469 [salt.state       :300 ][INFO    ][21422] All specified packages are already installed
2019-06-06 06:03:29,469 [salt.state       :1951][INFO    ][21422] Completed state [ca-certificates] at time 06:03:29.469902 duration_in_ms=5.388
2019-06-06 06:03:29,470 [salt.state       :1780][INFO    ][21422] Running state [update-ca-certificates] at time 06:03:29.470383
2019-06-06 06:03:29,470 [salt.state       :1813][INFO    ][21422] Executing state cmd.wait for [update-ca-certificates]
2019-06-06 06:03:29,470 [salt.state       :300 ][INFO    ][21422] No changes made for update-ca-certificates
2019-06-06 06:03:29,471 [salt.state       :1951][INFO    ][21422] Completed state [update-ca-certificates] at time 06:03:29.470970 duration_in_ms=0.587
2019-06-06 06:03:29,471 [salt.state       :1780][INFO    ][21422] Running state [iptables] at time 06:03:29.471137
2019-06-06 06:03:29,471 [salt.state       :1813][INFO    ][21422] Executing state pkg.installed for [iptables]
2019-06-06 06:03:29,475 [salt.state       :300 ][INFO    ][21422] All specified packages are already installed
2019-06-06 06:03:29,476 [salt.state       :1951][INFO    ][21422] Completed state [iptables] at time 06:03:29.476006 duration_in_ms=4.869
2019-06-06 06:03:29,476 [salt.state       :1780][INFO    ][21422] Running state [iptables-persistent] at time 06:03:29.476176
2019-06-06 06:03:29,476 [salt.state       :1813][INFO    ][21422] Executing state pkg.installed for [iptables-persistent]
2019-06-06 06:03:29,480 [salt.state       :300 ][INFO    ][21422] All specified packages are already installed
2019-06-06 06:03:29,481 [salt.state       :1951][INFO    ][21422] Completed state [iptables-persistent] at time 06:03:29.481052 duration_in_ms=4.876
2019-06-06 06:03:29,481 [salt.state       :1780][INFO    ][21422] Running state [iptables_modules_v4_load] at time 06:03:29.481736
2019-06-06 06:03:29,481 [salt.state       :1813][INFO    ][21422] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-06 06:03:29,482 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21422] Executing command 'lsmod' in directory '/root'
2019-06-06 06:03:29,503 [salt.state       :300 ][INFO    ][21422] Kernel modules iptable_filter, ip_tables are already present
2019-06-06 06:03:29,504 [salt.state       :1951][INFO    ][21422] Completed state [iptables_modules_v4_load] at time 06:03:29.503952 duration_in_ms=22.217
2019-06-06 06:03:29,504 [salt.state       :1780][INFO    ][21422] Running state [/etc/iptables/rules.v4] at time 06:03:29.504385
2019-06-06 06:03:29,504 [salt.state       :1813][INFO    ][21422] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-06 06:03:29,563 [salt.state       :300 ][INFO    ][21422] File /etc/iptables/rules.v4 is in the correct state
2019-06-06 06:03:29,563 [salt.state       :1951][INFO    ][21422] Completed state [/etc/iptables/rules.v4] at time 06:03:29.563220 duration_in_ms=58.835
2019-06-06 06:03:29,563 [salt.state       :1780][INFO    ][21422] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:03:29.563926
2019-06-06 06:03:29,564 [salt.state       :1813][INFO    ][21422] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-06 06:03:29,564 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21422] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-06 06:03:29,581 [salt.state       :300 ][INFO    ][21422] onlyif execution failed
2019-06-06 06:03:29,582 [salt.state       :1951][INFO    ][21422] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:03:29.581950 duration_in_ms=18.024
2019-06-06 06:03:29,582 [salt.state       :1780][INFO    ][21422] Running state [netfilter-persistent] at time 06:03:29.582676
2019-06-06 06:03:29,582 [salt.state       :1813][INFO    ][21422] Executing state service.running for [netfilter-persistent]
2019-06-06 06:03:29,583 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21422] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-06 06:03:29,601 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21422] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-06 06:03:29,617 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21422] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-06 06:03:29,632 [salt.state       :300 ][INFO    ][21422] The service netfilter-persistent is already running
2019-06-06 06:03:29,633 [salt.state       :1951][INFO    ][21422] Completed state [netfilter-persistent] at time 06:03:29.633228 duration_in_ms=50.551
2019-06-06 06:03:29,633 [salt.state       :1780][INFO    ][21422] Running state [iptables_extra.remove_stale_tables] at time 06:03:29.633869
2019-06-06 06:03:29,634 [salt.state       :1813][INFO    ][21422] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-06 06:03:29,634 [salt.state       :300 ][INFO    ][21422] No changes made for iptables_extra.remove_stale_tables
2019-06-06 06:03:29,634 [salt.state       :1951][INFO    ][21422] Completed state [iptables_extra.remove_stale_tables] at time 06:03:29.634584 duration_in_ms=0.715
2019-06-06 06:03:29,634 [salt.state       :1780][INFO    ][21422] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:03:29.634781
2019-06-06 06:03:29,635 [salt.state       :1813][INFO    ][21422] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-06 06:03:29,635 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21422] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-06 06:03:29,648 [salt.state       :300 ][INFO    ][21422] onlyif execution failed
2019-06-06 06:03:29,649 [salt.state       :1951][INFO    ][21422] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:03:29.649013 duration_in_ms=14.232
2019-06-06 06:03:29,649 [salt.state       :1780][INFO    ][21422] Running state [/etc/iptables/rules.v6] at time 06:03:29.649759
2019-06-06 06:03:29,650 [salt.state       :1813][INFO    ][21422] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-06 06:03:29,650 [salt.state       :300 ][INFO    ][21422] File /etc/iptables/rules.v6 is not present
2019-06-06 06:03:29,650 [salt.state       :1951][INFO    ][21422] Completed state [/etc/iptables/rules.v6] at time 06:03:29.650569 duration_in_ms=0.81
2019-06-06 06:03:29,651 [salt.state       :1780][INFO    ][21422] Running state [iptables_extra.flush_all] at time 06:03:29.651093
2019-06-06 06:03:29,651 [salt.state       :1813][INFO    ][21422] Executing state module.wait for [iptables_extra.flush_all]
2019-06-06 06:03:29,651 [salt.state       :300 ][INFO    ][21422] No changes made for iptables_extra.flush_all
2019-06-06 06:03:29,651 [salt.state       :1951][INFO    ][21422] Completed state [iptables_extra.flush_all] at time 06:03:29.651733 duration_in_ms=0.64
2019-06-06 06:03:29,654 [salt.minion      :1711][INFO    ][21422] Returning information for job: 20190606060322198867
2019-06-06 06:03:30,279 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command state.apply with jid 20190606060330266616
2019-06-06 06:03:30,301 [salt.minion      :1432][INFO    ][21526] Starting a new job with PID 21526
2019-06-06 06:03:31,056 [salt.state       :915 ][INFO    ][21526] Loading fresh modules for state activity
2019-06-06 06:03:31,816 [salt.state       :1780][INFO    ][21526] Running state [maas-rack-controller] at time 06:03:31.816679
2019-06-06 06:03:31,817 [salt.state       :1813][INFO    ][21526] Executing state pkg.installed for [maas-rack-controller]
2019-06-06 06:03:31,817 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21526] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-06 06:03:31,900 [salt.state       :300 ][INFO    ][21526] All specified packages are already installed
2019-06-06 06:03:31,900 [salt.state       :1951][INFO    ][21526] Completed state [maas-rack-controller] at time 06:03:31.900524 duration_in_ms=83.845
2019-06-06 06:03:31,900 [salt.state       :1780][INFO    ][21526] Running state [ipmitool] at time 06:03:31.900819
2019-06-06 06:03:31,901 [salt.state       :1813][INFO    ][21526] Executing state pkg.installed for [ipmitool]
2019-06-06 06:03:31,906 [salt.state       :300 ][INFO    ][21526] All specified packages are already installed
2019-06-06 06:03:31,906 [salt.state       :1951][INFO    ][21526] Completed state [ipmitool] at time 06:03:31.906693 duration_in_ms=5.874
2019-06-06 06:03:31,909 [salt.state       :1780][INFO    ][21526] Running state [/etc/maas/rackd.conf] at time 06:03:31.909291
2019-06-06 06:03:31,909 [salt.state       :1813][INFO    ][21526] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-06 06:03:31,910 [salt.state       :300 ][INFO    ][21526] No changes needed to be made
2019-06-06 06:03:31,910 [salt.state       :1951][INFO    ][21526] Completed state [/etc/maas/rackd.conf] at time 06:03:31.910610 duration_in_ms=1.319
2019-06-06 06:03:31,910 [salt.state       :1780][INFO    ][21526] Running state [/etc/maas/rackd.conf] at time 06:03:31.910804
2019-06-06 06:03:31,911 [salt.state       :1813][INFO    ][21526] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-06 06:03:31,911 [salt.loaded.int.states.file:2298][WARNING ][21526] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-06 06:03:31,911 [salt.state       :300 ][INFO    ][21526] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-06 06:03:31,911 [salt.state       :1951][INFO    ][21526] Completed state [/etc/maas/rackd.conf] at time 06:03:31.911848 duration_in_ms=1.043
2019-06-06 06:03:31,912 [salt.state       :1780][INFO    ][21526] Running state [maas-rackd] at time 06:03:31.912657
2019-06-06 06:03:31,912 [salt.state       :1813][INFO    ][21526] Executing state service.running for [maas-rackd]
2019-06-06 06:03:31,913 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21526] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-06 06:03:31,946 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21526] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-06 06:03:31,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21526] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-06 06:03:31,979 [salt.state       :300 ][INFO    ][21526] The service maas-rackd is already running
2019-06-06 06:03:31,980 [salt.state       :1951][INFO    ][21526] Completed state [maas-rackd] at time 06:03:31.979947 duration_in_ms=67.29
2019-06-06 06:03:31,981 [salt.minion      :1711][INFO    ][21526] Returning information for job: 20190606060330266616
2019-06-06 06:03:32,615 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command state.apply with jid 20190606060332601854
2019-06-06 06:03:32,637 [salt.minion      :1432][INFO    ][21552] Starting a new job with PID 21552
2019-06-06 06:03:33,396 [salt.state       :915 ][INFO    ][21552] Loading fresh modules for state activity
2019-06-06 06:03:34,069 [salt.state       :1780][INFO    ][21552] Running state [maas-region-controller] at time 06:03:34.069838
2019-06-06 06:03:34,070 [salt.state       :1813][INFO    ][21552] Executing state pkg.installed for [maas-region-controller]
2019-06-06 06:03:34,070 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21552] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-06 06:03:34,170 [salt.state       :300 ][INFO    ][21552] All specified packages are already installed
2019-06-06 06:03:34,170 [salt.state       :1951][INFO    ][21552] Completed state [maas-region-controller] at time 06:03:34.170579 duration_in_ms=100.74
2019-06-06 06:03:34,171 [salt.state       :1780][INFO    ][21552] Running state [python-oauth] at time 06:03:34.170981
2019-06-06 06:03:34,171 [salt.state       :1813][INFO    ][21552] Executing state pkg.installed for [python-oauth]
2019-06-06 06:03:34,179 [salt.state       :300 ][INFO    ][21552] All specified packages are already installed
2019-06-06 06:03:34,179 [salt.state       :1951][INFO    ][21552] Completed state [python-oauth] at time 06:03:34.179645 duration_in_ms=8.665
2019-06-06 06:03:34,183 [salt.state       :1780][INFO    ][21552] Running state [/etc/maas/regiond.conf] at time 06:03:34.183310
2019-06-06 06:03:34,183 [salt.state       :1813][INFO    ][21552] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-06 06:03:34,188 [salt.state       :300 ][INFO    ][21552] No changes needed to be made
2019-06-06 06:03:34,189 [salt.state       :1951][INFO    ][21552] Completed state [/etc/maas/regiond.conf] at time 06:03:34.189133 duration_in_ms=5.823
2019-06-06 06:03:34,189 [salt.state       :1780][INFO    ][21552] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:03:34.189653
2019-06-06 06:03:34,189 [salt.state       :1813][INFO    ][21552] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-06 06:03:34,238 [salt.state       :300 ][INFO    ][21552] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-06 06:03:34,238 [salt.state       :1951][INFO    ][21552] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:03:34.238783 duration_in_ms=49.13
2019-06-06 06:03:34,239 [salt.state       :1780][INFO    ][21552] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:03:34.239281
2019-06-06 06:03:34,239 [salt.state       :1813][INFO    ][21552] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-06 06:03:34,243 [salt.state       :300 ][INFO    ][21552] No changes needed to be made
2019-06-06 06:03:34,243 [salt.state       :1951][INFO    ][21552] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:03:34.243441 duration_in_ms=4.159
2019-06-06 06:03:34,244 [salt.state       :1780][INFO    ][21552] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:03:34.243981
2019-06-06 06:03:34,244 [salt.state       :1813][INFO    ][21552] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-06 06:03:34,247 [salt.state       :300 ][INFO    ][21552] No changes needed to be made
2019-06-06 06:03:34,247 [salt.state       :1951][INFO    ][21552] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:03:34.247921 duration_in_ms=3.94
2019-06-06 06:03:34,248 [salt.state       :1780][INFO    ][21552] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:03:34.248395
2019-06-06 06:03:34,248 [salt.state       :1813][INFO    ][21552] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-06 06:03:34,271 [salt.state       :300 ][INFO    ][21552] No changes needed to be made
2019-06-06 06:03:34,271 [salt.state       :1951][INFO    ][21552] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:03:34.271468 duration_in_ms=23.073
2019-06-06 06:03:34,271 [salt.state       :1780][INFO    ][21552] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:03:34.271934
2019-06-06 06:03:34,272 [salt.state       :1813][INFO    ][21552] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-06 06:03:34,283 [salt.state       :300 ][INFO    ][21552] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-06 06:03:34,283 [salt.state       :1951][INFO    ][21552] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:03:34.283701 duration_in_ms=11.767
2019-06-06 06:03:34,284 [salt.state       :1780][INFO    ][21552] Running state [a2enmod headers] at time 06:03:34.284869
2019-06-06 06:03:34,285 [salt.state       :1813][INFO    ][21552] Executing state cmd.run for [a2enmod headers]
2019-06-06 06:03:34,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21552] Executing command 'a2enmod headers' in directory '/root'
2019-06-06 06:03:34,352 [salt.state       :300 ][INFO    ][21552] {'pid': 21582, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-06 06:03:34,353 [salt.state       :1951][INFO    ][21552] Completed state [a2enmod headers] at time 06:03:34.353473 duration_in_ms=68.603
2019-06-06 06:03:34,354 [salt.state       :1780][INFO    ][21552] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:03:34.354084
2019-06-06 06:03:34,354 [salt.state       :1813][INFO    ][21552] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-06 06:03:34,373 [salt.state       :300 ][INFO    ][21552] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-06 06:03:34,373 [salt.state       :1951][INFO    ][21552] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:03:34.373847 duration_in_ms=19.763
2019-06-06 06:03:34,374 [salt.state       :1780][INFO    ][21552] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:03:34.374762
2019-06-06 06:03:34,375 [salt.state       :1813][INFO    ][21552] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-06 06:03:34,443 [salt.state       :300 ][INFO    ][21552] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-06 06:03:34,443 [salt.state       :1951][INFO    ][21552] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:03:34.443747 duration_in_ms=68.985
2019-06-06 06:03:34,444 [salt.state       :1780][INFO    ][21552] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:03:34.444577
2019-06-06 06:03:34,444 [salt.state       :1813][INFO    ][21552] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-06 06:03:34,497 [salt.state       :300 ][INFO    ][21552] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-06 06:03:34,497 [salt.state       :1951][INFO    ][21552] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:03:34.497926 duration_in_ms=53.349
2019-06-06 06:03:34,498 [salt.state       :1780][INFO    ][21552] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:03:34.498465
2019-06-06 06:03:34,498 [salt.state       :1813][INFO    ][21552] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-06 06:03:34,552 [salt.state       :300 ][INFO    ][21552] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-06 06:03:34,552 [salt.state       :1951][INFO    ][21552] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:03:34.552692 duration_in_ms=54.226
2019-06-06 06:03:34,553 [salt.state       :1780][INFO    ][21552] Running state [/root/.pgpass] at time 06:03:34.552993
2019-06-06 06:03:34,553 [salt.state       :1813][INFO    ][21552] Executing state file.managed for [/root/.pgpass]
2019-06-06 06:03:34,592 [salt.state       :300 ][INFO    ][21552] File /root/.pgpass is in the correct state
2019-06-06 06:03:34,592 [salt.state       :1951][INFO    ][21552] Completed state [/root/.pgpass] at time 06:03:34.592675 duration_in_ms=39.682
2019-06-06 06:03:34,598 [salt.state       :1780][INFO    ][21552] Running state [maas-region syncdb --noinput] at time 06:03:34.598053
2019-06-06 06:03:34,598 [salt.state       :1813][INFO    ][21552] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-06 06:03:34,599 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21552] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-06 06:03:36,506 [salt.state       :300 ][INFO    ][21552] {'pid': 21596, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, sites, auth, sessions, piston3, metadataserver, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-06 06:03:36,507 [salt.state       :1951][INFO    ][21552] Completed state [maas-region syncdb --noinput] at time 06:03:36.506999 duration_in_ms=1908.946
2019-06-06 06:03:36,507 [salt.state       :2022][WARNING ][21552] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-06 06:03:36,508 [salt.state       :1780][INFO    ][21552] Running state [maas-regiond] at time 06:03:36.508180
2019-06-06 06:03:36,508 [salt.state       :1813][INFO    ][21552] Executing state service.running for [maas-regiond]
2019-06-06 06:03:36,509 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21552] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-06 06:03:36,545 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21552] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-06 06:03:36,561 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21552] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-06 06:03:36,579 [salt.state       :300 ][INFO    ][21552] The service maas-regiond is already running
2019-06-06 06:03:36,580 [salt.state       :1951][INFO    ][21552] Completed state [maas-regiond] at time 06:03:36.579980 duration_in_ms=71.8
2019-06-06 06:03:36,581 [salt.state       :1780][INFO    ][21552] Running state [bind9] at time 06:03:36.581008
2019-06-06 06:03:36,581 [salt.state       :1813][INFO    ][21552] Executing state service.running for [bind9]
2019-06-06 06:03:36,581 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21552] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-06 06:03:36,600 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21552] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-06 06:03:36,616 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21552] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-06 06:03:36,631 [salt.state       :300 ][INFO    ][21552] The service bind9 is already running
2019-06-06 06:03:36,632 [salt.state       :1951][INFO    ][21552] Completed state [bind9] at time 06:03:36.632080 duration_in_ms=51.072
2019-06-06 06:03:36,633 [salt.state       :1780][INFO    ][21552] Running state [apache2] at time 06:03:36.633242
2019-06-06 06:03:36,633 [salt.state       :1813][INFO    ][21552] Executing state service.running for [apache2]
2019-06-06 06:03:36,634 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21552] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-06 06:03:36,650 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21552] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-06 06:03:36,666 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21552] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-06 06:03:36,686 [salt.state       :300 ][INFO    ][21552] The service apache2 is already running
2019-06-06 06:03:36,686 [salt.state       :1951][INFO    ][21552] Completed state [apache2] at time 06:03:36.686354 duration_in_ms=53.111
2019-06-06 06:03:36,687 [salt.state       :1780][INFO    ][21552] Running state [maasng.wait_for_http_code] at time 06:03:36.687410
2019-06-06 06:03:36,687 [salt.state       :1813][INFO    ][21552] Executing state module.run for [maasng.wait_for_http_code]
2019-06-06 06:03:36,688 [salt.utils.decorators:613 ][WARNING ][21552] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 06:03:36,841 [salt.state       :300 ][INFO    ][21552] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-06 06:03:36,841 [salt.state       :1951][INFO    ][21552] Completed state [maasng.wait_for_http_code] at time 06:03:36.841910 duration_in_ms=154.498
2019-06-06 06:03:36,843 [salt.state       :1780][INFO    ][21552] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:03:36.843134
2019-06-06 06:03:36,843 [salt.state       :1813][INFO    ][21552] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-06 06:03:36,844 [salt.state       :300 ][INFO    ][21552] /var/lib/maas/.setup_admin exists
2019-06-06 06:03:36,844 [salt.state       :1951][INFO    ][21552] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:03:36.844562 duration_in_ms=1.427
2019-06-06 06:03:36,845 [salt.state       :1780][INFO    ][21552] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:03:36.845523
2019-06-06 06:03:36,845 [salt.state       :1813][INFO    ][21552] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-06 06:03:36,846 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21552] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-06 06:03:38,305 [salt.state       :300 ][INFO    ][21552] {'pid': 21618, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-06 06:03:38,306 [salt.state       :1951][INFO    ][21552] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:03:38.305934 duration_in_ms=1460.409
2019-06-06 06:03:38,317 [salt.state       :1780][INFO    ][21552] Running state [maas_region_boot_source_resources_mirror] at time 06:03:38.316963
2019-06-06 06:03:38,317 [salt.state       :1813][INFO    ][21552] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-06 06:03:38,433 [salt.state       :300 ][INFO    ][21552] {'changes': {}}
2019-06-06 06:03:38,434 [salt.state       :1951][INFO    ][21552] Completed state [maas_region_boot_source_resources_mirror] at time 06:03:38.434240 duration_in_ms=117.276
2019-06-06 06:03:38,435 [salt.state       :1780][INFO    ][21552] Running state [maasng.boot_resources_import] at time 06:03:38.435421
2019-06-06 06:03:38,435 [salt.state       :1813][INFO    ][21552] Executing state module.run for [maasng.boot_resources_import]
2019-06-06 06:03:38,436 [salt.utils.decorators:613 ][WARNING ][21552] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 06:03:44,243 [salt.loaded.ext.module.maasng:1600][INFO    ][21552] Waiting boot-resources import done
sleep for:5s Left:894.0/900s
2019-06-06 06:03:47,670 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060347658175
2019-06-06 06:03:47,693 [salt.minion      :1432][INFO    ][21645] Starting a new job with PID 21645
2019-06-06 06:03:47,718 [salt.minion      :1711][INFO    ][21645] Returning information for job: 20190606060347658175
2019-06-06 06:03:49,309 [salt.loaded.ext.module.maasng:1600][INFO    ][21552] Waiting boot-resources import done
sleep for:5s Left:889.0/900s
2019-06-06 06:03:54,374 [salt.loaded.ext.module.maasng:1600][INFO    ][21552] Waiting boot-resources import done
sleep for:5s Left:884.0/900s
2019-06-06 06:03:59,482 [salt.state       :300 ][INFO    ][21552] {'ret': True}
2019-06-06 06:03:59,483 [salt.state       :1951][INFO    ][21552] Completed state [maasng.boot_resources_import] at time 06:03:59.483422 duration_in_ms=21048.0
2019-06-06 06:03:59,484 [salt.state       :1780][INFO    ][21552] Running state [maas_region_boot_sources_selection_xenial] at time 06:03:59.484682
2019-06-06 06:03:59,485 [salt.state       :1813][INFO    ][21552] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-06 06:03:59,698 [salt.state       :300 ][INFO    ][21552] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-06 06:03:59,698 [salt.state       :1951][INFO    ][21552] Completed state [maas_region_boot_sources_selection_xenial] at time 06:03:59.698551 duration_in_ms=213.868
2019-06-06 06:03:59,700 [salt.state       :1780][INFO    ][21552] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:03:59.699965
2019-06-06 06:03:59,700 [salt.state       :1813][INFO    ][21552] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-06 06:03:59,701 [salt.utils.decorators:613 ][WARNING ][21552] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 06:03:59,701 [salt.loaded.ext.module.maasng:1771][INFO    ][21552] boot-sources sync initiated for ALL Rack's
2019-06-06 06:04:00,823 [salt.state       :300 ][INFO    ][21552] {'ret': True}
2019-06-06 06:04:00,824 [salt.state       :1951][INFO    ][21552] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:04:00.824573 duration_in_ms=1124.606
2019-06-06 06:04:00,827 [salt.state       :1780][INFO    ][21552] Running state [maas.process_maas_config] at time 06:04:00.827460
2019-06-06 06:04:00,828 [salt.state       :1813][INFO    ][21552] Executing state module.run for [maas.process_maas_config]
2019-06-06 06:04:00,828 [salt.utils.decorators:613 ][WARNING ][21552] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 06:04:00,829 [salt.loaded.ext.module.maas:92  ][INFO    ][21552] maasconfig name=enable_http_proxy value=True
2019-06-06 06:04:00,891 [salt.loaded.ext.module.maas:92  ][INFO    ][21552] maasconfig name=upstream_dns value=8.8.8.8
2019-06-06 06:04:00,956 [salt.loaded.ext.module.maas:92  ][INFO    ][21552] maasconfig name=commissioning_distro_series value=xenial
2019-06-06 06:04:01,021 [salt.loaded.ext.module.maas:92  ][INFO    ][21552] maasconfig name=default_osystem value=ubuntu
2019-06-06 06:04:01,081 [salt.loaded.ext.module.maas:92  ][INFO    ][21552] maasconfig name=active_discovery_interval value=600
2019-06-06 06:04:01,135 [salt.loaded.ext.module.maas:92  ][INFO    ][21552] maasconfig name=dnssec_validation value=no
2019-06-06 06:04:01,195 [salt.loaded.ext.module.maas:92  ][INFO    ][21552] maasconfig name=maas_name value=mas01
2019-06-06 06:04:01,255 [salt.loaded.ext.module.maas:92  ][INFO    ][21552] maasconfig name=network_discovery value=enabled
2019-06-06 06:04:01,381 [salt.loaded.ext.module.maas:92  ][INFO    ][21552] maasconfig name=enable_third_party_drivers value=True
2019-06-06 06:04:09,505 [salt.loaded.ext.module.maas:92  ][INFO    ][21552] maasconfig name=default_storage_layout value=lvm
2019-06-06 06:04:09,589 [salt.loaded.ext.module.maas:92  ][INFO    ][21552] maasconfig name=ntp_external_only value=True
2019-06-06 06:04:09,647 [salt.loaded.ext.module.maas:92  ][INFO    ][21552] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-06 06:04:09,719 [salt.loaded.ext.module.maas:92  ][INFO    ][21552] maasconfig name=default_distro_series value=xenial
2019-06-06 06:04:09,783 [salt.loaded.ext.module.maas:92  ][INFO    ][21552] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-06 06:04:09,915 [salt.state       :300 ][INFO    ][21552] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-06-06 06:04:09,916 [salt.state       :1951][INFO    ][21552] Completed state [maas.process_maas_config] at time 06:04:09.915928 duration_in_ms=9088.468
2019-06-06 06:04:09,916 [salt.state       :1780][INFO    ][21552] Running state [pxe_admin] at time 06:04:09.916658
2019-06-06 06:04:09,917 [salt.state       :1813][INFO    ][21552] Executing state maasng.fabric_present for [pxe_admin]
2019-06-06 06:04:09,979 [salt.loaded.ext.module.maasng:945 ][INFO    ][21552] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xf86x3', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-06-06 06:04:10,046 [salt.loaded.ext.module.maasng:1008][WARNING ][21552] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-06 06:04:10,046 [salt.loaded.ext.module.maasng:1011][WARNING ][21552] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-06 06:04:10,111 [salt.state       :300 ][INFO    ][21552] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-06 06:04:10,112 [salt.state       :1951][INFO    ][21552] Completed state [pxe_admin] at time 06:04:10.112083 duration_in_ms=195.423
2019-06-06 06:04:10,112 [salt.state       :1780][INFO    ][21552] Running state [vlan 0] at time 06:04:10.112554
2019-06-06 06:04:10,113 [salt.state       :1813][INFO    ][21552] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-06 06:04:10,183 [salt.loaded.ext.module.maasng:945 ][INFO    ][21552] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xf86x3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-06 06:04:10,310 [salt.loaded.ext.module.maasng:945 ][INFO    ][21552] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xf86x3', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin', u'class_type': u''}]
2019-06-06 06:04:10,585 [salt.loaded.ext.module.maasng:945 ][INFO    ][21552] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xf86x3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-06 06:04:10,701 [salt.state       :300 ][INFO    ][21552] {'new': 'Vlan untagged was updated'}
2019-06-06 06:04:10,702 [salt.state       :1951][INFO    ][21552] Completed state [vlan 0] at time 06:04:10.702223 duration_in_ms=589.668
2019-06-06 06:04:10,703 [salt.state       :1780][INFO    ][21552] Running state [192.168.11.0/24] at time 06:04:10.703845
2019-06-06 06:04:10,704 [salt.state       :1813][INFO    ][21552] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-06 06:04:10,934 [salt.loaded.ext.module.maasng:945 ][INFO    ][21552] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xf86x3', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-06-06 06:04:10,935 [salt.loaded.ext.module.maasng:1235][WARNING ][21552] Ignoring parameter vlan:0
2019-06-06 06:04:11,018 [salt.state       :300 ][INFO    ][21552] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-06 06:04:11,018 [salt.state       :1951][INFO    ][21552] Completed state [192.168.11.0/24] at time 06:04:11.018379 duration_in_ms=314.533
2019-06-06 06:04:11,019 [salt.state       :1780][INFO    ][21552] Running state [maas_create_iprange_1] at time 06:04:11.019761
2019-06-06 06:04:11,020 [salt.state       :1813][INFO    ][21552] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-06 06:04:11,082 [salt.state       :300 ][INFO    ][21552] Iprange maas_create_iprange_1 already exist.
2019-06-06 06:04:11,082 [salt.state       :1951][INFO    ][21552] Completed state [maas_create_iprange_1] at time 06:04:11.082641 duration_in_ms=62.879
2019-06-06 06:04:11,083 [salt.state       :1780][INFO    ][21552] Running state [vlan 0] at time 06:04:11.083093
2019-06-06 06:04:11,083 [salt.state       :1813][INFO    ][21552] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-06 06:04:11,135 [salt.loaded.ext.module.maasng:945 ][INFO    ][21552] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xf86x3', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-06-06 06:04:11,255 [salt.loaded.ext.module.maasng:945 ][INFO    ][21552] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xf86x3', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-06-06 06:04:11,514 [salt.loaded.ext.module.maasng:945 ][INFO    ][21552] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xf86x3', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-06-06 06:04:11,598 [salt.state       :300 ][INFO    ][21552] {'new': 'Vlan untagged was updated'}
2019-06-06 06:04:11,599 [salt.state       :1951][INFO    ][21552] Completed state [vlan 0] at time 06:04:11.599304 duration_in_ms=516.207
2019-06-06 06:04:11,600 [salt.state       :1780][INFO    ][21552] Running state [opnfv] at time 06:04:11.600299
2019-06-06 06:04:11,600 [salt.state       :1813][INFO    ][21552] Executing state maasng.sshkey_present for [opnfv]
2019-06-06 06:04:11,652 [salt.loaded.ext.module.maasng:1903][INFO    ][21552] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-06-06 06:04:11,652 [salt.state       :300 ][INFO    ][21552] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-06-06 06:04:11,652 [salt.state       :1951][INFO    ][21552] Completed state [opnfv] at time 06:04:11.652703 duration_in_ms=52.405
2019-06-06 06:04:11,653 [salt.state       :1780][INFO    ][21552] Running state [maas.process_tags] at time 06:04:11.653409
2019-06-06 06:04:11,653 [salt.state       :1813][INFO    ][21552] Executing state module.run for [maas.process_tags]
2019-06-06 06:04:11,654 [salt.utils.decorators:613 ][WARNING ][21552] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 06:04:11,698 [salt.loaded.ext.module.maas:92  ][INFO    ][21552] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-06-06 06:04:11,773 [salt.state       :300 ][INFO    ][21552] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-06-06 06:04:11,773 [salt.state       :1951][INFO    ][21552] Completed state [maas.process_tags] at time 06:04:11.773815 duration_in_ms=120.404
2019-06-06 06:04:11,778 [salt.minion      :1711][INFO    ][21552] Returning information for job: 20190606060332601854
2019-06-06 06:04:12,295 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command state.apply with jid 20190606060412284908
2019-06-06 06:04:12,310 [salt.minion      :1432][INFO    ][22042] Starting a new job with PID 22042
2019-06-06 06:04:16,040 [salt.state       :915 ][INFO    ][22042] Loading fresh modules for state activity
2019-06-06 06:04:16,136 [salt.state       :1780][INFO    ][22042] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:04:16.136337
2019-06-06 06:04:16,136 [salt.state       :1813][INFO    ][22042] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-06 06:04:16,138 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22042] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-06 06:04:17,564 [salt.state       :300 ][INFO    ][22042] {'pid': 22066, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-06 06:04:17,565 [salt.state       :1951][INFO    ][22042] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:04:17.565794 duration_in_ms=1429.457
2019-06-06 06:04:17,568 [salt.state       :1780][INFO    ][22042] Running state [maas.process_machines] at time 06:04:17.568467
2019-06-06 06:04:17,569 [salt.state       :1813][INFO    ][22042] Executing state module.run for [maas.process_machines]
2019-06-06 06:04:17,570 [salt.utils.decorators:613 ][WARNING ][22042] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 06:04:18,310 [salt.loaded.ext.module.maas:412 ][WARNING ][22042] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-06 06:04:18,311 [salt.loaded.ext.module.maas:92  ][INFO    ][22042] machine hostname=cmp002 power_type=ipmi mac_addresses=['00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=bp74s6 architecture=amd64/generic power_parameters_power_user=admin
2019-06-06 06:04:19,610 [salt.loaded.ext.module.maas:412 ][WARNING ][22042] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-06 06:04:19,611 [salt.loaded.ext.module.maas:92  ][INFO    ][22042] machine hostname=cmp001 power_type=ipmi mac_addresses=['00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=4n7ar4 architecture=amd64/generic power_parameters_power_user=admin
2019-06-06 06:04:20,903 [salt.loaded.ext.module.maas:412 ][WARNING ][22042] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-06 06:04:20,904 [salt.loaded.ext.module.maas:92  ][INFO    ][22042] machine hostname=kvm01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=kn7mtd architecture=amd64/generic power_parameters_power_user=admin
2019-06-06 06:04:22,220 [salt.loaded.ext.module.maas:412 ][WARNING ][22042] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-06 06:04:22,220 [salt.loaded.ext.module.maas:92  ][INFO    ][22042] machine hostname=kvm03 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=gy8ed3 architecture=amd64/generic power_parameters_power_user=admin
2019-06-06 06:04:23,400 [salt.loaded.ext.module.maas:412 ][WARNING ][22042] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-06 06:04:23,401 [salt.loaded.ext.module.maas:92  ][INFO    ][22042] machine hostname=kvm02 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=gf8rs6 architecture=amd64/generic power_parameters_power_user=admin
2019-06-06 06:04:24,412 [salt.state       :300 ][INFO    ][22042] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-06 06:04:24,413 [salt.state       :1951][INFO    ][22042] Completed state [maas.process_machines] at time 06:04:24.413125 duration_in_ms=6844.658
2019-06-06 06:04:24,416 [salt.minion      :1711][INFO    ][22042] Returning information for job: 20190606060412284908
2019-06-06 06:04:57,762 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command state.apply with jid 20190606060457751315
2019-06-06 06:04:57,785 [salt.minion      :1432][INFO    ][22327] Starting a new job with PID 22327
2019-06-06 06:05:01,583 [salt.state       :915 ][INFO    ][22327] Loading fresh modules for state activity
2019-06-06 06:05:01,672 [salt.state       :1780][INFO    ][22327] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:05:01.672148
2019-06-06 06:05:01,672 [salt.state       :1813][INFO    ][22327] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-06 06:05:01,674 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22327] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-06 06:05:03,267 [salt.state       :300 ][INFO    ][22327] {'pid': 22335, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-06 06:05:03,268 [salt.state       :1951][INFO    ][22327] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:05:03.268499 duration_in_ms=1596.35
2019-06-06 06:05:03,271 [salt.state       :1780][INFO    ][22327] Running state [maas.wait_for_machine_status] at time 06:05:03.271870
2019-06-06 06:05:03,272 [salt.state       :1813][INFO    ][22327] Executing state module.run for [maas.wait_for_machine_status]
2019-06-06 06:05:03,273 [salt.utils.decorators:613 ][WARNING ][22327] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 06:05:06,562 [salt.loaded.ext.module.maas:993 ][INFO    ][22327] Machine gf8rs6 mark broken
2019-06-06 06:05:07,175 [salt.loaded.ext.module.maas:996 ][INFO    ][22327] Machine gf8rs6 mark fixed
2019-06-06 06:05:08,413 [salt.loaded.ext.module.maas:684 ][INFO    ][22327] deploymachines hwe_kernel=hwe-16.04 system_id=gf8rs6 distro_series=xenial
2019-06-06 06:05:11,176 [salt.loaded.ext.module.maas:1023][INFO    ][22327] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1492.10791302s left)
2019-06-06 06:05:12,867 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060512855829
2019-06-06 06:05:12,892 [salt.minion      :1432][INFO    ][22414] Starting a new job with PID 22414
2019-06-06 06:05:12,914 [salt.minion      :1711][INFO    ][22414] Returning information for job: 20190606060512855829
2019-06-06 06:05:42,955 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060542897673
2019-06-06 06:05:42,972 [salt.minion      :1432][INFO    ][22463] Starting a new job with PID 22463
2019-06-06 06:05:42,987 [salt.minion      :1711][INFO    ][22463] Returning information for job: 20190606060542897673
2019-06-06 06:05:44,120 [salt.loaded.ext.module.maas:1023][INFO    ][22327] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1459.16376305s left)
2019-06-06 06:06:12,987 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060612974655
2019-06-06 06:06:13,009 [salt.minion      :1432][INFO    ][22492] Starting a new job with PID 22492
2019-06-06 06:06:13,034 [salt.minion      :1711][INFO    ][22492] Returning information for job: 20190606060612974655
2019-06-06 06:06:17,700 [salt.loaded.ext.module.maas:1023][INFO    ][22327] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1425.58365321s left)
2019-06-06 06:06:43,035 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060643022549
2019-06-06 06:06:43,058 [salt.minion      :1432][INFO    ][22531] Starting a new job with PID 22531
2019-06-06 06:06:43,084 [salt.minion      :1711][INFO    ][22531] Returning information for job: 20190606060643022549
2019-06-06 06:06:51,201 [salt.loaded.ext.module.maas:1023][INFO    ][22327] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1392.08215117s left)
2019-06-06 06:07:13,101 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060713087735
2019-06-06 06:07:13,124 [salt.minion      :1432][INFO    ][22616] Starting a new job with PID 22616
2019-06-06 06:07:13,151 [salt.minion      :1711][INFO    ][22616] Returning information for job: 20190606060713087735
2019-06-06 06:07:24,729 [salt.loaded.ext.module.maas:1023][INFO    ][22327] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1358.55450106s left)
2019-06-06 06:07:43,164 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060743151409
2019-06-06 06:07:43,186 [salt.minion      :1432][INFO    ][22775] Starting a new job with PID 22775
2019-06-06 06:07:43,211 [salt.minion      :1711][INFO    ][22775] Returning information for job: 20190606060743151409
2019-06-06 06:07:57,984 [salt.loaded.ext.module.maas:1023][INFO    ][22327] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1325.29955411s left)
2019-06-06 06:08:13,224 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060813211953
2019-06-06 06:08:13,247 [salt.minion      :1432][INFO    ][22836] Starting a new job with PID 22836
2019-06-06 06:08:13,272 [salt.minion      :1711][INFO    ][22836] Returning information for job: 20190606060813211953
2019-06-06 06:08:31,475 [salt.loaded.ext.module.maas:1023][INFO    ][22327] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1291.80842614s left)
2019-06-06 06:08:43,289 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060843276775
2019-06-06 06:08:43,312 [salt.minion      :1432][INFO    ][22909] Starting a new job with PID 22909
2019-06-06 06:08:43,339 [salt.minion      :1711][INFO    ][22909] Returning information for job: 20190606060843276775
2019-06-06 06:09:05,066 [salt.loaded.ext.module.maas:1023][INFO    ][22327] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1258.21782804s left)
2019-06-06 06:09:13,362 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060913349462
2019-06-06 06:09:13,385 [salt.minion      :1432][INFO    ][22954] Starting a new job with PID 22954
2019-06-06 06:09:13,412 [salt.minion      :1711][INFO    ][22954] Returning information for job: 20190606060913349462
2019-06-06 06:09:38,607 [salt.loaded.ext.module.maas:1023][INFO    ][22327] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1224.67610312s left)
2019-06-06 06:09:43,437 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606060943424097
2019-06-06 06:09:43,456 [salt.minion      :1432][INFO    ][23061] Starting a new job with PID 23061
2019-06-06 06:09:43,479 [salt.minion      :1711][INFO    ][23061] Returning information for job: 20190606060943424097
2019-06-06 06:10:12,222 [salt.loaded.ext.module.maas:1023][INFO    ][22327] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1191.06155801s left)
2019-06-06 06:10:13,507 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606061013494382
2019-06-06 06:10:13,529 [salt.minion      :1432][INFO    ][23125] Starting a new job with PID 23125
2019-06-06 06:10:13,555 [salt.minion      :1711][INFO    ][23125] Returning information for job: 20190606061013494382
2019-06-06 06:10:43,588 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606061043575042
2019-06-06 06:10:43,610 [salt.minion      :1432][INFO    ][23241] Starting a new job with PID 23241
2019-06-06 06:10:43,636 [salt.minion      :1711][INFO    ][23241] Returning information for job: 20190606061043575042
2019-06-06 06:10:45,851 [salt.loaded.ext.module.maas:1023][INFO    ][22327] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1157.43279099s left)
2019-06-06 06:11:13,675 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606061113662282
2019-06-06 06:11:13,698 [salt.minion      :1432][INFO    ][23289] Starting a new job with PID 23289
2019-06-06 06:11:13,726 [salt.minion      :1711][INFO    ][23289] Returning information for job: 20190606061113662282
2019-06-06 06:11:19,336 [salt.loaded.ext.module.maas:1023][INFO    ][22327] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1123.94759107s left)
2019-06-06 06:11:43,768 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606061143755668
2019-06-06 06:11:43,791 [salt.minion      :1432][INFO    ][23334] Starting a new job with PID 23334
2019-06-06 06:11:43,819 [salt.minion      :1711][INFO    ][23334] Returning information for job: 20190606061143755668
2019-06-06 06:11:52,952 [salt.loaded.ext.module.maas:1023][INFO    ][22327] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1090.33202815s left)
2019-06-06 06:12:13,866 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command saltutil.find_job with jid 20190606061213854308
2019-06-06 06:12:13,886 [salt.minion      :1432][INFO    ][23393] Starting a new job with PID 23393
2019-06-06 06:12:13,911 [salt.minion      :1711][INFO    ][23393] Returning information for job: 20190606061213854308
2019-06-06 06:12:26,412 [salt.state       :300 ][INFO    ][22327] {'ret': True}
2019-06-06 06:12:26,413 [salt.state       :1951][INFO    ][22327] Completed state [maas.wait_for_machine_status] at time 06:12:26.413089 duration_in_ms=443141.217
2019-06-06 06:12:26,417 [salt.minion      :1711][INFO    ][22327] Returning information for job: 20190606060457751315
2019-06-06 06:12:27,076 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command state.apply with jid 20190606061227062851
2019-06-06 06:12:27,098 [salt.minion      :1432][INFO    ][23423] Starting a new job with PID 23423
2019-06-06 06:12:30,827 [salt.state       :915 ][INFO    ][23423] Loading fresh modules for state activity
2019-06-06 06:12:30,956 [salt.state       :1780][INFO    ][23423] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:12:30.956826
2019-06-06 06:12:30,957 [salt.state       :1813][INFO    ][23423] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-06 06:12:30,958 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23423] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-06 06:12:32,515 [salt.state       :300 ][INFO    ][23423] {'pid': 23448, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-06 06:12:32,516 [salt.state       :1951][INFO    ][23423] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:12:32.516122 duration_in_ms=1559.295
2019-06-06 06:12:32,519 [salt.state       :1780][INFO    ][23423] Running state [maas_machines_storage_cmp002_lvm] at time 06:12:32.519570
2019-06-06 06:12:32,520 [salt.state       :1813][INFO    ][23423] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-06 06:12:33,243 [salt.state       :300 ][INFO    ][23423] Machine cmp002 is not in Ready state.
2019-06-06 06:12:33,244 [salt.state       :1951][INFO    ][23423] Completed state [maas_machines_storage_cmp002_lvm] at time 06:12:33.243934 duration_in_ms=724.363
2019-06-06 06:12:33,244 [salt.state       :1780][INFO    ][23423] Running state [maas_machines_storage_cmp001_lvm] at time 06:12:33.244510
2019-06-06 06:12:33,245 [salt.state       :1813][INFO    ][23423] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-06 06:12:33,854 [salt.state       :300 ][INFO    ][23423] Machine cmp001 is not in Ready state.
2019-06-06 06:12:33,855 [salt.state       :1951][INFO    ][23423] Completed state [maas_machines_storage_cmp001_lvm] at time 06:12:33.855143 duration_in_ms=610.632
2019-06-06 06:12:33,859 [salt.minion      :1711][INFO    ][23423] Returning information for job: 20190606061227062851
2019-06-06 06:12:34,464 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command state.apply with jid 20190606061234452067
2019-06-06 06:12:34,483 [salt.minion      :1432][INFO    ][23507] Starting a new job with PID 23507
2019-06-06 06:12:35,224 [salt.state       :915 ][INFO    ][23507] Loading fresh modules for state activity
2019-06-06 06:12:35,311 [salt.state       :1780][INFO    ][23507] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:12:35.311742
2019-06-06 06:12:35,312 [salt.state       :1813][INFO    ][23507] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-06 06:12:35,314 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23507] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-06 06:12:36,901 [salt.state       :300 ][INFO    ][23507] {'pid': 23518, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-06 06:12:36,902 [salt.state       :1951][INFO    ][23507] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:12:36.902136 duration_in_ms=1590.393
2019-06-06 06:12:36,904 [salt.state       :1780][INFO    ][23507] Running state [maas.deploy_machines] at time 06:12:36.904893
2019-06-06 06:12:36,905 [salt.state       :1813][INFO    ][23507] Executing state module.run for [maas.deploy_machines]
2019-06-06 06:12:36,906 [salt.utils.decorators:613 ][WARNING ][23507] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 06:12:37,635 [salt.state       :300 ][INFO    ][23507] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-06 06:12:37,635 [salt.state       :1951][INFO    ][23507] Completed state [maas.deploy_machines] at time 06:12:37.635633 duration_in_ms=730.74
2019-06-06 06:12:37,639 [salt.minion      :1711][INFO    ][23507] Returning information for job: 20190606061234452067
2019-06-06 06:12:38,273 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command state.apply with jid 20190606061238259735
2019-06-06 06:12:38,295 [salt.minion      :1432][INFO    ][23527] Starting a new job with PID 23527
2019-06-06 06:12:39,027 [salt.state       :915 ][INFO    ][23527] Loading fresh modules for state activity
2019-06-06 06:12:39,116 [salt.state       :1780][INFO    ][23527] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:12:39.116045
2019-06-06 06:12:39,116 [salt.state       :1813][INFO    ][23527] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-06 06:12:39,118 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23527] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-06 06:12:40,576 [salt.state       :300 ][INFO    ][23527] {'pid': 23534, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-06 06:12:40,577 [salt.state       :1951][INFO    ][23527] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:12:40.577040 duration_in_ms=1460.996
2019-06-06 06:12:40,578 [salt.state       :1780][INFO    ][23527] Running state [maas.wait_for_machine_status] at time 06:12:40.578580
2019-06-06 06:12:40,578 [salt.state       :1813][INFO    ][23527] Executing state module.run for [maas.wait_for_machine_status]
2019-06-06 06:12:40,579 [salt.utils.decorators:613 ][WARNING ][23527] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-06 06:12:43,897 [salt.state       :300 ][INFO    ][23527] {'ret': True}
2019-06-06 06:12:43,898 [salt.state       :1951][INFO    ][23527] Completed state [maas.wait_for_machine_status] at time 06:12:43.898349 duration_in_ms=3319.766
2019-06-06 06:12:43,902 [salt.minion      :1711][INFO    ][23527] Returning information for job: 20190606061238259735
2019-06-06 06:42:58,403 [salt.utils.schedule:1377][INFO    ][15300] Running scheduled job: __mine_interval
2019-06-06 07:42:58,403 [salt.utils.schedule:1377][INFO    ][15300] Running scheduled job: __mine_interval
2019-06-06 07:49:32,032 [salt.minion      :1308][INFO    ][15300] User sudo_ubuntu Executing command cp.push_dir with jid 20190606074932019816
2019-06-06 07:49:32,054 [salt.minion      :1432][INFO    ][30417] Starting a new job with PID 30417
