2019-04-01 15:12:55,100 [salt.utils.decorators:613 ][WARNING ][2203] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:12:55,581 [salt.utils.decorators:613 ][WARNING ][2203] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:12:57,705 [salt.loaded.int.states.file:2298][WARNING ][2411] 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-04-01 15:13:20,321 [salt.state       :2022][WARNING ][2939] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-01 15:13:22,805 [salt.utils.decorators:613 ][WARNING ][2939] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:14:05,459 [salt.utils.decorators:613 ][WARNING ][2939] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:14:49,007 [salt.utils.decorators:613 ][WARNING ][2939] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:14:49,846 [salt.utils.decorators:613 ][WARNING ][2939] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:14:54,177 [salt.loaded.ext.module.maasng:1008][WARNING ][2939] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-04-01 15:14:54,177 [salt.loaded.ext.module.maasng:1011][WARNING ][2939] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-04-01 15:14:55,268 [salt.loaded.ext.module.maasng:1235][WARNING ][2939] Ignoring parameter vlan:0
2019-04-01 15:14:56,288 [salt.utils.decorators:613 ][WARNING ][2939] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:14:59,667 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6953] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-01 15:14:59,702 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6953] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-01 15:14:59,725 [salt.utils.parsers:1051][WARNING ][365] Minion received a SIGTERM. Exiting.
2019-04-01 15:15:00,740 [salt.cli.daemons :293 ][INFO    ][7005] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-04-01 15:15:00,869 [salt.cli.daemons :82  ][INFO    ][7005] Starting up the Salt Minion
2019-04-01 15:15:00,870 [salt.utils.event :1017][INFO    ][7005] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-04-01 15:15:01,636 [salt.minion      :976 ][INFO    ][7005] Creating minion process manager
2019-04-01 15:15:02,893 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][7005] Executing command ['date', '+%z'] in directory '/root'
2019-04-01 15:15:02,913 [salt.utils.schedule:568 ][INFO    ][7005] Updating job settings for scheduled job: __mine_interval
2019-04-01 15:15:02,915 [salt.minion      :1108][INFO    ][7005] Added mine.update to scheduler
2019-04-01 15:15:02,919 [salt.minion      :1975][INFO    ][7005] Minion is starting as user 'root'
2019-04-01 15:15:02,932 [salt.minion      :2336][INFO    ][7005] Minion is ready to receive requests!
2019-04-01 15:15:03,439 [salt.utils.decorators:613 ][WARNING ][6958] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:15:03,508 [salt.loaded.ext.module.maas:412 ][WARNING ][6958] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-01 15:15:05,114 [salt.loaded.ext.module.maas:412 ][WARNING ][6958] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-01 15:15:06,515 [salt.loaded.ext.module.maas:412 ][WARNING ][6958] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-01 15:15:07,845 [salt.loaded.ext.module.maas:412 ][WARNING ][6958] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-01 15:15:09,383 [salt.loaded.ext.module.maas:412 ][WARNING ][6958] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-01 15:15:41,960 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command state.apply with jid 20190401151541948224
2019-04-01 15:15:41,982 [salt.minion      :1432][INFO    ][7423] Starting a new job with PID 7423
2019-04-01 15:15:45,719 [salt.state       :915 ][INFO    ][7423] Loading fresh modules for state activity
2019-04-01 15:15:45,770 [salt.fileclient  :1219][INFO    ][7423] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-01 15:15:45,816 [salt.state       :1780][INFO    ][7423] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:15:45.816860
2019-04-01 15:15:45,817 [salt.state       :1813][INFO    ][7423] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-01 15:15:45,819 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7423] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-01 15:15:47,285 [salt.state       :300 ][INFO    ][7423] {'pid': 7433, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-01 15:15:47,285 [salt.state       :1951][INFO    ][7423] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:15:47.285413 duration_in_ms=1468.554
2019-04-01 15:15:47,286 [salt.state       :1780][INFO    ][7423] Running state [maas.wait_for_machine_status] at time 15:15:47.286910
2019-04-01 15:15:47,287 [salt.state       :1813][INFO    ][7423] Executing state module.run for [maas.wait_for_machine_status]
2019-04-01 15:15:47,287 [salt.utils.decorators:613 ][WARNING ][7423] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:15:48,360 [salt.loaded.ext.module.maas:1023][INFO    ][7423] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.93198395s left)
2019-04-01 15:15:57,001 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401151556988789
2019-04-01 15:15:57,023 [salt.minion      :1432][INFO    ][7448] Starting a new job with PID 7448
2019-04-01 15:15:57,048 [salt.minion      :1711][INFO    ][7448] Returning information for job: 20190401151556988789
2019-04-01 15:16:19,340 [salt.loaded.ext.module.maas:1023][INFO    ][7423] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.95171404s left)
2019-04-01 15:16:27,046 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401151627032657
2019-04-01 15:16:27,068 [salt.minion      :1432][INFO    ][7500] Starting a new job with PID 7500
2019-04-01 15:16:27,090 [salt.minion      :1711][INFO    ][7500] Returning information for job: 20190401151627032657
2019-04-01 15:16:50,507 [salt.loaded.ext.module.maas:1023][INFO    ][7423] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.78501201s left)
2019-04-01 15:16:57,136 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401151657128427
2019-04-01 15:16:57,148 [salt.minion      :1432][INFO    ][7633] Starting a new job with PID 7633
2019-04-01 15:16:57,159 [salt.minion      :1711][INFO    ][7633] Returning information for job: 20190401151657128427
2019-04-01 15:17:21,827 [salt.loaded.ext.module.maas:1023][INFO    ][7423] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.46433592s left)
2019-04-01 15:17:27,164 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401151727151187
2019-04-01 15:17:27,186 [salt.minion      :1432][INFO    ][8048] Starting a new job with PID 8048
2019-04-01 15:17:27,211 [salt.minion      :1711][INFO    ][8048] Returning information for job: 20190401151727151187
2019-04-01 15:17:53,481 [salt.loaded.ext.module.maas:1023][INFO    ][7423] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.81040597s left)
2019-04-01 15:17:57,222 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401151757208804
2019-04-01 15:17:57,244 [salt.minion      :1432][INFO    ][8204] Starting a new job with PID 8204
2019-04-01 15:17:57,267 [salt.minion      :1711][INFO    ][8204] Returning information for job: 20190401151757208804
2019-04-01 15:18:24,642 [salt.loaded.ext.module.maas:1023][INFO    ][7423] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.64994788s left)
2019-04-01 15:18:27,288 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401151827271192
2019-04-01 15:18:27,310 [salt.minion      :1432][INFO    ][8558] Starting a new job with PID 8558
2019-04-01 15:18:27,333 [salt.minion      :1711][INFO    ][8558] Returning information for job: 20190401151827271192
2019-04-01 15:18:57,358 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401151857341381
2019-04-01 15:18:57,379 [salt.minion      :1432][INFO    ][8750] Starting a new job with PID 8750
2019-04-01 15:18:57,401 [salt.minion      :1711][INFO    ][8750] Returning information for job: 20190401151857341381
2019-04-01 15:18:58,390 [salt.state       :300 ][INFO    ][7423] {'ret': True}
2019-04-01 15:18:58,391 [salt.state       :1951][INFO    ][7423] Completed state [maas.wait_for_machine_status] at time 15:18:58.391253 duration_in_ms=191104.341
2019-04-01 15:18:58,395 [salt.minion      :1711][INFO    ][7423] Returning information for job: 20190401151541948224
2019-04-01 15:18:58,972 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command state.apply with jid 20190401151858959181
2019-04-01 15:18:58,994 [salt.minion      :1432][INFO    ][8758] Starting a new job with PID 8758
2019-04-01 15:19:02,676 [salt.state       :915 ][INFO    ][8758] Loading fresh modules for state activity
2019-04-01 15:19:02,727 [salt.fileclient  :1219][INFO    ][8758] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-01 15:19:02,819 [salt.state       :1780][INFO    ][8758] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:19:02.819139
2019-04-01 15:19:02,819 [salt.state       :1813][INFO    ][8758] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-01 15:19:02,821 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8758] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-01 15:19:04,318 [salt.state       :300 ][INFO    ][8758] {'pid': 8775, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-01 15:19:04,319 [salt.state       :1951][INFO    ][8758] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:19:04.319516 duration_in_ms=1500.376
2019-04-01 15:19:04,322 [salt.state       :1780][INFO    ][8758] Running state [maas_machines_storage_cmp002_lvm] at time 15:19:04.322623
2019-04-01 15:19:04,323 [salt.state       :1813][INFO    ][8758] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-01 15:19:05,710 [salt.loaded.ext.module.maasng:610 ][INFO    ][8758] ga7kxa
2019-04-01 15:19:05,710 [salt.loaded.ext.module.maasng:626 ][INFO    ][8758] sda
2019-04-01 15:19:06,426 [salt.loaded.ext.module.maasng:361 ][INFO    ][8758] ga7kxa
2019-04-01 15:19:06,557 [salt.loaded.ext.module.maasng:367 ][INFO    ][8758] [{u'resource_uri': u'/MAAS/api/2.0/nodes/ga7kxa/blockdevices/4/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'50a374a5-b8d2-468a-930b-ad00f68a50c7', u'resource_uri': u'/MAAS/api/2.0/nodes/ga7kxa/blockdevices/4/partition/4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ga7kxa', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'e1cfd7a0-d625-4bdd-9e1d-eb365054852e', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 4, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'ga7kxa', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 4, u'path': u'/dev/disk/by-dname/sda', 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/ga7kxa/blockdevices/9/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'd89b71e9-a438-47ab-b708-0f6ee0805696', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'ga7kxa', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'83c16407-0014-4fa4-aed0-19c6b051cc10', u'mount_options': None}, u'id': 9, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 2397988454400, u'serial': None, u'size': 2397988454400}]
2019-04-01 15:19:06,557 [salt.loaded.ext.module.maasng:632 ][INFO    ][8758] vgroot
2019-04-01 15:19:06,558 [salt.loaded.ext.module.maasng:635 ][INFO    ][8758] lvroot
2019-04-01 15:19:06,558 [salt.loaded.ext.module.maasng:639 ][INFO    ][8758] 107374182400
2019-04-01 15:19:07,109 [salt.loaded.ext.module.maasng:645 ][INFO    ][8758] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, 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'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gcbpay', 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'fabric': u'pxe_admin'}, 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'primary_rack': u'gcbpay', 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'fabric': u'pxe_admin'}, 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.41', u'mode': u'dhcp', u'id': 29}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gcbpay', 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'fabric': u'pxe_admin'}, 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.41'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'system_id': u'ga7kxa', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ga7kxa/interfaces/4/'}, u'status_action': u'', 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'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ga7kxa/blockdevices/4/', u'type': u'physical', u'partitions': [{u'uuid': u'4f890819-b966-447f-8009-563a9abdbe22', u'resource_uri': u'/MAAS/api/2.0/nodes/ga7kxa/blockdevices/4/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ga7kxa', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'd3d87879-60d7-4c38-959c-85b9dd815115', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ga7kxa', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'ga7kxa', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ga7kxa/blockdevices/11/', u'type': u'virtual', u'partitions': [], u'tags': [], u'uuid': u'46f88c14-77d9-4bf7-a699-8be610993e2f', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ga7kxa', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'2c280868-06a4-4414-be69-4ffd3f9f53be', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/ga7kxa/blockdevices/4/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'4f890819-b966-447f-8009-563a9abdbe22', u'resource_uri': u'/MAAS/api/2.0/nodes/ga7kxa/blockdevices/4/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ga7kxa', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'd3d87879-60d7-4c38-959c-85b9dd815115', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'ga7kxa', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 4, u'path': u'/dev/disk/by-dname/sda', 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/ga7kxa/blockdevices/11/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'46f88c14-77d9-4bf7-a699-8be610993e2f', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'ga7kxa', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'2c280868-06a4-4414-be69-4ffd3f9f53be', u'mount_options': None}, u'id': 11, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ga7kxa', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'memory_test_status_name': u'Unknown', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ga7kxa/blockdevices/4/', u'type': u'physical', u'partitions': [{u'uuid': u'4f890819-b966-447f-8009-563a9abdbe22', u'resource_uri': u'/MAAS/api/2.0/nodes/ga7kxa/blockdevices/4/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ga7kxa', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'd3d87879-60d7-4c38-959c-85b9dd815115', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ga7kxa', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'ip_addresses': [u'192.168.11.41'], u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', 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'primary_rack': u'gcbpay', 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'fabric': u'pxe_admin'}, 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'primary_rack': u'gcbpay', 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'fabric': u'pxe_admin'}, 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.41', u'mode': u'dhcp', u'id': 29}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gcbpay', 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'fabric': u'pxe_admin'}, 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.41'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'system_id': u'ga7kxa', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ga7kxa/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'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'fabric': u'fabric-0'}, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 31}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'system_id': u'ga7kxa', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/ga7kxa/interfaces/18/'}, {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'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'fabric': u'fabric-0'}, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 32}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'system_id': u'ga7kxa', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/ga7kxa/interfaces/19/'}, {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'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'fabric': u'fabric-0'}, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 33}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'system_id': u'ga7kxa', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/ga7kxa/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/ga7kxa/'}
2019-04-01 15:19:07,112 [salt.state       :300 ][INFO    ][8758] {'new': {'storage_layout': 'lvm'}}
2019-04-01 15:19:07,112 [salt.state       :1951][INFO    ][8758] Completed state [maas_machines_storage_cmp002_lvm] at time 15:19:07.112526 duration_in_ms=2789.902
2019-04-01 15:19:07,113 [salt.state       :1780][INFO    ][8758] Running state [maas_machines_storage_cmp001_lvm] at time 15:19:07.113086
2019-04-01 15:19:07,113 [salt.state       :1813][INFO    ][8758] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-01 15:19:08,341 [salt.loaded.ext.module.maasng:610 ][INFO    ][8758] y6ntxf
2019-04-01 15:19:08,341 [salt.loaded.ext.module.maasng:626 ][INFO    ][8758] sda
2019-04-01 15:19:09,065 [salt.loaded.ext.module.maasng:361 ][INFO    ][8758] y6ntxf
2019-04-01 15:19:09,198 [salt.loaded.ext.module.maasng:367 ][INFO    ][8758] [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'id': 1, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/y6ntxf/blockdevices/1/', u'system_id': u'y6ntxf', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'93c4c914-663e-4b04-a1e8-2d2ffcaf2d59', u'resource_uri': u'/MAAS/api/2.0/nodes/y6ntxf/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'y6ntxf', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'724fd7c2-2b60-4277-9013-c2ffddf4e12e', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 2397992648704}]}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'85dbc080-4678-402d-aaba-d38d067b39f9', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'id': 6, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/y6ntxf/blockdevices/6/', u'system_id': u'y6ntxf', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'31e9b067-fb4b-474c-b1b4-9fe7aa96364c', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 2397988454400, u'used_size': 2397988454400, u'serial': None, u'partitions': []}]
2019-04-01 15:19:09,198 [salt.loaded.ext.module.maasng:632 ][INFO    ][8758] vgroot
2019-04-01 15:19:09,199 [salt.loaded.ext.module.maasng:635 ][INFO    ][8758] lvroot
2019-04-01 15:19:09,199 [salt.loaded.ext.module.maasng:639 ][INFO    ][8758] 107374182400
2019-04-01 15:19:09,870 [salt.loaded.ext.module.maasng:645 ][INFO    ][8758] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, 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'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gcbpay', 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'fabric': u'pxe_admin'}, 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'primary_rack': u'gcbpay', 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'fabric': u'pxe_admin'}, 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'mode': u'dhcp', u'id': 15}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gcbpay', 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'fabric': u'pxe_admin'}, 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'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'system_id': u'y6ntxf', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/y6ntxf/interfaces/5/'}, u'status_action': u'', 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'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/y6ntxf/blockdevices/1/', u'type': u'physical', u'partitions': [{u'uuid': u'639a424e-c4c6-4a5f-82f0-bd1366f4d306', u'resource_uri': u'/MAAS/api/2.0/nodes/y6ntxf/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'y6ntxf', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'792cd575-0581-4b0e-91e8-0a73d49d8cc4', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'y6ntxf', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'current_commissioning_result_id': 4, 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'y6ntxf', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/y6ntxf/blockdevices/12/', u'type': u'virtual', u'partitions': [], u'tags': [], u'uuid': u'bd644429-32da-4a3f-b2c0-74046019d47a', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'y6ntxf', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'edce3b8f-b069-4e4c-bc88-6a49debd9b76', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/y6ntxf/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'639a424e-c4c6-4a5f-82f0-bd1366f4d306', u'resource_uri': u'/MAAS/api/2.0/nodes/y6ntxf/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'y6ntxf', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'792cd575-0581-4b0e-91e8-0a73d49d8cc4', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'system_id': u'y6ntxf', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', 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/y6ntxf/blockdevices/12/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'bd644429-32da-4a3f-b2c0-74046019d47a', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'y6ntxf', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'edce3b8f-b069-4e4c-bc88-6a49debd9b76', u'mount_options': None}, u'id': 12, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'y6ntxf', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'memory_test_status_name': u'Unknown', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/y6ntxf/blockdevices/1/', u'type': u'physical', u'partitions': [{u'uuid': u'639a424e-c4c6-4a5f-82f0-bd1366f4d306', u'resource_uri': u'/MAAS/api/2.0/nodes/y6ntxf/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'y6ntxf', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'792cd575-0581-4b0e-91e8-0a73d49d8cc4', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'y6ntxf', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, 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'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', 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'primary_rack': u'gcbpay', 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'fabric': u'pxe_admin'}, 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'primary_rack': u'gcbpay', 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'fabric': u'pxe_admin'}, 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'mode': u'dhcp', u'id': 15}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gcbpay', 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'fabric': u'pxe_admin'}, 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'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'system_id': u'y6ntxf', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/y6ntxf/interfaces/5/'}, {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'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'fabric': u'fabric-0'}, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 16}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'system_id': u'y6ntxf', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/y6ntxf/interfaces/9/'}, {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'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'fabric': u'fabric-0'}, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 17}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'system_id': u'y6ntxf', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/y6ntxf/interfaces/10/'}, {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'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'fabric': u'fabric-0'}, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 18}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'system_id': u'y6ntxf', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/y6ntxf/interfaces/11/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/y6ntxf/'}
2019-04-01 15:19:09,872 [salt.state       :300 ][INFO    ][8758] {'new': {'storage_layout': 'lvm'}}
2019-04-01 15:19:09,873 [salt.state       :1951][INFO    ][8758] Completed state [maas_machines_storage_cmp001_lvm] at time 15:19:09.872930 duration_in_ms=2759.842
2019-04-01 15:19:09,876 [salt.minion      :1711][INFO    ][8758] Returning information for job: 20190401151858959181
2019-04-01 15:19:10,518 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command state.apply with jid 20190401151910503365
2019-04-01 15:19:10,539 [salt.minion      :1432][INFO    ][8856] Starting a new job with PID 8856
2019-04-01 15:19:11,272 [salt.state       :915 ][INFO    ][8856] Loading fresh modules for state activity
2019-04-01 15:19:11,325 [salt.fileclient  :1219][INFO    ][8856] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-01 15:19:11,366 [salt.state       :1780][INFO    ][8856] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:19:11.366016
2019-04-01 15:19:11,366 [salt.state       :1813][INFO    ][8856] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-01 15:19:11,368 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8856] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-01 15:19:12,936 [salt.state       :300 ][INFO    ][8856] {'pid': 8863, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-01 15:19:12,937 [salt.state       :1951][INFO    ][8856] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:19:12.937461 duration_in_ms=1571.445
2019-04-01 15:19:12,940 [salt.state       :1780][INFO    ][8856] Running state [maas.deploy_machines] at time 15:19:12.940153
2019-04-01 15:19:12,941 [salt.state       :1813][INFO    ][8856] Executing state module.run for [maas.deploy_machines]
2019-04-01 15:19:12,942 [salt.utils.decorators:613 ][WARNING ][8856] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:19:13,636 [salt.loaded.ext.module.maas:684 ][INFO    ][8856] deploymachines hwe_kernel=hwe-16.04 system_id=ga7kxa distro_series=xenial
2019-04-01 15:19:16,385 [salt.loaded.ext.module.maas:684 ][INFO    ][8856] deploymachines hwe_kernel=hwe-16.04 system_id=y6ntxf distro_series=xenial
2019-04-01 15:19:19,064 [salt.loaded.ext.module.maas:684 ][INFO    ][8856] deploymachines hwe_kernel=hwe-16.04 system_id=a6wmmn distro_series=xenial
2019-04-01 15:19:21,705 [salt.loaded.ext.module.maas:684 ][INFO    ][8856] deploymachines hwe_kernel=hwe-16.04 system_id=e8hwp8 distro_series=xenial
2019-04-01 15:19:24,155 [salt.loaded.ext.module.maas:684 ][INFO    ][8856] deploymachines hwe_kernel=hwe-16.04 system_id=ftbwf7 distro_series=xenial
2019-04-01 15:19:25,549 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401151925539006
2019-04-01 15:19:25,569 [salt.minion      :1432][INFO    ][9167] Starting a new job with PID 9167
2019-04-01 15:19:25,593 [salt.minion      :1711][INFO    ][9167] Returning information for job: 20190401151925539006
2019-04-01 15:19:26,542 [salt.state       :300 ][INFO    ][8856] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-01 15:19:26,542 [salt.state       :1951][INFO    ][8856] Completed state [maas.deploy_machines] at time 15:19:26.542488 duration_in_ms=13602.322
2019-04-01 15:19:26,546 [salt.minion      :1711][INFO    ][8856] Returning information for job: 20190401151910503365
2019-04-01 15:19:27,154 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command state.apply with jid 20190401151927140965
2019-04-01 15:19:27,176 [salt.minion      :1432][INFO    ][9186] Starting a new job with PID 9186
2019-04-01 15:19:30,999 [salt.state       :915 ][INFO    ][9186] Loading fresh modules for state activity
2019-04-01 15:19:31,050 [salt.fileclient  :1219][INFO    ][9186] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-01 15:19:31,095 [salt.state       :1780][INFO    ][9186] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:19:31.095275
2019-04-01 15:19:31,095 [salt.state       :1813][INFO    ][9186] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-01 15:19:31,097 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9186] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-01 15:19:32,567 [salt.state       :300 ][INFO    ][9186] {'pid': 9202, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-01 15:19:32,568 [salt.state       :1951][INFO    ][9186] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:19:32.568375 duration_in_ms=1473.1
2019-04-01 15:19:32,571 [salt.state       :1780][INFO    ][9186] Running state [maas.wait_for_machine_status] at time 15:19:32.571237
2019-04-01 15:19:32,571 [salt.state       :1813][INFO    ][9186] Executing state module.run for [maas.wait_for_machine_status]
2019-04-01 15:19:32,572 [salt.utils.decorators:613 ][WARNING ][9186] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:19:35,894 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.68789887s left)
2019-04-01 15:19:42,173 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401151942160142
2019-04-01 15:19:42,196 [salt.minion      :1432][INFO    ][9214] Starting a new job with PID 9214
2019-04-01 15:19:42,220 [salt.minion      :1711][INFO    ][9214] Returning information for job: 20190401151942160142
2019-04-01 15:20:09,148 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.43378496s left)
2019-04-01 15:20:12,214 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152012201762
2019-04-01 15:20:12,236 [salt.minion      :1432][INFO    ][9249] Starting a new job with PID 9249
2019-04-01 15:20:12,259 [salt.minion      :1711][INFO    ][9249] Returning information for job: 20190401152012201762
2019-04-01 15:20:42,260 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152042247615
2019-04-01 15:20:42,281 [salt.minion      :1432][INFO    ][9288] Starting a new job with PID 9288
2019-04-01 15:20:42,304 [salt.minion      :1711][INFO    ][9288] Returning information for job: 20190401152042247615
2019-04-01 15:20:42,713 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.86879396s left)
2019-04-01 15:21:12,300 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152112287628
2019-04-01 15:21:12,320 [salt.minion      :1432][INFO    ][9407] Starting a new job with PID 9407
2019-04-01 15:21:12,337 [salt.minion      :1711][INFO    ][9407] Returning information for job: 20190401152112287628
2019-04-01 15:21:16,088 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.49346209s left)
2019-04-01 15:21:42,357 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152142344841
2019-04-01 15:21:42,380 [salt.minion      :1432][INFO    ][9664] Starting a new job with PID 9664
2019-04-01 15:21:42,403 [salt.minion      :1711][INFO    ][9664] Returning information for job: 20190401152142344841
2019-04-01 15:21:49,623 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.95849609s left)
2019-04-01 15:22:12,417 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152212403057
2019-04-01 15:22:12,437 [salt.minion      :1432][INFO    ][9894] Starting a new job with PID 9894
2019-04-01 15:22:12,460 [salt.minion      :1711][INFO    ][9894] Returning information for job: 20190401152212403057
2019-04-01 15:22:22,908 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.67385197s left)
2019-04-01 15:22:42,473 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152242460274
2019-04-01 15:22:42,495 [salt.minion      :1432][INFO    ][10454] Starting a new job with PID 10454
2019-04-01 15:22:42,518 [salt.minion      :1711][INFO    ][10454] Returning information for job: 20190401152242460274
2019-04-01 15:22:56,543 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.03875995s left)
2019-04-01 15:23:12,532 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152312519984
2019-04-01 15:23:12,554 [salt.minion      :1432][INFO    ][10515] Starting a new job with PID 10515
2019-04-01 15:23:12,577 [salt.minion      :1711][INFO    ][10515] Returning information for job: 20190401152312519984
2019-04-01 15:23:30,151 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.43013906s left)
2019-04-01 15:23:42,602 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152342586121
2019-04-01 15:23:42,625 [salt.minion      :1432][INFO    ][10649] Starting a new job with PID 10649
2019-04-01 15:23:42,648 [salt.minion      :1711][INFO    ][10649] Returning information for job: 20190401152342586121
2019-04-01 15:24:03,698 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1978.88364697s left)
2019-04-01 15:24:12,678 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152412665540
2019-04-01 15:24:12,700 [salt.minion      :1432][INFO    ][10763] Starting a new job with PID 10763
2019-04-01 15:24:12,724 [salt.minion      :1711][INFO    ][10763] Returning information for job: 20190401152412665540
2019-04-01 15:24:37,179 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1945.40263295s left)
2019-04-01 15:24:42,754 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152442741113
2019-04-01 15:24:42,776 [salt.minion      :1432][INFO    ][11198] Starting a new job with PID 11198
2019-04-01 15:24:42,798 [salt.minion      :1711][INFO    ][11198] Returning information for job: 20190401152442741113
2019-04-01 15:25:10,640 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1911.94154787s left)
2019-04-01 15:25:12,831 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152512817880
2019-04-01 15:25:12,854 [salt.minion      :1432][INFO    ][11323] Starting a new job with PID 11323
2019-04-01 15:25:12,878 [salt.minion      :1711][INFO    ][11323] Returning information for job: 20190401152512817880
2019-04-01 15:25:42,917 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152542904412
2019-04-01 15:25:42,939 [salt.minion      :1432][INFO    ][11591] Starting a new job with PID 11591
2019-04-01 15:25:42,962 [salt.minion      :1711][INFO    ][11591] Returning information for job: 20190401152542904412
2019-04-01 15:25:44,387 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1878.19436288s left)
2019-04-01 15:26:13,005 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152612993153
2019-04-01 15:26:13,024 [salt.minion      :1432][INFO    ][11625] Starting a new job with PID 11625
2019-04-01 15:26:13,048 [salt.minion      :1711][INFO    ][11625] Returning information for job: 20190401152612993153
2019-04-01 15:26:17,896 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1844.68577695s left)
2019-04-01 15:26:43,103 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152643090130
2019-04-01 15:26:43,125 [salt.minion      :1432][INFO    ][11750] Starting a new job with PID 11750
2019-04-01 15:26:43,150 [salt.minion      :1711][INFO    ][11750] Returning information for job: 20190401152643090130
2019-04-01 15:26:50,818 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1811.7635529s left)
2019-04-01 15:27:13,170 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152713157848
2019-04-01 15:27:13,193 [salt.minion      :1432][INFO    ][11952] Starting a new job with PID 11952
2019-04-01 15:27:13,216 [salt.minion      :1711][INFO    ][11952] Returning information for job: 20190401152713157848
2019-04-01 15:27:24,224 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1778.35716701s left)
2019-04-01 15:27:43,292 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152743279818
2019-04-01 15:27:43,314 [salt.minion      :1432][INFO    ][12229] Starting a new job with PID 12229
2019-04-01 15:27:43,337 [salt.minion      :1711][INFO    ][12229] Returning information for job: 20190401152743279818
2019-04-01 15:27:57,628 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1744.95364404s left)
2019-04-01 15:28:13,409 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152813395801
2019-04-01 15:28:13,431 [salt.minion      :1432][INFO    ][12298] Starting a new job with PID 12298
2019-04-01 15:28:13,455 [salt.minion      :1711][INFO    ][12298] Returning information for job: 20190401152813395801
2019-04-01 15:28:31,133 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1711.44859099s left)
2019-04-01 15:28:43,535 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152843523798
2019-04-01 15:28:43,558 [salt.minion      :1432][INFO    ][12336] Starting a new job with PID 12336
2019-04-01 15:28:43,581 [salt.minion      :1711][INFO    ][12336] Returning information for job: 20190401152843523798
2019-04-01 15:29:04,535 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1678.04673409s left)
2019-04-01 15:29:13,662 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152913649323
2019-04-01 15:29:13,684 [salt.minion      :1432][INFO    ][12375] Starting a new job with PID 12375
2019-04-01 15:29:13,708 [salt.minion      :1711][INFO    ][12375] Returning information for job: 20190401152913649323
2019-04-01 15:29:38,043 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1644.53824806s left)
2019-04-01 15:29:43,796 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401152943782823
2019-04-01 15:29:43,819 [salt.minion      :1432][INFO    ][12416] Starting a new job with PID 12416
2019-04-01 15:29:43,844 [salt.minion      :1711][INFO    ][12416] Returning information for job: 20190401152943782823
2019-04-01 15:30:11,630 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1610.95131207s left)
2019-04-01 15:30:13,948 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153013932665
2019-04-01 15:30:13,971 [salt.minion      :1432][INFO    ][12452] Starting a new job with PID 12452
2019-04-01 15:30:13,994 [salt.minion      :1711][INFO    ][12452] Returning information for job: 20190401153013932665
2019-04-01 15:30:44,102 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153044087988
2019-04-01 15:30:44,123 [salt.minion      :1432][INFO    ][12498] Starting a new job with PID 12498
2019-04-01 15:30:44,146 [salt.minion      :1711][INFO    ][12498] Returning information for job: 20190401153044087988
2019-04-01 15:30:45,139 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1577.4425869s left)
2019-04-01 15:31:14,266 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153114251047
2019-04-01 15:31:14,289 [salt.minion      :1432][INFO    ][12543] Starting a new job with PID 12543
2019-04-01 15:31:14,312 [salt.minion      :1711][INFO    ][12543] Returning information for job: 20190401153114251047
2019-04-01 15:31:18,649 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1543.93250608s left)
2019-04-01 15:31:44,440 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153144426940
2019-04-01 15:31:44,463 [salt.minion      :1432][INFO    ][12582] Starting a new job with PID 12582
2019-04-01 15:31:44,488 [salt.minion      :1711][INFO    ][12582] Returning information for job: 20190401153144426940
2019-04-01 15:31:52,314 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1510.26742697s left)
2019-04-01 15:32:14,622 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153214612119
2019-04-01 15:32:14,642 [salt.minion      :1432][INFO    ][12630] Starting a new job with PID 12630
2019-04-01 15:32:14,659 [salt.minion      :1711][INFO    ][12630] Returning information for job: 20190401153214612119
2019-04-01 15:32:25,603 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1476.9783709s left)
2019-04-01 15:32:44,809 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153244795989
2019-04-01 15:32:44,833 [salt.minion      :1432][INFO    ][12662] Starting a new job with PID 12662
2019-04-01 15:32:44,858 [salt.minion      :1711][INFO    ][12662] Returning information for job: 20190401153244795989
2019-04-01 15:32:59,105 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1443.47627807s left)
2019-04-01 15:33:15,013 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153315000183
2019-04-01 15:33:15,035 [salt.minion      :1432][INFO    ][12838] Starting a new job with PID 12838
2019-04-01 15:33:15,058 [salt.minion      :1711][INFO    ][12838] Returning information for job: 20190401153315000183
2019-04-01 15:33:32,636 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1409.94583702s left)
2019-04-01 15:33:45,224 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153345211849
2019-04-01 15:33:45,246 [salt.minion      :1432][INFO    ][12886] Starting a new job with PID 12886
2019-04-01 15:33:45,271 [salt.minion      :1711][INFO    ][12886] Returning information for job: 20190401153345211849
2019-04-01 15:34:06,110 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1376.47151995s left)
2019-04-01 15:34:15,452 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153415439592
2019-04-01 15:34:15,475 [salt.minion      :1432][INFO    ][12934] Starting a new job with PID 12934
2019-04-01 15:34:15,498 [salt.minion      :1711][INFO    ][12934] Returning information for job: 20190401153415439592
2019-04-01 15:34:39,696 [salt.loaded.ext.module.maas:1023][INFO    ][9186] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1342.88503003s left)
2019-04-01 15:34:45,475 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153445463195
2019-04-01 15:34:45,495 [salt.minion      :1432][INFO    ][12966] Starting a new job with PID 12966
2019-04-01 15:34:45,519 [salt.minion      :1711][INFO    ][12966] Returning information for job: 20190401153445463195
2019-04-01 15:35:13,238 [salt.loaded.ext.module.maas:993 ][INFO    ][9186] Machine ftbwf7 mark broken
2019-04-01 15:35:13,974 [salt.loaded.ext.module.maas:996 ][INFO    ][9186] Machine ftbwf7 mark fixed
2019-04-01 15:35:15,021 [salt.loaded.ext.module.maas:684 ][INFO    ][9186] deploymachines hwe_kernel=hwe-16.04 system_id=ftbwf7 distro_series=xenial
2019-04-01 15:35:15,513 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153515499906
2019-04-01 15:35:15,535 [salt.minion      :1432][INFO    ][13027] Starting a new job with PID 13027
2019-04-01 15:35:15,558 [salt.minion      :1711][INFO    ][13027] Returning information for job: 20190401153515499906
2019-04-01 15:35:17,823 [salt.loaded.ext.module.maas:160 ][ERROR   ][9186] Failed for object kvm02 reason Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node.
2019-04-01 15:35:17,824 [salt.state       :302 ][ERROR   ][9186] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {'kvm02': "Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node."}, 'success': []}
2019-04-01 15:35:17,825 [salt.state       :1951][INFO    ][9186] Completed state [maas.wait_for_machine_status] at time 15:35:17.825263 duration_in_ms=945254.021
2019-04-01 15:35:17,830 [salt.minion      :1711][INFO    ][9186] Returning information for job: 20190401151927140965
2019-04-01 15:35:28,575 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command pillar.get with jid 20190401153528563187
2019-04-01 15:35:28,597 [salt.minion      :1432][INFO    ][13107] Starting a new job with PID 13107
2019-04-01 15:35:28,606 [salt.minion      :1711][INFO    ][13107] Returning information for job: 20190401153528563187
2019-04-01 15:35:29,121 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command service.status with jid 20190401153529109472
2019-04-01 15:35:29,142 [salt.minion      :1432][INFO    ][13113] Starting a new job with PID 13113
2019-04-01 15:35:29,516 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13113] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-01 15:35:29,548 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13113] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-01 15:35:29,562 [salt.minion      :1711][INFO    ][13113] Returning information for job: 20190401153529109472
2019-04-01 15:35:30,104 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command state.apply with jid 20190401153530097664
2019-04-01 15:35:30,126 [salt.minion      :1432][INFO    ][13126] Starting a new job with PID 13126
2019-04-01 15:35:33,749 [salt.state       :915 ][INFO    ][13126] Loading fresh modules for state activity
2019-04-01 15:35:34,163 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13126] Executing command 'salt-minion --version' in directory '/root'
2019-04-01 15:35:34,514 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13126] Executing command 'salt-minion --version' in directory '/root'
2019-04-01 15:35:35,439 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13126] Executing command 'salt-minion --version' in directory '/root'
2019-04-01 15:35:35,805 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13126] Executing command 'salt-minion --version' in directory '/root'
2019-04-01 15:35:37,193 [salt.state       :1780][INFO    ][13126] Running state [salt-minion] at time 15:35:37.193606
2019-04-01 15:35:37,193 [salt.state       :1813][INFO    ][13126] Executing state pkg.installed for [salt-minion]
2019-04-01 15:35:37,194 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13126] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-01 15:35:37,288 [salt.state       :300 ][INFO    ][13126] All specified packages are already installed
2019-04-01 15:35:37,288 [salt.state       :1951][INFO    ][13126] Completed state [salt-minion] at time 15:35:37.288366 duration_in_ms=94.761
2019-04-01 15:35:37,288 [salt.state       :1780][INFO    ][13126] Running state [salt_minion_dependency_packages] at time 15:35:37.288769
2019-04-01 15:35:37,289 [salt.state       :1813][INFO    ][13126] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-01 15:35:37,296 [salt.state       :300 ][INFO    ][13126] All specified packages are already installed
2019-04-01 15:35:37,296 [salt.state       :1951][INFO    ][13126] Completed state [salt_minion_dependency_packages] at time 15:35:37.296718 duration_in_ms=7.949
2019-04-01 15:35:37,300 [salt.state       :1780][INFO    ][13126] Running state [/etc/salt/minion.d/minion.conf] at time 15:35:37.300194
2019-04-01 15:35:37,300 [salt.state       :1813][INFO    ][13126] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-01 15:35:37,503 [salt.state       :300 ][INFO    ][13126] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-01 15:35:37,503 [salt.state       :1951][INFO    ][13126] Completed state [/etc/salt/minion.d/minion.conf] at time 15:35:37.503701 duration_in_ms=203.508
2019-04-01 15:35:37,503 [salt.state       :1780][INFO    ][13126] Running state [python-netaddr] at time 15:35:37.503891
2019-04-01 15:35:37,504 [salt.state       :1813][INFO    ][13126] Executing state pkg.installed for [python-netaddr]
2019-04-01 15:35:37,508 [salt.state       :300 ][INFO    ][13126] All specified packages are already installed
2019-04-01 15:35:37,509 [salt.state       :1951][INFO    ][13126] Completed state [python-netaddr] at time 15:35:37.509061 duration_in_ms=5.17
2019-04-01 15:35:37,511 [salt.state       :1780][INFO    ][13126] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 15:35:37.511318
2019-04-01 15:35:37,511 [salt.state       :1813][INFO    ][13126] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-01 15:35:37,521 [salt.state       :300 ][INFO    ][13126] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-01 15:35:37,521 [salt.state       :1951][INFO    ][13126] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 15:35:37.521248 duration_in_ms=9.93
2019-04-01 15:35:37,521 [salt.state       :1780][INFO    ][13126] Running state [salt-minion] at time 15:35:37.521952
2019-04-01 15:35:37,522 [salt.state       :1813][INFO    ][13126] Executing state service.running for [salt-minion]
2019-04-01 15:35:37,522 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13126] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-01 15:35:37,558 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13126] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-01 15:35:37,575 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13126] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-01 15:35:37,592 [salt.state       :300 ][INFO    ][13126] The service salt-minion is already running
2019-04-01 15:35:37,592 [salt.state       :1951][INFO    ][13126] Completed state [salt-minion] at time 15:35:37.592718 duration_in_ms=70.765
2019-04-01 15:35:37,593 [salt.state       :1780][INFO    ][13126] Running state [/etc/salt/grains.d] at time 15:35:37.593892
2019-04-01 15:35:37,594 [salt.state       :1813][INFO    ][13126] Executing state file.directory for [/etc/salt/grains.d]
2019-04-01 15:35:37,594 [salt.state       :300 ][INFO    ][13126] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-01 15:35:37,595 [salt.state       :1951][INFO    ][13126] Completed state [/etc/salt/grains.d] at time 15:35:37.595111 duration_in_ms=1.218
2019-04-01 15:35:37,595 [salt.state       :1780][INFO    ][13126] Running state [/etc/salt/grains] at time 15:35:37.595650
2019-04-01 15:35:37,595 [salt.state       :1813][INFO    ][13126] Executing state file.managed for [/etc/salt/grains]
2019-04-01 15:35:37,596 [salt.state       :300 ][INFO    ][13126] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-01 15:35:37,596 [salt.state       :1951][INFO    ][13126] Completed state [/etc/salt/grains] at time 15:35:37.596496 duration_in_ms=0.846
2019-04-01 15:35:37,596 [salt.state       :1780][INFO    ][13126] Running state [/etc/salt/grains.d/placeholder] at time 15:35:37.596867
2019-04-01 15:35:37,597 [salt.state       :1813][INFO    ][13126] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-01 15:35:37,597 [salt.state       :300 ][INFO    ][13126] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-01 15:35:37,597 [salt.state       :1951][INFO    ][13126] Completed state [/etc/salt/grains.d/placeholder] at time 15:35:37.597689 duration_in_ms=0.822
2019-04-01 15:35:37,598 [salt.state       :1780][INFO    ][13126] Running state [/etc/salt/grains.d/sphinx] at time 15:35:37.598049
2019-04-01 15:35:37,598 [salt.state       :1813][INFO    ][13126] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-01 15:35:37,611 [salt.state       :300 ][INFO    ][13126] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-01 15:35:37,611 [salt.state       :1951][INFO    ][13126] Completed state [/etc/salt/grains.d/sphinx] at time 15:35:37.611206 duration_in_ms=13.157
2019-04-01 15:35:37,612 [salt.state       :1780][INFO    ][13126] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 15:35:37.612941
2019-04-01 15:35:37,613 [salt.state       :1813][INFO    ][13126] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-01 15:35:37,613 [salt.state       :300 ][INFO    ][13126] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-01 15:35:37,613 [salt.state       :1951][INFO    ][13126] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 15:35:37.613636 duration_in_ms=0.696
2019-04-01 15:35:37,614 [salt.state       :1780][INFO    ][13126] Running state [/etc/salt/grains.d/dns_records] at time 15:35:37.613993
2019-04-01 15:35:37,614 [salt.state       :1813][INFO    ][13126] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-01 15:35:37,622 [salt.state       :300 ][INFO    ][13126] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-01 15:35:37,623 [salt.state       :1951][INFO    ][13126] Completed state [/etc/salt/grains.d/dns_records] at time 15:35:37.623011 duration_in_ms=9.018
2019-04-01 15:35:37,623 [salt.state       :1780][INFO    ][13126] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 15:35:37.623699
2019-04-01 15:35:37,623 [salt.state       :1813][INFO    ][13126] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-01 15:35:37,624 [salt.state       :300 ][INFO    ][13126] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-01 15:35:37,624 [salt.state       :1951][INFO    ][13126] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 15:35:37.624357 duration_in_ms=0.658
2019-04-01 15:35:37,624 [salt.state       :1780][INFO    ][13126] Running state [/etc/salt/grains.d/salt] at time 15:35:37.624729
2019-04-01 15:35:37,624 [salt.state       :1813][INFO    ][13126] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-01 15:35:37,635 [salt.state       :300 ][INFO    ][13126] File /etc/salt/grains.d/salt is in the correct state
2019-04-01 15:35:37,635 [salt.state       :1951][INFO    ][13126] Completed state [/etc/salt/grains.d/salt] at time 15:35:37.635177 duration_in_ms=10.448
2019-04-01 15:35:37,635 [salt.state       :1780][INFO    ][13126] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 15:35:37.635832
2019-04-01 15:35:37,636 [salt.state       :1813][INFO    ][13126] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-01 15:35:37,636 [salt.state       :300 ][INFO    ][13126] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-01 15:35:37,636 [salt.state       :1951][INFO    ][13126] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 15:35:37.636502 duration_in_ms=0.67
2019-04-01 15:35:37,637 [salt.state       :1780][INFO    ][13126] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 15:35:37.637878
2019-04-01 15:35:37,638 [salt.state       :1813][INFO    ][13126] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-01 15:35:37,638 [salt.state       :300 ][INFO    ][13126] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-01 15:35:37,638 [salt.state       :1951][INFO    ][13126] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 15:35:37.638566 duration_in_ms=0.689
2019-04-01 15:35:37,639 [salt.state       :1780][INFO    ][13126] Running state [mine.update] at time 15:35:37.639073
2019-04-01 15:35:37,639 [salt.state       :1813][INFO    ][13126] Executing state module.wait for [mine.update]
2019-04-01 15:35:37,639 [salt.state       :300 ][INFO    ][13126] No changes made for mine.update
2019-04-01 15:35:37,639 [salt.state       :1951][INFO    ][13126] Completed state [mine.update] at time 15:35:37.639716 duration_in_ms=0.643
2019-04-01 15:35:37,639 [salt.state       :1780][INFO    ][13126] Running state [ca-certificates] at time 15:35:37.639914
2019-04-01 15:35:37,640 [salt.state       :1813][INFO    ][13126] Executing state pkg.installed for [ca-certificates]
2019-04-01 15:35:37,645 [salt.state       :300 ][INFO    ][13126] All specified packages are already installed
2019-04-01 15:35:37,645 [salt.state       :1951][INFO    ][13126] Completed state [ca-certificates] at time 15:35:37.645745 duration_in_ms=5.831
2019-04-01 15:35:37,646 [salt.state       :1780][INFO    ][13126] Running state [update-ca-certificates] at time 15:35:37.646273
2019-04-01 15:35:37,646 [salt.state       :1813][INFO    ][13126] Executing state cmd.wait for [update-ca-certificates]
2019-04-01 15:35:37,646 [salt.state       :300 ][INFO    ][13126] No changes made for update-ca-certificates
2019-04-01 15:35:37,646 [salt.state       :1951][INFO    ][13126] Completed state [update-ca-certificates] at time 15:35:37.646948 duration_in_ms=0.675
2019-04-01 15:35:37,647 [salt.state       :1780][INFO    ][13126] Running state [iptables] at time 15:35:37.647140
2019-04-01 15:35:37,647 [salt.state       :1813][INFO    ][13126] Executing state pkg.installed for [iptables]
2019-04-01 15:35:37,652 [salt.state       :300 ][INFO    ][13126] All specified packages are already installed
2019-04-01 15:35:37,652 [salt.state       :1951][INFO    ][13126] Completed state [iptables] at time 15:35:37.652466 duration_in_ms=5.326
2019-04-01 15:35:37,652 [salt.state       :1780][INFO    ][13126] Running state [iptables-persistent] at time 15:35:37.652671
2019-04-01 15:35:37,652 [salt.state       :1813][INFO    ][13126] Executing state pkg.installed for [iptables-persistent]
2019-04-01 15:35:37,657 [salt.state       :300 ][INFO    ][13126] All specified packages are already installed
2019-04-01 15:35:37,657 [salt.state       :1951][INFO    ][13126] Completed state [iptables-persistent] at time 15:35:37.657942 duration_in_ms=5.272
2019-04-01 15:35:37,658 [salt.state       :1780][INFO    ][13126] Running state [iptables_modules_v4_load] at time 15:35:37.658702
2019-04-01 15:35:37,658 [salt.state       :1813][INFO    ][13126] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-01 15:35:37,659 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13126] Executing command 'lsmod' in directory '/root'
2019-04-01 15:35:37,681 [salt.state       :300 ][INFO    ][13126] Kernel modules iptable_filter, ip_tables are already present
2019-04-01 15:35:37,682 [salt.state       :1951][INFO    ][13126] Completed state [iptables_modules_v4_load] at time 15:35:37.682029 duration_in_ms=23.328
2019-04-01 15:35:37,682 [salt.state       :1780][INFO    ][13126] Running state [/etc/iptables/rules.v4] at time 15:35:37.682508
2019-04-01 15:35:37,682 [salt.state       :1813][INFO    ][13126] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-01 15:35:37,755 [salt.state       :300 ][INFO    ][13126] File /etc/iptables/rules.v4 is in the correct state
2019-04-01 15:35:37,755 [salt.state       :1951][INFO    ][13126] Completed state [/etc/iptables/rules.v4] at time 15:35:37.755236 duration_in_ms=72.728
2019-04-01 15:35:37,756 [salt.state       :1780][INFO    ][13126] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 15:35:37.756022
2019-04-01 15:35:37,756 [salt.state       :1813][INFO    ][13126] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-01 15:35:37,756 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13126] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-01 15:35:37,777 [salt.state       :300 ][INFO    ][13126] onlyif execution failed
2019-04-01 15:35:37,777 [salt.state       :1951][INFO    ][13126] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 15:35:37.777429 duration_in_ms=21.407
2019-04-01 15:35:37,778 [salt.state       :1780][INFO    ][13126] Running state [netfilter-persistent] at time 15:35:37.778190
2019-04-01 15:35:37,778 [salt.state       :1813][INFO    ][13126] Executing state service.running for [netfilter-persistent]
2019-04-01 15:35:37,779 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13126] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-01 15:35:37,799 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13126] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-01 15:35:37,816 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13126] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-01 15:35:37,833 [salt.state       :300 ][INFO    ][13126] The service netfilter-persistent is already running
2019-04-01 15:35:37,833 [salt.state       :1951][INFO    ][13126] Completed state [netfilter-persistent] at time 15:35:37.833910 duration_in_ms=55.72
2019-04-01 15:35:37,834 [salt.state       :1780][INFO    ][13126] Running state [iptables_extra.remove_stale_tables] at time 15:35:37.834633
2019-04-01 15:35:37,834 [salt.state       :1813][INFO    ][13126] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-01 15:35:37,835 [salt.state       :300 ][INFO    ][13126] No changes made for iptables_extra.remove_stale_tables
2019-04-01 15:35:37,835 [salt.state       :1951][INFO    ][13126] Completed state [iptables_extra.remove_stale_tables] at time 15:35:37.835455 duration_in_ms=0.822
2019-04-01 15:35:37,835 [salt.state       :1780][INFO    ][13126] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 15:35:37.835711
2019-04-01 15:35:37,835 [salt.state       :1813][INFO    ][13126] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-01 15:35:37,836 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13126] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-01 15:35:37,851 [salt.state       :300 ][INFO    ][13126] onlyif execution failed
2019-04-01 15:35:37,852 [salt.state       :1951][INFO    ][13126] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 15:35:37.851959 duration_in_ms=16.248
2019-04-01 15:35:37,853 [salt.state       :1780][INFO    ][13126] Running state [/etc/iptables/rules.v6] at time 15:35:37.853124
2019-04-01 15:35:37,853 [salt.state       :1813][INFO    ][13126] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-01 15:35:37,854 [salt.state       :300 ][INFO    ][13126] File /etc/iptables/rules.v6 is not present
2019-04-01 15:35:37,854 [salt.state       :1951][INFO    ][13126] Completed state [/etc/iptables/rules.v6] at time 15:35:37.854344 duration_in_ms=1.22
2019-04-01 15:35:37,855 [salt.state       :1780][INFO    ][13126] Running state [iptables_extra.flush_all] at time 15:35:37.855169
2019-04-01 15:35:37,855 [salt.state       :1813][INFO    ][13126] Executing state module.wait for [iptables_extra.flush_all]
2019-04-01 15:35:37,855 [salt.state       :300 ][INFO    ][13126] No changes made for iptables_extra.flush_all
2019-04-01 15:35:37,856 [salt.state       :1951][INFO    ][13126] Completed state [iptables_extra.flush_all] at time 15:35:37.856137 duration_in_ms=0.967
2019-04-01 15:35:37,859 [salt.minion      :1711][INFO    ][13126] Returning information for job: 20190401153530097664
2019-04-01 15:35:38,479 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command state.apply with jid 20190401153538466192
2019-04-01 15:35:38,501 [salt.minion      :1432][INFO    ][13202] Starting a new job with PID 13202
2019-04-01 15:35:39,224 [salt.state       :915 ][INFO    ][13202] Loading fresh modules for state activity
2019-04-01 15:35:39,897 [salt.state       :1780][INFO    ][13202] Running state [maas-rack-controller] at time 15:35:39.897597
2019-04-01 15:35:39,897 [salt.state       :1813][INFO    ][13202] Executing state pkg.installed for [maas-rack-controller]
2019-04-01 15:35:39,898 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13202] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-01 15:35:39,978 [salt.state       :300 ][INFO    ][13202] All specified packages are already installed
2019-04-01 15:35:39,978 [salt.state       :1951][INFO    ][13202] Completed state [maas-rack-controller] at time 15:35:39.978552 duration_in_ms=80.954
2019-04-01 15:35:39,978 [salt.state       :1780][INFO    ][13202] Running state [ipmitool] at time 15:35:39.978829
2019-04-01 15:35:39,979 [salt.state       :1813][INFO    ][13202] Executing state pkg.installed for [ipmitool]
2019-04-01 15:35:39,983 [salt.state       :300 ][INFO    ][13202] All specified packages are already installed
2019-04-01 15:35:39,983 [salt.state       :1951][INFO    ][13202] Completed state [ipmitool] at time 15:35:39.983912 duration_in_ms=5.083
2019-04-01 15:35:39,986 [salt.state       :1780][INFO    ][13202] Running state [/etc/maas/rackd.conf] at time 15:35:39.986392
2019-04-01 15:35:39,986 [salt.state       :1813][INFO    ][13202] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-01 15:35:39,987 [salt.state       :300 ][INFO    ][13202] No changes needed to be made
2019-04-01 15:35:39,987 [salt.state       :1951][INFO    ][13202] Completed state [/etc/maas/rackd.conf] at time 15:35:39.987581 duration_in_ms=1.19
2019-04-01 15:35:39,987 [salt.state       :1780][INFO    ][13202] Running state [/etc/maas/rackd.conf] at time 15:35:39.987758
2019-04-01 15:35:39,987 [salt.state       :1813][INFO    ][13202] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-01 15:35:39,988 [salt.loaded.int.states.file:2298][WARNING ][13202] 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-04-01 15:35:39,988 [salt.state       :300 ][INFO    ][13202] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-01 15:35:39,988 [salt.state       :1951][INFO    ][13202] Completed state [/etc/maas/rackd.conf] at time 15:35:39.988680 duration_in_ms=0.922
2019-04-01 15:35:39,989 [salt.state       :1780][INFO    ][13202] Running state [maas-rackd] at time 15:35:39.989376
2019-04-01 15:35:39,989 [salt.state       :1813][INFO    ][13202] Executing state service.running for [maas-rackd]
2019-04-01 15:35:39,990 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13202] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-01 15:35:40,024 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13202] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-01 15:35:40,040 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13202] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-01 15:35:40,057 [salt.state       :300 ][INFO    ][13202] The service maas-rackd is already running
2019-04-01 15:35:40,057 [salt.state       :1951][INFO    ][13202] Completed state [maas-rackd] at time 15:35:40.057457 duration_in_ms=68.079
2019-04-01 15:35:40,059 [salt.minion      :1711][INFO    ][13202] Returning information for job: 20190401153538466192
2019-04-01 15:35:40,637 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command state.apply with jid 20190401153540624030
2019-04-01 15:35:40,659 [salt.minion      :1432][INFO    ][13225] Starting a new job with PID 13225
2019-04-01 15:35:41,394 [salt.state       :915 ][INFO    ][13225] Loading fresh modules for state activity
2019-04-01 15:35:42,121 [salt.state       :1780][INFO    ][13225] Running state [maas-region-controller] at time 15:35:42.121587
2019-04-01 15:35:42,121 [salt.state       :1813][INFO    ][13225] Executing state pkg.installed for [maas-region-controller]
2019-04-01 15:35:42,122 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13225] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-01 15:35:42,201 [salt.state       :300 ][INFO    ][13225] All specified packages are already installed
2019-04-01 15:35:42,201 [salt.state       :1951][INFO    ][13225] Completed state [maas-region-controller] at time 15:35:42.201665 duration_in_ms=80.078
2019-04-01 15:35:42,201 [salt.state       :1780][INFO    ][13225] Running state [python-oauth] at time 15:35:42.201932
2019-04-01 15:35:42,202 [salt.state       :1813][INFO    ][13225] Executing state pkg.installed for [python-oauth]
2019-04-01 15:35:42,207 [salt.state       :300 ][INFO    ][13225] All specified packages are already installed
2019-04-01 15:35:42,207 [salt.state       :1951][INFO    ][13225] Completed state [python-oauth] at time 15:35:42.207479 duration_in_ms=5.547
2019-04-01 15:35:42,210 [salt.state       :1780][INFO    ][13225] Running state [/etc/maas/regiond.conf] at time 15:35:42.210028
2019-04-01 15:35:42,210 [salt.state       :1813][INFO    ][13225] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-01 15:35:42,259 [salt.state       :300 ][INFO    ][13225] No changes needed to be made
2019-04-01 15:35:42,259 [salt.state       :1951][INFO    ][13225] Completed state [/etc/maas/regiond.conf] at time 15:35:42.259574 duration_in_ms=49.545
2019-04-01 15:35:42,260 [salt.state       :1780][INFO    ][13225] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 15:35:42.260292
2019-04-01 15:35:42,260 [salt.state       :1813][INFO    ][13225] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-01 15:35:42,336 [salt.state       :300 ][INFO    ][13225] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-01 15:35:42,336 [salt.state       :1951][INFO    ][13225] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 15:35:42.336760 duration_in_ms=76.468
2019-04-01 15:35:42,337 [salt.state       :1780][INFO    ][13225] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 15:35:42.337559
2019-04-01 15:35:42,338 [salt.state       :1813][INFO    ][13225] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-01 15:35:42,350 [salt.state       :300 ][INFO    ][13225] No changes needed to be made
2019-04-01 15:35:42,350 [salt.state       :1951][INFO    ][13225] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 15:35:42.350692 duration_in_ms=13.133
2019-04-01 15:35:42,351 [salt.state       :1780][INFO    ][13225] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 15:35:42.351399
2019-04-01 15:35:42,351 [salt.state       :1813][INFO    ][13225] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-01 15:35:42,380 [salt.state       :300 ][INFO    ][13225] No changes needed to be made
2019-04-01 15:35:42,380 [salt.state       :1951][INFO    ][13225] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 15:35:42.380673 duration_in_ms=29.273
2019-04-01 15:35:42,381 [salt.state       :1780][INFO    ][13225] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 15:35:42.381390
2019-04-01 15:35:42,381 [salt.state       :1813][INFO    ][13225] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-01 15:35:42,422 [salt.state       :300 ][INFO    ][13225] No changes needed to be made
2019-04-01 15:35:42,423 [salt.state       :1951][INFO    ][13225] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 15:35:42.423004 duration_in_ms=41.605
2019-04-01 15:35:42,424 [salt.state       :1780][INFO    ][13225] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 15:35:42.423957
2019-04-01 15:35:42,424 [salt.state       :1813][INFO    ][13225] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-01 15:35:42,440 [salt.state       :300 ][INFO    ][13225] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-01 15:35:42,440 [salt.state       :1951][INFO    ][13225] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 15:35:42.440493 duration_in_ms=16.521
2019-04-01 15:35:42,443 [salt.state       :1780][INFO    ][13225] Running state [a2enmod headers] at time 15:35:42.443060
2019-04-01 15:35:42,443 [salt.state       :1813][INFO    ][13225] Executing state cmd.run for [a2enmod headers]
2019-04-01 15:35:42,444 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13225] Executing command 'a2enmod headers' in directory '/root'
2019-04-01 15:35:42,518 [salt.state       :300 ][INFO    ][13225] {'pid': 13244, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-01 15:35:42,519 [salt.state       :1951][INFO    ][13225] Completed state [a2enmod headers] at time 15:35:42.519450 duration_in_ms=76.389
2019-04-01 15:35:42,520 [salt.state       :1780][INFO    ][13225] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 15:35:42.520016
2019-04-01 15:35:42,520 [salt.state       :1813][INFO    ][13225] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-01 15:35:42,538 [salt.state       :300 ][INFO    ][13225] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-01 15:35:42,539 [salt.state       :1951][INFO    ][13225] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 15:35:42.538957 duration_in_ms=18.942
2019-04-01 15:35:42,539 [salt.state       :1780][INFO    ][13225] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 15:35:42.539762
2019-04-01 15:35:42,540 [salt.state       :1813][INFO    ][13225] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-01 15:35:42,624 [salt.state       :300 ][INFO    ][13225] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-01 15:35:42,624 [salt.state       :1951][INFO    ][13225] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 15:35:42.624617 duration_in_ms=84.854
2019-04-01 15:35:42,625 [salt.state       :1780][INFO    ][13225] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 15:35:42.625458
2019-04-01 15:35:42,626 [salt.state       :1813][INFO    ][13225] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-01 15:35:42,701 [salt.state       :300 ][INFO    ][13225] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-01 15:35:42,702 [salt.state       :1951][INFO    ][13225] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 15:35:42.702033 duration_in_ms=76.575
2019-04-01 15:35:42,702 [salt.state       :1780][INFO    ][13225] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 15:35:42.702630
2019-04-01 15:35:42,703 [salt.state       :1813][INFO    ][13225] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-01 15:35:42,773 [salt.state       :300 ][INFO    ][13225] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-01 15:35:42,774 [salt.state       :1951][INFO    ][13225] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 15:35:42.774305 duration_in_ms=71.674
2019-04-01 15:35:42,774 [salt.state       :1780][INFO    ][13225] Running state [/root/.pgpass] at time 15:35:42.774762
2019-04-01 15:35:42,775 [salt.state       :1813][INFO    ][13225] Executing state file.managed for [/root/.pgpass]
2019-04-01 15:35:42,833 [salt.state       :300 ][INFO    ][13225] File /root/.pgpass is in the correct state
2019-04-01 15:35:42,833 [salt.state       :1951][INFO    ][13225] Completed state [/root/.pgpass] at time 15:35:42.833876 duration_in_ms=59.115
2019-04-01 15:35:42,841 [salt.state       :1780][INFO    ][13225] Running state [maas-region syncdb --noinput] at time 15:35:42.841070
2019-04-01 15:35:42,841 [salt.state       :1813][INFO    ][13225] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-01 15:35:42,842 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13225] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-01 15:35:44,861 [salt.state       :300 ][INFO    ][13225] {'pid': 13257, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, auth, maasserver, sessions, sites, piston3, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-01 15:35:44,861 [salt.state       :1951][INFO    ][13225] Completed state [maas-region syncdb --noinput] at time 15:35:44.861641 duration_in_ms=2020.571
2019-04-01 15:35:44,862 [salt.state       :2022][WARNING ][13225] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-01 15:35:44,864 [salt.state       :1780][INFO    ][13225] Running state [maas-regiond] at time 15:35:44.864628
2019-04-01 15:35:44,865 [salt.state       :1813][INFO    ][13225] Executing state service.running for [maas-regiond]
2019-04-01 15:35:44,866 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13225] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-01 15:35:44,905 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13225] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-01 15:35:44,924 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13225] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-01 15:35:44,941 [salt.state       :300 ][INFO    ][13225] The service maas-regiond is already running
2019-04-01 15:35:44,942 [salt.state       :1951][INFO    ][13225] Completed state [maas-regiond] at time 15:35:44.942335 duration_in_ms=77.706
2019-04-01 15:35:44,945 [salt.state       :1780][INFO    ][13225] Running state [bind9] at time 15:35:44.945025
2019-04-01 15:35:44,945 [salt.state       :1813][INFO    ][13225] Executing state service.running for [bind9]
2019-04-01 15:35:44,946 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13225] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-01 15:35:44,966 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13225] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-01 15:35:44,985 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13225] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-01 15:35:45,002 [salt.state       :300 ][INFO    ][13225] The service bind9 is already running
2019-04-01 15:35:45,003 [salt.state       :1951][INFO    ][13225] Completed state [bind9] at time 15:35:45.003451 duration_in_ms=58.426
2019-04-01 15:35:45,006 [salt.state       :1780][INFO    ][13225] Running state [apache2] at time 15:35:45.006141
2019-04-01 15:35:45,006 [salt.state       :1813][INFO    ][13225] Executing state service.running for [apache2]
2019-04-01 15:35:45,007 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13225] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-01 15:35:45,025 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13225] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-01 15:35:45,041 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13225] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-01 15:35:45,062 [salt.state       :300 ][INFO    ][13225] The service apache2 is already running
2019-04-01 15:35:45,062 [salt.state       :1951][INFO    ][13225] Completed state [apache2] at time 15:35:45.062743 duration_in_ms=56.602
2019-04-01 15:35:45,064 [salt.state       :1780][INFO    ][13225] Running state [maasng.wait_for_http_code] at time 15:35:45.064719
2019-04-01 15:35:45,065 [salt.state       :1813][INFO    ][13225] Executing state module.run for [maasng.wait_for_http_code]
2019-04-01 15:35:45,065 [salt.utils.decorators:613 ][WARNING ][13225] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:35:45,195 [salt.state       :300 ][INFO    ][13225] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-01 15:35:45,196 [salt.state       :1951][INFO    ][13225] Completed state [maasng.wait_for_http_code] at time 15:35:45.196566 duration_in_ms=131.847
2019-04-01 15:35:45,198 [salt.state       :1780][INFO    ][13225] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 15:35:45.198084
2019-04-01 15:35:45,198 [salt.state       :1813][INFO    ][13225] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-01 15:35:45,199 [salt.state       :300 ][INFO    ][13225] /var/lib/maas/.setup_admin exists
2019-04-01 15:35:45,199 [salt.state       :1951][INFO    ][13225] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 15:35:45.199756 duration_in_ms=1.672
2019-04-01 15:35:45,200 [salt.state       :1780][INFO    ][13225] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:35:45.200910
2019-04-01 15:35:45,201 [salt.state       :1813][INFO    ][13225] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-01 15:35:45,202 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13225] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-01 15:35:46,599 [salt.state       :300 ][INFO    ][13225] {'pid': 13284, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-01 15:35:46,599 [salt.state       :1951][INFO    ][13225] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:35:46.599641 duration_in_ms=1398.731
2019-04-01 15:35:46,603 [salt.state       :1780][INFO    ][13225] Running state [maas_region_boot_source_resources_mirror] at time 15:35:46.603204
2019-04-01 15:35:46,603 [salt.state       :1813][INFO    ][13225] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-01 15:35:46,697 [salt.state       :300 ][INFO    ][13225] {'changes': {}}
2019-04-01 15:35:46,698 [salt.state       :1951][INFO    ][13225] Completed state [maas_region_boot_source_resources_mirror] at time 15:35:46.698380 duration_in_ms=95.175
2019-04-01 15:35:46,699 [salt.state       :1780][INFO    ][13225] Running state [maasng.boot_resources_import] at time 15:35:46.699431
2019-04-01 15:35:46,699 [salt.state       :1813][INFO    ][13225] Executing state module.run for [maasng.boot_resources_import]
2019-04-01 15:35:46,700 [salt.utils.decorators:613 ][WARNING ][13225] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:35:46,825 [salt.loaded.ext.module.maasng:1600][INFO    ][13225] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-01 15:35:51,879 [salt.loaded.ext.module.maasng:1600][INFO    ][13225] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-01 15:35:55,656 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153555643583
2019-04-01 15:35:55,679 [salt.minion      :1432][INFO    ][13310] Starting a new job with PID 13310
2019-04-01 15:35:55,703 [salt.minion      :1711][INFO    ][13310] Returning information for job: 20190401153555643583
2019-04-01 15:35:56,940 [salt.loaded.ext.module.maasng:1600][INFO    ][13225] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-04-01 15:36:02,006 [salt.loaded.ext.module.maasng:1600][INFO    ][13225] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-04-01 15:36:07,126 [salt.state       :300 ][INFO    ][13225] {'ret': True}
2019-04-01 15:36:07,126 [salt.state       :1951][INFO    ][13225] Completed state [maasng.boot_resources_import] at time 15:36:07.126838 duration_in_ms=20427.405
2019-04-01 15:36:07,128 [salt.state       :1780][INFO    ][13225] Running state [maas_region_boot_sources_selection_xenial] at time 15:36:07.127987
2019-04-01 15:36:07,128 [salt.state       :1813][INFO    ][13225] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-01 15:36:07,335 [salt.state       :300 ][INFO    ][13225] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-01 15:36:07,335 [salt.state       :1951][INFO    ][13225] Completed state [maas_region_boot_sources_selection_xenial] at time 15:36:07.335412 duration_in_ms=207.424
2019-04-01 15:36:07,336 [salt.state       :1780][INFO    ][13225] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 15:36:07.336841
2019-04-01 15:36:07,337 [salt.state       :1813][INFO    ][13225] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-01 15:36:07,337 [salt.utils.decorators:613 ][WARNING ][13225] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:36:07,338 [salt.loaded.ext.module.maasng:1771][INFO    ][13225] boot-sources sync initiated for ALL Rack's
2019-04-01 15:36:08,329 [salt.state       :300 ][INFO    ][13225] {'ret': True}
2019-04-01 15:36:08,329 [salt.state       :1951][INFO    ][13225] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 15:36:08.329866 duration_in_ms=993.024
2019-04-01 15:36:08,332 [salt.state       :1780][INFO    ][13225] Running state [maas.process_maas_config] at time 15:36:08.332043
2019-04-01 15:36:08,332 [salt.state       :1813][INFO    ][13225] Executing state module.run for [maas.process_maas_config]
2019-04-01 15:36:08,333 [salt.utils.decorators:613 ][WARNING ][13225] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:36:08,334 [salt.loaded.ext.module.maas:92  ][INFO    ][13225] maasconfig name=enable_http_proxy value=True
2019-04-01 15:36:08,390 [salt.loaded.ext.module.maas:92  ][INFO    ][13225] maasconfig name=upstream_dns value=8.8.8.8
2019-04-01 15:36:08,455 [salt.loaded.ext.module.maas:92  ][INFO    ][13225] maasconfig name=commissioning_distro_series value=xenial
2019-04-01 15:36:08,520 [salt.loaded.ext.module.maas:92  ][INFO    ][13225] maasconfig name=default_osystem value=ubuntu
2019-04-01 15:36:08,586 [salt.loaded.ext.module.maas:92  ][INFO    ][13225] maasconfig name=active_discovery_interval value=600
2019-04-01 15:36:08,646 [salt.loaded.ext.module.maas:92  ][INFO    ][13225] maasconfig name=dnssec_validation value=no
2019-04-01 15:36:08,706 [salt.loaded.ext.module.maas:92  ][INFO    ][13225] maasconfig name=maas_name value=mas01
2019-04-01 15:36:08,760 [salt.loaded.ext.module.maas:92  ][INFO    ][13225] maasconfig name=network_discovery value=enabled
2019-04-01 15:36:08,868 [salt.loaded.ext.module.maas:92  ][INFO    ][13225] maasconfig name=enable_third_party_drivers value=True
2019-04-01 15:36:12,697 [salt.loaded.ext.module.maas:92  ][INFO    ][13225] maasconfig name=default_storage_layout value=lvm
2019-04-01 15:36:12,787 [salt.loaded.ext.module.maas:92  ][INFO    ][13225] maasconfig name=ntp_external_only value=True
2019-04-01 15:36:12,852 [salt.loaded.ext.module.maas:92  ][INFO    ][13225] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-01 15:36:12,918 [salt.loaded.ext.module.maas:92  ][INFO    ][13225] maasconfig name=default_distro_series value=xenial
2019-04-01 15:36:12,988 [salt.loaded.ext.module.maas:92  ][INFO    ][13225] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-01 15:36:13,108 [salt.state       :300 ][INFO    ][13225] {'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-04-01 15:36:13,109 [salt.state       :1951][INFO    ][13225] Completed state [maas.process_maas_config] at time 15:36:13.109262 duration_in_ms=4777.218
2019-04-01 15:36:13,110 [salt.state       :1780][INFO    ][13225] Running state [pxe_admin] at time 15:36:13.110176
2019-04-01 15:36:13,110 [salt.state       :1813][INFO    ][13225] Executing state maasng.fabric_present for [pxe_admin]
2019-04-01 15:36:13,167 [salt.loaded.ext.module.maasng:945 ][INFO    ][13225] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gcbpay', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-01 15:36:13,245 [salt.loaded.ext.module.maasng:1008][WARNING ][13225] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-01 15:36:13,246 [salt.loaded.ext.module.maasng:1011][WARNING ][13225] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-01 15:36:13,317 [salt.state       :300 ][INFO    ][13225] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-01 15:36:13,318 [salt.state       :1951][INFO    ][13225] Completed state [pxe_admin] at time 15:36:13.318276 duration_in_ms=208.099
2019-04-01 15:36:13,318 [salt.state       :1780][INFO    ][13225] Running state [vlan 0] at time 15:36:13.318838
2019-04-01 15:36:13,319 [salt.state       :1813][INFO    ][13225] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-01 15:36:13,376 [salt.loaded.ext.module.maasng:945 ][INFO    ][13225] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gcbpay', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-04-01 15:36:13,509 [salt.loaded.ext.module.maasng:945 ][INFO    ][13225] [{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'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'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gcbpay', 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'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-04-01 15:36:13,712 [salt.loaded.ext.module.maasng:945 ][INFO    ][13225] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gcbpay', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-01 15:36:13,797 [salt.state       :300 ][INFO    ][13225] {'new': 'Vlan untagged was updated'}
2019-04-01 15:36:13,798 [salt.state       :1951][INFO    ][13225] Completed state [vlan 0] at time 15:36:13.797899 duration_in_ms=479.054
2019-04-01 15:36:13,799 [salt.state       :1780][INFO    ][13225] Running state [192.168.11.0/24] at time 15:36:13.799525
2019-04-01 15:36:13,800 [salt.state       :1813][INFO    ][13225] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-01 15:36:13,994 [salt.loaded.ext.module.maasng:945 ][INFO    ][13225] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gcbpay', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-01 15:36:13,995 [salt.loaded.ext.module.maasng:1235][WARNING ][13225] Ignoring parameter vlan:0
2019-04-01 15:36:14,085 [salt.state       :300 ][INFO    ][13225] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-01 15:36:14,085 [salt.state       :1951][INFO    ][13225] Completed state [192.168.11.0/24] at time 15:36:14.085862 duration_in_ms=286.337
2019-04-01 15:36:14,087 [salt.state       :1780][INFO    ][13225] Running state [maas_create_iprange_1] at time 15:36:14.087051
2019-04-01 15:36:14,087 [salt.state       :1813][INFO    ][13225] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-01 15:36:14,149 [salt.state       :300 ][INFO    ][13225] Iprange maas_create_iprange_1 already exist.
2019-04-01 15:36:14,149 [salt.state       :1951][INFO    ][13225] Completed state [maas_create_iprange_1] at time 15:36:14.149830 duration_in_ms=62.778
2019-04-01 15:36:14,150 [salt.state       :1780][INFO    ][13225] Running state [vlan 0] at time 15:36:14.150214
2019-04-01 15:36:14,150 [salt.state       :1813][INFO    ][13225] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-01 15:36:14,203 [salt.loaded.ext.module.maasng:945 ][INFO    ][13225] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gcbpay', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-04-01 15:36:14,305 [salt.loaded.ext.module.maasng:945 ][INFO    ][13225] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gcbpay', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-01 15:36:14,581 [salt.loaded.ext.module.maasng:945 ][INFO    ][13225] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gcbpay', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-04-01 15:36:14,690 [salt.state       :300 ][INFO    ][13225] {'new': 'Vlan untagged was updated'}
2019-04-01 15:36:14,691 [salt.state       :1951][INFO    ][13225] Completed state [vlan 0] at time 15:36:14.691060 duration_in_ms=540.845
2019-04-01 15:36:14,692 [salt.state       :1780][INFO    ][13225] Running state [opnfv] at time 15:36:14.692030
2019-04-01 15:36:14,692 [salt.state       :1813][INFO    ][13225] Executing state maasng.sshkey_present for [opnfv]
2019-04-01 15:36:14,742 [salt.loaded.ext.module.maasng:1903][INFO    ][13225] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-04-01 15:36:14,743 [salt.state       :300 ][INFO    ][13225] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-04-01 15:36:14,743 [salt.state       :1951][INFO    ][13225] Completed state [opnfv] at time 15:36:14.743618 duration_in_ms=51.587
2019-04-01 15:36:14,744 [salt.state       :1780][INFO    ][13225] Running state [maas.process_tags] at time 15:36:14.744574
2019-04-01 15:36:14,745 [salt.state       :1813][INFO    ][13225] Executing state module.run for [maas.process_tags]
2019-04-01 15:36:14,745 [salt.utils.decorators:613 ][WARNING ][13225] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:36:14,796 [salt.loaded.ext.module.maas:92  ][INFO    ][13225] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-04-01 15:36:14,874 [salt.state       :300 ][INFO    ][13225] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-04-01 15:36:14,874 [salt.state       :1951][INFO    ][13225] Completed state [maas.process_tags] at time 15:36:14.874551 duration_in_ms=129.976
2019-04-01 15:36:14,879 [salt.minion      :1711][INFO    ][13225] Returning information for job: 20190401153540624030
2019-04-01 15:36:15,407 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command state.apply with jid 20190401153615394398
2019-04-01 15:36:15,427 [salt.minion      :1432][INFO    ][13697] Starting a new job with PID 13697
2019-04-01 15:36:19,134 [salt.state       :915 ][INFO    ][13697] Loading fresh modules for state activity
2019-04-01 15:36:19,231 [salt.state       :1780][INFO    ][13697] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:36:19.231137
2019-04-01 15:36:19,231 [salt.state       :1813][INFO    ][13697] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-01 15:36:19,233 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13697] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-01 15:36:20,664 [salt.state       :300 ][INFO    ][13697] {'pid': 13720, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-01 15:36:20,665 [salt.state       :1951][INFO    ][13697] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:36:20.665207 duration_in_ms=1434.071
2019-04-01 15:36:20,666 [salt.state       :1780][INFO    ][13697] Running state [maas.process_machines] at time 15:36:20.666306
2019-04-01 15:36:20,666 [salt.state       :1813][INFO    ][13697] Executing state module.run for [maas.process_machines]
2019-04-01 15:36:20,667 [salt.utils.decorators:613 ][WARNING ][13697] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:36:21,295 [salt.loaded.ext.module.maas:412 ][WARNING ][13697] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-01 15:36:21,296 [salt.loaded.ext.module.maas:92  ][INFO    ][13697] 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=ga7kxa architecture=amd64/generic power_parameters_power_user=admin
2019-04-01 15:36:22,553 [salt.loaded.ext.module.maas:412 ][WARNING ][13697] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-01 15:36:22,554 [salt.loaded.ext.module.maas:92  ][INFO    ][13697] 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=y6ntxf architecture=amd64/generic power_parameters_power_user=admin
2019-04-01 15:36:23,594 [salt.loaded.ext.module.maas:412 ][WARNING ][13697] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-01 15:36:23,594 [salt.loaded.ext.module.maas:92  ][INFO    ][13697] 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=a6wmmn architecture=amd64/generic power_parameters_power_user=admin
2019-04-01 15:36:24,863 [salt.loaded.ext.module.maas:412 ][WARNING ][13697] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-01 15:36:24,864 [salt.loaded.ext.module.maas:92  ][INFO    ][13697] 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=e8hwp8 architecture=amd64/generic power_parameters_power_user=admin
2019-04-01 15:36:26,138 [salt.loaded.ext.module.maas:412 ][WARNING ][13697] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-01 15:36:26,139 [salt.loaded.ext.module.maas:92  ][INFO    ][13697] 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=ftbwf7 architecture=amd64/generic power_parameters_power_user=admin
2019-04-01 15:36:27,337 [salt.state       :300 ][INFO    ][13697] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-01 15:36:27,338 [salt.state       :1951][INFO    ][13697] Completed state [maas.process_machines] at time 15:36:27.338064 duration_in_ms=6671.755
2019-04-01 15:36:27,341 [salt.minion      :1711][INFO    ][13697] Returning information for job: 20190401153615394398
2019-04-01 15:37:00,674 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command state.apply with jid 20190401153700666318
2019-04-01 15:37:00,697 [salt.minion      :1432][INFO    ][13964] Starting a new job with PID 13964
2019-04-01 15:37:04,225 [salt.state       :915 ][INFO    ][13964] Loading fresh modules for state activity
2019-04-01 15:37:04,316 [salt.state       :1780][INFO    ][13964] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:37:04.316391
2019-04-01 15:37:04,316 [salt.state       :1813][INFO    ][13964] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-01 15:37:04,319 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13964] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-01 15:37:05,774 [salt.state       :300 ][INFO    ][13964] {'pid': 13971, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-01 15:37:05,774 [salt.state       :1951][INFO    ][13964] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:37:05.774469 duration_in_ms=1458.079
2019-04-01 15:37:05,776 [salt.state       :1780][INFO    ][13964] Running state [maas.wait_for_machine_status] at time 15:37:05.776042
2019-04-01 15:37:05,776 [salt.state       :1813][INFO    ][13964] Executing state module.run for [maas.wait_for_machine_status]
2019-04-01 15:37:05,776 [salt.utils.decorators:613 ][WARNING ][13964] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:37:09,312 [salt.loaded.ext.module.maas:993 ][INFO    ][13964] Machine ftbwf7 mark broken
2019-04-01 15:37:09,972 [salt.loaded.ext.module.maas:996 ][INFO    ][13964] Machine ftbwf7 mark fixed
2019-04-01 15:37:11,264 [salt.loaded.ext.module.maas:684 ][INFO    ][13964] deploymachines hwe_kernel=hwe-16.04 system_id=ftbwf7 distro_series=xenial
2019-04-01 15:37:13,943 [salt.loaded.ext.module.maas:1023][INFO    ][13964] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1491.83799005s left)
2019-04-01 15:37:15,733 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153715720445
2019-04-01 15:37:15,757 [salt.minion      :1432][INFO    ][14069] Starting a new job with PID 14069
2019-04-01 15:37:15,779 [salt.minion      :1711][INFO    ][14069] Returning information for job: 20190401153715720445
2019-04-01 15:37:45,780 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153745768590
2019-04-01 15:37:45,803 [salt.minion      :1432][INFO    ][14100] Starting a new job with PID 14100
2019-04-01 15:37:45,828 [salt.minion      :1711][INFO    ][14100] Returning information for job: 20190401153745768590
2019-04-01 15:37:47,239 [salt.loaded.ext.module.maas:1023][INFO    ][13964] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1458.54142594s left)
2019-04-01 15:38:16,040 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153815990642
2019-04-01 15:38:16,061 [salt.minion      :1432][INFO    ][14142] Starting a new job with PID 14142
2019-04-01 15:38:16,083 [salt.minion      :1711][INFO    ][14142] Returning information for job: 20190401153815990642
2019-04-01 15:38:20,750 [salt.loaded.ext.module.maas:1023][INFO    ][13964] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1425.03123307s left)
2019-04-01 15:38:46,086 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153846071384
2019-04-01 15:38:46,108 [salt.minion      :1432][INFO    ][14172] Starting a new job with PID 14172
2019-04-01 15:38:46,136 [salt.minion      :1711][INFO    ][14172] Returning information for job: 20190401153846071384
2019-04-01 15:38:53,780 [salt.loaded.ext.module.maas:1023][INFO    ][13964] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1392.00067091s left)
2019-04-01 15:39:16,142 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153916127963
2019-04-01 15:39:16,165 [salt.minion      :1432][INFO    ][14267] Starting a new job with PID 14267
2019-04-01 15:39:16,191 [salt.minion      :1711][INFO    ][14267] Returning information for job: 20190401153916127963
2019-04-01 15:39:27,385 [salt.loaded.ext.module.maas:1023][INFO    ][13964] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1358.39620614s left)
2019-04-01 15:39:46,200 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401153946186821
2019-04-01 15:39:46,222 [salt.minion      :1432][INFO    ][14354] Starting a new job with PID 14354
2019-04-01 15:39:46,248 [salt.minion      :1711][INFO    ][14354] Returning information for job: 20190401153946186821
2019-04-01 15:40:00,592 [salt.loaded.ext.module.maas:1023][INFO    ][13964] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1325.18872309s left)
2019-04-01 15:40:16,255 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401154016242747
2019-04-01 15:40:16,277 [salt.minion      :1432][INFO    ][14438] Starting a new job with PID 14438
2019-04-01 15:40:16,302 [salt.minion      :1711][INFO    ][14438] Returning information for job: 20190401154016242747
2019-04-01 15:40:34,235 [salt.loaded.ext.module.maas:1023][INFO    ][13964] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1291.54545307s left)
2019-04-01 15:40:46,314 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401154046302030
2019-04-01 15:40:46,336 [salt.minion      :1432][INFO    ][14543] Starting a new job with PID 14543
2019-04-01 15:40:46,362 [salt.minion      :1711][INFO    ][14543] Returning information for job: 20190401154046302030
2019-04-01 15:41:07,398 [salt.loaded.ext.module.maas:1023][INFO    ][13964] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1258.38288307s left)
2019-04-01 15:41:16,381 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401154116368279
2019-04-01 15:41:16,404 [salt.minion      :1432][INFO    ][14597] Starting a new job with PID 14597
2019-04-01 15:41:16,430 [salt.minion      :1711][INFO    ][14597] Returning information for job: 20190401154116368279
2019-04-01 15:41:41,115 [salt.loaded.ext.module.maas:1023][INFO    ][13964] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1224.66596699s left)
2019-04-01 15:41:46,453 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401154146440311
2019-04-01 15:41:46,475 [salt.minion      :1432][INFO    ][14657] Starting a new job with PID 14657
2019-04-01 15:41:46,500 [salt.minion      :1711][INFO    ][14657] Returning information for job: 20190401154146440311
2019-04-01 15:42:14,168 [salt.loaded.ext.module.maas:1023][INFO    ][13964] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1191.61252904s left)
2019-04-01 15:42:16,528 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401154216515366
2019-04-01 15:42:16,551 [salt.minion      :1432][INFO    ][14736] Starting a new job with PID 14736
2019-04-01 15:42:16,577 [salt.minion      :1711][INFO    ][14736] Returning information for job: 20190401154216515366
2019-04-01 15:42:46,611 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401154246598649
2019-04-01 15:42:46,633 [salt.minion      :1432][INFO    ][14831] Starting a new job with PID 14831
2019-04-01 15:42:46,659 [salt.minion      :1711][INFO    ][14831] Returning information for job: 20190401154246598649
2019-04-01 15:42:47,944 [salt.loaded.ext.module.maas:1023][INFO    ][13964] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1157.83655214s left)
2019-04-01 15:43:16,695 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401154316683073
2019-04-01 15:43:16,714 [salt.minion      :1432][INFO    ][14898] Starting a new job with PID 14898
2019-04-01 15:43:16,739 [salt.minion      :1711][INFO    ][14898] Returning information for job: 20190401154316683073
2019-04-01 15:43:21,191 [salt.loaded.ext.module.maas:1023][INFO    ][13964] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1124.59028697s left)
2019-04-01 15:43:46,783 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401154346769972
2019-04-01 15:43:46,805 [salt.minion      :1432][INFO    ][14990] Starting a new job with PID 14990
2019-04-01 15:43:46,831 [salt.minion      :1711][INFO    ][14990] Returning information for job: 20190401154346769972
2019-04-01 15:43:54,772 [salt.loaded.ext.module.maas:1023][INFO    ][13964] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1091.00883293s left)
2019-04-01 15:44:16,879 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401154416866681
2019-04-01 15:44:16,902 [salt.minion      :1432][INFO    ][15061] Starting a new job with PID 15061
2019-04-01 15:44:16,928 [salt.minion      :1711][INFO    ][15061] Returning information for job: 20190401154416866681
2019-04-01 15:44:28,354 [salt.loaded.ext.module.maas:1023][INFO    ][13964] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1057.42719913s left)
2019-04-01 15:44:47,028 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command saltutil.find_job with jid 20190401154447014771
2019-04-01 15:44:47,050 [salt.minion      :1432][INFO    ][15110] Starting a new job with PID 15110
2019-04-01 15:44:47,075 [salt.minion      :1711][INFO    ][15110] Returning information for job: 20190401154447014771
2019-04-01 15:45:01,941 [salt.state       :300 ][INFO    ][13964] {'ret': True}
2019-04-01 15:45:01,942 [salt.state       :1951][INFO    ][13964] Completed state [maas.wait_for_machine_status] at time 15:45:01.942055 duration_in_ms=476166.01
2019-04-01 15:45:01,945 [salt.minion      :1711][INFO    ][13964] Returning information for job: 20190401153700666318
2019-04-01 15:45:02,596 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command state.apply with jid 20190401154502583604
2019-04-01 15:45:02,618 [salt.minion      :1432][INFO    ][15145] Starting a new job with PID 15145
2019-04-01 15:45:06,410 [salt.state       :915 ][INFO    ][15145] Loading fresh modules for state activity
2019-04-01 15:45:06,545 [salt.state       :1780][INFO    ][15145] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:45:06.545161
2019-04-01 15:45:06,545 [salt.state       :1813][INFO    ][15145] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-01 15:45:06,547 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15145] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-01 15:45:07,990 [salt.state       :300 ][INFO    ][15145] {'pid': 15152, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-01 15:45:07,991 [salt.state       :1951][INFO    ][15145] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:45:07.991214 duration_in_ms=1446.054
2019-04-01 15:45:07,992 [salt.state       :1780][INFO    ][15145] Running state [maas_machines_storage_cmp002_lvm] at time 15:45:07.992751
2019-04-01 15:45:07,993 [salt.state       :1813][INFO    ][15145] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-01 15:45:08,749 [salt.state       :300 ][INFO    ][15145] Machine cmp002 is not in Ready state.
2019-04-01 15:45:08,749 [salt.state       :1951][INFO    ][15145] Completed state [maas_machines_storage_cmp002_lvm] at time 15:45:08.749429 duration_in_ms=756.658
2019-04-01 15:45:08,750 [salt.state       :1780][INFO    ][15145] Running state [maas_machines_storage_cmp001_lvm] at time 15:45:08.749958
2019-04-01 15:45:08,750 [salt.state       :1813][INFO    ][15145] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-01 15:45:09,480 [salt.state       :300 ][INFO    ][15145] Machine cmp001 is not in Ready state.
2019-04-01 15:45:09,480 [salt.state       :1951][INFO    ][15145] Completed state [maas_machines_storage_cmp001_lvm] at time 15:45:09.480603 duration_in_ms=730.644
2019-04-01 15:45:09,484 [salt.minion      :1711][INFO    ][15145] Returning information for job: 20190401154502583604
2019-04-01 15:45:09,961 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command state.apply with jid 20190401154509953814
2019-04-01 15:45:09,981 [salt.minion      :1432][INFO    ][15206] Starting a new job with PID 15206
2019-04-01 15:45:10,774 [salt.state       :915 ][INFO    ][15206] Loading fresh modules for state activity
2019-04-01 15:45:10,867 [salt.state       :1780][INFO    ][15206] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:45:10.867218
2019-04-01 15:45:10,867 [salt.state       :1813][INFO    ][15206] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-01 15:45:10,870 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15206] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-01 15:45:12,332 [salt.state       :300 ][INFO    ][15206] {'pid': 15213, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-01 15:45:12,333 [salt.state       :1951][INFO    ][15206] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:45:12.333320 duration_in_ms=1466.102
2019-04-01 15:45:12,334 [salt.state       :1780][INFO    ][15206] Running state [maas.deploy_machines] at time 15:45:12.334623
2019-04-01 15:45:12,334 [salt.state       :1813][INFO    ][15206] Executing state module.run for [maas.deploy_machines]
2019-04-01 15:45:12,335 [salt.utils.decorators:613 ][WARNING ][15206] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:45:12,805 [salt.state       :300 ][INFO    ][15206] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-01 15:45:12,805 [salt.state       :1951][INFO    ][15206] Completed state [maas.deploy_machines] at time 15:45:12.805601 duration_in_ms=470.977
2019-04-01 15:45:12,807 [salt.minion      :1711][INFO    ][15206] Returning information for job: 20190401154509953814
2019-04-01 15:45:13,417 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command state.apply with jid 20190401154513404138
2019-04-01 15:45:13,440 [salt.minion      :1432][INFO    ][15222] Starting a new job with PID 15222
2019-04-01 15:45:14,194 [salt.state       :915 ][INFO    ][15222] Loading fresh modules for state activity
2019-04-01 15:45:14,257 [salt.state       :1780][INFO    ][15222] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:45:14.257628
2019-04-01 15:45:14,257 [salt.state       :1813][INFO    ][15222] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-01 15:45:14,259 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15222] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-01 15:45:15,726 [salt.state       :300 ][INFO    ][15222] {'pid': 15242, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-01 15:45:15,727 [salt.state       :1951][INFO    ][15222] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:45:15.727244 duration_in_ms=1469.617
2019-04-01 15:45:15,728 [salt.state       :1780][INFO    ][15222] Running state [maas.wait_for_machine_status] at time 15:45:15.728773
2019-04-01 15:45:15,729 [salt.state       :1813][INFO    ][15222] Executing state module.run for [maas.wait_for_machine_status]
2019-04-01 15:45:15,729 [salt.utils.decorators:613 ][WARNING ][15222] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-01 15:45:19,399 [salt.state       :300 ][INFO    ][15222] {'ret': True}
2019-04-01 15:45:19,400 [salt.state       :1951][INFO    ][15222] Completed state [maas.wait_for_machine_status] at time 15:45:19.399972 duration_in_ms=3671.196
2019-04-01 15:45:19,403 [salt.minion      :1711][INFO    ][15222] Returning information for job: 20190401154513404138
2019-04-01 16:15:03,934 [salt.utils.schedule:1377][INFO    ][7005] Running scheduled job: __mine_interval
2019-04-01 17:15:03,934 [salt.utils.schedule:1377][INFO    ][7005] Running scheduled job: __mine_interval
2019-04-01 17:20:44,258 [salt.minion      :1308][INFO    ][7005] User sudo_ubuntu Executing command cp.push_dir with jid 20190401172044245881
2019-04-01 17:20:44,273 [salt.minion      :1432][INFO    ][22116] Starting a new job with PID 22116
