2019-03-01 05:08:30,905 [salt.utils.decorators:613 ][WARNING ][2047] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:08:31,362 [salt.utils.decorators:613 ][WARNING ][2047] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:08:33,385 [salt.loaded.int.states.file:2298][WARNING ][2366] 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-03-01 05:09:05,918 [salt.state       :2022][WARNING ][3162] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-01 05:09:08,388 [salt.utils.decorators:613 ][WARNING ][3162] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:09:51,463 [salt.utils.decorators:613 ][WARNING ][3162] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:10:25,757 [salt.utils.decorators:613 ][WARNING ][3162] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:10:26,757 [salt.utils.decorators:613 ][WARNING ][3162] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:10:30,348 [salt.loaded.ext.module.maasng:1008][WARNING ][3162] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-03-01 05:10:30,349 [salt.loaded.ext.module.maasng:1011][WARNING ][3162] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-03-01 05:10:30,995 [salt.loaded.ext.module.maasng:1235][WARNING ][3162] Ignoring parameter vlan:0
2019-03-01 05:10:32,096 [salt.utils.decorators:613 ][WARNING ][3162] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:10:33,866 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7185] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-01 05:10:33,897 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7185] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-01 05:10:33,918 [salt.utils.parsers:1051][WARNING ][364] Minion received a SIGTERM. Exiting.
2019-03-01 05:10:34,899 [salt.cli.daemons :293 ][INFO    ][7252] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-03-01 05:10:35,031 [salt.cli.daemons :82  ][INFO    ][7252] Starting up the Salt Minion
2019-03-01 05:10:35,032 [salt.utils.event :1017][INFO    ][7252] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-03-01 05:10:35,784 [salt.minion      :976 ][INFO    ][7252] Creating minion process manager
2019-03-01 05:10:37,059 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][7252] Executing command ['date', '+%z'] in directory '/root'
2019-03-01 05:10:37,124 [salt.utils.schedule:568 ][INFO    ][7252] Updating job settings for scheduled job: __mine_interval
2019-03-01 05:10:37,126 [salt.minion      :1108][INFO    ][7252] Added mine.update to scheduler
2019-03-01 05:10:37,132 [salt.minion      :1975][INFO    ][7252] Minion is starting as user 'root'
2019-03-01 05:10:37,147 [salt.minion      :2336][INFO    ][7252] Minion is ready to receive requests!
2019-03-01 05:10:37,321 [salt.utils.decorators:613 ][WARNING ][7190] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:10:37,387 [salt.loaded.ext.module.maas:412 ][WARNING ][7190] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 05:10:39,029 [salt.loaded.ext.module.maas:412 ][WARNING ][7190] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 05:10:40,029 [salt.loaded.ext.module.maas:412 ][WARNING ][7190] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 05:10:41,442 [salt.loaded.ext.module.maas:412 ][WARNING ][7190] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 05:10:44,399 [salt.loaded.ext.module.maas:412 ][WARNING ][7190] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 05:11:16,721 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command state.apply with jid 20190301051116708395
2019-03-01 05:11:16,741 [salt.minion      :1432][INFO    ][7656] Starting a new job with PID 7656
2019-03-01 05:11:20,238 [salt.state       :915 ][INFO    ][7656] Loading fresh modules for state activity
2019-03-01 05:11:20,293 [salt.fileclient  :1219][INFO    ][7656] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-01 05:11:20,335 [salt.state       :1780][INFO    ][7656] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:11:20.335572
2019-03-01 05:11:20,335 [salt.state       :1813][INFO    ][7656] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 05:11:20,337 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7656] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 05:11:21,804 [salt.state       :300 ][INFO    ][7656] {'pid': 7663, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 05:11:21,804 [salt.state       :1951][INFO    ][7656] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:11:21.804495 duration_in_ms=1468.924
2019-03-01 05:11:21,805 [salt.state       :1780][INFO    ][7656] Running state [maas.wait_for_machine_status] at time 05:11:21.805694
2019-03-01 05:11:21,805 [salt.state       :1813][INFO    ][7656] Executing state module.run for [maas.wait_for_machine_status]
2019-03-01 05:11:21,806 [salt.utils.decorators:613 ][WARNING ][7656] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:11:22,798 [salt.loaded.ext.module.maas:1023][INFO    ][7656] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.01239896s left)
2019-03-01 05:11:31,749 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051131734050
2019-03-01 05:11:31,770 [salt.minion      :1432][INFO    ][7693] Starting a new job with PID 7693
2019-03-01 05:11:31,794 [salt.minion      :1711][INFO    ][7693] Returning information for job: 20190301051131734050
2019-03-01 05:11:53,753 [salt.loaded.ext.module.maas:1023][INFO    ][7656] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.05745006s left)
2019-03-01 05:12:01,789 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051201776669
2019-03-01 05:12:01,811 [salt.minion      :1432][INFO    ][7721] Starting a new job with PID 7721
2019-03-01 05:12:01,837 [salt.minion      :1711][INFO    ][7721] Returning information for job: 20190301051201776669
2019-03-01 05:12:24,840 [salt.loaded.ext.module.maas:1023][INFO    ][7656] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.97102308s left)
2019-03-01 05:12:31,897 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051231881688
2019-03-01 05:12:31,917 [salt.minion      :1432][INFO    ][7871] Starting a new job with PID 7871
2019-03-01 05:12:31,940 [salt.minion      :1711][INFO    ][7871] Returning information for job: 20190301051231881688
2019-03-01 05:12:56,199 [salt.loaded.ext.module.maas:1023][INFO    ][7656] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.61128807s left)
2019-03-01 05:13:01,943 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051301931116
2019-03-01 05:13:01,966 [salt.minion      :1432][INFO    ][7990] Starting a new job with PID 7990
2019-03-01 05:13:01,991 [salt.minion      :1711][INFO    ][7990] Returning information for job: 20190301051301931116
2019-03-01 05:13:27,606 [salt.loaded.ext.module.maas:1023][INFO    ][7656] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.20442796s left)
2019-03-01 05:13:32,007 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051331992024
2019-03-01 05:13:32,026 [salt.minion      :1432][INFO    ][8405] Starting a new job with PID 8405
2019-03-01 05:13:32,050 [salt.minion      :1711][INFO    ][8405] Returning information for job: 20190301051331992024
2019-03-01 05:13:59,274 [salt.loaded.ext.module.maas:1023][INFO    ][7656] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.53685498s left)
2019-03-01 05:14:02,073 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051402056775
2019-03-01 05:14:02,095 [salt.minion      :1432][INFO    ][8562] Starting a new job with PID 8562
2019-03-01 05:14:02,118 [salt.minion      :1711][INFO    ][8562] Returning information for job: 20190301051402056775
2019-03-01 05:14:30,774 [salt.loaded.ext.module.maas:1023][INFO    ][7656] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1311.03669214s left)
2019-03-01 05:14:32,137 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051432120844
2019-03-01 05:14:32,158 [salt.minion      :1432][INFO    ][8825] Starting a new job with PID 8825
2019-03-01 05:14:32,182 [salt.minion      :1711][INFO    ][8825] Returning information for job: 20190301051432120844
2019-03-01 05:15:02,211 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051502195720
2019-03-01 05:15:02,234 [salt.minion      :1432][INFO    ][8995] Starting a new job with PID 8995
2019-03-01 05:15:02,258 [salt.minion      :1711][INFO    ][8995] Returning information for job: 20190301051502195720
2019-03-01 05:15:04,269 [salt.state       :300 ][INFO    ][7656] {'ret': True}
2019-03-01 05:15:04,270 [salt.state       :1951][INFO    ][7656] Completed state [maas.wait_for_machine_status] at time 05:15:04.270037 duration_in_ms=222464.341
2019-03-01 05:15:04,273 [salt.minion      :1711][INFO    ][7656] Returning information for job: 20190301051116708395
2019-03-01 05:15:04,830 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command state.apply with jid 20190301051504817786
2019-03-01 05:15:04,851 [salt.minion      :1432][INFO    ][9005] Starting a new job with PID 9005
2019-03-01 05:15:08,227 [salt.state       :915 ][INFO    ][9005] Loading fresh modules for state activity
2019-03-01 05:15:08,253 [salt.fileclient  :1219][INFO    ][9005] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-01 05:15:08,307 [salt.state       :1780][INFO    ][9005] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:08.307921
2019-03-01 05:15:08,308 [salt.state       :1813][INFO    ][9005] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 05:15:08,309 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9005] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 05:15:09,608 [salt.state       :300 ][INFO    ][9005] {'pid': 9073, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 05:15:09,608 [salt.state       :1951][INFO    ][9005] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:09.608803 duration_in_ms=1300.881
2019-03-01 05:15:09,612 [salt.state       :1780][INFO    ][9005] Running state [maas_machines_storage_cmp002_lvm] at time 05:15:09.611972
2019-03-01 05:15:09,612 [salt.state       :1813][INFO    ][9005] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-01 05:15:11,110 [salt.loaded.ext.module.maasng:610 ][INFO    ][9005] ttwhfp
2019-03-01 05:15:11,110 [salt.loaded.ext.module.maasng:626 ][INFO    ][9005] sda
2019-03-01 05:15:11,827 [salt.loaded.ext.module.maasng:361 ][INFO    ][9005] ttwhfp
2019-03-01 05:15:11,941 [salt.loaded.ext.module.maasng:367 ][INFO    ][9005] [{u'size': 2397998940160, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 1, u'partitions': [{u'uuid': u'50efad4d-4339-43ad-8eef-85ef00204735', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ttwhfp', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'7161fb56-8c4c-46e9-a74a-9c86625bf46f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/ttwhfp/blockdevices/1/partition/1'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ttwhfp', 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'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/ttwhfp/blockdevices/1/'}, {u'size': 2397988454400, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'id': 6, u'partitions': [], u'uuid': u'641016df-637e-4cf0-ac87-2d0d577b2a55', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ttwhfp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'459e0d8d-74ac-45c4-b252-c830d4e103f1', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ttwhfp/blockdevices/6/'}]
2019-03-01 05:15:11,942 [salt.loaded.ext.module.maasng:632 ][INFO    ][9005] vgroot
2019-03-01 05:15:11,942 [salt.loaded.ext.module.maasng:635 ][INFO    ][9005] lvroot
2019-03-01 05:15:11,942 [salt.loaded.ext.module.maasng:639 ][INFO    ][9005] 107374182400
2019-03-01 05:15:12,656 [salt.loaded.ext.module.maasng:645 ][INFO    ][9005] {u'hwe_kernel': 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'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'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'78712e73-60e3-4435-b2df-0d928b12f04f', u'resource_uri': u'/MAAS/api/2.0/nodes/ttwhfp/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ttwhfp', u'filesystem': {u'mount_options': None, u'uuid': u'6d0cc9f0-14f8-48aa-9cf2-a9f08881c4aa', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ttwhfp', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 1, u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/ttwhfp/blockdevices/1/', u'type': u'physical', u'model': u'UCSB-MRAID12G', u'size': 2397998940160}, u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'78712e73-60e3-4435-b2df-0d928b12f04f', u'resource_uri': u'/MAAS/api/2.0/nodes/ttwhfp/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ttwhfp', u'filesystem': {u'mount_options': None, u'uuid': u'6d0cc9f0-14f8-48aa-9cf2-a9f08881c4aa', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'name': u'sda', u'system_id': u'ttwhfp', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/ttwhfp/blockdevices/1/', u'type': u'physical', u'id': 1, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'uuid': u'4814a9f1-3beb-48b3-ab13-4f488c76e357', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'd8c72008-92b7-48ea-9642-382c20cbf0ac', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'ttwhfp', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ttwhfp/blockdevices/12/', u'type': u'virtual', u'id': 12, u'size': 107374182400}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/ttwhfp/', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'ttwhfp', 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"'cloudinit' running modules for final", u'virtualblockdevice_set': [{u'block_size': 4096, u'id_path': None, u'uuid': u'4814a9f1-3beb-48b3-ab13-4f488c76e357', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'ttwhfp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'd8c72008-92b7-48ea-9642-382c20cbf0ac', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id': 12, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ttwhfp/blockdevices/12/', u'type': u'virtual', u'model': None, u'size': 107374182400}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'physicalblockdevice_set': [{u'block_size': 4096, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'78712e73-60e3-4435-b2df-0d928b12f04f', u'resource_uri': u'/MAAS/api/2.0/nodes/ttwhfp/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ttwhfp', u'filesystem': {u'mount_options': None, u'uuid': u'6d0cc9f0-14f8-48aa-9cf2-a9f08881c4aa', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ttwhfp', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 1, u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/ttwhfp/blockdevices/1/', u'type': u'physical', u'model': u'UCSB-MRAID12G', u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ttwhfp', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 2, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'mtu': 1500, 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': 16, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'mtu': 1500, 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'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'ttwhfp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ttwhfp/interfaces/4/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'modules-final', u'disable_ipv4': False, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'mtu': 1500, 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': 16, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'mtu': 1500, 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'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'ttwhfp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ttwhfp/interfaces/4/'}, {u'name': u'enp9s0', u'links': [{u'id': 17, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'system_id': u'ttwhfp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/ttwhfp/interfaces/9/'}, {u'name': u'enp7s0', 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'system_id': u'ttwhfp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/ttwhfp/interfaces/10/'}, {u'name': u'enp8s0', 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'system_id': u'ttwhfp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ttwhfp/interfaces/11/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-03-01 05:15:12,657 [salt.state       :300 ][INFO    ][9005] {'new': {'storage_layout': 'lvm'}}
2019-03-01 05:15:12,658 [salt.state       :1951][INFO    ][9005] Completed state [maas_machines_storage_cmp002_lvm] at time 05:15:12.658188 duration_in_ms=3046.215
2019-03-01 05:15:12,658 [salt.state       :1780][INFO    ][9005] Running state [maas_machines_storage_cmp001_lvm] at time 05:15:12.658670
2019-03-01 05:15:12,659 [salt.state       :1813][INFO    ][9005] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-01 05:15:13,995 [salt.loaded.ext.module.maasng:610 ][INFO    ][9005] hhsmgx
2019-03-01 05:15:13,996 [salt.loaded.ext.module.maasng:626 ][INFO    ][9005] sda
2019-03-01 05:15:14,689 [salt.loaded.ext.module.maasng:361 ][INFO    ][9005] hhsmgx
2019-03-01 05:15:14,822 [salt.loaded.ext.module.maasng:367 ][INFO    ][9005] [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'2d7758ae-9dfe-4f51-95e9-94328ca3e011', u'resource_uri': u'/MAAS/api/2.0/nodes/hhsmgx/blockdevices/2/partition/3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hhsmgx', u'filesystem': {u'mount_options': None, u'uuid': u'6c07c150-45a1-48f9-b5bb-7492537a7714', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 3, u'size': 2397992648704}], u'filesystem': None, u'name': u'sda', u'system_id': u'hhsmgx', 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'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/hhsmgx/blockdevices/2/', u'type': u'physical', u'id': 2, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'uuid': u'8b50653b-8e56-44cb-89e8-e046cb9ed21d', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 2397988454400, u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'28da9366-9a9e-47f4-a0af-2db8d0b4993b', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'hhsmgx', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hhsmgx/blockdevices/8/', u'type': u'virtual', u'id': 8, u'size': 2397988454400}]
2019-03-01 05:15:14,823 [salt.loaded.ext.module.maasng:632 ][INFO    ][9005] vgroot
2019-03-01 05:15:14,823 [salt.loaded.ext.module.maasng:635 ][INFO    ][9005] lvroot
2019-03-01 05:15:14,823 [salt.loaded.ext.module.maasng:639 ][INFO    ][9005] 107374182400
2019-03-01 05:15:15,533 [salt.loaded.ext.module.maasng:645 ][INFO    ][9005] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38'], 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'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'da6c161d-7c53-4884-98fe-68a109b89ab3', u'resource_uri': u'/MAAS/api/2.0/nodes/hhsmgx/blockdevices/2/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hhsmgx', u'filesystem': {u'mount_options': None, u'uuid': u'4d7f4297-1411-4490-b994-3f9bc62981e6', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'hhsmgx', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 2, u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/hhsmgx/blockdevices/2/', u'type': u'physical', u'model': u'UCSB-MRAID12G', u'size': 2397998940160}, u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'da6c161d-7c53-4884-98fe-68a109b89ab3', u'resource_uri': u'/MAAS/api/2.0/nodes/hhsmgx/blockdevices/2/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hhsmgx', u'filesystem': {u'mount_options': None, u'uuid': u'4d7f4297-1411-4490-b994-3f9bc62981e6', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'filesystem': None, u'name': u'sda', u'system_id': u'hhsmgx', 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'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/hhsmgx/blockdevices/2/', u'type': u'physical', u'id': 2, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'uuid': u'd668452c-7256-4b2b-832e-9ca642413708', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'95542b95-40e9-4dac-9950-1b7bef604e5c', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'hhsmgx', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hhsmgx/blockdevices/13/', u'type': u'virtual', u'id': 13, u'size': 107374182400}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/hhsmgx/', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'hhsmgx', u'power_state': u'off', 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'virtualblockdevice_set': [{u'block_size': 4096, u'id_path': None, u'uuid': u'd668452c-7256-4b2b-832e-9ca642413708', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'hhsmgx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'95542b95-40e9-4dac-9950-1b7bef604e5c', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id': 13, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hhsmgx/blockdevices/13/', u'type': u'virtual', u'model': None, u'size': 107374182400}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'physicalblockdevice_set': [{u'block_size': 4096, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'da6c161d-7c53-4884-98fe-68a109b89ab3', u'resource_uri': u'/MAAS/api/2.0/nodes/hhsmgx/blockdevices/2/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hhsmgx', u'filesystem': {u'mount_options': None, u'uuid': u'4d7f4297-1411-4490-b994-3f9bc62981e6', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'hhsmgx', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 2, u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/hhsmgx/blockdevices/2/', u'type': u'physical', u'model': u'UCSB-MRAID12G', u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'hhsmgx', u'id': 8}], u'special_filesystems': [], u'current_commissioning_result_id': 4, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'mtu': 1500, 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': 25, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'mtu': 1500, 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'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'hhsmgx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/hhsmgx/interfaces/5/'}, 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'disable_ipv4': False, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'mtu': 1500, 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': 25, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'mtu': 1500, 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'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'hhsmgx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/hhsmgx/interfaces/5/'}, {u'name': u'enp9s0', u'links': [{u'id': 26, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u'hhsmgx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/hhsmgx/interfaces/12/'}, {u'name': u'enp7s0', u'links': [{u'id': 27, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u'hhsmgx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/hhsmgx/interfaces/13/'}, {u'name': u'enp8s0', u'links': [{u'id': 28, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u'hhsmgx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/hhsmgx/interfaces/14/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-03-01 05:15:15,535 [salt.state       :300 ][INFO    ][9005] {'new': {'storage_layout': 'lvm'}}
2019-03-01 05:15:15,536 [salt.state       :1951][INFO    ][9005] Completed state [maas_machines_storage_cmp001_lvm] at time 05:15:15.536061 duration_in_ms=2877.389
2019-03-01 05:15:15,539 [salt.minion      :1711][INFO    ][9005] Returning information for job: 20190301051504817786
2019-03-01 05:15:16,153 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command state.apply with jid 20190301051516140006
2019-03-01 05:15:16,175 [salt.minion      :1432][INFO    ][9146] Starting a new job with PID 9146
2019-03-01 05:15:16,791 [salt.state       :915 ][INFO    ][9146] Loading fresh modules for state activity
2019-03-01 05:15:16,844 [salt.fileclient  :1219][INFO    ][9146] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-01 05:15:16,882 [salt.state       :1780][INFO    ][9146] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:16.882474
2019-03-01 05:15:16,882 [salt.state       :1813][INFO    ][9146] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 05:15:16,884 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9146] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 05:15:18,346 [salt.state       :300 ][INFO    ][9146] {'pid': 9153, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 05:15:18,346 [salt.state       :1951][INFO    ][9146] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:18.346759 duration_in_ms=1464.286
2019-03-01 05:15:18,348 [salt.state       :1780][INFO    ][9146] Running state [maas.deploy_machines] at time 05:15:18.347981
2019-03-01 05:15:18,348 [salt.state       :1813][INFO    ][9146] Executing state module.run for [maas.deploy_machines]
2019-03-01 05:15:18,348 [salt.utils.decorators:613 ][WARNING ][9146] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:15:18,932 [salt.loaded.ext.module.maas:684 ][INFO    ][9146] deploymachines hwe_kernel=hwe-16.04 system_id=ttwhfp distro_series=xenial
2019-03-01 05:15:21,612 [salt.loaded.ext.module.maas:684 ][INFO    ][9146] deploymachines hwe_kernel=hwe-16.04 system_id=hhsmgx distro_series=xenial
2019-03-01 05:15:23,618 [salt.loaded.ext.module.maas:684 ][INFO    ][9146] deploymachines hwe_kernel=hwe-16.04 system_id=ck6pw4 distro_series=xenial
2019-03-01 05:15:26,036 [salt.loaded.ext.module.maas:684 ][INFO    ][9146] deploymachines hwe_kernel=hwe-16.04 system_id=fdk4h7 distro_series=xenial
2019-03-01 05:15:28,337 [salt.loaded.ext.module.maas:684 ][INFO    ][9146] deploymachines hwe_kernel=hwe-16.04 system_id=an7bda distro_series=xenial
2019-03-01 05:15:31,025 [salt.state       :300 ][INFO    ][9146] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-01 05:15:31,025 [salt.state       :1951][INFO    ][9146] Completed state [maas.deploy_machines] at time 05:15:31.025499 duration_in_ms=12677.515
2019-03-01 05:15:31,029 [salt.minion      :1711][INFO    ][9146] Returning information for job: 20190301051516140006
2019-03-01 05:15:31,669 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command state.apply with jid 20190301051531653680
2019-03-01 05:15:31,691 [salt.minion      :1432][INFO    ][9457] Starting a new job with PID 9457
2019-03-01 05:15:35,271 [salt.state       :915 ][INFO    ][9457] Loading fresh modules for state activity
2019-03-01 05:15:35,324 [salt.fileclient  :1219][INFO    ][9457] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-01 05:15:35,366 [salt.state       :1780][INFO    ][9457] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:35.366315
2019-03-01 05:15:35,366 [salt.state       :1813][INFO    ][9457] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 05:15:35,368 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9457] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 05:15:36,747 [salt.state       :300 ][INFO    ][9457] {'pid': 9470, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 05:15:36,748 [salt.state       :1951][INFO    ][9457] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:36.748562 duration_in_ms=1382.247
2019-03-01 05:15:36,751 [salt.state       :1780][INFO    ][9457] Running state [maas.wait_for_machine_status] at time 05:15:36.751207
2019-03-01 05:15:36,751 [salt.state       :1813][INFO    ][9457] Executing state module.run for [maas.wait_for_machine_status]
2019-03-01 05:15:36,752 [salt.utils.decorators:613 ][WARNING ][9457] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:15:40,234 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.52903104s left)
2019-03-01 05:15:46,780 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051546767898
2019-03-01 05:15:46,802 [salt.minion      :1432][INFO    ][9487] Starting a new job with PID 9487
2019-03-01 05:15:46,825 [salt.minion      :1711][INFO    ][9487] Returning information for job: 20190301051546767898
2019-03-01 05:16:13,814 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.9493649s left)
2019-03-01 05:16:16,834 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051616820728
2019-03-01 05:16:16,857 [salt.minion      :1432][INFO    ][9528] Starting a new job with PID 9528
2019-03-01 05:16:16,880 [salt.minion      :1711][INFO    ][9528] Returning information for job: 20190301051616820728
2019-03-01 05:16:46,925 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051646911150
2019-03-01 05:16:46,945 [salt.minion      :1432][INFO    ][9563] Starting a new job with PID 9563
2019-03-01 05:16:46,970 [salt.minion      :1711][INFO    ][9563] Returning information for job: 20190301051646911150
2019-03-01 05:16:47,489 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.27383304s left)
2019-03-01 05:17:16,971 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051716959518
2019-03-01 05:17:16,988 [salt.minion      :1432][INFO    ][9699] Starting a new job with PID 9699
2019-03-01 05:17:17,012 [salt.minion      :1711][INFO    ][9699] Returning information for job: 20190301051716959518
2019-03-01 05:17:21,078 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2145.68554902s left)
2019-03-01 05:17:47,022 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051747010081
2019-03-01 05:17:47,044 [salt.minion      :1432][INFO    ][9875] Starting a new job with PID 9875
2019-03-01 05:17:47,068 [salt.minion      :1711][INFO    ][9875] Returning information for job: 20190301051747010081
2019-03-01 05:17:54,752 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.0115149s left)
2019-03-01 05:18:17,080 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051817067856
2019-03-01 05:18:17,103 [salt.minion      :1432][INFO    ][10339] Starting a new job with PID 10339
2019-03-01 05:18:17,126 [salt.minion      :1711][INFO    ][10339] Returning information for job: 20190301051817067856
2019-03-01 05:18:27,993 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2078.77045894s left)
2019-03-01 05:18:47,140 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051847126992
2019-03-01 05:18:47,162 [salt.minion      :1432][INFO    ][10539] Starting a new job with PID 10539
2019-03-01 05:18:47,186 [salt.minion      :1711][INFO    ][10539] Returning information for job: 20190301051847126992
2019-03-01 05:19:01,353 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2045.41008496s left)
2019-03-01 05:19:17,198 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051917185219
2019-03-01 05:19:17,220 [salt.minion      :1432][INFO    ][10805] Starting a new job with PID 10805
2019-03-01 05:19:17,244 [salt.minion      :1711][INFO    ][10805] Returning information for job: 20190301051917185219
2019-03-01 05:19:34,941 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2011.82199097s left)
2019-03-01 05:19:47,260 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301051947247508
2019-03-01 05:19:47,283 [salt.minion      :1432][INFO    ][10880] Starting a new job with PID 10880
2019-03-01 05:19:47,307 [salt.minion      :1711][INFO    ][10880] Returning information for job: 20190301051947247508
2019-03-01 05:20:08,291 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1978.47166204s left)
2019-03-01 05:20:17,335 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052017322060
2019-03-01 05:20:17,357 [salt.minion      :1432][INFO    ][11020] Starting a new job with PID 11020
2019-03-01 05:20:17,380 [salt.minion      :1711][INFO    ][11020] Returning information for job: 20190301052017322060
2019-03-01 05:20:41,799 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1944.96401s left)
2019-03-01 05:20:47,407 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052047395045
2019-03-01 05:20:47,428 [salt.minion      :1432][INFO    ][11110] Starting a new job with PID 11110
2019-03-01 05:20:47,452 [salt.minion      :1711][INFO    ][11110] Returning information for job: 20190301052047395045
2019-03-01 05:21:15,484 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1911.27920103s left)
2019-03-01 05:21:17,483 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052117470922
2019-03-01 05:21:17,506 [salt.minion      :1432][INFO    ][11231] Starting a new job with PID 11231
2019-03-01 05:21:17,530 [salt.minion      :1711][INFO    ][11231] Returning information for job: 20190301052117470922
2019-03-01 05:21:47,565 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052147554792
2019-03-01 05:21:47,586 [salt.minion      :1432][INFO    ][11324] Starting a new job with PID 11324
2019-03-01 05:21:47,613 [salt.minion      :1711][INFO    ][11324] Returning information for job: 20190301052147554792
2019-03-01 05:21:48,829 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1877.93371105s left)
2019-03-01 05:22:17,654 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052217641410
2019-03-01 05:22:17,676 [salt.minion      :1432][INFO    ][11584] Starting a new job with PID 11584
2019-03-01 05:22:17,701 [salt.minion      :1711][INFO    ][11584] Returning information for job: 20190301052217641410
2019-03-01 05:22:22,622 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1844.14126611s left)
2019-03-01 05:22:47,747 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052247735089
2019-03-01 05:22:47,769 [salt.minion      :1432][INFO    ][11689] Starting a new job with PID 11689
2019-03-01 05:22:47,791 [salt.minion      :1711][INFO    ][11689] Returning information for job: 20190301052247735089
2019-03-01 05:22:55,547 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1811.21663809s left)
2019-03-01 05:23:17,840 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052317826556
2019-03-01 05:23:17,862 [salt.minion      :1432][INFO    ][11755] Starting a new job with PID 11755
2019-03-01 05:23:17,886 [salt.minion      :1711][INFO    ][11755] Returning information for job: 20190301052317826556
2019-03-01 05:23:29,044 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1777.71946502s left)
2019-03-01 05:23:47,921 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052347907830
2019-03-01 05:23:47,940 [salt.minion      :1432][INFO    ][11938] Starting a new job with PID 11938
2019-03-01 05:23:47,962 [salt.minion      :1711][INFO    ][11938] Returning information for job: 20190301052347907830
2019-03-01 05:24:02,250 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1744.51325107s left)
2019-03-01 05:24:17,999 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052417986368
2019-03-01 05:24:18,022 [salt.minion      :1432][INFO    ][12269] Starting a new job with PID 12269
2019-03-01 05:24:18,044 [salt.minion      :1711][INFO    ][12269] Returning information for job: 20190301052417986368
2019-03-01 05:24:35,526 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1711.23692608s left)
2019-03-01 05:24:48,121 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052448108233
2019-03-01 05:24:48,142 [salt.minion      :1432][INFO    ][12435] Starting a new job with PID 12435
2019-03-01 05:24:48,166 [salt.minion      :1711][INFO    ][12435] Returning information for job: 20190301052448108233
2019-03-01 05:25:09,341 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1677.42242694s left)
2019-03-01 05:25:18,235 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052518222377
2019-03-01 05:25:18,258 [salt.minion      :1432][INFO    ][12476] Starting a new job with PID 12476
2019-03-01 05:25:18,282 [salt.minion      :1711][INFO    ][12476] Returning information for job: 20190301052518222377
2019-03-01 05:25:42,927 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1643.83643103s left)
2019-03-01 05:25:48,378 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052548365325
2019-03-01 05:25:48,401 [salt.minion      :1432][INFO    ][12511] Starting a new job with PID 12511
2019-03-01 05:25:48,425 [salt.minion      :1711][INFO    ][12511] Returning information for job: 20190301052548365325
2019-03-01 05:26:16,537 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1610.225775s left)
2019-03-01 05:26:18,523 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052618509796
2019-03-01 05:26:18,546 [salt.minion      :1432][INFO    ][12553] Starting a new job with PID 12553
2019-03-01 05:26:18,569 [salt.minion      :1711][INFO    ][12553] Returning information for job: 20190301052618509796
2019-03-01 05:26:48,672 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052648660850
2019-03-01 05:26:48,693 [salt.minion      :1432][INFO    ][12588] Starting a new job with PID 12588
2019-03-01 05:26:48,717 [salt.minion      :1711][INFO    ][12588] Returning information for job: 20190301052648660850
2019-03-01 05:26:50,051 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1576.71197295s left)
2019-03-01 05:27:18,824 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052718808885
2019-03-01 05:27:18,846 [salt.minion      :1432][INFO    ][12626] Starting a new job with PID 12626
2019-03-01 05:27:18,870 [salt.minion      :1711][INFO    ][12626] Returning information for job: 20190301052718808885
2019-03-01 05:27:23,655 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1543.10790896s left)
2019-03-01 05:27:48,999 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052748983281
2019-03-01 05:27:49,021 [salt.minion      :1432][INFO    ][12664] Starting a new job with PID 12664
2019-03-01 05:27:49,046 [salt.minion      :1711][INFO    ][12664] Returning information for job: 20190301052748983281
2019-03-01 05:27:57,349 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1509.41394806s left)
2019-03-01 05:28:19,178 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052819165364
2019-03-01 05:28:19,200 [salt.minion      :1432][INFO    ][12711] Starting a new job with PID 12711
2019-03-01 05:28:19,225 [salt.minion      :1711][INFO    ][12711] Returning information for job: 20190301052819165364
2019-03-01 05:28:30,666 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1476.09726906s left)
2019-03-01 05:28:49,372 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052849360046
2019-03-01 05:28:49,394 [salt.minion      :1432][INFO    ][12755] Starting a new job with PID 12755
2019-03-01 05:28:49,417 [salt.minion      :1711][INFO    ][12755] Returning information for job: 20190301052849360046
2019-03-01 05:29:04,278 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1442.485461s left)
2019-03-01 05:29:19,571 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052919559012
2019-03-01 05:29:19,589 [salt.minion      :1432][INFO    ][12939] Starting a new job with PID 12939
2019-03-01 05:29:19,610 [salt.minion      :1711][INFO    ][12939] Returning information for job: 20190301052919559012
2019-03-01 05:29:37,766 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1408.99679494s left)
2019-03-01 05:29:49,772 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301052949759966
2019-03-01 05:29:49,794 [salt.minion      :1432][INFO    ][12984] Starting a new job with PID 12984
2019-03-01 05:29:49,819 [salt.minion      :1711][INFO    ][12984] Returning information for job: 20190301052949759966
2019-03-01 05:30:11,090 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1375.673558s left)
2019-03-01 05:30:19,996 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053019983830
2019-03-01 05:30:20,018 [salt.minion      :1432][INFO    ][13025] Starting a new job with PID 13025
2019-03-01 05:30:20,042 [salt.minion      :1711][INFO    ][13025] Returning information for job: 20190301053019983830
2019-03-01 05:30:44,358 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1342.40517688s left)
2019-03-01 05:30:50,017 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053050004278
2019-03-01 05:30:50,040 [salt.minion      :1432][INFO    ][13061] Starting a new job with PID 13061
2019-03-01 05:30:50,064 [salt.minion      :1711][INFO    ][13061] Returning information for job: 20190301053050004278
2019-03-01 05:31:17,914 [salt.loaded.ext.module.maas:1023][INFO    ][9457] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1308.84904408s left)
2019-03-01 05:31:20,048 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053120035724
2019-03-01 05:31:20,069 [salt.minion      :1432][INFO    ][13101] Starting a new job with PID 13101
2019-03-01 05:31:20,093 [salt.minion      :1711][INFO    ][13101] Returning information for job: 20190301053120035724
2019-03-01 05:31:50,088 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053150075569
2019-03-01 05:31:50,108 [salt.minion      :1432][INFO    ][13142] Starting a new job with PID 13142
2019-03-01 05:31:50,127 [salt.minion      :1711][INFO    ][13142] Returning information for job: 20190301053150075569
2019-03-01 05:31:51,515 [salt.loaded.ext.module.maas:993 ][INFO    ][9457] Machine an7bda mark broken
2019-03-01 05:31:52,271 [salt.loaded.ext.module.maas:996 ][INFO    ][9457] Machine an7bda mark fixed
2019-03-01 05:31:53,359 [salt.loaded.ext.module.maas:684 ][INFO    ][9457] deploymachines hwe_kernel=hwe-16.04 system_id=an7bda distro_series=xenial
2019-03-01 05:31:55,965 [salt.loaded.ext.module.maas:160 ][ERROR   ][9457] 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-03-01 05:31:55,967 [salt.state       :302 ][ERROR   ][9457] 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-03-01 05:31:55,967 [salt.state       :1951][INFO    ][9457] Completed state [maas.wait_for_machine_status] at time 05:31:55.967651 duration_in_ms=979216.439
2019-03-01 05:31:55,972 [salt.minion      :1711][INFO    ][9457] Returning information for job: 20190301051531653680
2019-03-01 05:32:06,783 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command pillar.get with jid 20190301053206770563
2019-03-01 05:32:06,804 [salt.minion      :1432][INFO    ][13226] Starting a new job with PID 13226
2019-03-01 05:32:06,812 [salt.minion      :1711][INFO    ][13226] Returning information for job: 20190301053206770563
2019-03-01 05:32:07,303 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command service.status with jid 20190301053207291143
2019-03-01 05:32:07,324 [salt.minion      :1432][INFO    ][13231] Starting a new job with PID 13231
2019-03-01 05:32:07,701 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13231] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-01 05:32:07,738 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13231] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-01 05:32:07,755 [salt.minion      :1711][INFO    ][13231] Returning information for job: 20190301053207291143
2019-03-01 05:32:08,353 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command state.apply with jid 20190301053208339804
2019-03-01 05:32:08,374 [salt.minion      :1432][INFO    ][13251] Starting a new job with PID 13251
2019-03-01 05:32:11,905 [salt.state       :915 ][INFO    ][13251] Loading fresh modules for state activity
2019-03-01 05:32:12,304 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13251] Executing command 'salt-minion --version' in directory '/root'
2019-03-01 05:32:12,661 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13251] Executing command 'salt-minion --version' in directory '/root'
2019-03-01 05:32:13,506 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13251] Executing command 'salt-minion --version' in directory '/root'
2019-03-01 05:32:13,856 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13251] Executing command 'salt-minion --version' in directory '/root'
2019-03-01 05:32:15,227 [salt.state       :1780][INFO    ][13251] Running state [salt-minion] at time 05:32:15.227321
2019-03-01 05:32:15,227 [salt.state       :1813][INFO    ][13251] Executing state pkg.installed for [salt-minion]
2019-03-01 05:32:15,228 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13251] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-01 05:32:15,331 [salt.state       :300 ][INFO    ][13251] All specified packages are already installed
2019-03-01 05:32:15,332 [salt.state       :1951][INFO    ][13251] Completed state [salt-minion] at time 05:32:15.331910 duration_in_ms=104.588
2019-03-01 05:32:15,332 [salt.state       :1780][INFO    ][13251] Running state [salt_minion_dependency_packages] at time 05:32:15.332313
2019-03-01 05:32:15,332 [salt.state       :1813][INFO    ][13251] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-01 05:32:15,340 [salt.state       :300 ][INFO    ][13251] All specified packages are already installed
2019-03-01 05:32:15,340 [salt.state       :1951][INFO    ][13251] Completed state [salt_minion_dependency_packages] at time 05:32:15.340933 duration_in_ms=8.621
2019-03-01 05:32:15,344 [salt.state       :1780][INFO    ][13251] Running state [/etc/salt/minion.d/minion.conf] at time 05:32:15.344571
2019-03-01 05:32:15,344 [salt.state       :1813][INFO    ][13251] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-01 05:32:15,558 [salt.state       :300 ][INFO    ][13251] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-01 05:32:15,558 [salt.state       :1951][INFO    ][13251] Completed state [/etc/salt/minion.d/minion.conf] at time 05:32:15.558440 duration_in_ms=213.869
2019-03-01 05:32:15,558 [salt.state       :1780][INFO    ][13251] Running state [python-netaddr] at time 05:32:15.558709
2019-03-01 05:32:15,558 [salt.state       :1813][INFO    ][13251] Executing state pkg.installed for [python-netaddr]
2019-03-01 05:32:15,565 [salt.state       :300 ][INFO    ][13251] All specified packages are already installed
2019-03-01 05:32:15,566 [salt.state       :1951][INFO    ][13251] Completed state [python-netaddr] at time 05:32:15.565967 duration_in_ms=7.258
2019-03-01 05:32:15,569 [salt.state       :1780][INFO    ][13251] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:32:15.569145
2019-03-01 05:32:15,569 [salt.state       :1813][INFO    ][13251] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-01 05:32:15,582 [salt.state       :300 ][INFO    ][13251] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-01 05:32:15,582 [salt.state       :1951][INFO    ][13251] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:32:15.582338 duration_in_ms=13.193
2019-03-01 05:32:15,583 [salt.state       :1780][INFO    ][13251] Running state [salt-minion] at time 05:32:15.583388
2019-03-01 05:32:15,583 [salt.state       :1813][INFO    ][13251] Executing state service.running for [salt-minion]
2019-03-01 05:32:15,584 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13251] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-01 05:32:15,620 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13251] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-01 05:32:15,637 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13251] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-01 05:32:15,654 [salt.state       :300 ][INFO    ][13251] The service salt-minion is already running
2019-03-01 05:32:15,655 [salt.state       :1951][INFO    ][13251] Completed state [salt-minion] at time 05:32:15.655188 duration_in_ms=71.799
2019-03-01 05:32:15,657 [salt.state       :1780][INFO    ][13251] Running state [/etc/salt/grains.d] at time 05:32:15.657119
2019-03-01 05:32:15,657 [salt.state       :1813][INFO    ][13251] Executing state file.directory for [/etc/salt/grains.d]
2019-03-01 05:32:15,658 [salt.state       :300 ][INFO    ][13251] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-01 05:32:15,659 [salt.state       :1951][INFO    ][13251] Completed state [/etc/salt/grains.d] at time 05:32:15.659012 duration_in_ms=1.893
2019-03-01 05:32:15,659 [salt.state       :1780][INFO    ][13251] Running state [/etc/salt/grains] at time 05:32:15.659849
2019-03-01 05:32:15,660 [salt.state       :1813][INFO    ][13251] Executing state file.managed for [/etc/salt/grains]
2019-03-01 05:32:15,660 [salt.state       :300 ][INFO    ][13251] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-01 05:32:15,661 [salt.state       :1951][INFO    ][13251] Completed state [/etc/salt/grains] at time 05:32:15.661167 duration_in_ms=1.318
2019-03-01 05:32:15,661 [salt.state       :1780][INFO    ][13251] Running state [/etc/salt/grains.d/placeholder] at time 05:32:15.661732
2019-03-01 05:32:15,662 [salt.state       :1813][INFO    ][13251] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-01 05:32:15,662 [salt.state       :300 ][INFO    ][13251] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-01 05:32:15,663 [salt.state       :1951][INFO    ][13251] Completed state [/etc/salt/grains.d/placeholder] at time 05:32:15.662961 duration_in_ms=1.23
2019-03-01 05:32:15,663 [salt.state       :1780][INFO    ][13251] Running state [/etc/salt/grains.d/sphinx] at time 05:32:15.663535
2019-03-01 05:32:15,663 [salt.state       :1813][INFO    ][13251] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-01 05:32:15,683 [salt.state       :300 ][INFO    ][13251] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-01 05:32:15,684 [salt.state       :1951][INFO    ][13251] Completed state [/etc/salt/grains.d/sphinx] at time 05:32:15.684041 duration_in_ms=20.507
2019-03-01 05:32:15,686 [salt.state       :1780][INFO    ][13251] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:32:15.686630
2019-03-01 05:32:15,687 [salt.state       :1813][INFO    ][13251] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-01 05:32:15,687 [salt.state       :300 ][INFO    ][13251] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-01 05:32:15,687 [salt.state       :1951][INFO    ][13251] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:32:15.687638 duration_in_ms=1.009
2019-03-01 05:32:15,688 [salt.state       :1780][INFO    ][13251] Running state [/etc/salt/grains.d/dns_records] at time 05:32:15.688190
2019-03-01 05:32:15,688 [salt.state       :1813][INFO    ][13251] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-01 05:32:15,701 [salt.state       :300 ][INFO    ][13251] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-01 05:32:15,701 [salt.state       :1951][INFO    ][13251] Completed state [/etc/salt/grains.d/dns_records] at time 05:32:15.701856 duration_in_ms=13.667
2019-03-01 05:32:15,702 [salt.state       :1780][INFO    ][13251] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:32:15.702920
2019-03-01 05:32:15,703 [salt.state       :1813][INFO    ][13251] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-03-01 05:32:15,703 [salt.state       :300 ][INFO    ][13251] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-01 05:32:15,703 [salt.state       :1951][INFO    ][13251] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:32:15.703916 duration_in_ms=0.995
2019-03-01 05:32:15,704 [salt.state       :1780][INFO    ][13251] Running state [/etc/salt/grains.d/salt] at time 05:32:15.704459
2019-03-01 05:32:15,704 [salt.state       :1813][INFO    ][13251] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-01 05:32:15,719 [salt.state       :300 ][INFO    ][13251] File /etc/salt/grains.d/salt is in the correct state
2019-03-01 05:32:15,719 [salt.state       :1951][INFO    ][13251] Completed state [/etc/salt/grains.d/salt] at time 05:32:15.719895 duration_in_ms=15.435
2019-03-01 05:32:15,720 [salt.state       :1780][INFO    ][13251] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:32:15.720917
2019-03-01 05:32:15,721 [salt.state       :1813][INFO    ][13251] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-01 05:32:15,721 [salt.state       :300 ][INFO    ][13251] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-01 05:32:15,721 [salt.state       :1951][INFO    ][13251] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:32:15.721921 duration_in_ms=1.004
2019-03-01 05:32:15,724 [salt.state       :1780][INFO    ][13251] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:32:15.724115
2019-03-01 05:32:15,724 [salt.state       :1813][INFO    ][13251] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-01 05:32:15,724 [salt.state       :300 ][INFO    ][13251] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-01 05:32:15,725 [salt.state       :1951][INFO    ][13251] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:32:15.725123 duration_in_ms=1.009
2019-03-01 05:32:15,725 [salt.state       :1780][INFO    ][13251] Running state [mine.update] at time 05:32:15.725915
2019-03-01 05:32:15,726 [salt.state       :1813][INFO    ][13251] Executing state module.wait for [mine.update]
2019-03-01 05:32:15,726 [salt.state       :300 ][INFO    ][13251] No changes made for mine.update
2019-03-01 05:32:15,726 [salt.state       :1951][INFO    ][13251] Completed state [mine.update] at time 05:32:15.726845 duration_in_ms=0.93
2019-03-01 05:32:15,727 [salt.state       :1780][INFO    ][13251] Running state [ca-certificates] at time 05:32:15.727134
2019-03-01 05:32:15,727 [salt.state       :1813][INFO    ][13251] Executing state pkg.installed for [ca-certificates]
2019-03-01 05:32:15,736 [salt.state       :300 ][INFO    ][13251] All specified packages are already installed
2019-03-01 05:32:15,736 [salt.state       :1951][INFO    ][13251] Completed state [ca-certificates] at time 05:32:15.736254 duration_in_ms=9.121
2019-03-01 05:32:15,737 [salt.state       :1780][INFO    ][13251] Running state [update-ca-certificates] at time 05:32:15.737064
2019-03-01 05:32:15,737 [salt.state       :1813][INFO    ][13251] Executing state cmd.wait for [update-ca-certificates]
2019-03-01 05:32:15,737 [salt.state       :300 ][INFO    ][13251] No changes made for update-ca-certificates
2019-03-01 05:32:15,738 [salt.state       :1951][INFO    ][13251] Completed state [update-ca-certificates] at time 05:32:15.738017 duration_in_ms=0.953
2019-03-01 05:32:15,738 [salt.state       :1780][INFO    ][13251] Running state [iptables] at time 05:32:15.738307
2019-03-01 05:32:15,738 [salt.state       :1813][INFO    ][13251] Executing state pkg.installed for [iptables]
2019-03-01 05:32:15,746 [salt.state       :300 ][INFO    ][13251] All specified packages are already installed
2019-03-01 05:32:15,747 [salt.state       :1951][INFO    ][13251] Completed state [iptables] at time 05:32:15.747177 duration_in_ms=8.87
2019-03-01 05:32:15,747 [salt.state       :1780][INFO    ][13251] Running state [iptables-persistent] at time 05:32:15.747475
2019-03-01 05:32:15,747 [salt.state       :1813][INFO    ][13251] Executing state pkg.installed for [iptables-persistent]
2019-03-01 05:32:15,755 [salt.state       :300 ][INFO    ][13251] All specified packages are already installed
2019-03-01 05:32:15,755 [salt.state       :1951][INFO    ][13251] Completed state [iptables-persistent] at time 05:32:15.755496 duration_in_ms=8.021
2019-03-01 05:32:15,756 [salt.state       :1780][INFO    ][13251] Running state [iptables_modules_v4_load] at time 05:32:15.756604
2019-03-01 05:32:15,756 [salt.state       :1813][INFO    ][13251] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-01 05:32:15,757 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13251] Executing command 'lsmod' in directory '/root'
2019-03-01 05:32:15,782 [salt.state       :300 ][INFO    ][13251] Kernel modules iptable_filter, ip_tables are already present
2019-03-01 05:32:15,782 [salt.state       :1951][INFO    ][13251] Completed state [iptables_modules_v4_load] at time 05:32:15.782413 duration_in_ms=25.809
2019-03-01 05:32:15,783 [salt.state       :1780][INFO    ][13251] Running state [/etc/iptables/rules.v4] at time 05:32:15.783279
2019-03-01 05:32:15,783 [salt.state       :1813][INFO    ][13251] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-01 05:32:15,887 [salt.state       :300 ][INFO    ][13251] File /etc/iptables/rules.v4 is in the correct state
2019-03-01 05:32:15,887 [salt.state       :1951][INFO    ][13251] Completed state [/etc/iptables/rules.v4] at time 05:32:15.887697 duration_in_ms=104.418
2019-03-01 05:32:15,888 [salt.state       :1780][INFO    ][13251] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:32:15.888754
2019-03-01 05:32:15,889 [salt.state       :1813][INFO    ][13251] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-01 05:32:15,889 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13251] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-01 05:32:15,909 [salt.state       :300 ][INFO    ][13251] onlyif execution failed
2019-03-01 05:32:15,909 [salt.state       :1951][INFO    ][13251] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:32:15.909829 duration_in_ms=21.075
2019-03-01 05:32:15,910 [salt.state       :1780][INFO    ][13251] Running state [netfilter-persistent] at time 05:32:15.910912
2019-03-01 05:32:15,911 [salt.state       :1813][INFO    ][13251] Executing state service.running for [netfilter-persistent]
2019-03-01 05:32:15,912 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13251] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-01 05:32:15,933 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13251] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-01 05:32:15,950 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13251] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-01 05:32:15,968 [salt.state       :300 ][INFO    ][13251] The service netfilter-persistent is already running
2019-03-01 05:32:15,969 [salt.state       :1951][INFO    ][13251] Completed state [netfilter-persistent] at time 05:32:15.969342 duration_in_ms=58.429
2019-03-01 05:32:15,970 [salt.state       :1780][INFO    ][13251] Running state [iptables_extra.remove_stale_tables] at time 05:32:15.970705
2019-03-01 05:32:15,971 [salt.state       :1813][INFO    ][13251] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-01 05:32:15,971 [salt.state       :300 ][INFO    ][13251] No changes made for iptables_extra.remove_stale_tables
2019-03-01 05:32:15,972 [salt.state       :1951][INFO    ][13251] Completed state [iptables_extra.remove_stale_tables] at time 05:32:15.972052 duration_in_ms=1.346
2019-03-01 05:32:15,972 [salt.state       :1780][INFO    ][13251] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:32:15.972426
2019-03-01 05:32:15,972 [salt.state       :1813][INFO    ][13251] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-01 05:32:15,973 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13251] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-01 05:32:15,989 [salt.state       :300 ][INFO    ][13251] onlyif execution failed
2019-03-01 05:32:15,989 [salt.state       :1951][INFO    ][13251] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:32:15.989649 duration_in_ms=17.222
2019-03-01 05:32:15,991 [salt.state       :1780][INFO    ][13251] Running state [/etc/iptables/rules.v6] at time 05:32:15.991270
2019-03-01 05:32:15,991 [salt.state       :1813][INFO    ][13251] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-01 05:32:15,992 [salt.state       :300 ][INFO    ][13251] File /etc/iptables/rules.v6 is not present
2019-03-01 05:32:15,993 [salt.state       :1951][INFO    ][13251] Completed state [/etc/iptables/rules.v6] at time 05:32:15.992917 duration_in_ms=1.647
2019-03-01 05:32:15,994 [salt.state       :1780][INFO    ][13251] Running state [iptables_extra.flush_all] at time 05:32:15.994083
2019-03-01 05:32:15,994 [salt.state       :1813][INFO    ][13251] Executing state module.wait for [iptables_extra.flush_all]
2019-03-01 05:32:15,995 [salt.state       :300 ][INFO    ][13251] No changes made for iptables_extra.flush_all
2019-03-01 05:32:15,995 [salt.state       :1951][INFO    ][13251] Completed state [iptables_extra.flush_all] at time 05:32:15.995370 duration_in_ms=1.287
2019-03-01 05:32:16,000 [salt.minion      :1711][INFO    ][13251] Returning information for job: 20190301053208339804
2019-03-01 05:32:16,602 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command state.apply with jid 20190301053216589382
2019-03-01 05:32:16,624 [salt.minion      :1432][INFO    ][13332] Starting a new job with PID 13332
2019-03-01 05:32:17,225 [salt.state       :915 ][INFO    ][13332] Loading fresh modules for state activity
2019-03-01 05:32:17,858 [salt.state       :1780][INFO    ][13332] Running state [maas-rack-controller] at time 05:32:17.858556
2019-03-01 05:32:17,858 [salt.state       :1813][INFO    ][13332] Executing state pkg.installed for [maas-rack-controller]
2019-03-01 05:32:17,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13332] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-01 05:32:17,998 [salt.state       :300 ][INFO    ][13332] All specified packages are already installed
2019-03-01 05:32:17,998 [salt.state       :1951][INFO    ][13332] Completed state [maas-rack-controller] at time 05:32:17.998561 duration_in_ms=140.005
2019-03-01 05:32:17,998 [salt.state       :1780][INFO    ][13332] Running state [ipmitool] at time 05:32:17.998843
2019-03-01 05:32:17,999 [salt.state       :1813][INFO    ][13332] Executing state pkg.installed for [ipmitool]
2019-03-01 05:32:18,004 [salt.state       :300 ][INFO    ][13332] All specified packages are already installed
2019-03-01 05:32:18,004 [salt.state       :1951][INFO    ][13332] Completed state [ipmitool] at time 05:32:18.004781 duration_in_ms=5.938
2019-03-01 05:32:18,007 [salt.state       :1780][INFO    ][13332] Running state [/etc/maas/rackd.conf] at time 05:32:18.007148
2019-03-01 05:32:18,007 [salt.state       :1813][INFO    ][13332] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-01 05:32:18,008 [salt.state       :300 ][INFO    ][13332] No changes needed to be made
2019-03-01 05:32:18,008 [salt.state       :1951][INFO    ][13332] Completed state [/etc/maas/rackd.conf] at time 05:32:18.008460 duration_in_ms=1.312
2019-03-01 05:32:18,008 [salt.state       :1780][INFO    ][13332] Running state [/etc/maas/rackd.conf] at time 05:32:18.008673
2019-03-01 05:32:18,008 [salt.state       :1813][INFO    ][13332] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-01 05:32:18,009 [salt.loaded.int.states.file:2298][WARNING ][13332] 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-03-01 05:32:18,009 [salt.state       :300 ][INFO    ][13332] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-01 05:32:18,009 [salt.state       :1951][INFO    ][13332] Completed state [/etc/maas/rackd.conf] at time 05:32:18.009722 duration_in_ms=1.049
2019-03-01 05:32:18,010 [salt.state       :1780][INFO    ][13332] Running state [maas-rackd] at time 05:32:18.010543
2019-03-01 05:32:18,010 [salt.state       :1813][INFO    ][13332] Executing state service.running for [maas-rackd]
2019-03-01 05:32:18,011 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13332] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-01 05:32:18,046 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13332] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-01 05:32:18,062 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13332] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-01 05:32:18,079 [salt.state       :300 ][INFO    ][13332] The service maas-rackd is already running
2019-03-01 05:32:18,079 [salt.state       :1951][INFO    ][13332] Completed state [maas-rackd] at time 05:32:18.079662 duration_in_ms=69.118
2019-03-01 05:32:18,081 [salt.minion      :1711][INFO    ][13332] Returning information for job: 20190301053216589382
2019-03-01 05:32:18,661 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command state.apply with jid 20190301053218648894
2019-03-01 05:32:18,681 [salt.minion      :1432][INFO    ][13355] Starting a new job with PID 13355
2019-03-01 05:32:19,291 [salt.state       :915 ][INFO    ][13355] Loading fresh modules for state activity
2019-03-01 05:32:19,974 [salt.state       :1780][INFO    ][13355] Running state [maas-region-controller] at time 05:32:19.973957
2019-03-01 05:32:19,974 [salt.state       :1813][INFO    ][13355] Executing state pkg.installed for [maas-region-controller]
2019-03-01 05:32:19,974 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13355] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-01 05:32:20,061 [salt.state       :300 ][INFO    ][13355] All specified packages are already installed
2019-03-01 05:32:20,062 [salt.state       :1951][INFO    ][13355] Completed state [maas-region-controller] at time 05:32:20.062361 duration_in_ms=88.403
2019-03-01 05:32:20,062 [salt.state       :1780][INFO    ][13355] Running state [python-oauth] at time 05:32:20.062719
2019-03-01 05:32:20,062 [salt.state       :1813][INFO    ][13355] Executing state pkg.installed for [python-oauth]
2019-03-01 05:32:20,069 [salt.state       :300 ][INFO    ][13355] All specified packages are already installed
2019-03-01 05:32:20,069 [salt.state       :1951][INFO    ][13355] Completed state [python-oauth] at time 05:32:20.069693 duration_in_ms=6.974
2019-03-01 05:32:20,072 [salt.state       :1780][INFO    ][13355] Running state [/etc/maas/regiond.conf] at time 05:32:20.072266
2019-03-01 05:32:20,072 [salt.state       :1813][INFO    ][13355] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-01 05:32:20,109 [salt.state       :300 ][INFO    ][13355] No changes needed to be made
2019-03-01 05:32:20,110 [salt.state       :1951][INFO    ][13355] Completed state [/etc/maas/regiond.conf] at time 05:32:20.110228 duration_in_ms=37.961
2019-03-01 05:32:20,110 [salt.state       :1780][INFO    ][13355] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:32:20.110849
2019-03-01 05:32:20,111 [salt.state       :1813][INFO    ][13355] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-01 05:32:20,181 [salt.state       :300 ][INFO    ][13355] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-01 05:32:20,181 [salt.state       :1951][INFO    ][13355] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:32:20.181270 duration_in_ms=70.422
2019-03-01 05:32:20,181 [salt.state       :1780][INFO    ][13355] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:32:20.181856
2019-03-01 05:32:20,182 [salt.state       :1813][INFO    ][13355] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-01 05:32:20,194 [salt.state       :300 ][INFO    ][13355] No changes needed to be made
2019-03-01 05:32:20,194 [salt.state       :1951][INFO    ][13355] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:32:20.194760 duration_in_ms=12.904
2019-03-01 05:32:20,195 [salt.state       :1780][INFO    ][13355] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:32:20.195311
2019-03-01 05:32:20,195 [salt.state       :1813][INFO    ][13355] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-01 05:32:20,224 [salt.state       :300 ][INFO    ][13355] No changes needed to be made
2019-03-01 05:32:20,224 [salt.state       :1951][INFO    ][13355] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:32:20.224776 duration_in_ms=29.464
2019-03-01 05:32:20,225 [salt.state       :1780][INFO    ][13355] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:32:20.225320
2019-03-01 05:32:20,225 [salt.state       :1813][INFO    ][13355] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-01 05:32:20,252 [salt.state       :300 ][INFO    ][13355] No changes needed to be made
2019-03-01 05:32:20,252 [salt.state       :1951][INFO    ][13355] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:32:20.252685 duration_in_ms=27.364
2019-03-01 05:32:20,253 [salt.state       :1780][INFO    ][13355] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:32:20.253212
2019-03-01 05:32:20,253 [salt.state       :1813][INFO    ][13355] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-01 05:32:20,266 [salt.state       :300 ][INFO    ][13355] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-01 05:32:20,266 [salt.state       :1951][INFO    ][13355] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:32:20.266253 duration_in_ms=13.041
2019-03-01 05:32:20,267 [salt.state       :1780][INFO    ][13355] Running state [a2enmod headers] at time 05:32:20.267657
2019-03-01 05:32:20,268 [salt.state       :1813][INFO    ][13355] Executing state cmd.run for [a2enmod headers]
2019-03-01 05:32:20,268 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13355] Executing command 'a2enmod headers' in directory '/root'
2019-03-01 05:32:20,340 [salt.state       :300 ][INFO    ][13355] {'pid': 13374, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-01 05:32:20,340 [salt.state       :1951][INFO    ][13355] Completed state [a2enmod headers] at time 05:32:20.340527 duration_in_ms=72.869
2019-03-01 05:32:20,340 [salt.state       :1780][INFO    ][13355] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:32:20.340899
2019-03-01 05:32:20,341 [salt.state       :1813][INFO    ][13355] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-01 05:32:20,356 [salt.state       :300 ][INFO    ][13355] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-01 05:32:20,356 [salt.state       :1951][INFO    ][13355] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:32:20.356476 duration_in_ms=15.577
2019-03-01 05:32:20,357 [salt.state       :1780][INFO    ][13355] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:32:20.357043
2019-03-01 05:32:20,357 [salt.state       :1813][INFO    ][13355] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-01 05:32:20,427 [salt.state       :300 ][INFO    ][13355] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-01 05:32:20,427 [salt.state       :1951][INFO    ][13355] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:32:20.427517 duration_in_ms=70.472
2019-03-01 05:32:20,428 [salt.state       :1780][INFO    ][13355] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:32:20.428128
2019-03-01 05:32:20,428 [salt.state       :1813][INFO    ][13355] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-01 05:32:20,486 [salt.state       :300 ][INFO    ][13355] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-01 05:32:20,486 [salt.state       :1951][INFO    ][13355] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:32:20.486672 duration_in_ms=58.544
2019-03-01 05:32:20,487 [salt.state       :1780][INFO    ][13355] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:32:20.487137
2019-03-01 05:32:20,487 [salt.state       :1813][INFO    ][13355] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-01 05:32:20,546 [salt.state       :300 ][INFO    ][13355] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-01 05:32:20,546 [salt.state       :1951][INFO    ][13355] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:32:20.546529 duration_in_ms=59.392
2019-03-01 05:32:20,546 [salt.state       :1780][INFO    ][13355] Running state [/root/.pgpass] at time 05:32:20.546785
2019-03-01 05:32:20,547 [salt.state       :1813][INFO    ][13355] Executing state file.managed for [/root/.pgpass]
2019-03-01 05:32:20,588 [salt.state       :300 ][INFO    ][13355] File /root/.pgpass is in the correct state
2019-03-01 05:32:20,588 [salt.state       :1951][INFO    ][13355] Completed state [/root/.pgpass] at time 05:32:20.588508 duration_in_ms=41.723
2019-03-01 05:32:20,592 [salt.state       :1780][INFO    ][13355] Running state [maas-region syncdb --noinput] at time 05:32:20.592282
2019-03-01 05:32:20,592 [salt.state       :1813][INFO    ][13355] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-01 05:32:20,593 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13355] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-01 05:32:22,569 [salt.state       :300 ][INFO    ][13355] {'pid': 13387, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sessions, metadataserver, sites, auth, contenttypes, piston3, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-01 05:32:22,570 [salt.state       :1951][INFO    ][13355] Completed state [maas-region syncdb --noinput] at time 05:32:22.570162 duration_in_ms=1977.88
2019-03-01 05:32:22,570 [salt.state       :2022][WARNING ][13355] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-01 05:32:22,571 [salt.state       :1780][INFO    ][13355] Running state [maas-regiond] at time 05:32:22.571396
2019-03-01 05:32:22,571 [salt.state       :1813][INFO    ][13355] Executing state service.running for [maas-regiond]
2019-03-01 05:32:22,572 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13355] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-01 05:32:22,606 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13355] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-01 05:32:22,622 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13355] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-01 05:32:22,638 [salt.state       :300 ][INFO    ][13355] The service maas-regiond is already running
2019-03-01 05:32:22,638 [salt.state       :1951][INFO    ][13355] Completed state [maas-regiond] at time 05:32:22.638415 duration_in_ms=67.019
2019-03-01 05:32:22,639 [salt.state       :1780][INFO    ][13355] Running state [bind9] at time 05:32:22.639512
2019-03-01 05:32:22,639 [salt.state       :1813][INFO    ][13355] Executing state service.running for [bind9]
2019-03-01 05:32:22,640 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13355] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-01 05:32:22,656 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13355] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-01 05:32:22,671 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13355] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-01 05:32:22,685 [salt.state       :300 ][INFO    ][13355] The service bind9 is already running
2019-03-01 05:32:22,686 [salt.state       :1951][INFO    ][13355] Completed state [bind9] at time 05:32:22.685965 duration_in_ms=46.454
2019-03-01 05:32:22,687 [salt.state       :1780][INFO    ][13355] Running state [apache2] at time 05:32:22.687101
2019-03-01 05:32:22,687 [salt.state       :1813][INFO    ][13355] Executing state service.running for [apache2]
2019-03-01 05:32:22,687 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13355] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-01 05:32:22,703 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13355] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-01 05:32:22,718 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13355] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-01 05:32:22,737 [salt.state       :300 ][INFO    ][13355] The service apache2 is already running
2019-03-01 05:32:22,737 [salt.state       :1951][INFO    ][13355] Completed state [apache2] at time 05:32:22.737344 duration_in_ms=50.242
2019-03-01 05:32:22,738 [salt.state       :1780][INFO    ][13355] Running state [maasng.wait_for_http_code] at time 05:32:22.738403
2019-03-01 05:32:22,738 [salt.state       :1813][INFO    ][13355] Executing state module.run for [maasng.wait_for_http_code]
2019-03-01 05:32:22,739 [salt.utils.decorators:613 ][WARNING ][13355] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:32:22,836 [salt.state       :300 ][INFO    ][13355] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-01 05:32:22,837 [salt.state       :1951][INFO    ][13355] Completed state [maasng.wait_for_http_code] at time 05:32:22.837334 duration_in_ms=98.93
2019-03-01 05:32:22,838 [salt.state       :1780][INFO    ][13355] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:32:22.838810
2019-03-01 05:32:22,839 [salt.state       :1813][INFO    ][13355] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-01 05:32:22,840 [salt.state       :300 ][INFO    ][13355] /var/lib/maas/.setup_admin exists
2019-03-01 05:32:22,840 [salt.state       :1951][INFO    ][13355] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:32:22.840497 duration_in_ms=1.668
2019-03-01 05:32:22,841 [salt.state       :1780][INFO    ][13355] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:32:22.841690
2019-03-01 05:32:22,842 [salt.state       :1813][INFO    ][13355] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 05:32:22,843 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13355] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 05:32:24,181 [salt.state       :300 ][INFO    ][13355] {'pid': 13412, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 05:32:24,182 [salt.state       :1951][INFO    ][13355] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:32:24.182001 duration_in_ms=1340.31
2019-03-01 05:32:24,193 [salt.state       :1780][INFO    ][13355] Running state [maas_region_boot_source_resources_mirror] at time 05:32:24.193069
2019-03-01 05:32:24,193 [salt.state       :1813][INFO    ][13355] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-01 05:32:24,294 [salt.state       :300 ][INFO    ][13355] {'changes': {}}
2019-03-01 05:32:24,294 [salt.state       :1951][INFO    ][13355] Completed state [maas_region_boot_source_resources_mirror] at time 05:32:24.294607 duration_in_ms=101.537
2019-03-01 05:32:24,295 [salt.state       :1780][INFO    ][13355] Running state [maasng.boot_resources_import] at time 05:32:24.295832
2019-03-01 05:32:24,296 [salt.state       :1813][INFO    ][13355] Executing state module.run for [maasng.boot_resources_import]
2019-03-01 05:32:24,297 [salt.utils.decorators:613 ][WARNING ][13355] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:32:24,417 [salt.loaded.ext.module.maasng:1600][INFO    ][13355] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-01 05:32:29,469 [salt.loaded.ext.module.maasng:1600][INFO    ][13355] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-01 05:32:33,783 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053233770341
2019-03-01 05:32:33,805 [salt.minion      :1432][INFO    ][13457] Starting a new job with PID 13457
2019-03-01 05:32:33,830 [salt.minion      :1711][INFO    ][13457] Returning information for job: 20190301053233770341
2019-03-01 05:32:34,536 [salt.loaded.ext.module.maasng:1600][INFO    ][13355] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-03-01 05:32:39,644 [salt.state       :300 ][INFO    ][13355] {'ret': True}
2019-03-01 05:32:39,644 [salt.state       :1951][INFO    ][13355] Completed state [maasng.boot_resources_import] at time 05:32:39.644774 duration_in_ms=15348.942
2019-03-01 05:32:39,645 [salt.state       :1780][INFO    ][13355] Running state [maas_region_boot_sources_selection_xenial] at time 05:32:39.645873
2019-03-01 05:32:39,646 [salt.state       :1813][INFO    ][13355] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-01 05:32:39,859 [salt.state       :300 ][INFO    ][13355] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-01 05:32:39,860 [salt.state       :1951][INFO    ][13355] Completed state [maas_region_boot_sources_selection_xenial] at time 05:32:39.860074 duration_in_ms=214.201
2019-03-01 05:32:39,861 [salt.state       :1780][INFO    ][13355] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:32:39.861288
2019-03-01 05:32:39,861 [salt.state       :1813][INFO    ][13355] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-01 05:32:39,862 [salt.utils.decorators:613 ][WARNING ][13355] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:32:39,862 [salt.loaded.ext.module.maasng:1771][INFO    ][13355] boot-sources sync initiated for ALL Rack's
2019-03-01 05:32:40,901 [salt.state       :300 ][INFO    ][13355] {'ret': True}
2019-03-01 05:32:40,901 [salt.state       :1951][INFO    ][13355] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:32:40.901535 duration_in_ms=1040.246
2019-03-01 05:32:40,902 [salt.state       :1780][INFO    ][13355] Running state [maas.process_maas_config] at time 05:32:40.902866
2019-03-01 05:32:40,903 [salt.state       :1813][INFO    ][13355] Executing state module.run for [maas.process_maas_config]
2019-03-01 05:32:40,903 [salt.utils.decorators:613 ][WARNING ][13355] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:32:40,904 [salt.loaded.ext.module.maas:92  ][INFO    ][13355] maasconfig name=enable_http_proxy value=True
2019-03-01 05:32:40,963 [salt.loaded.ext.module.maas:92  ][INFO    ][13355] maasconfig name=upstream_dns value=8.8.8.8
2019-03-01 05:32:41,027 [salt.loaded.ext.module.maas:92  ][INFO    ][13355] maasconfig name=commissioning_distro_series value=xenial
2019-03-01 05:32:41,087 [salt.loaded.ext.module.maas:92  ][INFO    ][13355] maasconfig name=default_osystem value=ubuntu
2019-03-01 05:32:41,153 [salt.loaded.ext.module.maas:92  ][INFO    ][13355] maasconfig name=active_discovery_interval value=600
2019-03-01 05:32:41,207 [salt.loaded.ext.module.maas:92  ][INFO    ][13355] maasconfig name=dnssec_validation value=no
2019-03-01 05:32:44,060 [salt.loaded.ext.module.maas:92  ][INFO    ][13355] maasconfig name=maas_name value=mas01
2019-03-01 05:32:44,117 [salt.loaded.ext.module.maas:92  ][INFO    ][13355] maasconfig name=network_discovery value=enabled
2019-03-01 05:32:44,231 [salt.loaded.ext.module.maas:92  ][INFO    ][13355] maasconfig name=enable_third_party_drivers value=True
2019-03-01 05:32:44,278 [salt.loaded.ext.module.maas:92  ][INFO    ][13355] maasconfig name=default_storage_layout value=lvm
2019-03-01 05:32:44,339 [salt.loaded.ext.module.maas:92  ][INFO    ][13355] maasconfig name=ntp_external_only value=True
2019-03-01 05:32:44,392 [salt.loaded.ext.module.maas:92  ][INFO    ][13355] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-01 05:32:44,467 [salt.loaded.ext.module.maas:92  ][INFO    ][13355] maasconfig name=default_distro_series value=xenial
2019-03-01 05:32:44,524 [salt.loaded.ext.module.maas:92  ][INFO    ][13355] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-03-01 05:32:44,665 [salt.state       :300 ][INFO    ][13355] {'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-03-01 05:32:44,665 [salt.state       :1951][INFO    ][13355] Completed state [maas.process_maas_config] at time 05:32:44.665742 duration_in_ms=3762.873
2019-03-01 05:32:44,667 [salt.state       :1780][INFO    ][13355] Running state [pxe_admin] at time 05:32:44.666970
2019-03-01 05:32:44,667 [salt.state       :1813][INFO    ][13355] Executing state maasng.fabric_present for [pxe_admin]
2019-03-01 05:32:44,741 [salt.loaded.ext.module.maasng:945 ][INFO    ][13355] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, 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'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, 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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'78kbyg', 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/'}]
2019-03-01 05:32:44,805 [salt.loaded.ext.module.maasng:1008][WARNING ][13355] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-01 05:32:44,806 [salt.loaded.ext.module.maasng:1011][WARNING ][13355] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-01 05:32:44,864 [salt.state       :300 ][INFO    ][13355] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-01 05:32:44,865 [salt.state       :1951][INFO    ][13355] Completed state [pxe_admin] at time 05:32:44.865031 duration_in_ms=198.062
2019-03-01 05:32:44,865 [salt.state       :1780][INFO    ][13355] Running state [vlan 0] at time 05:32:44.865260
2019-03-01 05:32:44,865 [salt.state       :1813][INFO    ][13355] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-01 05:32:44,912 [salt.loaded.ext.module.maasng:945 ][INFO    ][13355] [{u'class_type': None, 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, 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'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-01 05:32:45,021 [salt.loaded.ext.module.maasng:945 ][INFO    ][13355] [{u'class_type': None, 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, 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'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-01 05:32:45,285 [salt.loaded.ext.module.maasng:945 ][INFO    ][13355] [{u'class_type': None, 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, 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'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-01 05:32:45,381 [salt.state       :300 ][INFO    ][13355] {'new': 'Vlan untagged was updated'}
2019-03-01 05:32:45,382 [salt.state       :1951][INFO    ][13355] Completed state [vlan 0] at time 05:32:45.382412 duration_in_ms=517.148
2019-03-01 05:32:45,384 [salt.state       :1780][INFO    ][13355] Running state [192.168.11.0/24] at time 05:32:45.384471
2019-03-01 05:32:45,384 [salt.state       :1813][INFO    ][13355] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-01 05:32:45,542 [salt.loaded.ext.module.maasng:945 ][INFO    ][13355] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'id': 1}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'mtu': 1500, 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'id': 2}]
2019-03-01 05:32:45,542 [salt.loaded.ext.module.maasng:1235][WARNING ][13355] Ignoring parameter vlan:0
2019-03-01 05:32:45,640 [salt.state       :300 ][INFO    ][13355] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-01 05:32:45,640 [salt.state       :1951][INFO    ][13355] Completed state [192.168.11.0/24] at time 05:32:45.640770 duration_in_ms=256.299
2019-03-01 05:32:45,642 [salt.state       :1780][INFO    ][13355] Running state [maas_create_iprange_1] at time 05:32:45.642149
2019-03-01 05:32:45,642 [salt.state       :1813][INFO    ][13355] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-01 05:32:45,717 [salt.state       :300 ][INFO    ][13355] Iprange maas_create_iprange_1 already exist.
2019-03-01 05:32:45,717 [salt.state       :1951][INFO    ][13355] Completed state [maas_create_iprange_1] at time 05:32:45.717772 duration_in_ms=75.623
2019-03-01 05:32:45,718 [salt.state       :1780][INFO    ][13355] Running state [vlan 0] at time 05:32:45.718257
2019-03-01 05:32:45,718 [salt.state       :1813][INFO    ][13355] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-01 05:32:45,776 [salt.loaded.ext.module.maasng:945 ][INFO    ][13355] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'id': 1}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'78kbyg', u'mtu': 1500, 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'id': 2}]
2019-03-01 05:32:45,865 [salt.loaded.ext.module.maasng:945 ][INFO    ][13355] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, 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'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, 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'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'78kbyg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, 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'id': 2}]
2019-03-01 05:32:46,058 [salt.loaded.ext.module.maasng:945 ][INFO    ][13355] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, 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'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, 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'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'78kbyg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, 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'id': 2}]
2019-03-01 05:32:46,118 [salt.state       :300 ][INFO    ][13355] {'new': 'Vlan untagged was updated'}
2019-03-01 05:32:46,118 [salt.state       :1951][INFO    ][13355] Completed state [vlan 0] at time 05:32:46.118717 duration_in_ms=400.459
2019-03-01 05:32:46,119 [salt.state       :1780][INFO    ][13355] Running state [opnfv] at time 05:32:46.119705
2019-03-01 05:32:46,120 [salt.state       :1813][INFO    ][13355] Executing state maasng.sshkey_present for [opnfv]
2019-03-01 05:32:46,159 [salt.loaded.ext.module.maasng:1903][INFO    ][13355] [{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-03-01 05:32:46,160 [salt.state       :300 ][INFO    ][13355] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-03-01 05:32:46,160 [salt.state       :1951][INFO    ][13355] Completed state [opnfv] at time 05:32:46.160558 duration_in_ms=40.852
2019-03-01 05:32:46,161 [salt.state       :1780][INFO    ][13355] Running state [maas.process_tags] at time 05:32:46.161573
2019-03-01 05:32:46,162 [salt.state       :1813][INFO    ][13355] Executing state module.run for [maas.process_tags]
2019-03-01 05:32:46,162 [salt.utils.decorators:613 ][WARNING ][13355] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:32:46,207 [salt.loaded.ext.module.maas:92  ][INFO    ][13355] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-03-01 05:32:46,255 [salt.state       :300 ][INFO    ][13355] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-03-01 05:32:46,255 [salt.state       :1951][INFO    ][13355] Completed state [maas.process_tags] at time 05:32:46.255574 duration_in_ms=94.001
2019-03-01 05:32:46,259 [salt.minion      :1711][INFO    ][13355] Returning information for job: 20190301053218648894
2019-03-01 05:32:46,774 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command state.apply with jid 20190301053246761921
2019-03-01 05:32:46,791 [salt.minion      :1432][INFO    ][13819] Starting a new job with PID 13819
2019-03-01 05:32:50,327 [salt.state       :915 ][INFO    ][13819] Loading fresh modules for state activity
2019-03-01 05:32:50,422 [salt.state       :1780][INFO    ][13819] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:32:50.422444
2019-03-01 05:32:50,422 [salt.state       :1813][INFO    ][13819] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 05:32:50,425 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13819] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 05:32:51,954 [salt.state       :300 ][INFO    ][13819] {'pid': 13845, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 05:32:51,955 [salt.state       :1951][INFO    ][13819] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:32:51.955079 duration_in_ms=1532.635
2019-03-01 05:32:51,957 [salt.state       :1780][INFO    ][13819] Running state [maas.process_machines] at time 05:32:51.957773
2019-03-01 05:32:51,958 [salt.state       :1813][INFO    ][13819] Executing state module.run for [maas.process_machines]
2019-03-01 05:32:51,959 [salt.utils.decorators:613 ][WARNING ][13819] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:32:52,676 [salt.loaded.ext.module.maas:412 ][WARNING ][13819] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 05:32:52,677 [salt.loaded.ext.module.maas:92  ][INFO    ][13819] 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=ttwhfp architecture=amd64/generic power_parameters_power_user=admin
2019-03-01 05:32:53,753 [salt.loaded.ext.module.maas:412 ][WARNING ][13819] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 05:32:53,754 [salt.loaded.ext.module.maas:92  ][INFO    ][13819] 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=hhsmgx architecture=amd64/generic power_parameters_power_user=admin
2019-03-01 05:32:55,017 [salt.loaded.ext.module.maas:412 ][WARNING ][13819] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 05:32:55,018 [salt.loaded.ext.module.maas:92  ][INFO    ][13819] 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=ck6pw4 architecture=amd64/generic power_parameters_power_user=admin
2019-03-01 05:32:56,214 [salt.loaded.ext.module.maas:412 ][WARNING ][13819] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 05:32:56,214 [salt.loaded.ext.module.maas:92  ][INFO    ][13819] 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=fdk4h7 architecture=amd64/generic power_parameters_power_user=admin
2019-03-01 05:32:57,267 [salt.loaded.ext.module.maas:412 ][WARNING ][13819] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 05:32:57,268 [salt.loaded.ext.module.maas:92  ][INFO    ][13819] 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=an7bda architecture=amd64/generic power_parameters_power_user=admin
2019-03-01 05:32:58,460 [salt.state       :300 ][INFO    ][13819] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-01 05:32:58,460 [salt.state       :1951][INFO    ][13819] Completed state [maas.process_machines] at time 05:32:58.460831 duration_in_ms=6503.057
2019-03-01 05:32:58,464 [salt.minion      :1711][INFO    ][13819] Returning information for job: 20190301053246761921
2019-03-01 05:33:31,846 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command state.apply with jid 20190301053331832382
2019-03-01 05:33:31,867 [salt.minion      :1432][INFO    ][14119] Starting a new job with PID 14119
2019-03-01 05:33:35,204 [salt.state       :915 ][INFO    ][14119] Loading fresh modules for state activity
2019-03-01 05:33:35,250 [salt.state       :1780][INFO    ][14119] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:33:35.250653
2019-03-01 05:33:35,250 [salt.state       :1813][INFO    ][14119] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 05:33:35,252 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14119] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 05:33:36,558 [salt.state       :300 ][INFO    ][14119] {'pid': 14126, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 05:33:36,559 [salt.state       :1951][INFO    ][14119] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:33:36.559479 duration_in_ms=1308.824
2019-03-01 05:33:36,561 [salt.state       :1780][INFO    ][14119] Running state [maas.wait_for_machine_status] at time 05:33:36.561884
2019-03-01 05:33:36,562 [salt.state       :1813][INFO    ][14119] Executing state module.run for [maas.wait_for_machine_status]
2019-03-01 05:33:36,562 [salt.utils.decorators:613 ][WARNING ][14119] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:33:39,590 [salt.loaded.ext.module.maas:993 ][INFO    ][14119] Machine an7bda mark broken
2019-03-01 05:33:40,117 [salt.loaded.ext.module.maas:996 ][INFO    ][14119] Machine an7bda mark fixed
2019-03-01 05:33:41,381 [salt.loaded.ext.module.maas:684 ][INFO    ][14119] deploymachines hwe_kernel=hwe-16.04 system_id=an7bda distro_series=xenial
2019-03-01 05:33:44,006 [salt.loaded.ext.module.maas:1023][INFO    ][14119] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1492.56428003s left)
2019-03-01 05:33:47,011 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053346945734
2019-03-01 05:33:47,034 [salt.minion      :1432][INFO    ][14197] Starting a new job with PID 14197
2019-03-01 05:33:47,056 [salt.minion      :1711][INFO    ][14197] Returning information for job: 20190301053346945734
2019-03-01 05:34:17,060 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053417047818
2019-03-01 05:34:17,084 [salt.minion      :1432][INFO    ][14246] Starting a new job with PID 14246
2019-03-01 05:34:17,109 [salt.minion      :1711][INFO    ][14246] Returning information for job: 20190301053417047818
2019-03-01 05:34:17,261 [salt.loaded.ext.module.maas:1023][INFO    ][14119] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1459.30835986s left)
2019-03-01 05:34:47,157 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053447144969
2019-03-01 05:34:47,180 [salt.minion      :1432][INFO    ][14274] Starting a new job with PID 14274
2019-03-01 05:34:47,205 [salt.minion      :1711][INFO    ][14274] Returning information for job: 20190301053447144969
2019-03-01 05:34:50,663 [salt.loaded.ext.module.maas:1023][INFO    ][14119] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1425.90673804s left)
2019-03-01 05:35:17,211 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053517196014
2019-03-01 05:35:17,233 [salt.minion      :1432][INFO    ][14314] Starting a new job with PID 14314
2019-03-01 05:35:17,258 [salt.minion      :1711][INFO    ][14314] Returning information for job: 20190301053517196014
2019-03-01 05:35:23,559 [salt.loaded.ext.module.maas:1023][INFO    ][14119] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1393.01035786s left)
2019-03-01 05:35:47,263 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053547250120
2019-03-01 05:35:47,285 [salt.minion      :1432][INFO    ][14394] Starting a new job with PID 14394
2019-03-01 05:35:47,309 [salt.minion      :1711][INFO    ][14394] Returning information for job: 20190301053547250120
2019-03-01 05:35:57,187 [salt.loaded.ext.module.maas:1023][INFO    ][14119] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1359.38290095s left)
2019-03-01 05:36:17,316 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053617303167
2019-03-01 05:36:17,338 [salt.minion      :1432][INFO    ][14513] Starting a new job with PID 14513
2019-03-01 05:36:17,361 [salt.minion      :1711][INFO    ][14513] Returning information for job: 20190301053617303167
2019-03-01 05:36:30,797 [salt.loaded.ext.module.maas:1023][INFO    ][14119] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1325.77239299s left)
2019-03-01 05:36:47,373 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053647358933
2019-03-01 05:36:47,392 [salt.minion      :1432][INFO    ][14547] Starting a new job with PID 14547
2019-03-01 05:36:47,414 [salt.minion      :1711][INFO    ][14547] Returning information for job: 20190301053647358933
2019-03-01 05:37:04,288 [salt.loaded.ext.module.maas:1023][INFO    ][14119] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1292.28137994s left)
2019-03-01 05:37:17,427 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053717414848
2019-03-01 05:37:17,451 [salt.minion      :1432][INFO    ][14662] Starting a new job with PID 14662
2019-03-01 05:37:17,475 [salt.minion      :1711][INFO    ][14662] Returning information for job: 20190301053717414848
2019-03-01 05:37:37,906 [salt.loaded.ext.module.maas:1023][INFO    ][14119] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1258.66385603s left)
2019-03-01 05:37:47,499 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053747485776
2019-03-01 05:37:47,522 [salt.minion      :1432][INFO    ][14700] Starting a new job with PID 14700
2019-03-01 05:37:47,546 [salt.minion      :1711][INFO    ][14700] Returning information for job: 20190301053747485776
2019-03-01 05:38:11,157 [salt.loaded.ext.module.maas:1023][INFO    ][14119] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1225.41312885s left)
2019-03-01 05:38:17,567 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053817554887
2019-03-01 05:38:17,591 [salt.minion      :1432][INFO    ][14771] Starting a new job with PID 14771
2019-03-01 05:38:17,616 [salt.minion      :1711][INFO    ][14771] Returning information for job: 20190301053817554887
2019-03-01 05:38:44,598 [salt.loaded.ext.module.maas:1023][INFO    ][14119] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1191.97201586s left)
2019-03-01 05:38:47,645 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053847632415
2019-03-01 05:38:47,668 [salt.minion      :1432][INFO    ][14830] Starting a new job with PID 14830
2019-03-01 05:38:47,693 [salt.minion      :1711][INFO    ][14830] Returning information for job: 20190301053847632415
2019-03-01 05:39:17,724 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053917712645
2019-03-01 05:39:17,745 [salt.minion      :1432][INFO    ][14938] Starting a new job with PID 14938
2019-03-01 05:39:17,768 [salt.minion      :1711][INFO    ][14938] Returning information for job: 20190301053917712645
2019-03-01 05:39:18,422 [salt.loaded.ext.module.maas:1023][INFO    ][14119] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1158.14816284s left)
2019-03-01 05:39:47,802 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301053947790618
2019-03-01 05:39:47,825 [salt.minion      :1432][INFO    ][14990] Starting a new job with PID 14990
2019-03-01 05:39:47,849 [salt.minion      :1711][INFO    ][14990] Returning information for job: 20190301053947790618
2019-03-01 05:39:52,035 [salt.loaded.ext.module.maas:1023][INFO    ][14119] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1124.53481603s left)
2019-03-01 05:40:17,890 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301054017877038
2019-03-01 05:40:17,913 [salt.minion      :1432][INFO    ][15036] Starting a new job with PID 15036
2019-03-01 05:40:17,938 [salt.minion      :1711][INFO    ][15036] Returning information for job: 20190301054017877038
2019-03-01 05:40:25,647 [salt.loaded.ext.module.maas:1023][INFO    ][14119] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1090.92267704s left)
2019-03-01 05:40:47,987 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301054047974311
2019-03-01 05:40:48,009 [salt.minion      :1432][INFO    ][15107] Starting a new job with PID 15107
2019-03-01 05:40:48,031 [salt.minion      :1711][INFO    ][15107] Returning information for job: 20190301054047974311
2019-03-01 05:40:59,127 [salt.state       :300 ][INFO    ][14119] {'ret': True}
2019-03-01 05:40:59,127 [salt.state       :1951][INFO    ][14119] Completed state [maas.wait_for_machine_status] at time 05:40:59.127719 duration_in_ms=442565.833
2019-03-01 05:40:59,131 [salt.minion      :1711][INFO    ][14119] Returning information for job: 20190301053331832382
2019-03-01 05:40:59,806 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command state.apply with jid 20190301054059793735
2019-03-01 05:40:59,829 [salt.minion      :1432][INFO    ][15136] Starting a new job with PID 15136
2019-03-01 05:41:03,330 [salt.state       :915 ][INFO    ][15136] Loading fresh modules for state activity
2019-03-01 05:41:03,482 [salt.state       :1780][INFO    ][15136] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:41:03.482019
2019-03-01 05:41:03,482 [salt.state       :1813][INFO    ][15136] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 05:41:03,484 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15136] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 05:41:04,912 [salt.state       :300 ][INFO    ][15136] {'pid': 15157, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 05:41:04,913 [salt.state       :1951][INFO    ][15136] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:41:04.913255 duration_in_ms=1431.236
2019-03-01 05:41:04,916 [salt.state       :1780][INFO    ][15136] Running state [maas_machines_storage_cmp002_lvm] at time 05:41:04.916072
2019-03-01 05:41:04,916 [salt.state       :1813][INFO    ][15136] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-01 05:41:05,399 [salt.state       :300 ][INFO    ][15136] Machine cmp002 is not in Ready state.
2019-03-01 05:41:05,399 [salt.state       :1951][INFO    ][15136] Completed state [maas_machines_storage_cmp002_lvm] at time 05:41:05.399427 duration_in_ms=483.354
2019-03-01 05:41:05,400 [salt.state       :1780][INFO    ][15136] Running state [maas_machines_storage_cmp001_lvm] at time 05:41:05.399988
2019-03-01 05:41:05,400 [salt.state       :1813][INFO    ][15136] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-01 05:41:05,840 [salt.state       :300 ][INFO    ][15136] Machine cmp001 is not in Ready state.
2019-03-01 05:41:05,841 [salt.state       :1951][INFO    ][15136] Completed state [maas_machines_storage_cmp001_lvm] at time 05:41:05.841314 duration_in_ms=441.325
2019-03-01 05:41:05,844 [salt.minion      :1711][INFO    ][15136] Returning information for job: 20190301054059793735
2019-03-01 05:41:06,403 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command state.apply with jid 20190301054106390677
2019-03-01 05:41:06,425 [salt.minion      :1432][INFO    ][15167] Starting a new job with PID 15167
2019-03-01 05:41:07,029 [salt.state       :915 ][INFO    ][15167] Loading fresh modules for state activity
2019-03-01 05:41:07,118 [salt.state       :1780][INFO    ][15167] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:41:07.118281
2019-03-01 05:41:07,118 [salt.state       :1813][INFO    ][15167] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 05:41:07,122 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15167] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 05:41:08,746 [salt.state       :300 ][INFO    ][15167] {'pid': 15174, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 05:41:08,746 [salt.state       :1951][INFO    ][15167] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:41:08.746719 duration_in_ms=1628.437
2019-03-01 05:41:08,749 [salt.state       :1780][INFO    ][15167] Running state [maas.deploy_machines] at time 05:41:08.749034
2019-03-01 05:41:08,749 [salt.state       :1813][INFO    ][15167] Executing state module.run for [maas.deploy_machines]
2019-03-01 05:41:08,750 [salt.utils.decorators:613 ][WARNING ][15167] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:41:09,425 [salt.state       :300 ][INFO    ][15167] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-01 05:41:09,426 [salt.state       :1951][INFO    ][15167] Completed state [maas.deploy_machines] at time 05:41:09.426226 duration_in_ms=677.192
2019-03-01 05:41:09,430 [salt.minion      :1711][INFO    ][15167] Returning information for job: 20190301054106390677
2019-03-01 05:41:10,058 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command state.apply with jid 20190301054110048361
2019-03-01 05:41:10,082 [salt.minion      :1432][INFO    ][15287] Starting a new job with PID 15287
2019-03-01 05:41:10,708 [salt.state       :915 ][INFO    ][15287] Loading fresh modules for state activity
2019-03-01 05:41:10,799 [salt.state       :1780][INFO    ][15287] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:41:10.799522
2019-03-01 05:41:10,799 [salt.state       :1813][INFO    ][15287] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 05:41:10,802 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15287] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 05:41:12,250 [salt.state       :300 ][INFO    ][15287] {'pid': 15294, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 05:41:12,250 [salt.state       :1951][INFO    ][15287] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:41:12.250425 duration_in_ms=1450.903
2019-03-01 05:41:12,251 [salt.state       :1780][INFO    ][15287] Running state [maas.wait_for_machine_status] at time 05:41:12.251645
2019-03-01 05:41:12,251 [salt.state       :1813][INFO    ][15287] Executing state module.run for [maas.wait_for_machine_status]
2019-03-01 05:41:12,252 [salt.utils.decorators:613 ][WARNING ][15287] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 05:41:15,716 [salt.state       :300 ][INFO    ][15287] {'ret': True}
2019-03-01 05:41:15,717 [salt.state       :1951][INFO    ][15287] Completed state [maas.wait_for_machine_status] at time 05:41:15.716985 duration_in_ms=3465.338
2019-03-01 05:41:15,720 [salt.minion      :1711][INFO    ][15287] Returning information for job: 20190301054110048361
2019-03-01 06:10:38,151 [salt.utils.schedule:1377][INFO    ][7252] Running scheduled job: __mine_interval
2019-03-01 07:10:38,151 [salt.utils.schedule:1377][INFO    ][7252] Running scheduled job: __mine_interval
2019-03-01 07:25:16,584 [salt.minion      :1308][INFO    ][7252] User sudo_ubuntu Executing command cp.push_dir with jid 20190301072516572407
2019-03-01 07:25:16,602 [salt.minion      :1432][INFO    ][22659] Starting a new job with PID 22659
