2019-05-10 13:56:32,667 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-10 13:57:22,717 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-10 13:58:12,763 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-10 13:59:02,807 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-10 13:59:52,845 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-10 14:02:02,314 [salt.utils.decorators:613 ][WARNING ][2844] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:02:02,952 [salt.utils.decorators:613 ][WARNING ][2844] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:02:05,072 [salt.loaded.int.states.file:2298][WARNING ][2987] 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-05-10 14:02:10,611 [salt.state       :2022][WARNING ][3095] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-10 14:02:13,142 [salt.utils.decorators:613 ][WARNING ][3095] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:02:28,237 [salt.utils.decorators:613 ][WARNING ][3095] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:03:01,939 [salt.utils.decorators:613 ][WARNING ][3095] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:03:02,964 [salt.utils.decorators:613 ][WARNING ][3095] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:03:06,838 [salt.loaded.ext.module.maasng:1008][WARNING ][3095] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-05-10 14:03:06,839 [salt.loaded.ext.module.maasng:1011][WARNING ][3095] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-05-10 14:03:07,737 [salt.loaded.ext.module.maasng:1235][WARNING ][3095] Ignoring parameter vlan:0
2019-05-10 14:03:08,741 [salt.utils.decorators:613 ][WARNING ][3095] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:03:14,451 [salt.utils.decorators:613 ][WARNING ][6042] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:03:14,516 [salt.loaded.ext.module.maas:412 ][WARNING ][6042] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 14:03:15,918 [salt.loaded.ext.module.maas:412 ][WARNING ][6042] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 14:03:17,158 [salt.loaded.ext.module.maas:412 ][WARNING ][6042] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 14:03:18,530 [salt.loaded.ext.module.maas:412 ][WARNING ][6042] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 14:03:19,811 [salt.loaded.ext.module.maas:412 ][WARNING ][6042] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 14:03:23,792 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6653] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-10 14:03:23,824 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6653] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-10 14:03:23,846 [salt.utils.parsers:1051][WARNING ][359] Minion received a SIGTERM. Exiting.
2019-05-10 14:03:24,920 [salt.cli.daemons :293 ][INFO    ][6704] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-05-10 14:03:25,011 [salt.cli.daemons :82  ][INFO    ][6704] Starting up the Salt Minion
2019-05-10 14:03:25,012 [salt.utils.event :1017][INFO    ][6704] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-05-10 14:03:25,930 [salt.minion      :976 ][INFO    ][6704] Creating minion process manager
2019-05-10 14:03:27,467 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6704] Executing command ['date', '+%z'] in directory '/root'
2019-05-10 14:03:27,486 [salt.utils.schedule:568 ][INFO    ][6704] Updating job settings for scheduled job: __mine_interval
2019-05-10 14:03:27,488 [salt.minion      :1108][INFO    ][6704] Added mine.update to scheduler
2019-05-10 14:03:27,491 [salt.minion      :1975][INFO    ][6704] Minion is starting as user 'root'
2019-05-10 14:03:27,503 [salt.minion      :2336][INFO    ][6704] Minion is ready to receive requests!
2019-05-10 14:03:52,322 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command state.apply with jid 20190510140352312322
2019-05-10 14:03:52,344 [salt.minion      :1432][INFO    ][6798] Starting a new job with PID 6798
2019-05-10 14:03:56,029 [salt.state       :915 ][INFO    ][6798] Loading fresh modules for state activity
2019-05-10 14:03:56,057 [salt.fileclient  :1219][INFO    ][6798] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-10 14:03:56,083 [salt.state       :1780][INFO    ][6798] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:03:56.083140
2019-05-10 14:03:56,083 [salt.state       :1813][INFO    ][6798] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 14:03:56,084 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6798] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 14:03:57,559 [salt.state       :300 ][INFO    ][6798] {'pid': 6806, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 14:03:57,560 [salt.state       :1951][INFO    ][6798] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:03:57.560332 duration_in_ms=1477.19
2019-05-10 14:03:57,563 [salt.state       :1780][INFO    ][6798] Running state [maas.wait_for_machine_status] at time 14:03:57.563659
2019-05-10 14:03:57,564 [salt.state       :1813][INFO    ][6798] Executing state module.run for [maas.wait_for_machine_status]
2019-05-10 14:03:57,565 [salt.utils.decorators:613 ][WARNING ][6798] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:03:58,514 [salt.loaded.ext.module.maas:1023][INFO    ][6798] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.06067514s left)
2019-05-10 14:04:07,436 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510140407421269
2019-05-10 14:04:07,460 [salt.minion      :1432][INFO    ][6832] Starting a new job with PID 6832
2019-05-10 14:04:07,483 [salt.minion      :1711][INFO    ][6832] Returning information for job: 20190510140407421269
2019-05-10 14:04:29,512 [salt.loaded.ext.module.maas:1023][INFO    ][6798] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.06277108s left)
2019-05-10 14:04:37,491 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510140437479319
2019-05-10 14:04:37,515 [salt.minion      :1432][INFO    ][6877] Starting a new job with PID 6877
2019-05-10 14:04:37,534 [salt.minion      :1711][INFO    ][6877] Returning information for job: 20190510140437479319
2019-05-10 14:05:00,523 [salt.loaded.ext.module.maas:1023][INFO    ][6798] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.05160403s left)
2019-05-10 14:05:07,570 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510140507557888
2019-05-10 14:05:07,590 [salt.minion      :1432][INFO    ][7022] Starting a new job with PID 7022
2019-05-10 14:05:07,601 [salt.minion      :1711][INFO    ][7022] Returning information for job: 20190510140507557888
2019-05-10 14:05:31,728 [salt.loaded.ext.module.maas:1023][INFO    ][6798] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.84669018s left)
2019-05-10 14:05:37,603 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510140537590380
2019-05-10 14:05:37,627 [salt.minion      :1432][INFO    ][7212] Starting a new job with PID 7212
2019-05-10 14:05:37,649 [salt.minion      :1711][INFO    ][7212] Returning information for job: 20190510140537590380
2019-05-10 14:06:03,426 [salt.loaded.ext.module.maas:1023][INFO    ][6798] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.14857697s left)
2019-05-10 14:06:07,662 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510140607646555
2019-05-10 14:06:07,686 [salt.minion      :1432][INFO    ][7383] Starting a new job with PID 7383
2019-05-10 14:06:07,707 [salt.minion      :1711][INFO    ][7383] Returning information for job: 20190510140607646555
2019-05-10 14:06:35,269 [salt.loaded.ext.module.maas:1023][INFO    ][6798] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.30571103s left)
2019-05-10 14:06:37,719 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510140637703128
2019-05-10 14:06:37,743 [salt.minion      :1432][INFO    ][7926] Starting a new job with PID 7926
2019-05-10 14:06:37,766 [salt.minion      :1711][INFO    ][7926] Returning information for job: 20190510140637703128
2019-05-10 14:07:07,790 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510140707773568
2019-05-10 14:07:07,814 [salt.minion      :1432][INFO    ][8140] Starting a new job with PID 8140
2019-05-10 14:07:07,834 [salt.minion      :1711][INFO    ][8140] Returning information for job: 20190510140707773568
2019-05-10 14:07:08,587 [salt.state       :300 ][INFO    ][6798] {'ret': True}
2019-05-10 14:07:08,587 [salt.state       :1951][INFO    ][6798] Completed state [maas.wait_for_machine_status] at time 14:07:08.587440 duration_in_ms=191023.779
2019-05-10 14:07:08,592 [salt.minion      :1711][INFO    ][6798] Returning information for job: 20190510140352312322
2019-05-10 14:07:09,215 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command state.apply with jid 20190510140709203549
2019-05-10 14:07:09,237 [salt.minion      :1432][INFO    ][8148] Starting a new job with PID 8148
2019-05-10 14:07:12,963 [salt.state       :915 ][INFO    ][8148] Loading fresh modules for state activity
2019-05-10 14:07:12,995 [salt.fileclient  :1219][INFO    ][8148] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-10 14:07:13,057 [salt.state       :1780][INFO    ][8148] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:07:13.057227
2019-05-10 14:07:13,057 [salt.state       :1813][INFO    ][8148] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 14:07:13,058 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8148] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 14:07:14,382 [salt.state       :300 ][INFO    ][8148] {'pid': 8239, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 14:07:14,383 [salt.state       :1951][INFO    ][8148] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:07:14.382953 duration_in_ms=1325.725
2019-05-10 14:07:14,386 [salt.state       :1780][INFO    ][8148] Running state [maas_machines_storage_cmp002_lvm] at time 14:07:14.385937
2019-05-10 14:07:14,386 [salt.state       :1813][INFO    ][8148] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-10 14:07:15,578 [salt.loaded.ext.module.maasng:610 ][INFO    ][8148] apsdm8
2019-05-10 14:07:15,578 [salt.loaded.ext.module.maasng:626 ][INFO    ][8148] sda
2019-05-10 14:07:16,279 [salt.loaded.ext.module.maasng:361 ][INFO    ][8148] apsdm8
2019-05-10 14:07:16,400 [salt.loaded.ext.module.maasng:367 ][INFO    ][8148] [{u'resource_uri': u'/MAAS/api/2.0/nodes/apsdm8/blockdevices/2/', u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'c62d8060-13b3-4e9e-b848-12f9de0009c6', u'resource_uri': u'/MAAS/api/2.0/nodes/apsdm8/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'apsdm8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'34f31bba-7fdb-4ca0-b466-fecf2a16df2b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 2, u'device_id': 2}], u'uuid': None, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'apsdm8', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/apsdm8/blockdevices/7/', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'uuid': u'e63d1450-6870-40e8-8bd2-e8212da31e8a', u'id': 7, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'apsdm8', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'24551813-3e33-4e72-b750-2de910fb4efe', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 2397988454400, u'serial': None, u'size': 2397988454400}]
2019-05-10 14:07:16,401 [salt.loaded.ext.module.maasng:632 ][INFO    ][8148] vgroot
2019-05-10 14:07:16,401 [salt.loaded.ext.module.maasng:635 ][INFO    ][8148] lvroot
2019-05-10 14:07:16,401 [salt.loaded.ext.module.maasng:639 ][INFO    ][8148] 107374182400
2019-05-10 14:07:17,107 [salt.loaded.ext.module.maasng:645 ][INFO    ][8148] {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'iscsiblockdevice_set': [], u'boot_disk': {u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/apsdm8/blockdevices/2/', u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'f63863da-cd5d-425f-bc25-d69148e61e3f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'apsdm8', u'filesystem': {u'uuid': u'0fbc74f7-2bb9-410e-830c-fd20165497cb', u'label': None, u'mount_point': None, u'mount_options': 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': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/apsdm8/blockdevices/2/partition/6'}], u'filesystem': None, u'uuid': None, u'id': 2, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'apsdm8', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'model': u'UCSB-MRAID12G', u'tags': [u'rotary'], u'size': 2397998940160}, u'blockdevice_set': [{u'partition_table_type': u'GPT', u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'f63863da-cd5d-425f-bc25-d69148e61e3f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'apsdm8', u'filesystem': {u'uuid': u'0fbc74f7-2bb9-410e-830c-fd20165497cb', u'label': None, u'mount_point': None, u'mount_options': 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': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/apsdm8/blockdevices/2/partition/6'}], u'filesystem': None, u'uuid': None, u'id': 2, u'system_id': u'apsdm8', u'resource_uri': u'/MAAS/api/2.0/nodes/apsdm8/blockdevices/2/', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'model': u'UCSB-MRAID12G', u'size': 2397998940160}, {u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'efa0002f-b264-40e8-9723-b116d44f211f', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'd6edff33-9f0c-4ac1-b0f7-540b866da893', u'id': 11, u'system_id': u'apsdm8', u'resource_uri': u'/MAAS/api/2.0/nodes/apsdm8/blockdevices/11/', u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'size': 107374182400}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'apsdm8', 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'owner_data': {}, u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'physicalblockdevice_set': [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/apsdm8/blockdevices/2/', u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'f63863da-cd5d-425f-bc25-d69148e61e3f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'apsdm8', u'filesystem': {u'uuid': u'0fbc74f7-2bb9-410e-830c-fd20165497cb', u'label': None, u'mount_point': None, u'mount_options': 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': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/apsdm8/blockdevices/2/partition/6'}], u'filesystem': None, u'uuid': None, u'id': 2, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'apsdm8', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'model': u'UCSB-MRAID12G', u'tags': [u'rotary'], u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'apsdm8', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_interface': {u'vlan': {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'8bmmsr', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'8bmmsr', 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': 21, u'mode': u'dhcp'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'8bmmsr', 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'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'id': 4, u'system_id': u'apsdm8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/apsdm8/interfaces/4/'}, u'interface_set': [{u'vlan': {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'8bmmsr', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'8bmmsr', 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': 21, u'mode': u'dhcp'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'8bmmsr', 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'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'id': 4, u'system_id': u'apsdm8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/apsdm8/interfaces/4/'}, {u'vlan': {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'enp9s0', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'id': 12, u'system_id': u'apsdm8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/apsdm8/interfaces/12/'}, {u'vlan': {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'enp7s0', u'links': [{u'id': 23, u'mode': u'link_up'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'id': 13, u'system_id': u'apsdm8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/apsdm8/interfaces/13/'}, {u'vlan': {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'enp8s0', u'links': [{u'id': 24, u'mode': u'link_up'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'id': 14, u'system_id': u'apsdm8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/apsdm8/interfaces/14/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/apsdm8/blockdevices/11/', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'efa0002f-b264-40e8-9723-b116d44f211f', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'd6edff33-9f0c-4ac1-b0f7-540b866da893', u'id': 11, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'apsdm8', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'tags': [], u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/apsdm8/', u'distro_series': u'', u'node_type_name': u'Machine'}
2019-05-10 14:07:17,110 [salt.state       :300 ][INFO    ][8148] {'new': {'storage_layout': 'lvm'}}
2019-05-10 14:07:17,110 [salt.state       :1951][INFO    ][8148] Completed state [maas_machines_storage_cmp002_lvm] at time 14:07:17.110805 duration_in_ms=2724.866
2019-05-10 14:07:17,111 [salt.state       :1780][INFO    ][8148] Running state [maas_machines_storage_cmp001_lvm] at time 14:07:17.111411
2019-05-10 14:07:17,111 [salt.state       :1813][INFO    ][8148] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-10 14:07:18,603 [salt.loaded.ext.module.maasng:610 ][INFO    ][8148] qsrkqn
2019-05-10 14:07:18,603 [salt.loaded.ext.module.maasng:626 ][INFO    ][8148] sda
2019-05-10 14:07:19,303 [salt.loaded.ext.module.maasng:361 ][INFO    ][8148] qsrkqn
2019-05-10 14:07:19,417 [salt.loaded.ext.module.maasng:367 ][INFO    ][8148] [{u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/qsrkqn/blockdevices/1/', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'a215df61-cfaa-4a65-98c3-1cc1ac462fc4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qsrkqn', u'device_id': 1, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'3d659675-a24d-48c2-9186-5ae13a0500b0', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/qsrkqn/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 2397992648704}], u'tags': [u'rotary'], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'qsrkqn', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 2397998940160}, {u'model': None, u'uuid': u'aab1b944-5606-49ee-bdc4-dd62f8f107f5', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/qsrkqn/blockdevices/6/', u'used_size': 2397988454400, u'partitions': [], u'tags': [], u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'9ea1f094-9fab-462e-bffd-e409d914f33e', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qsrkqn', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 6, u'size': 2397988454400}]
2019-05-10 14:07:19,418 [salt.loaded.ext.module.maasng:632 ][INFO    ][8148] vgroot
2019-05-10 14:07:19,418 [salt.loaded.ext.module.maasng:635 ][INFO    ][8148] lvroot
2019-05-10 14:07:19,419 [salt.loaded.ext.module.maasng:639 ][INFO    ][8148] 107374182400
2019-05-10 14:07:20,119 [salt.loaded.ext.module.maasng:645 ][INFO    ][8148] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8bmmsr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, 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': 15, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8bmmsr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:5a', 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'mtu': 1500, u'primary_rack': u'8bmmsr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'qsrkqn', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/qsrkqn/interfaces/5/'}, u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/qsrkqn/blockdevices/1/', u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'a6a833be-387e-435a-b8fe-3401e62eab4d', u'resource_uri': u'/MAAS/api/2.0/nodes/qsrkqn/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qsrkqn', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ee035b83-59db-4c17-8ec4-674ec3f8453d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 1}], u'uuid': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qsrkqn', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/qsrkqn/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'uuid': u'66a0ed01-836c-4184-8c66-3c1f4f92a5ae', u'id': 12, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'qsrkqn', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'21009b10-55ae-4519-ae9a-8cb6b20be2ca', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'qsrkqn', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/qsrkqn/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'uuid': u'66a0ed01-836c-4184-8c66-3c1f4f92a5ae', u'id': 12, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'qsrkqn', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'21009b10-55ae-4519-ae9a-8cb6b20be2ca', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'off', u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qsrkqn', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/qsrkqn/blockdevices/1/', u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'a6a833be-387e-435a-b8fe-3401e62eab4d', u'resource_uri': u'/MAAS/api/2.0/nodes/qsrkqn/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qsrkqn', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ee035b83-59db-4c17-8ec4-674ec3f8453d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 1}], u'uuid': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qsrkqn', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/qsrkqn/blockdevices/1/', u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'a6a833be-387e-435a-b8fe-3401e62eab4d', u'resource_uri': u'/MAAS/api/2.0/nodes/qsrkqn/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qsrkqn', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ee035b83-59db-4c17-8ec4-674ec3f8453d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 1}], u'uuid': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qsrkqn', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'ip_addresses': [u'192.168.11.38'], u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8bmmsr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, 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': 15, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8bmmsr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:5a', 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'mtu': 1500, u'primary_rack': u'8bmmsr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'qsrkqn', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/qsrkqn/interfaces/5/'}, {u'name': u'enp9s0', u'links': [{u'id': 16, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:5d', u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'qsrkqn', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/qsrkqn/interfaces/9/'}, {u'name': u'enp7s0', 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'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:5b', u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'qsrkqn', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/qsrkqn/interfaces/10/'}, {u'name': u'enp8s0', u'links': [{u'id': 18, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:5c', u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'qsrkqn', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/qsrkqn/interfaces/11/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/qsrkqn/'}
2019-05-10 14:07:20,122 [salt.state       :300 ][INFO    ][8148] {'new': {'storage_layout': 'lvm'}}
2019-05-10 14:07:20,122 [salt.state       :1951][INFO    ][8148] Completed state [maas_machines_storage_cmp001_lvm] at time 14:07:20.122571 duration_in_ms=3011.159
2019-05-10 14:07:20,126 [salt.minion      :1711][INFO    ][8148] Returning information for job: 20190510140709203549
2019-05-10 14:07:20,749 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command state.apply with jid 20190510140720734041
2019-05-10 14:07:20,772 [salt.minion      :1432][INFO    ][8310] Starting a new job with PID 8310
2019-05-10 14:07:21,566 [salt.state       :915 ][INFO    ][8310] Loading fresh modules for state activity
2019-05-10 14:07:21,617 [salt.fileclient  :1219][INFO    ][8310] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-10 14:07:21,656 [salt.state       :1780][INFO    ][8310] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:07:21.655956
2019-05-10 14:07:21,656 [salt.state       :1813][INFO    ][8310] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 14:07:21,658 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8310] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 14:07:22,996 [salt.state       :300 ][INFO    ][8310] {'pid': 8318, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 14:07:22,997 [salt.state       :1951][INFO    ][8310] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:07:22.997269 duration_in_ms=1341.312
2019-05-10 14:07:22,999 [salt.state       :1780][INFO    ][8310] Running state [maas.deploy_machines] at time 14:07:22.999771
2019-05-10 14:07:23,000 [salt.state       :1813][INFO    ][8310] Executing state module.run for [maas.deploy_machines]
2019-05-10 14:07:23,001 [salt.utils.decorators:613 ][WARNING ][8310] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:07:23,700 [salt.loaded.ext.module.maas:684 ][INFO    ][8310] deploymachines hwe_kernel=hwe-16.04 system_id=apsdm8 distro_series=xenial
2019-05-10 14:07:26,515 [salt.loaded.ext.module.maas:684 ][INFO    ][8310] deploymachines hwe_kernel=hwe-16.04 system_id=qsrkqn distro_series=xenial
2019-05-10 14:07:29,273 [salt.loaded.ext.module.maas:684 ][INFO    ][8310] deploymachines hwe_kernel=hwe-16.04 system_id=ap6axc distro_series=xenial
2019-05-10 14:07:31,889 [salt.loaded.ext.module.maas:684 ][INFO    ][8310] deploymachines hwe_kernel=hwe-16.04 system_id=x3mapm distro_series=xenial
2019-05-10 14:07:34,543 [salt.loaded.ext.module.maas:684 ][INFO    ][8310] deploymachines hwe_kernel=hwe-16.04 system_id=kkp3mx distro_series=xenial
2019-05-10 14:07:35,837 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510140735825024
2019-05-10 14:07:35,861 [salt.minion      :1432][INFO    ][8574] Starting a new job with PID 8574
2019-05-10 14:07:35,882 [salt.minion      :1711][INFO    ][8574] Returning information for job: 20190510140735825024
2019-05-10 14:07:36,724 [salt.state       :300 ][INFO    ][8310] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-10 14:07:36,725 [salt.state       :1951][INFO    ][8310] Completed state [maas.deploy_machines] at time 14:07:36.725410 duration_in_ms=13725.638
2019-05-10 14:07:36,729 [salt.minion      :1711][INFO    ][8310] Returning information for job: 20190510140720734041
2019-05-10 14:07:37,331 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command state.apply with jid 20190510140737318537
2019-05-10 14:07:37,355 [salt.minion      :1432][INFO    ][8598] Starting a new job with PID 8598
2019-05-10 14:07:40,940 [salt.state       :915 ][INFO    ][8598] Loading fresh modules for state activity
2019-05-10 14:07:40,996 [salt.fileclient  :1219][INFO    ][8598] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-10 14:07:41,040 [salt.state       :1780][INFO    ][8598] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:07:41.040063
2019-05-10 14:07:41,040 [salt.state       :1813][INFO    ][8598] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 14:07:41,042 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8598] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 14:07:42,506 [salt.state       :300 ][INFO    ][8598] {'pid': 8611, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 14:07:42,507 [salt.state       :1951][INFO    ][8598] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:07:42.507687 duration_in_ms=1467.622
2019-05-10 14:07:42,511 [salt.state       :1780][INFO    ][8598] Running state [maas.wait_for_machine_status] at time 14:07:42.510987
2019-05-10 14:07:42,511 [salt.state       :1813][INFO    ][8598] Executing state module.run for [maas.wait_for_machine_status]
2019-05-10 14:07:42,512 [salt.utils.decorators:613 ][WARNING ][8598] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:07:45,759 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.76326299s left)
2019-05-10 14:07:52,447 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510140752434076
2019-05-10 14:07:52,467 [salt.minion      :1432][INFO    ][8625] Starting a new job with PID 8625
2019-05-10 14:07:52,487 [salt.minion      :1711][INFO    ][8625] Returning information for job: 20190510140752434076
2019-05-10 14:08:19,401 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.12082505s left)
2019-05-10 14:08:22,487 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510140822473931
2019-05-10 14:08:22,511 [salt.minion      :1432][INFO    ][8683] Starting a new job with PID 8683
2019-05-10 14:08:22,533 [salt.minion      :1711][INFO    ][8683] Returning information for job: 20190510140822473931
2019-05-10 14:08:52,558 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510140852545818
2019-05-10 14:08:52,583 [salt.minion      :1432][INFO    ][8703] Starting a new job with PID 8703
2019-05-10 14:08:52,605 [salt.minion      :1711][INFO    ][8703] Returning information for job: 20190510140852545818
2019-05-10 14:08:52,813 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.70862794s left)
2019-05-10 14:09:22,591 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510140922579770
2019-05-10 14:09:22,611 [salt.minion      :1432][INFO    ][8851] Starting a new job with PID 8851
2019-05-10 14:09:22,622 [salt.minion      :1711][INFO    ][8851] Returning information for job: 20190510140922579770
2019-05-10 14:09:25,727 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.79540801s left)
2019-05-10 14:09:52,634 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510140952621614
2019-05-10 14:09:52,658 [salt.minion      :1432][INFO    ][9017] Starting a new job with PID 9017
2019-05-10 14:09:52,681 [salt.minion      :1711][INFO    ][9017] Returning information for job: 20190510140952621614
2019-05-10 14:09:59,437 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.08549213s left)
2019-05-10 14:10:22,691 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141022678499
2019-05-10 14:10:22,716 [salt.minion      :1432][INFO    ][9775] Starting a new job with PID 9775
2019-05-10 14:10:22,738 [salt.minion      :1711][INFO    ][9775] Returning information for job: 20190510141022678499
2019-05-10 14:10:32,134 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.38822198s left)
2019-05-10 14:10:52,748 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141052733665
2019-05-10 14:10:52,772 [salt.minion      :1432][INFO    ][9795] Starting a new job with PID 9795
2019-05-10 14:10:52,795 [salt.minion      :1711][INFO    ][9795] Returning information for job: 20190510141052733665
2019-05-10 14:11:05,655 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.867414s left)
2019-05-10 14:11:22,807 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141122794225
2019-05-10 14:11:22,831 [salt.minion      :1432][INFO    ][9971] Starting a new job with PID 9971
2019-05-10 14:11:22,851 [salt.minion      :1711][INFO    ][9971] Returning information for job: 20190510141122794225
2019-05-10 14:11:38,793 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.72924709s left)
2019-05-10 14:11:52,868 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141152855459
2019-05-10 14:11:52,893 [salt.minion      :1432][INFO    ][10001] Starting a new job with PID 10001
2019-05-10 14:11:52,916 [salt.minion      :1711][INFO    ][10001] Returning information for job: 20190510141152855459
2019-05-10 14:12:11,505 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1981.016572s left)
2019-05-10 14:12:22,942 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141222926094
2019-05-10 14:12:22,967 [salt.minion      :1432][INFO    ][10625] Starting a new job with PID 10625
2019-05-10 14:12:22,988 [salt.minion      :1711][INFO    ][10625] Returning information for job: 20190510141222926094
2019-05-10 14:12:45,184 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.337672s left)
2019-05-10 14:12:53,014 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141253001928
2019-05-10 14:12:53,038 [salt.minion      :1432][INFO    ][10663] Starting a new job with PID 10663
2019-05-10 14:12:53,061 [salt.minion      :1711][INFO    ][10663] Returning information for job: 20190510141253001928
2019-05-10 14:13:18,042 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1914.47962403s left)
2019-05-10 14:13:23,091 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141323078321
2019-05-10 14:13:23,115 [salt.minion      :1432][INFO    ][11035] Starting a new job with PID 11035
2019-05-10 14:13:23,138 [salt.minion      :1711][INFO    ][11035] Returning information for job: 20190510141323078321
2019-05-10 14:13:51,858 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.66370201s left)
2019-05-10 14:13:53,173 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141353160869
2019-05-10 14:13:53,197 [salt.minion      :1432][INFO    ][11055] Starting a new job with PID 11055
2019-05-10 14:13:53,219 [salt.minion      :1711][INFO    ][11055] Returning information for job: 20190510141353160869
2019-05-10 14:14:23,257 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141423245196
2019-05-10 14:14:23,281 [salt.minion      :1432][INFO    ][11111] Starting a new job with PID 11111
2019-05-10 14:14:23,304 [salt.minion      :1711][INFO    ][11111] Returning information for job: 20190510141423245196
2019-05-10 14:14:25,307 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1847.2150991s left)
2019-05-10 14:14:53,352 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141453339762
2019-05-10 14:14:53,376 [salt.minion      :1432][INFO    ][11213] Starting a new job with PID 11213
2019-05-10 14:14:53,400 [salt.minion      :1711][INFO    ][11213] Returning information for job: 20190510141453339762
2019-05-10 14:14:58,528 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1813.99373913s left)
2019-05-10 14:15:23,481 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141523469544
2019-05-10 14:15:23,502 [salt.minion      :1432][INFO    ][11659] Starting a new job with PID 11659
2019-05-10 14:15:23,524 [salt.minion      :1711][INFO    ][11659] Returning information for job: 20190510141523469544
2019-05-10 14:15:32,026 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1780.49614s left)
2019-05-10 14:15:53,583 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141553569437
2019-05-10 14:15:53,607 [salt.minion      :1432][INFO    ][11696] Starting a new job with PID 11696
2019-05-10 14:15:53,630 [salt.minion      :1711][INFO    ][11696] Returning information for job: 20190510141553569437
2019-05-10 14:16:05,602 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1746.91976309s left)
2019-05-10 14:16:23,696 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141623683995
2019-05-10 14:16:23,721 [salt.minion      :1432][INFO    ][11958] Starting a new job with PID 11958
2019-05-10 14:16:23,743 [salt.minion      :1711][INFO    ][11958] Returning information for job: 20190510141623683995
2019-05-10 14:16:38,986 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1713.53652501s left)
2019-05-10 14:16:53,817 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141653803563
2019-05-10 14:16:53,841 [salt.minion      :1432][INFO    ][11977] Starting a new job with PID 11977
2019-05-10 14:16:53,864 [salt.minion      :1711][INFO    ][11977] Returning information for job: 20190510141653803563
2019-05-10 14:17:12,440 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1680.08227301s left)
2019-05-10 14:17:23,949 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141723933353
2019-05-10 14:17:23,973 [salt.minion      :1432][INFO    ][12040] Starting a new job with PID 12040
2019-05-10 14:17:23,995 [salt.minion      :1711][INFO    ][12040] Returning information for job: 20190510141723933353
2019-05-10 14:17:45,942 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1646.57977414s left)
2019-05-10 14:17:54,069 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141754056415
2019-05-10 14:17:54,092 [salt.minion      :1432][INFO    ][12060] Starting a new job with PID 12060
2019-05-10 14:17:54,116 [salt.minion      :1711][INFO    ][12060] Returning information for job: 20190510141754056415
2019-05-10 14:18:19,528 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1612.9940331s left)
2019-05-10 14:18:24,218 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141824201909
2019-05-10 14:18:24,243 [salt.minion      :1432][INFO    ][12116] Starting a new job with PID 12116
2019-05-10 14:18:24,265 [salt.minion      :1711][INFO    ][12116] Returning information for job: 20190510141824201909
2019-05-10 14:18:53,211 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1579.31100512s left)
2019-05-10 14:18:54,368 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141854352682
2019-05-10 14:18:54,392 [salt.minion      :1432][INFO    ][12135] Starting a new job with PID 12135
2019-05-10 14:18:54,415 [salt.minion      :1711][INFO    ][12135] Returning information for job: 20190510141854352682
2019-05-10 14:19:24,529 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141924515608
2019-05-10 14:19:24,551 [salt.minion      :1432][INFO    ][12188] Starting a new job with PID 12188
2019-05-10 14:19:24,575 [salt.minion      :1711][INFO    ][12188] Returning information for job: 20190510141924515608
2019-05-10 14:19:26,816 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1545.70646691s left)
2019-05-10 14:19:54,703 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510141954686614
2019-05-10 14:19:54,726 [salt.minion      :1432][INFO    ][12211] Starting a new job with PID 12211
2019-05-10 14:19:54,749 [salt.minion      :1711][INFO    ][12211] Returning information for job: 20190510141954686614
2019-05-10 14:20:00,499 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1512.02280712s left)
2019-05-10 14:20:24,882 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142024869336
2019-05-10 14:20:24,907 [salt.minion      :1432][INFO    ][12277] Starting a new job with PID 12277
2019-05-10 14:20:24,929 [salt.minion      :1711][INFO    ][12277] Returning information for job: 20190510142024869336
2019-05-10 14:20:34,002 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1478.51970196s left)
2019-05-10 14:20:55,074 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142055061293
2019-05-10 14:20:55,098 [salt.minion      :1432][INFO    ][12297] Starting a new job with PID 12297
2019-05-10 14:20:55,121 [salt.minion      :1711][INFO    ][12297] Returning information for job: 20190510142055061293
2019-05-10 14:21:07,351 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1445.17140913s left)
2019-05-10 14:21:25,276 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142125263785
2019-05-10 14:21:25,301 [salt.minion      :1432][INFO    ][12354] Starting a new job with PID 12354
2019-05-10 14:21:25,322 [salt.minion      :1711][INFO    ][12354] Returning information for job: 20190510142125263785
2019-05-10 14:21:40,860 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1411.66234112s left)
2019-05-10 14:21:55,481 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142155468240
2019-05-10 14:21:55,506 [salt.minion      :1432][INFO    ][12374] Starting a new job with PID 12374
2019-05-10 14:21:55,530 [salt.minion      :1711][INFO    ][12374] Returning information for job: 20190510142155468240
2019-05-10 14:22:14,030 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1378.49231505s left)
2019-05-10 14:22:25,708 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142225695445
2019-05-10 14:22:25,732 [salt.minion      :1432][INFO    ][12574] Starting a new job with PID 12574
2019-05-10 14:22:25,754 [salt.minion      :1711][INFO    ][12574] Returning information for job: 20190510142225695445
2019-05-10 14:22:47,647 [salt.loaded.ext.module.maas:1023][INFO    ][8598] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1344.87526798s left)
2019-05-10 14:22:55,725 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142255712752
2019-05-10 14:22:55,749 [salt.minion      :1432][INFO    ][12601] Starting a new job with PID 12601
2019-05-10 14:22:55,771 [salt.minion      :1711][INFO    ][12601] Returning information for job: 20190510142255712752
2019-05-10 14:23:20,599 [salt.loaded.ext.module.maas:993 ][INFO    ][8598] Machine x3mapm mark broken
2019-05-10 14:23:21,383 [salt.loaded.ext.module.maas:996 ][INFO    ][8598] Machine x3mapm mark fixed
2019-05-10 14:23:22,633 [salt.loaded.ext.module.maas:684 ][INFO    ][8598] deploymachines hwe_kernel=hwe-16.04 system_id=x3mapm distro_series=xenial
2019-05-10 14:23:25,368 [salt.loaded.ext.module.maas:160 ][ERROR   ][8598] Failed for object kvm03 reason Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node.
2019-05-10 14:23:25,370 [salt.state       :302 ][ERROR   ][8598] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm02'], 'errors': {'kvm03': "Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node."}, 'success': []}
2019-05-10 14:23:25,371 [salt.state       :1951][INFO    ][8598] Completed state [maas.wait_for_machine_status] at time 14:23:25.371286 duration_in_ms=942860.294
2019-05-10 14:23:25,381 [salt.minion      :1711][INFO    ][8598] Returning information for job: 20190510140737318537
2019-05-10 14:23:36,198 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command pillar.get with jid 20190510142336186604
2019-05-10 14:23:36,220 [salt.minion      :1432][INFO    ][12737] Starting a new job with PID 12737
2019-05-10 14:23:36,229 [salt.minion      :1711][INFO    ][12737] Returning information for job: 20190510142336186604
2019-05-10 14:23:36,798 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command service.status with jid 20190510142336785608
2019-05-10 14:23:36,820 [salt.minion      :1432][INFO    ][12750] Starting a new job with PID 12750
2019-05-10 14:23:37,184 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12750] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-10 14:23:37,218 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12750] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-10 14:23:37,233 [salt.minion      :1711][INFO    ][12750] Returning information for job: 20190510142336785608
2019-05-10 14:23:37,785 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command state.apply with jid 20190510142337773271
2019-05-10 14:23:37,809 [salt.minion      :1432][INFO    ][12761] Starting a new job with PID 12761
2019-05-10 14:23:41,438 [salt.state       :915 ][INFO    ][12761] Loading fresh modules for state activity
2019-05-10 14:23:41,738 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12761] Executing command 'salt-minion --version' in directory '/root'
2019-05-10 14:23:42,066 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12761] Executing command 'salt-minion --version' in directory '/root'
2019-05-10 14:23:42,872 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12761] Executing command 'salt-minion --version' in directory '/root'
2019-05-10 14:23:43,225 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12761] Executing command 'salt-minion --version' in directory '/root'
2019-05-10 14:23:44,580 [salt.state       :1780][INFO    ][12761] Running state [salt-minion] at time 14:23:44.580794
2019-05-10 14:23:44,581 [salt.state       :1813][INFO    ][12761] Executing state pkg.installed for [salt-minion]
2019-05-10 14:23:44,581 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12761] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-10 14:23:44,661 [salt.state       :300 ][INFO    ][12761] All specified packages are already installed
2019-05-10 14:23:44,661 [salt.state       :1951][INFO    ][12761] Completed state [salt-minion] at time 14:23:44.661311 duration_in_ms=80.517
2019-05-10 14:23:44,661 [salt.state       :1780][INFO    ][12761] Running state [salt_minion_dependency_packages] at time 14:23:44.661620
2019-05-10 14:23:44,661 [salt.state       :1813][INFO    ][12761] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-10 14:23:44,667 [salt.state       :300 ][INFO    ][12761] All specified packages are already installed
2019-05-10 14:23:44,667 [salt.state       :1951][INFO    ][12761] Completed state [salt_minion_dependency_packages] at time 14:23:44.667289 duration_in_ms=5.67
2019-05-10 14:23:44,670 [salt.state       :1780][INFO    ][12761] Running state [/etc/salt/minion.d/minion.conf] at time 14:23:44.670076
2019-05-10 14:23:44,670 [salt.state       :1813][INFO    ][12761] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-10 14:23:44,888 [salt.state       :300 ][INFO    ][12761] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-10 14:23:44,889 [salt.state       :1951][INFO    ][12761] Completed state [/etc/salt/minion.d/minion.conf] at time 14:23:44.889049 duration_in_ms=218.972
2019-05-10 14:23:44,889 [salt.state       :1780][INFO    ][12761] Running state [python-netaddr] at time 14:23:44.889468
2019-05-10 14:23:44,889 [salt.state       :1813][INFO    ][12761] Executing state pkg.installed for [python-netaddr]
2019-05-10 14:23:44,901 [salt.state       :300 ][INFO    ][12761] All specified packages are already installed
2019-05-10 14:23:44,901 [salt.state       :1951][INFO    ][12761] Completed state [python-netaddr] at time 14:23:44.901630 duration_in_ms=12.161
2019-05-10 14:23:44,906 [salt.state       :1780][INFO    ][12761] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 14:23:44.906462
2019-05-10 14:23:44,906 [salt.state       :1813][INFO    ][12761] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-10 14:23:44,920 [salt.state       :300 ][INFO    ][12761] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-10 14:23:44,920 [salt.state       :1951][INFO    ][12761] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 14:23:44.920425 duration_in_ms=13.963
2019-05-10 14:23:44,921 [salt.state       :1780][INFO    ][12761] Running state [salt-minion] at time 14:23:44.921923
2019-05-10 14:23:44,922 [salt.state       :1813][INFO    ][12761] Executing state service.running for [salt-minion]
2019-05-10 14:23:44,923 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12761] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-10 14:23:44,961 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12761] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-10 14:23:44,978 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12761] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-10 14:23:44,995 [salt.state       :300 ][INFO    ][12761] The service salt-minion is already running
2019-05-10 14:23:44,995 [salt.state       :1951][INFO    ][12761] Completed state [salt-minion] at time 14:23:44.995592 duration_in_ms=73.669
2019-05-10 14:23:44,997 [salt.state       :1780][INFO    ][12761] Running state [/etc/salt/grains.d] at time 14:23:44.997843
2019-05-10 14:23:44,998 [salt.state       :1813][INFO    ][12761] Executing state file.directory for [/etc/salt/grains.d]
2019-05-10 14:23:44,999 [salt.state       :300 ][INFO    ][12761] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-10 14:23:45,000 [salt.state       :1951][INFO    ][12761] Completed state [/etc/salt/grains.d] at time 14:23:45.000039 duration_in_ms=2.196
2019-05-10 14:23:45,001 [salt.state       :1780][INFO    ][12761] Running state [/etc/salt/grains] at time 14:23:45.001045
2019-05-10 14:23:45,001 [salt.state       :1813][INFO    ][12761] Executing state file.managed for [/etc/salt/grains]
2019-05-10 14:23:45,002 [salt.state       :300 ][INFO    ][12761] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-10 14:23:45,002 [salt.state       :1951][INFO    ][12761] Completed state [/etc/salt/grains] at time 14:23:45.002646 duration_in_ms=1.6
2019-05-10 14:23:45,003 [salt.state       :1780][INFO    ][12761] Running state [/etc/salt/grains.d/placeholder] at time 14:23:45.003327
2019-05-10 14:23:45,003 [salt.state       :1813][INFO    ][12761] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-10 14:23:45,004 [salt.state       :300 ][INFO    ][12761] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-10 14:23:45,004 [salt.state       :1951][INFO    ][12761] Completed state [/etc/salt/grains.d/placeholder] at time 14:23:45.004860 duration_in_ms=1.533
2019-05-10 14:23:45,005 [salt.state       :1780][INFO    ][12761] Running state [/etc/salt/grains.d/sphinx] at time 14:23:45.005558
2019-05-10 14:23:45,006 [salt.state       :1813][INFO    ][12761] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-10 14:23:45,038 [salt.state       :300 ][INFO    ][12761] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-10 14:23:45,038 [salt.state       :1951][INFO    ][12761] Completed state [/etc/salt/grains.d/sphinx] at time 14:23:45.038431 duration_in_ms=32.873
2019-05-10 14:23:45,042 [salt.state       :1780][INFO    ][12761] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 14:23:45.042165
2019-05-10 14:23:45,042 [salt.state       :1813][INFO    ][12761] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-10 14:23:45,043 [salt.state       :300 ][INFO    ][12761] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-10 14:23:45,043 [salt.state       :1951][INFO    ][12761] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 14:23:45.043588 duration_in_ms=1.423
2019-05-10 14:23:45,044 [salt.state       :1780][INFO    ][12761] Running state [/etc/salt/grains.d/dns_records] at time 14:23:45.044324
2019-05-10 14:23:45,044 [salt.state       :1813][INFO    ][12761] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-10 14:23:45,055 [salt.state       :300 ][INFO    ][12761] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-10 14:23:45,056 [salt.state       :1951][INFO    ][12761] Completed state [/etc/salt/grains.d/dns_records] at time 14:23:45.056194 duration_in_ms=11.87
2019-05-10 14:23:45,057 [salt.state       :1780][INFO    ][12761] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 14:23:45.057667
2019-05-10 14:23:45,058 [salt.state       :1813][INFO    ][12761] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-10 14:23:45,058 [salt.state       :300 ][INFO    ][12761] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-10 14:23:45,059 [salt.state       :1951][INFO    ][12761] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 14:23:45.058980 duration_in_ms=1.312
2019-05-10 14:23:45,059 [salt.state       :1780][INFO    ][12761] Running state [/etc/salt/grains.d/salt] at time 14:23:45.059733
2019-05-10 14:23:45,060 [salt.state       :1813][INFO    ][12761] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-10 14:23:45,073 [salt.state       :300 ][INFO    ][12761] File /etc/salt/grains.d/salt is in the correct state
2019-05-10 14:23:45,074 [salt.state       :1951][INFO    ][12761] Completed state [/etc/salt/grains.d/salt] at time 14:23:45.074101 duration_in_ms=14.368
2019-05-10 14:23:45,075 [salt.state       :1780][INFO    ][12761] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 14:23:45.075408
2019-05-10 14:23:45,075 [salt.state       :1813][INFO    ][12761] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-10 14:23:45,076 [salt.state       :300 ][INFO    ][12761] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-10 14:23:45,076 [salt.state       :1951][INFO    ][12761] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 14:23:45.076666 duration_in_ms=1.257
2019-05-10 14:23:45,079 [salt.state       :1780][INFO    ][12761] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 14:23:45.079538
2019-05-10 14:23:45,080 [salt.state       :1813][INFO    ][12761] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-10 14:23:45,080 [salt.state       :300 ][INFO    ][12761] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-10 14:23:45,080 [salt.state       :1951][INFO    ][12761] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 14:23:45.080787 duration_in_ms=1.249
2019-05-10 14:23:45,081 [salt.state       :1780][INFO    ][12761] Running state [mine.update] at time 14:23:45.081794
2019-05-10 14:23:45,082 [salt.state       :1813][INFO    ][12761] Executing state module.wait for [mine.update]
2019-05-10 14:23:45,082 [salt.state       :300 ][INFO    ][12761] No changes made for mine.update
2019-05-10 14:23:45,083 [salt.state       :1951][INFO    ][12761] Completed state [mine.update] at time 14:23:45.082942 duration_in_ms=1.148
2019-05-10 14:23:45,083 [salt.state       :1780][INFO    ][12761] Running state [ca-certificates] at time 14:23:45.083300
2019-05-10 14:23:45,083 [salt.state       :1813][INFO    ][12761] Executing state pkg.installed for [ca-certificates]
2019-05-10 14:23:45,094 [salt.state       :300 ][INFO    ][12761] All specified packages are already installed
2019-05-10 14:23:45,094 [salt.state       :1951][INFO    ][12761] Completed state [ca-certificates] at time 14:23:45.094588 duration_in_ms=11.287
2019-05-10 14:23:45,095 [salt.state       :1780][INFO    ][12761] Running state [update-ca-certificates] at time 14:23:45.095554
2019-05-10 14:23:45,095 [salt.state       :1813][INFO    ][12761] Executing state cmd.wait for [update-ca-certificates]
2019-05-10 14:23:45,096 [salt.state       :300 ][INFO    ][12761] No changes made for update-ca-certificates
2019-05-10 14:23:45,096 [salt.state       :1951][INFO    ][12761] Completed state [update-ca-certificates] at time 14:23:45.096677 duration_in_ms=1.123
2019-05-10 14:23:45,097 [salt.state       :1780][INFO    ][12761] Running state [iptables] at time 14:23:45.097016
2019-05-10 14:23:45,097 [salt.state       :1813][INFO    ][12761] Executing state pkg.installed for [iptables]
2019-05-10 14:23:45,106 [salt.state       :300 ][INFO    ][12761] All specified packages are already installed
2019-05-10 14:23:45,107 [salt.state       :1951][INFO    ][12761] Completed state [iptables] at time 14:23:45.107141 duration_in_ms=10.125
2019-05-10 14:23:45,107 [salt.state       :1780][INFO    ][12761] Running state [iptables-persistent] at time 14:23:45.107468
2019-05-10 14:23:45,107 [salt.state       :1813][INFO    ][12761] Executing state pkg.installed for [iptables-persistent]
2019-05-10 14:23:45,116 [salt.state       :300 ][INFO    ][12761] All specified packages are already installed
2019-05-10 14:23:45,117 [salt.state       :1951][INFO    ][12761] Completed state [iptables-persistent] at time 14:23:45.117165 duration_in_ms=9.697
2019-05-10 14:23:45,118 [salt.state       :1780][INFO    ][12761] Running state [iptables_modules_v4_load] at time 14:23:45.118451
2019-05-10 14:23:45,118 [salt.state       :1813][INFO    ][12761] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-10 14:23:45,119 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12761] Executing command 'lsmod' in directory '/root'
2019-05-10 14:23:45,141 [salt.state       :300 ][INFO    ][12761] Kernel modules iptable_filter, ip_tables are already present
2019-05-10 14:23:45,141 [salt.state       :1951][INFO    ][12761] Completed state [iptables_modules_v4_load] at time 14:23:45.141609 duration_in_ms=23.158
2019-05-10 14:23:45,142 [salt.state       :1780][INFO    ][12761] Running state [/etc/iptables/rules.v4] at time 14:23:45.142377
2019-05-10 14:23:45,142 [salt.state       :1813][INFO    ][12761] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-10 14:23:45,247 [salt.state       :300 ][INFO    ][12761] File /etc/iptables/rules.v4 is in the correct state
2019-05-10 14:23:45,247 [salt.state       :1951][INFO    ][12761] Completed state [/etc/iptables/rules.v4] at time 14:23:45.247708 duration_in_ms=105.33
2019-05-10 14:23:45,248 [salt.state       :1780][INFO    ][12761] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 14:23:45.248766
2019-05-10 14:23:45,249 [salt.state       :1813][INFO    ][12761] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-10 14:23:45,249 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12761] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-10 14:23:45,268 [salt.state       :300 ][INFO    ][12761] onlyif execution failed
2019-05-10 14:23:45,268 [salt.state       :1951][INFO    ][12761] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 14:23:45.268643 duration_in_ms=19.877
2019-05-10 14:23:45,269 [salt.state       :1780][INFO    ][12761] Running state [netfilter-persistent] at time 14:23:45.269650
2019-05-10 14:23:45,270 [salt.state       :1813][INFO    ][12761] Executing state service.running for [netfilter-persistent]
2019-05-10 14:23:45,270 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12761] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-10 14:23:45,289 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12761] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-10 14:23:45,306 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12761] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-10 14:23:45,323 [salt.state       :300 ][INFO    ][12761] The service netfilter-persistent is already running
2019-05-10 14:23:45,323 [salt.state       :1951][INFO    ][12761] Completed state [netfilter-persistent] at time 14:23:45.323368 duration_in_ms=53.718
2019-05-10 14:23:45,324 [salt.state       :1780][INFO    ][12761] Running state [iptables_extra.remove_stale_tables] at time 14:23:45.324214
2019-05-10 14:23:45,324 [salt.state       :1813][INFO    ][12761] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-10 14:23:45,324 [salt.state       :300 ][INFO    ][12761] No changes made for iptables_extra.remove_stale_tables
2019-05-10 14:23:45,325 [salt.state       :1951][INFO    ][12761] Completed state [iptables_extra.remove_stale_tables] at time 14:23:45.325153 duration_in_ms=0.939
2019-05-10 14:23:45,325 [salt.state       :1780][INFO    ][12761] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 14:23:45.325413
2019-05-10 14:23:45,325 [salt.state       :1813][INFO    ][12761] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-10 14:23:45,326 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12761] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-10 14:23:45,340 [salt.state       :300 ][INFO    ][12761] onlyif execution failed
2019-05-10 14:23:45,340 [salt.state       :1951][INFO    ][12761] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 14:23:45.340614 duration_in_ms=15.201
2019-05-10 14:23:45,341 [salt.state       :1780][INFO    ][12761] Running state [/etc/iptables/rules.v6] at time 14:23:45.341752
2019-05-10 14:23:45,342 [salt.state       :1813][INFO    ][12761] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-10 14:23:45,342 [salt.state       :300 ][INFO    ][12761] File /etc/iptables/rules.v6 is not present
2019-05-10 14:23:45,343 [salt.state       :1951][INFO    ][12761] Completed state [/etc/iptables/rules.v6] at time 14:23:45.342976 duration_in_ms=1.225
2019-05-10 14:23:45,343 [salt.state       :1780][INFO    ][12761] Running state [iptables_extra.flush_all] at time 14:23:45.343799
2019-05-10 14:23:45,344 [salt.state       :1813][INFO    ][12761] Executing state module.wait for [iptables_extra.flush_all]
2019-05-10 14:23:45,344 [salt.state       :300 ][INFO    ][12761] No changes made for iptables_extra.flush_all
2019-05-10 14:23:45,344 [salt.state       :1951][INFO    ][12761] Completed state [iptables_extra.flush_all] at time 14:23:45.344764 duration_in_ms=0.964
2019-05-10 14:23:45,348 [salt.minion      :1711][INFO    ][12761] Returning information for job: 20190510142337773271
2019-05-10 14:23:45,983 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command state.apply with jid 20190510142345970899
2019-05-10 14:23:46,007 [salt.minion      :1432][INFO    ][12836] Starting a new job with PID 12836
2019-05-10 14:23:46,760 [salt.state       :915 ][INFO    ][12836] Loading fresh modules for state activity
2019-05-10 14:23:47,433 [salt.state       :1780][INFO    ][12836] Running state [maas-rack-controller] at time 14:23:47.433555
2019-05-10 14:23:47,433 [salt.state       :1813][INFO    ][12836] Executing state pkg.installed for [maas-rack-controller]
2019-05-10 14:23:47,434 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12836] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-10 14:23:47,535 [salt.state       :300 ][INFO    ][12836] All specified packages are already installed
2019-05-10 14:23:47,535 [salt.state       :1951][INFO    ][12836] Completed state [maas-rack-controller] at time 14:23:47.535566 duration_in_ms=102.011
2019-05-10 14:23:47,536 [salt.state       :1780][INFO    ][12836] Running state [ipmitool] at time 14:23:47.535960
2019-05-10 14:23:47,536 [salt.state       :1813][INFO    ][12836] Executing state pkg.installed for [ipmitool]
2019-05-10 14:23:47,544 [salt.state       :300 ][INFO    ][12836] All specified packages are already installed
2019-05-10 14:23:47,544 [salt.state       :1951][INFO    ][12836] Completed state [ipmitool] at time 14:23:47.544282 duration_in_ms=8.322
2019-05-10 14:23:47,547 [salt.state       :1780][INFO    ][12836] Running state [/etc/maas/rackd.conf] at time 14:23:47.547919
2019-05-10 14:23:47,548 [salt.state       :1813][INFO    ][12836] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-10 14:23:47,549 [salt.state       :300 ][INFO    ][12836] No changes needed to be made
2019-05-10 14:23:47,549 [salt.state       :1951][INFO    ][12836] Completed state [/etc/maas/rackd.conf] at time 14:23:47.549763 duration_in_ms=1.843
2019-05-10 14:23:47,550 [salt.state       :1780][INFO    ][12836] Running state [/etc/maas/rackd.conf] at time 14:23:47.550034
2019-05-10 14:23:47,550 [salt.state       :1813][INFO    ][12836] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-10 14:23:47,550 [salt.loaded.int.states.file:2298][WARNING ][12836] 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-05-10 14:23:47,551 [salt.state       :300 ][INFO    ][12836] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-10 14:23:47,551 [salt.state       :1951][INFO    ][12836] Completed state [/etc/maas/rackd.conf] at time 14:23:47.551469 duration_in_ms=1.436
2019-05-10 14:23:47,552 [salt.state       :1780][INFO    ][12836] Running state [maas-rackd] at time 14:23:47.552612
2019-05-10 14:23:47,553 [salt.state       :1813][INFO    ][12836] Executing state service.running for [maas-rackd]
2019-05-10 14:23:47,553 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12836] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-10 14:23:47,589 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12836] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-10 14:23:47,606 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12836] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-10 14:23:47,623 [salt.state       :300 ][INFO    ][12836] The service maas-rackd is already running
2019-05-10 14:23:47,624 [salt.state       :1951][INFO    ][12836] Completed state [maas-rackd] at time 14:23:47.624132 duration_in_ms=71.52
2019-05-10 14:23:47,626 [salt.minion      :1711][INFO    ][12836] Returning information for job: 20190510142345970899
2019-05-10 14:23:48,162 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command state.apply with jid 20190510142348150758
2019-05-10 14:23:48,182 [salt.minion      :1432][INFO    ][12859] Starting a new job with PID 12859
2019-05-10 14:23:48,911 [salt.state       :915 ][INFO    ][12859] Loading fresh modules for state activity
2019-05-10 14:23:49,594 [salt.state       :1780][INFO    ][12859] Running state [maas-region-controller] at time 14:23:49.594083
2019-05-10 14:23:49,594 [salt.state       :1813][INFO    ][12859] Executing state pkg.installed for [maas-region-controller]
2019-05-10 14:23:49,594 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12859] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-10 14:23:49,696 [salt.state       :300 ][INFO    ][12859] All specified packages are already installed
2019-05-10 14:23:49,696 [salt.state       :1951][INFO    ][12859] Completed state [maas-region-controller] at time 14:23:49.696691 duration_in_ms=102.608
2019-05-10 14:23:49,697 [salt.state       :1780][INFO    ][12859] Running state [python-oauth] at time 14:23:49.697089
2019-05-10 14:23:49,697 [salt.state       :1813][INFO    ][12859] Executing state pkg.installed for [python-oauth]
2019-05-10 14:23:49,705 [salt.state       :300 ][INFO    ][12859] All specified packages are already installed
2019-05-10 14:23:49,705 [salt.state       :1951][INFO    ][12859] Completed state [python-oauth] at time 14:23:49.705751 duration_in_ms=8.662
2019-05-10 14:23:49,709 [salt.state       :1780][INFO    ][12859] Running state [/etc/maas/regiond.conf] at time 14:23:49.709420
2019-05-10 14:23:49,709 [salt.state       :1813][INFO    ][12859] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-10 14:23:49,769 [salt.state       :300 ][INFO    ][12859] No changes needed to be made
2019-05-10 14:23:49,770 [salt.state       :1951][INFO    ][12859] Completed state [/etc/maas/regiond.conf] at time 14:23:49.770062 duration_in_ms=60.641
2019-05-10 14:23:49,770 [salt.state       :1780][INFO    ][12859] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 14:23:49.770768
2019-05-10 14:23:49,771 [salt.state       :1813][INFO    ][12859] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-10 14:23:49,852 [salt.state       :300 ][INFO    ][12859] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-10 14:23:49,852 [salt.state       :1951][INFO    ][12859] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 14:23:49.852897 duration_in_ms=82.128
2019-05-10 14:23:49,853 [salt.state       :1780][INFO    ][12859] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 14:23:49.853522
2019-05-10 14:23:49,853 [salt.state       :1813][INFO    ][12859] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-10 14:23:49,872 [salt.state       :300 ][INFO    ][12859] No changes needed to be made
2019-05-10 14:23:49,872 [salt.state       :1951][INFO    ][12859] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 14:23:49.872660 duration_in_ms=19.138
2019-05-10 14:23:49,873 [salt.state       :1780][INFO    ][12859] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 14:23:49.873253
2019-05-10 14:23:49,873 [salt.state       :1813][INFO    ][12859] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-10 14:23:49,902 [salt.state       :300 ][INFO    ][12859] No changes needed to be made
2019-05-10 14:23:49,903 [salt.state       :1951][INFO    ][12859] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 14:23:49.903227 duration_in_ms=29.973
2019-05-10 14:23:49,904 [salt.state       :1780][INFO    ][12859] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 14:23:49.904065
2019-05-10 14:23:49,904 [salt.state       :1813][INFO    ][12859] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-10 14:23:49,941 [salt.state       :300 ][INFO    ][12859] No changes needed to be made
2019-05-10 14:23:49,942 [salt.state       :1951][INFO    ][12859] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 14:23:49.942138 duration_in_ms=38.074
2019-05-10 14:23:49,942 [salt.state       :1780][INFO    ][12859] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 14:23:49.942809
2019-05-10 14:23:49,943 [salt.state       :1813][INFO    ][12859] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-10 14:23:49,956 [salt.state       :300 ][INFO    ][12859] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-10 14:23:49,956 [salt.state       :1951][INFO    ][12859] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 14:23:49.956529 duration_in_ms=13.72
2019-05-10 14:23:49,958 [salt.state       :1780][INFO    ][12859] Running state [a2enmod headers] at time 14:23:49.958161
2019-05-10 14:23:49,958 [salt.state       :1813][INFO    ][12859] Executing state cmd.run for [a2enmod headers]
2019-05-10 14:23:49,959 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12859] Executing command 'a2enmod headers' in directory '/root'
2019-05-10 14:23:50,034 [salt.state       :300 ][INFO    ][12859] {'pid': 12878, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-10 14:23:50,035 [salt.state       :1951][INFO    ][12859] Completed state [a2enmod headers] at time 14:23:50.035554 duration_in_ms=77.392
2019-05-10 14:23:50,036 [salt.state       :1780][INFO    ][12859] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 14:23:50.036168
2019-05-10 14:23:50,036 [salt.state       :1813][INFO    ][12859] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-10 14:23:50,056 [salt.state       :300 ][INFO    ][12859] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-10 14:23:50,056 [salt.state       :1951][INFO    ][12859] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 14:23:50.056800 duration_in_ms=20.632
2019-05-10 14:23:50,057 [salt.state       :1780][INFO    ][12859] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 14:23:50.057639
2019-05-10 14:23:50,058 [salt.state       :1813][INFO    ][12859] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-10 14:23:50,140 [salt.state       :300 ][INFO    ][12859] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-10 14:23:50,140 [salt.state       :1951][INFO    ][12859] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 14:23:50.140812 duration_in_ms=83.173
2019-05-10 14:23:50,141 [salt.state       :1780][INFO    ][12859] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 14:23:50.141603
2019-05-10 14:23:50,142 [salt.state       :1813][INFO    ][12859] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-10 14:23:50,206 [salt.state       :300 ][INFO    ][12859] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-10 14:23:50,206 [salt.state       :1951][INFO    ][12859] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 14:23:50.206235 duration_in_ms=64.633
2019-05-10 14:23:50,206 [salt.state       :1780][INFO    ][12859] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 14:23:50.206787
2019-05-10 14:23:50,207 [salt.state       :1813][INFO    ][12859] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-10 14:23:50,271 [salt.state       :300 ][INFO    ][12859] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-10 14:23:50,272 [salt.state       :1951][INFO    ][12859] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 14:23:50.272238 duration_in_ms=65.451
2019-05-10 14:23:50,272 [salt.state       :1780][INFO    ][12859] Running state [/root/.pgpass] at time 14:23:50.272546
2019-05-10 14:23:50,272 [salt.state       :1813][INFO    ][12859] Executing state file.managed for [/root/.pgpass]
2019-05-10 14:23:50,319 [salt.state       :300 ][INFO    ][12859] File /root/.pgpass is in the correct state
2019-05-10 14:23:50,320 [salt.state       :1951][INFO    ][12859] Completed state [/root/.pgpass] at time 14:23:50.320019 duration_in_ms=47.473
2019-05-10 14:23:50,325 [salt.state       :1780][INFO    ][12859] Running state [maas-region syncdb --noinput] at time 14:23:50.325065
2019-05-10 14:23:50,325 [salt.state       :1813][INFO    ][12859] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-10 14:23:50,326 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12859] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-10 14:23:52,382 [salt.state       :300 ][INFO    ][12859] {'pid': 12891, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: maasserver, piston3, sessions, metadataserver, contenttypes, auth, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-10 14:23:52,383 [salt.state       :1951][INFO    ][12859] Completed state [maas-region syncdb --noinput] at time 14:23:52.383112 duration_in_ms=2058.045
2019-05-10 14:23:52,383 [salt.state       :2022][WARNING ][12859] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-10 14:23:52,386 [salt.state       :1780][INFO    ][12859] Running state [maas-regiond] at time 14:23:52.386134
2019-05-10 14:23:52,386 [salt.state       :1813][INFO    ][12859] Executing state service.running for [maas-regiond]
2019-05-10 14:23:52,387 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12859] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-10 14:23:52,427 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12859] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-10 14:23:52,445 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12859] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-10 14:23:52,462 [salt.state       :300 ][INFO    ][12859] The service maas-regiond is already running
2019-05-10 14:23:52,463 [salt.state       :1951][INFO    ][12859] Completed state [maas-regiond] at time 14:23:52.463268 duration_in_ms=77.134
2019-05-10 14:23:52,465 [salt.state       :1780][INFO    ][12859] Running state [bind9] at time 14:23:52.465806
2019-05-10 14:23:52,466 [salt.state       :1813][INFO    ][12859] Executing state service.running for [bind9]
2019-05-10 14:23:52,467 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12859] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-10 14:23:52,485 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12859] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-10 14:23:52,502 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12859] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-10 14:23:52,521 [salt.state       :300 ][INFO    ][12859] The service bind9 is already running
2019-05-10 14:23:52,521 [salt.state       :1951][INFO    ][12859] Completed state [bind9] at time 14:23:52.521791 duration_in_ms=55.986
2019-05-10 14:23:52,524 [salt.state       :1780][INFO    ][12859] Running state [apache2] at time 14:23:52.523994
2019-05-10 14:23:52,524 [salt.state       :1813][INFO    ][12859] Executing state service.running for [apache2]
2019-05-10 14:23:52,525 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12859] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-10 14:23:52,544 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12859] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-10 14:23:52,560 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12859] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-10 14:23:52,579 [salt.state       :300 ][INFO    ][12859] The service apache2 is already running
2019-05-10 14:23:52,580 [salt.state       :1951][INFO    ][12859] Completed state [apache2] at time 14:23:52.580017 duration_in_ms=56.024
2019-05-10 14:23:52,581 [salt.state       :1780][INFO    ][12859] Running state [maasng.wait_for_http_code] at time 14:23:52.581659
2019-05-10 14:23:52,582 [salt.state       :1813][INFO    ][12859] Executing state module.run for [maasng.wait_for_http_code]
2019-05-10 14:23:52,582 [salt.utils.decorators:613 ][WARNING ][12859] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:23:52,706 [salt.state       :300 ][INFO    ][12859] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-10 14:23:52,706 [salt.state       :1951][INFO    ][12859] Completed state [maasng.wait_for_http_code] at time 14:23:52.706541 duration_in_ms=124.881
2019-05-10 14:23:52,708 [salt.state       :1780][INFO    ][12859] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 14:23:52.707924
2019-05-10 14:23:52,708 [salt.state       :1813][INFO    ][12859] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-10 14:23:52,709 [salt.state       :300 ][INFO    ][12859] /var/lib/maas/.setup_admin exists
2019-05-10 14:23:52,709 [salt.state       :1951][INFO    ][12859] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 14:23:52.709578 duration_in_ms=1.653
2019-05-10 14:23:52,710 [salt.state       :1780][INFO    ][12859] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:23:52.710769
2019-05-10 14:23:52,711 [salt.state       :1813][INFO    ][12859] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 14:23:52,712 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12859] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 14:23:54,127 [salt.state       :300 ][INFO    ][12859] {'pid': 12913, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 14:23:54,128 [salt.state       :1951][INFO    ][12859] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:23:54.127967 duration_in_ms=1417.198
2019-05-10 14:23:54,133 [salt.state       :1780][INFO    ][12859] Running state [maas_region_boot_source_resources_mirror] at time 14:23:54.133616
2019-05-10 14:23:54,133 [salt.state       :1813][INFO    ][12859] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-10 14:23:54,228 [salt.state       :300 ][INFO    ][12859] {'changes': {}}
2019-05-10 14:23:54,229 [salt.state       :1951][INFO    ][12859] Completed state [maas_region_boot_source_resources_mirror] at time 14:23:54.229301 duration_in_ms=95.682
2019-05-10 14:23:54,231 [salt.state       :1780][INFO    ][12859] Running state [maasng.boot_resources_import] at time 14:23:54.231118
2019-05-10 14:23:54,231 [salt.state       :1813][INFO    ][12859] Executing state module.run for [maasng.boot_resources_import]
2019-05-10 14:23:54,232 [salt.utils.decorators:613 ][WARNING ][12859] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:23:57,104 [salt.loaded.ext.module.maasng:1600][INFO    ][12859] Waiting boot-resources import done
sleep for:5s Left:897.0/900s
2019-05-10 14:24:02,178 [salt.loaded.ext.module.maasng:1600][INFO    ][12859] Waiting boot-resources import done
sleep for:5s Left:892.0/900s
2019-05-10 14:24:03,187 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142403175208
2019-05-10 14:24:03,211 [salt.minion      :1432][INFO    ][12938] Starting a new job with PID 12938
2019-05-10 14:24:03,234 [salt.minion      :1711][INFO    ][12938] Returning information for job: 20190510142403175208
2019-05-10 14:24:07,290 [salt.state       :300 ][INFO    ][12859] {'ret': True}
2019-05-10 14:24:07,291 [salt.state       :1951][INFO    ][12859] Completed state [maasng.boot_resources_import] at time 14:24:07.291290 duration_in_ms=13060.171
2019-05-10 14:24:07,292 [salt.state       :1780][INFO    ][12859] Running state [maas_region_boot_sources_selection_xenial] at time 14:24:07.292531
2019-05-10 14:24:07,293 [salt.state       :1813][INFO    ][12859] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-10 14:24:07,494 [salt.state       :300 ][INFO    ][12859] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-10 14:24:07,494 [salt.state       :1951][INFO    ][12859] Completed state [maas_region_boot_sources_selection_xenial] at time 14:24:07.494529 duration_in_ms=201.997
2019-05-10 14:24:07,495 [salt.state       :1780][INFO    ][12859] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 14:24:07.495848
2019-05-10 14:24:07,496 [salt.state       :1813][INFO    ][12859] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-10 14:24:07,496 [salt.utils.decorators:613 ][WARNING ][12859] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:24:07,497 [salt.loaded.ext.module.maasng:1771][INFO    ][12859] boot-sources sync initiated for ALL Rack's
2019-05-10 14:24:08,668 [salt.state       :300 ][INFO    ][12859] {'ret': True}
2019-05-10 14:24:08,668 [salt.state       :1951][INFO    ][12859] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 14:24:08.668455 duration_in_ms=1172.607
2019-05-10 14:24:08,670 [salt.state       :1780][INFO    ][12859] Running state [maas.process_maas_config] at time 14:24:08.670300
2019-05-10 14:24:08,670 [salt.state       :1813][INFO    ][12859] Executing state module.run for [maas.process_maas_config]
2019-05-10 14:24:08,671 [salt.utils.decorators:613 ][WARNING ][12859] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:24:08,672 [salt.loaded.ext.module.maas:92  ][INFO    ][12859] maasconfig name=enable_http_proxy value=True
2019-05-10 14:24:08,735 [salt.loaded.ext.module.maas:92  ][INFO    ][12859] maasconfig name=upstream_dns value=8.8.8.8
2019-05-10 14:24:08,806 [salt.loaded.ext.module.maas:92  ][INFO    ][12859] maasconfig name=commissioning_distro_series value=xenial
2019-05-10 14:24:12,047 [salt.loaded.ext.module.maas:92  ][INFO    ][12859] maasconfig name=default_osystem value=ubuntu
2019-05-10 14:24:12,116 [salt.loaded.ext.module.maas:92  ][INFO    ][12859] maasconfig name=active_discovery_interval value=600
2019-05-10 14:24:12,169 [salt.loaded.ext.module.maas:92  ][INFO    ][12859] maasconfig name=dnssec_validation value=no
2019-05-10 14:24:12,217 [salt.loaded.ext.module.maas:92  ][INFO    ][12859] maasconfig name=maas_name value=mas01
2019-05-10 14:24:12,286 [salt.loaded.ext.module.maas:92  ][INFO    ][12859] maasconfig name=network_discovery value=enabled
2019-05-10 14:24:12,419 [salt.loaded.ext.module.maas:92  ][INFO    ][12859] maasconfig name=enable_third_party_drivers value=True
2019-05-10 14:24:12,485 [salt.loaded.ext.module.maas:92  ][INFO    ][12859] maasconfig name=default_storage_layout value=lvm
2019-05-10 14:24:12,556 [salt.loaded.ext.module.maas:92  ][INFO    ][12859] maasconfig name=ntp_external_only value=True
2019-05-10 14:24:12,620 [salt.loaded.ext.module.maas:92  ][INFO    ][12859] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-10 14:24:12,674 [salt.loaded.ext.module.maas:92  ][INFO    ][12859] maasconfig name=default_distro_series value=xenial
2019-05-10 14:24:12,748 [salt.loaded.ext.module.maas:92  ][INFO    ][12859] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-10 14:24:12,864 [salt.state       :300 ][INFO    ][12859] {'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-05-10 14:24:12,865 [salt.state       :1951][INFO    ][12859] Completed state [maas.process_maas_config] at time 14:24:12.865206 duration_in_ms=4194.906
2019-05-10 14:24:12,865 [salt.state       :1780][INFO    ][12859] Running state [pxe_admin] at time 14:24:12.865938
2019-05-10 14:24:12,866 [salt.state       :1813][INFO    ][12859] Executing state maasng.fabric_present for [pxe_admin]
2019-05-10 14:24:12,942 [salt.loaded.ext.module.maasng:945 ][INFO    ][12859] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'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'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8bmmsr', 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'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-10 14:24:13,025 [salt.loaded.ext.module.maasng:1008][WARNING ][12859] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-10 14:24:13,025 [salt.loaded.ext.module.maasng:1011][WARNING ][12859] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-10 14:24:13,092 [salt.state       :300 ][INFO    ][12859] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-10 14:24:13,093 [salt.state       :1951][INFO    ][12859] Completed state [pxe_admin] at time 14:24:13.092959 duration_in_ms=227.021
2019-05-10 14:24:13,093 [salt.state       :1780][INFO    ][12859] Running state [vlan 0] at time 14:24:13.093213
2019-05-10 14:24:13,093 [salt.state       :1813][INFO    ][12859] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-10 14:24:13,133 [salt.loaded.ext.module.maasng:945 ][INFO    ][12859] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', 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'primary_rack': u'8bmmsr', u'relay_vlan': None, u'external_dhcp': None, 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-05-10 14:24:13,258 [salt.loaded.ext.module.maasng:945 ][INFO    ][12859] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'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'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8bmmsr', 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'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-10 14:24:13,524 [salt.loaded.ext.module.maasng:945 ][INFO    ][12859] [{u'id': 0, 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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, 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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, 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'8bmmsr', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-05-10 14:24:13,621 [salt.state       :300 ][INFO    ][12859] {'new': 'Vlan untagged was updated'}
2019-05-10 14:24:13,621 [salt.state       :1951][INFO    ][12859] Completed state [vlan 0] at time 14:24:13.621470 duration_in_ms=528.254
2019-05-10 14:24:13,623 [salt.state       :1780][INFO    ][12859] Running state [192.168.11.0/24] at time 14:24:13.623158
2019-05-10 14:24:13,623 [salt.state       :1813][INFO    ][12859] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-10 14:24:13,877 [salt.loaded.ext.module.maasng:945 ][INFO    ][12859] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'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'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8bmmsr', 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'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-10 14:24:13,877 [salt.loaded.ext.module.maasng:1235][WARNING ][12859] Ignoring parameter vlan:0
2019-05-10 14:24:13,960 [salt.state       :300 ][INFO    ][12859] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-10 14:24:13,961 [salt.state       :1951][INFO    ][12859] Completed state [192.168.11.0/24] at time 14:24:13.961016 duration_in_ms=337.857
2019-05-10 14:24:13,962 [salt.state       :1780][INFO    ][12859] Running state [maas_create_iprange_1] at time 14:24:13.962413
2019-05-10 14:24:13,962 [salt.state       :1813][INFO    ][12859] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-10 14:24:14,008 [salt.state       :300 ][INFO    ][12859] Iprange maas_create_iprange_1 already exist.
2019-05-10 14:24:14,008 [salt.state       :1951][INFO    ][12859] Completed state [maas_create_iprange_1] at time 14:24:14.008554 duration_in_ms=46.141
2019-05-10 14:24:14,008 [salt.state       :1780][INFO    ][12859] Running state [vlan 0] at time 14:24:14.008934
2019-05-10 14:24:14,009 [salt.state       :1813][INFO    ][12859] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-10 14:24:14,075 [salt.loaded.ext.module.maasng:945 ][INFO    ][12859] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'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'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8bmmsr', 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'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-10 14:24:14,181 [salt.loaded.ext.module.maasng:945 ][INFO    ][12859] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8bmmsr', u'relay_vlan': None, u'external_dhcp': None, 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-05-10 14:24:14,452 [salt.loaded.ext.module.maasng:945 ][INFO    ][12859] [{u'id': 0, 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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, 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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, 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'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8bmmsr', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-05-10 14:24:14,550 [salt.state       :300 ][INFO    ][12859] {'new': 'Vlan untagged was updated'}
2019-05-10 14:24:14,550 [salt.state       :1951][INFO    ][12859] Completed state [vlan 0] at time 14:24:14.550818 duration_in_ms=541.884
2019-05-10 14:24:14,551 [salt.state       :1780][INFO    ][12859] Running state [opnfv] at time 14:24:14.551673
2019-05-10 14:24:14,552 [salt.state       :1813][INFO    ][12859] Executing state maasng.sshkey_present for [opnfv]
2019-05-10 14:24:14,602 [salt.loaded.ext.module.maasng:1903][INFO    ][12859] [{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-05-10 14:24:14,603 [salt.state       :300 ][INFO    ][12859] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-05-10 14:24:14,603 [salt.state       :1951][INFO    ][12859] Completed state [opnfv] at time 14:24:14.603455 duration_in_ms=51.782
2019-05-10 14:24:14,604 [salt.state       :1780][INFO    ][12859] Running state [maas.process_tags] at time 14:24:14.604241
2019-05-10 14:24:14,604 [salt.state       :1813][INFO    ][12859] Executing state module.run for [maas.process_tags]
2019-05-10 14:24:14,605 [salt.utils.decorators:613 ][WARNING ][12859] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:24:14,650 [salt.loaded.ext.module.maas:92  ][INFO    ][12859] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-10 14:24:14,699 [salt.state       :300 ][INFO    ][12859] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-10 14:24:14,699 [salt.state       :1951][INFO    ][12859] Completed state [maas.process_tags] at time 14:24:14.699820 duration_in_ms=95.578
2019-05-10 14:24:14,703 [salt.minion      :1711][INFO    ][12859] Returning information for job: 20190510142348150758
2019-05-10 14:24:15,305 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command state.apply with jid 20190510142415293095
2019-05-10 14:24:15,324 [salt.minion      :1432][INFO    ][13337] Starting a new job with PID 13337
2019-05-10 14:24:18,990 [salt.state       :915 ][INFO    ][13337] Loading fresh modules for state activity
2019-05-10 14:24:19,089 [salt.state       :1780][INFO    ][13337] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:24:19.089793
2019-05-10 14:24:19,090 [salt.state       :1813][INFO    ][13337] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 14:24:19,092 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13337] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 14:24:20,511 [salt.state       :300 ][INFO    ][13337] {'pid': 13361, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 14:24:20,511 [salt.state       :1951][INFO    ][13337] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:24:20.511634 duration_in_ms=1421.842
2019-05-10 14:24:20,512 [salt.state       :1780][INFO    ][13337] Running state [maas.process_machines] at time 14:24:20.512758
2019-05-10 14:24:20,512 [salt.state       :1813][INFO    ][13337] Executing state module.run for [maas.process_machines]
2019-05-10 14:24:20,513 [salt.utils.decorators:613 ][WARNING ][13337] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:24:21,224 [salt.loaded.ext.module.maas:412 ][WARNING ][13337] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 14:24:21,225 [salt.loaded.ext.module.maas:92  ][INFO    ][13337] 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=apsdm8 architecture=amd64/generic power_parameters_power_user=admin
2019-05-10 14:24:22,396 [salt.loaded.ext.module.maas:412 ][WARNING ][13337] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 14:24:22,397 [salt.loaded.ext.module.maas:92  ][INFO    ][13337] 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=qsrkqn architecture=amd64/generic power_parameters_power_user=admin
2019-05-10 14:24:23,689 [salt.loaded.ext.module.maas:412 ][WARNING ][13337] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 14:24:23,690 [salt.loaded.ext.module.maas:92  ][INFO    ][13337] 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=ap6axc architecture=amd64/generic power_parameters_power_user=admin
2019-05-10 14:24:24,975 [salt.loaded.ext.module.maas:412 ][WARNING ][13337] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 14:24:24,976 [salt.loaded.ext.module.maas:92  ][INFO    ][13337] 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=x3mapm architecture=amd64/generic power_parameters_power_user=admin
2019-05-10 14:24:26,245 [salt.loaded.ext.module.maas:412 ][WARNING ][13337] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 14:24:26,246 [salt.loaded.ext.module.maas:92  ][INFO    ][13337] 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=kkp3mx architecture=amd64/generic power_parameters_power_user=admin
2019-05-10 14:24:27,515 [salt.state       :300 ][INFO    ][13337] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-10 14:24:27,516 [salt.state       :1951][INFO    ][13337] Completed state [maas.process_machines] at time 14:24:27.516229 duration_in_ms=7003.466
2019-05-10 14:24:27,520 [salt.minion      :1711][INFO    ][13337] Returning information for job: 20190510142415293095
2019-05-10 14:24:59,875 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command state.apply with jid 20190510142459862967
2019-05-10 14:24:59,892 [salt.minion      :1432][INFO    ][13589] Starting a new job with PID 13589
2019-05-10 14:25:03,507 [salt.state       :915 ][INFO    ][13589] Loading fresh modules for state activity
2019-05-10 14:25:03,554 [salt.state       :1780][INFO    ][13589] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:25:03.554669
2019-05-10 14:25:03,554 [salt.state       :1813][INFO    ][13589] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 14:25:03,556 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13589] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 14:25:05,039 [salt.state       :300 ][INFO    ][13589] {'pid': 13596, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 14:25:05,040 [salt.state       :1951][INFO    ][13589] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:25:05.040460 duration_in_ms=1485.789
2019-05-10 14:25:05,043 [salt.state       :1780][INFO    ][13589] Running state [maas.wait_for_machine_status] at time 14:25:05.043683
2019-05-10 14:25:05,044 [salt.state       :1813][INFO    ][13589] Executing state module.run for [maas.wait_for_machine_status]
2019-05-10 14:25:05,044 [salt.utils.decorators:613 ][WARNING ][13589] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:25:07,669 [salt.loaded.ext.module.maas:993 ][INFO    ][13589] Machine x3mapm mark broken
2019-05-10 14:25:08,327 [salt.loaded.ext.module.maas:996 ][INFO    ][13589] Machine x3mapm mark fixed
2019-05-10 14:25:09,559 [salt.loaded.ext.module.maas:684 ][INFO    ][13589] deploymachines hwe_kernel=hwe-16.04 system_id=x3mapm distro_series=xenial
2019-05-10 14:25:12,788 [salt.loaded.ext.module.maas:1023][INFO    ][13589] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1492.26603723s left)
2019-05-10 14:25:14,960 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142514906575
2019-05-10 14:25:14,982 [salt.minion      :1432][INFO    ][13708] Starting a new job with PID 13708
2019-05-10 14:25:15,009 [salt.minion      :1711][INFO    ][13708] Returning information for job: 20190510142514906575
2019-05-10 14:25:45,006 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142544994032
2019-05-10 14:25:45,029 [salt.minion      :1432][INFO    ][13739] Starting a new job with PID 13739
2019-05-10 14:25:45,053 [salt.minion      :1711][INFO    ][13739] Returning information for job: 20190510142544994032
2019-05-10 14:25:46,268 [salt.loaded.ext.module.maas:1023][INFO    ][13589] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1458.78656507s left)
2019-05-10 14:26:15,123 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142615109009
2019-05-10 14:26:15,147 [salt.minion      :1432][INFO    ][13787] Starting a new job with PID 13787
2019-05-10 14:26:15,174 [salt.minion      :1711][INFO    ][13787] Returning information for job: 20190510142615109009
2019-05-10 14:26:19,339 [salt.loaded.ext.module.maas:1023][INFO    ][13589] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1425.71480918s left)
2019-05-10 14:26:45,174 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142645160122
2019-05-10 14:26:45,199 [salt.minion      :1432][INFO    ][13806] Starting a new job with PID 13806
2019-05-10 14:26:45,223 [salt.minion      :1711][INFO    ][13806] Returning information for job: 20190510142645160122
2019-05-10 14:26:52,324 [salt.loaded.ext.module.maas:1023][INFO    ][13589] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1392.73068619s left)
2019-05-10 14:27:15,220 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142715205650
2019-05-10 14:27:15,244 [salt.minion      :1432][INFO    ][13963] Starting a new job with PID 13963
2019-05-10 14:27:15,271 [salt.minion      :1711][INFO    ][13963] Returning information for job: 20190510142715205650
2019-05-10 14:27:25,734 [salt.loaded.ext.module.maas:1023][INFO    ][13589] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1359.31993222s left)
2019-05-10 14:27:45,283 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142745270392
2019-05-10 14:27:45,307 [salt.minion      :1432][INFO    ][13991] Starting a new job with PID 13991
2019-05-10 14:27:45,332 [salt.minion      :1711][INFO    ][13991] Returning information for job: 20190510142745270392
2019-05-10 14:27:59,291 [salt.loaded.ext.module.maas:1023][INFO    ][13589] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1325.763731s left)
2019-05-10 14:28:15,346 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142815329158
2019-05-10 14:28:15,369 [salt.minion      :1432][INFO    ][14161] Starting a new job with PID 14161
2019-05-10 14:28:15,393 [salt.minion      :1711][INFO    ][14161] Returning information for job: 20190510142815329158
2019-05-10 14:28:32,657 [salt.loaded.ext.module.maas:1023][INFO    ][13589] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1292.39776802s left)
2019-05-10 14:28:45,405 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142845392408
2019-05-10 14:28:45,430 [salt.minion      :1432][INFO    ][14186] Starting a new job with PID 14186
2019-05-10 14:28:45,453 [salt.minion      :1711][INFO    ][14186] Returning information for job: 20190510142845392408
2019-05-10 14:29:05,956 [salt.loaded.ext.module.maas:1023][INFO    ][13589] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1259.09861708s left)
2019-05-10 14:29:15,465 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142915452704
2019-05-10 14:29:15,485 [salt.minion      :1432][INFO    ][14271] Starting a new job with PID 14271
2019-05-10 14:29:15,508 [salt.minion      :1711][INFO    ][14271] Returning information for job: 20190510142915452704
2019-05-10 14:29:39,531 [salt.loaded.ext.module.maas:1023][INFO    ][13589] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1225.52281809s left)
2019-05-10 14:29:45,520 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510142945506923
2019-05-10 14:29:45,544 [salt.minion      :1432][INFO    ][14292] Starting a new job with PID 14292
2019-05-10 14:29:45,571 [salt.minion      :1711][INFO    ][14292] Returning information for job: 20190510142945506923
2019-05-10 14:30:13,373 [salt.loaded.ext.module.maas:1023][INFO    ][13589] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1191.68174505s left)
2019-05-10 14:30:15,603 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510143015589246
2019-05-10 14:30:15,627 [salt.minion      :1432][INFO    ][14449] Starting a new job with PID 14449
2019-05-10 14:30:15,652 [salt.minion      :1711][INFO    ][14449] Returning information for job: 20190510143015589246
2019-05-10 14:30:45,678 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510143045665479
2019-05-10 14:30:45,701 [salt.minion      :1432][INFO    ][14475] Starting a new job with PID 14475
2019-05-10 14:30:45,726 [salt.minion      :1711][INFO    ][14475] Returning information for job: 20190510143045665479
2019-05-10 14:30:46,826 [salt.loaded.ext.module.maas:1023][INFO    ][13589] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1158.22802019s left)
2019-05-10 14:31:15,764 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510143115750227
2019-05-10 14:31:15,789 [salt.minion      :1432][INFO    ][14593] Starting a new job with PID 14593
2019-05-10 14:31:15,813 [salt.minion      :1711][INFO    ][14593] Returning information for job: 20190510143115750227
2019-05-10 14:31:20,322 [salt.loaded.ext.module.maas:1023][INFO    ][13589] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1124.73182511s left)
2019-05-10 14:31:45,855 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510143145842970
2019-05-10 14:31:45,880 [salt.minion      :1432][INFO    ][14614] Starting a new job with PID 14614
2019-05-10 14:31:45,906 [salt.minion      :1711][INFO    ][14614] Returning information for job: 20190510143145842970
2019-05-10 14:31:53,891 [salt.loaded.ext.module.maas:1023][INFO    ][13589] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1091.16288519s left)
2019-05-10 14:32:15,957 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510143215944007
2019-05-10 14:32:15,981 [salt.minion      :1432][INFO    ][14704] Starting a new job with PID 14704
2019-05-10 14:32:16,006 [salt.minion      :1711][INFO    ][14704] Returning information for job: 20190510143215944007
2019-05-10 14:32:27,122 [salt.loaded.ext.module.maas:1023][INFO    ][13589] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1057.93187022s left)
2019-05-10 14:32:45,974 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510143245962226
2019-05-10 14:32:45,999 [salt.minion      :1432][INFO    ][14756] Starting a new job with PID 14756
2019-05-10 14:32:46,024 [salt.minion      :1711][INFO    ][14756] Returning information for job: 20190510143245962226
2019-05-10 14:33:00,862 [salt.state       :300 ][INFO    ][13589] {'ret': True}
2019-05-10 14:33:00,863 [salt.state       :1951][INFO    ][13589] Completed state [maas.wait_for_machine_status] at time 14:33:00.863319 duration_in_ms=475819.635
2019-05-10 14:33:00,867 [salt.minion      :1711][INFO    ][13589] Returning information for job: 20190510142459862967
2019-05-10 14:33:01,544 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command state.apply with jid 20190510143301530799
2019-05-10 14:33:01,567 [salt.minion      :1432][INFO    ][14786] Starting a new job with PID 14786
2019-05-10 14:33:05,347 [salt.state       :915 ][INFO    ][14786] Loading fresh modules for state activity
2019-05-10 14:33:05,474 [salt.state       :1780][INFO    ][14786] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:33:05.474086
2019-05-10 14:33:05,474 [salt.state       :1813][INFO    ][14786] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 14:33:05,475 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14786] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 14:33:06,942 [salt.state       :300 ][INFO    ][14786] {'pid': 14797, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 14:33:06,942 [salt.state       :1951][INFO    ][14786] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:33:06.942512 duration_in_ms=1468.427
2019-05-10 14:33:06,944 [salt.state       :1780][INFO    ][14786] Running state [maas_machines_storage_cmp002_lvm] at time 14:33:06.943974
2019-05-10 14:33:06,944 [salt.state       :1813][INFO    ][14786] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-10 14:33:07,618 [salt.state       :300 ][INFO    ][14786] Machine cmp002 is not in Ready state.
2019-05-10 14:33:07,619 [salt.state       :1951][INFO    ][14786] Completed state [maas_machines_storage_cmp002_lvm] at time 14:33:07.619364 duration_in_ms=675.387
2019-05-10 14:33:07,620 [salt.state       :1780][INFO    ][14786] Running state [maas_machines_storage_cmp001_lvm] at time 14:33:07.619989
2019-05-10 14:33:07,620 [salt.state       :1813][INFO    ][14786] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-10 14:33:08,174 [salt.state       :300 ][INFO    ][14786] Machine cmp001 is not in Ready state.
2019-05-10 14:33:08,175 [salt.state       :1951][INFO    ][14786] Completed state [maas_machines_storage_cmp001_lvm] at time 14:33:08.175372 duration_in_ms=555.382
2019-05-10 14:33:08,179 [salt.minion      :1711][INFO    ][14786] Returning information for job: 20190510143301530799
2019-05-10 14:33:08,806 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command state.apply with jid 20190510143308793709
2019-05-10 14:33:08,830 [salt.minion      :1432][INFO    ][14828] Starting a new job with PID 14828
2019-05-10 14:33:09,624 [salt.state       :915 ][INFO    ][14828] Loading fresh modules for state activity
2019-05-10 14:33:09,712 [salt.state       :1780][INFO    ][14828] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:33:09.712301
2019-05-10 14:33:09,712 [salt.state       :1813][INFO    ][14828] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 14:33:09,714 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14828] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 14:33:11,175 [salt.state       :300 ][INFO    ][14828] {'pid': 14835, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 14:33:11,176 [salt.state       :1951][INFO    ][14828] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:33:11.176125 duration_in_ms=1463.825
2019-05-10 14:33:11,177 [salt.state       :1780][INFO    ][14828] Running state [maas.deploy_machines] at time 14:33:11.177333
2019-05-10 14:33:11,177 [salt.state       :1813][INFO    ][14828] Executing state module.run for [maas.deploy_machines]
2019-05-10 14:33:11,178 [salt.utils.decorators:613 ][WARNING ][14828] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:33:11,893 [salt.state       :300 ][INFO    ][14828] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-10 14:33:11,894 [salt.state       :1951][INFO    ][14828] Completed state [maas.deploy_machines] at time 14:33:11.894049 duration_in_ms=716.715
2019-05-10 14:33:11,897 [salt.minion      :1711][INFO    ][14828] Returning information for job: 20190510143308793709
2019-05-10 14:33:12,487 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command state.apply with jid 20190510143312474945
2019-05-10 14:33:12,509 [salt.minion      :1432][INFO    ][14849] Starting a new job with PID 14849
2019-05-10 14:33:13,089 [salt.state       :915 ][INFO    ][14849] Loading fresh modules for state activity
2019-05-10 14:33:13,138 [salt.state       :1780][INFO    ][14849] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:33:13.138557
2019-05-10 14:33:13,138 [salt.state       :1813][INFO    ][14849] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 14:33:13,140 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14849] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 14:33:14,342 [salt.state       :300 ][INFO    ][14849] {'pid': 14945, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 14:33:14,343 [salt.state       :1951][INFO    ][14849] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:33:14.343420 duration_in_ms=1204.862
2019-05-10 14:33:14,346 [salt.state       :1780][INFO    ][14849] Running state [maas.wait_for_machine_status] at time 14:33:14.346440
2019-05-10 14:33:14,346 [salt.state       :1813][INFO    ][14849] Executing state module.run for [maas.wait_for_machine_status]
2019-05-10 14:33:14,347 [salt.utils.decorators:613 ][WARNING ][14849] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 14:33:17,947 [salt.state       :300 ][INFO    ][14849] {'ret': True}
2019-05-10 14:33:17,948 [salt.state       :1951][INFO    ][14849] Completed state [maas.wait_for_machine_status] at time 14:33:17.948154 duration_in_ms=3601.713
2019-05-10 14:33:17,952 [salt.minion      :1711][INFO    ][14849] Returning information for job: 20190510143312474945
2019-05-10 15:03:28,505 [salt.utils.schedule:1377][INFO    ][6704] Running scheduled job: __mine_interval
2019-05-10 16:03:28,505 [salt.utils.schedule:1377][INFO    ][6704] Running scheduled job: __mine_interval
2019-05-10 16:04:00,833 [salt.minion      :1308][INFO    ][6704] User sudo_ubuntu Executing command cp.push_dir with jid 20190510160400819912
2019-05-10 16:04:00,853 [salt.minion      :1432][INFO    ][21400] Starting a new job with PID 21400
