2019-04-07 05:14:43,214 [salt.utils.decorators:613 ][WARNING ][2096] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:14:43,778 [salt.utils.decorators:613 ][WARNING ][2096] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:14:45,834 [salt.loaded.int.states.file:2298][WARNING ][2508] 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-07 05:15:09,757 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3185] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-07 05:15:09,781 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3185] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-07 05:15:09,798 [salt.utils.parsers:1051][WARNING ][362] Minion received a SIGTERM. Exiting.
2019-04-07 05:15:10,753 [salt.cli.daemons :293 ][INFO    ][3296] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-04-07 05:15:10,841 [salt.cli.daemons :82  ][INFO    ][3296] Starting up the Salt Minion
2019-04-07 05:15:10,842 [salt.utils.event :1017][INFO    ][3296] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-04-07 05:15:11,717 [salt.minion      :976 ][INFO    ][3296] Creating minion process manager
2019-04-07 05:15:13,243 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3296] Executing command ['date', '+%z'] in directory '/root'
2019-04-07 05:15:13,265 [salt.utils.schedule:568 ][INFO    ][3296] Updating job settings for scheduled job: __mine_interval
2019-04-07 05:15:13,266 [salt.minion      :1108][INFO    ][3296] Added mine.update to scheduler
2019-04-07 05:15:13,271 [salt.minion      :1975][INFO    ][3296] Minion is starting as user 'root'
2019-04-07 05:15:13,282 [salt.minion      :2336][INFO    ][3296] Minion is ready to receive requests!
2019-04-07 05:15:15,200 [salt.state       :2022][WARNING ][3189] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-07 05:15:17,754 [salt.utils.decorators:613 ][WARNING ][3189] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:15:23,485 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407051523472537
2019-04-07 05:15:23,506 [salt.minion      :1432][INFO    ][3830] Starting a new job with PID 3830
2019-04-07 05:15:23,531 [salt.minion      :1711][INFO    ][3830] Returning information for job: 20190407051523472537
2019-04-07 05:15:53,543 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407051553528876
2019-04-07 05:15:53,564 [salt.minion      :1432][INFO    ][4042] Starting a new job with PID 4042
2019-04-07 05:15:53,588 [salt.minion      :1711][INFO    ][4042] Returning information for job: 20190407051553528876
2019-04-07 05:16:23,681 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407051623672597
2019-04-07 05:16:23,697 [salt.minion      :1432][INFO    ][4345] Starting a new job with PID 4345
2019-04-07 05:16:23,713 [salt.minion      :1711][INFO    ][4345] Returning information for job: 20190407051623672597
2019-04-07 05:16:48,267 [salt.utils.decorators:613 ][WARNING ][3189] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:16:53,714 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407051653700464
2019-04-07 05:16:53,736 [salt.minion      :1432][INFO    ][4683] Starting a new job with PID 4683
2019-04-07 05:16:53,761 [salt.minion      :1711][INFO    ][4683] Returning information for job: 20190407051653700464
2019-04-07 05:17:23,773 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407051723759528
2019-04-07 05:17:23,792 [salt.minion      :1432][INFO    ][5078] Starting a new job with PID 5078
2019-04-07 05:17:23,812 [salt.minion      :1711][INFO    ][5078] Returning information for job: 20190407051723759528
2019-04-07 05:17:26,946 [salt.utils.decorators:613 ][WARNING ][3189] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:17:27,964 [salt.utils.decorators:613 ][WARNING ][3189] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:17:31,944 [salt.loaded.ext.module.maasng:1008][WARNING ][3189] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-04-07 05:17:31,944 [salt.loaded.ext.module.maasng:1011][WARNING ][3189] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-04-07 05:17:32,810 [salt.loaded.ext.module.maasng:1235][WARNING ][3189] Ignoring parameter vlan:0
2019-04-07 05:17:33,824 [salt.utils.decorators:613 ][WARNING ][3189] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:17:34,523 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command state.apply with jid 20190407051734509650
2019-04-07 05:17:34,542 [salt.minion      :1432][INFO    ][5299] Starting a new job with PID 5299
2019-04-07 05:17:38,320 [salt.state       :915 ][INFO    ][5299] Loading fresh modules for state activity
2019-04-07 05:17:38,377 [salt.fileclient  :1219][INFO    ][5299] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-04-07 05:17:38,414 [salt.state       :1780][INFO    ][5299] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:17:38.414795
2019-04-07 05:17:38,415 [salt.state       :1813][INFO    ][5299] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-07 05:17:38,417 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5299] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-07 05:17:39,790 [salt.state       :300 ][INFO    ][5299] {'pid': 5325, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-07 05:17:39,790 [salt.state       :1951][INFO    ][5299] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:17:39.790792 duration_in_ms=1375.997
2019-04-07 05:17:39,792 [salt.state       :1780][INFO    ][5299] Running state [maas.process_machines] at time 05:17:39.791990
2019-04-07 05:17:39,792 [salt.state       :1813][INFO    ][5299] Executing state module.run for [maas.process_machines]
2019-04-07 05:17:39,792 [salt.utils.decorators:613 ][WARNING ][5299] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:17:39,868 [salt.loaded.ext.module.maas:412 ][WARNING ][5299] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-07 05:17:39,869 [salt.loaded.ext.module.maas:92  ][INFO    ][5299] 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 architecture=amd64/generic power_parameters_power_user=admin
2019-04-07 05:17:41,359 [salt.loaded.ext.module.maas:412 ][WARNING ][5299] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-07 05:17:41,360 [salt.loaded.ext.module.maas:92  ][INFO    ][5299] 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 architecture=amd64/generic power_parameters_power_user=admin
2019-04-07 05:17:42,811 [salt.loaded.ext.module.maas:412 ][WARNING ][5299] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-07 05:17:42,812 [salt.loaded.ext.module.maas:92  ][INFO    ][5299] 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 architecture=amd64/generic power_parameters_power_user=admin
2019-04-07 05:17:44,248 [salt.loaded.ext.module.maas:412 ][WARNING ][5299] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-07 05:17:44,249 [salt.loaded.ext.module.maas:92  ][INFO    ][5299] 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 architecture=amd64/generic power_parameters_power_user=admin
2019-04-07 05:17:45,626 [salt.loaded.ext.module.maas:412 ][WARNING ][5299] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-07 05:17:45,627 [salt.loaded.ext.module.maas:92  ][INFO    ][5299] 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 architecture=amd64/generic power_parameters_power_user=admin
2019-04-07 05:17:47,049 [salt.state       :300 ][INFO    ][5299] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-07 05:17:47,049 [salt.state       :1951][INFO    ][5299] Completed state [maas.process_machines] at time 05:17:47.049635 duration_in_ms=7257.642
2019-04-07 05:17:47,064 [salt.minion      :1711][INFO    ][5299] Returning information for job: 20190407051734509650
2019-04-07 05:18:18,153 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command state.apply with jid 20190407051818138053
2019-04-07 05:18:18,174 [salt.minion      :1432][INFO    ][5639] Starting a new job with PID 5639
2019-04-07 05:18:21,941 [salt.state       :915 ][INFO    ][5639] Loading fresh modules for state activity
2019-04-07 05:18:21,993 [salt.fileclient  :1219][INFO    ][5639] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-07 05:18:22,035 [salt.state       :1780][INFO    ][5639] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:22.035426
2019-04-07 05:18:22,035 [salt.state       :1813][INFO    ][5639] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-07 05:18:22,037 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5639] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-07 05:18:23,383 [salt.state       :300 ][INFO    ][5639] {'pid': 5646, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-07 05:18:23,383 [salt.state       :1951][INFO    ][5639] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:23.383717 duration_in_ms=1348.292
2019-04-07 05:18:23,384 [salt.state       :1780][INFO    ][5639] Running state [maas.wait_for_machine_status] at time 05:18:23.384802
2019-04-07 05:18:23,385 [salt.state       :1813][INFO    ][5639] Executing state module.run for [maas.wait_for_machine_status]
2019-04-07 05:18:23,385 [salt.utils.decorators:613 ][WARNING ][5639] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:18:24,336 [salt.loaded.ext.module.maas:1023][INFO    ][5639] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.0534811s left)
2019-04-07 05:18:33,222 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407051833209906
2019-04-07 05:18:33,245 [salt.minion      :1432][INFO    ][5674] Starting a new job with PID 5674
2019-04-07 05:18:33,268 [salt.minion      :1711][INFO    ][5674] Returning information for job: 20190407051833209906
2019-04-07 05:18:55,313 [salt.loaded.ext.module.maas:1023][INFO    ][5639] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.07650399s left)
2019-04-07 05:19:03,272 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407051903259632
2019-04-07 05:19:03,295 [salt.minion      :1432][INFO    ][5695] Starting a new job with PID 5695
2019-04-07 05:19:03,319 [salt.minion      :1711][INFO    ][5695] Returning information for job: 20190407051903259632
2019-04-07 05:19:26,365 [salt.loaded.ext.module.maas:1023][INFO    ][5639] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.02412415s left)
2019-04-07 05:19:33,299 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407051933292957
2019-04-07 05:19:33,318 [salt.minion      :1432][INFO    ][5861] Starting a new job with PID 5861
2019-04-07 05:19:33,341 [salt.minion      :1711][INFO    ][5861] Returning information for job: 20190407051933292957
2019-04-07 05:19:57,578 [salt.loaded.ext.module.maas:1023][INFO    ][5639] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.81159115s left)
2019-04-07 05:20:03,345 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052003332380
2019-04-07 05:20:03,367 [salt.minion      :1432][INFO    ][5978] Starting a new job with PID 5978
2019-04-07 05:20:03,390 [salt.minion      :1711][INFO    ][5978] Returning information for job: 20190407052003332380
2019-04-07 05:20:29,035 [salt.loaded.ext.module.maas:1023][INFO    ][5639] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.35465813s left)
2019-04-07 05:20:33,414 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052033401642
2019-04-07 05:20:33,437 [salt.minion      :1432][INFO    ][6725] Starting a new job with PID 6725
2019-04-07 05:20:33,460 [salt.minion      :1711][INFO    ][6725] Returning information for job: 20190407052033401642
2019-04-07 05:21:00,828 [salt.loaded.ext.module.maas:1023][INFO    ][5639] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.5612042s left)
2019-04-07 05:21:03,469 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052103454532
2019-04-07 05:21:03,489 [salt.minion      :1432][INFO    ][6746] Starting a new job with PID 6746
2019-04-07 05:21:03,509 [salt.minion      :1711][INFO    ][6746] Returning information for job: 20190407052103454532
2019-04-07 05:21:33,536 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052133520514
2019-04-07 05:21:33,556 [salt.minion      :1432][INFO    ][6974] Starting a new job with PID 6974
2019-04-07 05:21:33,577 [salt.minion      :1711][INFO    ][6974] Returning information for job: 20190407052133520514
2019-04-07 05:21:34,238 [salt.state       :300 ][INFO    ][5639] {'ret': True}
2019-04-07 05:21:34,238 [salt.state       :1951][INFO    ][5639] Completed state [maas.wait_for_machine_status] at time 05:21:34.238614 duration_in_ms=190853.81
2019-04-07 05:21:34,242 [salt.minion      :1711][INFO    ][5639] Returning information for job: 20190407051818138053
2019-04-07 05:21:34,805 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command state.apply with jid 20190407052134795722
2019-04-07 05:21:34,824 [salt.minion      :1432][INFO    ][6982] Starting a new job with PID 6982
2019-04-07 05:21:38,657 [salt.state       :915 ][INFO    ][6982] Loading fresh modules for state activity
2019-04-07 05:21:38,710 [salt.fileclient  :1219][INFO    ][6982] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-07 05:21:38,797 [salt.state       :1780][INFO    ][6982] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:21:38.797749
2019-04-07 05:21:38,798 [salt.state       :1813][INFO    ][6982] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-07 05:21:38,799 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6982] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-07 05:21:40,227 [salt.state       :300 ][INFO    ][6982] {'pid': 6993, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-07 05:21:40,227 [salt.state       :1951][INFO    ][6982] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:21:40.227816 duration_in_ms=1430.067
2019-04-07 05:21:40,231 [salt.state       :1780][INFO    ][6982] Running state [maas_machines_storage_cmp002_lvm] at time 05:21:40.231022
2019-04-07 05:21:40,231 [salt.state       :1813][INFO    ][6982] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-07 05:21:41,544 [salt.loaded.ext.module.maasng:610 ][INFO    ][6982] w3366c
2019-04-07 05:21:41,544 [salt.loaded.ext.module.maasng:626 ][INFO    ][6982] sda
2019-04-07 05:21:42,263 [salt.loaded.ext.module.maasng:361 ][INFO    ][6982] w3366c
2019-04-07 05:21:42,394 [salt.loaded.ext.module.maasng:367 ][INFO    ][6982] [{u'size': 2397998940160, u'available_size': 0, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'70569ec3-9911-45f2-8e4b-3c3d63e8b249', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w3366c', u'device_id': 5, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'46d26387-44ab-40f9-b794-16d76c89d1ae', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/w3366c/blockdevices/5/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'uuid': None, u'name': u'sda', u'system_id': u'w3366c', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/w3366c/blockdevices/5/'}, {u'size': 2397988454400, u'available_size': 0, u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 2397988454400, u'partitions': [], u'uuid': u'd3ff0693-d6d1-48db-9de6-cd128f3fb7ba', u'name': u'vgroot-lvroot', u'system_id': u'w3366c', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'ee83a5f4-6cc3-483f-9b21-868701240757', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/w3366c/blockdevices/12/'}]
2019-04-07 05:21:42,395 [salt.loaded.ext.module.maasng:632 ][INFO    ][6982] vgroot
2019-04-07 05:21:42,395 [salt.loaded.ext.module.maasng:635 ][INFO    ][6982] lvroot
2019-04-07 05:21:42,395 [salt.loaded.ext.module.maasng:639 ][INFO    ][6982] 107374182400
2019-04-07 05:21:42,995 [salt.loaded.ext.module.maasng:645 ][INFO    ][6982] {u'hwe_kernel': u'', u'status_action': u'modules-final', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'a312ed58-fe7e-4293-b473-2458e4ce1f0d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w3366c', u'filesystem': {u'uuid': u'd89d62ac-93e2-48ea-b707-e68892cd2e23', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/w3366c/blockdevices/5/partition/8', u'type': u'partition', u'id': 8, u'device_id': 5}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'w3366c', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/w3366c/blockdevices/5/'}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'w3366c', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"'cloudinit' running modules for final", u'owner_data': {}, u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'a312ed58-fe7e-4293-b473-2458e4ce1f0d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w3366c', u'filesystem': {u'uuid': u'd89d62ac-93e2-48ea-b707-e68892cd2e23', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/w3366c/blockdevices/5/partition/8', u'type': u'partition', u'id': 8, u'device_id': 5}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'w3366c', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/w3366c/blockdevices/5/'}, {u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'49b59ad2-fa0f-46a2-9b07-48f1987cdb34', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'8b2a2cca-5a84-43dd-8d98-7c46113dcf68', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'w3366c', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/w3366c/blockdevices/13/'}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'on', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'a312ed58-fe7e-4293-b473-2458e4ce1f0d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w3366c', u'filesystem': {u'uuid': u'd89d62ac-93e2-48ea-b707-e68892cd2e23', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/w3366c/blockdevices/5/partition/8', u'type': u'partition', u'id': 8, u'device_id': 5}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'w3366c', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/w3366c/blockdevices/5/'}], u'ip_addresses': [u'192.168.11.42'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'w3366c', u'id': 8}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n8tner', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 43}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n8tner', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n8tner', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'w3366c', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/w3366c/interfaces/4/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 44}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'w3366c', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/w3366c/interfaces/21/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 45}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'w3366c', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/w3366c/interfaces/22/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 46}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'w3366c', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/w3366c/interfaces/23/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'8b2a2cca-5a84-43dd-8d98-7c46113dcf68', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'w3366c', u'partition_table_type': None, u'filesystem': {u'uuid': u'49b59ad2-fa0f-46a2-9b07-48f1987cdb34', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/w3366c/blockdevices/13/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n8tner', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 43}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n8tner', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n8tner', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'w3366c', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/w3366c/interfaces/4/'}, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/w3366c/', u'distro_series': u'', u'memory_test_status': -1}
2019-04-07 05:21:42,997 [salt.state       :300 ][INFO    ][6982] {'new': {'storage_layout': 'lvm'}}
2019-04-07 05:21:42,997 [salt.state       :1951][INFO    ][6982] Completed state [maas_machines_storage_cmp002_lvm] at time 05:21:42.997300 duration_in_ms=2766.278
2019-04-07 05:21:42,997 [salt.state       :1780][INFO    ][6982] Running state [maas_machines_storage_cmp001_lvm] at time 05:21:42.997699
2019-04-07 05:21:42,998 [salt.state       :1813][INFO    ][6982] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-07 05:21:44,095 [salt.loaded.ext.module.maasng:610 ][INFO    ][6982] tfpykq
2019-04-07 05:21:44,095 [salt.loaded.ext.module.maasng:626 ][INFO    ][6982] sda
2019-04-07 05:21:44,820 [salt.loaded.ext.module.maasng:361 ][INFO    ][6982] tfpykq
2019-04-07 05:21:44,946 [salt.loaded.ext.module.maasng:367 ][INFO    ][6982] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'2344a41d-714a-422c-ad81-19293c25ba56', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tfpykq', u'filesystem': {u'uuid': u'8b31f426-e53e-4ec1-a662-349ebddfa249', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/tfpykq/blockdevices/3/partition/5', u'type': u'partition', u'id': 5, u'device_id': 3}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'tfpykq', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/tfpykq/blockdevices/3/'}, {u'size': 2397988454400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'filesystem': {u'uuid': u'a6c69d00-196b-4a0e-93de-6657320f6c19', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'1b146c44-0c4d-45f9-b7fa-20157ca8ed5f', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'tfpykq', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/tfpykq/blockdevices/10/'}]
2019-04-07 05:21:44,946 [salt.loaded.ext.module.maasng:632 ][INFO    ][6982] vgroot
2019-04-07 05:21:44,947 [salt.loaded.ext.module.maasng:635 ][INFO    ][6982] lvroot
2019-04-07 05:21:44,947 [salt.loaded.ext.module.maasng:639 ][INFO    ][6982] 107374182400
2019-04-07 05:21:45,642 [salt.loaded.ext.module.maasng:645 ][INFO    ][6982] {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'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, u'owner_data': {}, u'hwe_kernel': u'', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n8tner', 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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n8tner', 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': 30}], 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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n8tner', 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'tfpykq', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/tfpykq/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', 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'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/tfpykq/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'tfpykq', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'id_path': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'6b3fb903-39c8-49f5-952d-c9526f48b0b5', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'tfpykq', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'970dde65-6246-4271-8f6d-921810eef4ce', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id': 14, u'available_size': 0, u'serial': None, u'partitions': [], u'used_size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/tfpykq/blockdevices/14/'}], u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'2f03b0aa-cf8f-42b0-a0e1-fc5c690cecf4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tfpykq', u'device_id': 3, u'filesystem': {u'mount_options': None, u'uuid': u'0324a0c9-833d-4781-abfb-f929d7e5c19f', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/tfpykq/blockdevices/3/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'tfpykq', 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'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/tfpykq/blockdevices/3/', u'type': u'physical', u'id': 3, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'6b3fb903-39c8-49f5-952d-c9526f48b0b5', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'tfpykq', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'970dde65-6246-4271-8f6d-921810eef4ce', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/tfpykq/blockdevices/14/', u'type': u'virtual', u'id': 14, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'tfpykq', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 3, u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'2f03b0aa-cf8f-42b0-a0e1-fc5c690cecf4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tfpykq', u'device_id': 3, u'filesystem': {u'mount_options': None, u'uuid': u'0324a0c9-833d-4781-abfb-f929d7e5c19f', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/tfpykq/blockdevices/3/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/tfpykq/blockdevices/3/'}], u'ip_addresses': [u'192.168.11.38'], u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'tfpykq', u'id': 9}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'tfpykq', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 3, u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'2f03b0aa-cf8f-42b0-a0e1-fc5c690cecf4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tfpykq', u'device_id': 3, u'filesystem': {u'mount_options': None, u'uuid': u'0324a0c9-833d-4781-abfb-f929d7e5c19f', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/tfpykq/blockdevices/3/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/tfpykq/blockdevices/3/'}, u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n8tner', 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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n8tner', 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': 30}], 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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n8tner', 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'tfpykq', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/tfpykq/interfaces/5/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'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'name': u'enp7s0', 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:5b', u'parents': [], u'system_id': u'tfpykq', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/tfpykq/interfaces/12/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'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'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 34}], 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'tfpykq', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/tfpykq/interfaces/13/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'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'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 36}], 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'tfpykq', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/tfpykq/interfaces/14/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-04-07 05:21:45,645 [salt.state       :300 ][INFO    ][6982] {'new': {'storage_layout': 'lvm'}}
2019-04-07 05:21:45,645 [salt.state       :1951][INFO    ][6982] Completed state [maas_machines_storage_cmp001_lvm] at time 05:21:45.645514 duration_in_ms=2647.813
2019-04-07 05:21:45,649 [salt.minion      :1711][INFO    ][6982] Returning information for job: 20190407052134795722
2019-04-07 05:21:46,276 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command state.apply with jid 20190407052146263342
2019-04-07 05:21:46,299 [salt.minion      :1432][INFO    ][7055] Starting a new job with PID 7055
2019-04-07 05:21:47,096 [salt.state       :915 ][INFO    ][7055] Loading fresh modules for state activity
2019-04-07 05:21:47,149 [salt.fileclient  :1219][INFO    ][7055] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-07 05:21:47,191 [salt.state       :1780][INFO    ][7055] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:21:47.191882
2019-04-07 05:21:47,192 [salt.state       :1813][INFO    ][7055] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-07 05:21:47,194 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7055] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-07 05:21:48,626 [salt.state       :300 ][INFO    ][7055] {'pid': 7064, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-07 05:21:48,627 [salt.state       :1951][INFO    ][7055] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:21:48.627707 duration_in_ms=1435.824
2019-04-07 05:21:48,630 [salt.state       :1780][INFO    ][7055] Running state [maas.deploy_machines] at time 05:21:48.630310
2019-04-07 05:21:48,630 [salt.state       :1813][INFO    ][7055] Executing state module.run for [maas.deploy_machines]
2019-04-07 05:21:48,631 [salt.utils.decorators:613 ][WARNING ][7055] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:21:49,265 [salt.loaded.ext.module.maas:684 ][INFO    ][7055] deploymachines hwe_kernel=hwe-16.04 system_id=w3366c distro_series=xenial
2019-04-07 05:21:51,996 [salt.loaded.ext.module.maas:684 ][INFO    ][7055] deploymachines hwe_kernel=hwe-16.04 system_id=tfpykq distro_series=xenial
2019-04-07 05:21:54,734 [salt.loaded.ext.module.maas:684 ][INFO    ][7055] deploymachines hwe_kernel=hwe-16.04 system_id=nggphk distro_series=xenial
2019-04-07 05:21:57,409 [salt.loaded.ext.module.maas:684 ][INFO    ][7055] deploymachines hwe_kernel=hwe-16.04 system_id=7ymkgb distro_series=xenial
2019-04-07 05:21:59,866 [salt.loaded.ext.module.maas:684 ][INFO    ][7055] deploymachines hwe_kernel=hwe-16.04 system_id=qpwefc distro_series=xenial
2019-04-07 05:22:01,369 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052201356650
2019-04-07 05:22:01,392 [salt.minion      :1432][INFO    ][7322] Starting a new job with PID 7322
2019-04-07 05:22:01,415 [salt.minion      :1711][INFO    ][7322] Returning information for job: 20190407052201356650
2019-04-07 05:22:02,544 [salt.state       :300 ][INFO    ][7055] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-07 05:22:02,544 [salt.state       :1951][INFO    ][7055] Completed state [maas.deploy_machines] at time 05:22:02.544748 duration_in_ms=13914.436
2019-04-07 05:22:02,576 [salt.minion      :1711][INFO    ][7055] Returning information for job: 20190407052146263342
2019-04-07 05:22:03,217 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command state.apply with jid 20190407052203207654
2019-04-07 05:22:03,239 [salt.minion      :1432][INFO    ][7338] Starting a new job with PID 7338
2019-04-07 05:22:07,007 [salt.state       :915 ][INFO    ][7338] Loading fresh modules for state activity
2019-04-07 05:22:07,057 [salt.fileclient  :1219][INFO    ][7338] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-07 05:22:07,101 [salt.state       :1780][INFO    ][7338] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:07.101604
2019-04-07 05:22:07,102 [salt.state       :1813][INFO    ][7338] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-07 05:22:07,104 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7338] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-07 05:22:08,563 [salt.state       :300 ][INFO    ][7338] {'pid': 7353, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-07 05:22:08,564 [salt.state       :1951][INFO    ][7338] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:08.564239 duration_in_ms=1462.634
2019-04-07 05:22:08,567 [salt.state       :1780][INFO    ][7338] Running state [maas.wait_for_machine_status] at time 05:22:08.567409
2019-04-07 05:22:08,567 [salt.state       :1813][INFO    ][7338] Executing state module.run for [maas.wait_for_machine_status]
2019-04-07 05:22:08,568 [salt.utils.decorators:613 ][WARNING ][7338] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:22:12,037 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.54156113s left)
2019-04-07 05:22:18,297 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052218282062
2019-04-07 05:22:18,320 [salt.minion      :1432][INFO    ][7396] Starting a new job with PID 7396
2019-04-07 05:22:18,343 [salt.minion      :1711][INFO    ][7396] Returning information for job: 20190407052218282062
2019-04-07 05:22:45,752 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.82716012s left)
2019-04-07 05:22:48,351 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052248338857
2019-04-07 05:22:48,374 [salt.minion      :1432][INFO    ][7433] Starting a new job with PID 7433
2019-04-07 05:22:48,398 [salt.minion      :1711][INFO    ][7433] Returning information for job: 20190407052248338857
2019-04-07 05:23:18,397 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052318384697
2019-04-07 05:23:18,418 [salt.minion      :1432][INFO    ][7474] Starting a new job with PID 7474
2019-04-07 05:23:18,440 [salt.minion      :1711][INFO    ][7474] Returning information for job: 20190407052318384697
2019-04-07 05:23:19,069 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.51005816s left)
2019-04-07 05:23:48,431 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052348418724
2019-04-07 05:23:48,449 [salt.minion      :1432][INFO    ][7602] Starting a new job with PID 7602
2019-04-07 05:23:48,472 [salt.minion      :1711][INFO    ][7602] Returning information for job: 20190407052348418724
2019-04-07 05:23:52,600 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2145.97868609s left)
2019-04-07 05:24:18,475 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052418468981
2019-04-07 05:24:18,491 [salt.minion      :1432][INFO    ][7923] Starting a new job with PID 7923
2019-04-07 05:24:18,504 [salt.minion      :1711][INFO    ][7923] Returning information for job: 20190407052418468981
2019-04-07 05:24:26,027 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.55143404s left)
2019-04-07 05:24:48,518 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052448501757
2019-04-07 05:24:48,534 [salt.minion      :1432][INFO    ][8271] Starting a new job with PID 8271
2019-04-07 05:24:48,557 [salt.minion      :1711][INFO    ][8271] Returning information for job: 20190407052448501757
2019-04-07 05:24:59,221 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.35751915s left)
2019-04-07 05:25:18,563 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052518555264
2019-04-07 05:25:18,578 [salt.minion      :1432][INFO    ][8461] Starting a new job with PID 8461
2019-04-07 05:25:18,593 [salt.minion      :1711][INFO    ][8461] Returning information for job: 20190407052518555264
2019-04-07 05:25:32,832 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2045.74717402s left)
2019-04-07 05:25:48,609 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052548596450
2019-04-07 05:25:48,632 [salt.minion      :1432][INFO    ][8742] Starting a new job with PID 8742
2019-04-07 05:25:48,655 [salt.minion      :1711][INFO    ][8742] Returning information for job: 20190407052548596450
2019-04-07 05:26:06,252 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.32683897s left)
2019-04-07 05:26:18,673 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052618664198
2019-04-07 05:26:18,687 [salt.minion      :1432][INFO    ][8954] Starting a new job with PID 8954
2019-04-07 05:26:18,700 [salt.minion      :1711][INFO    ][8954] Returning information for job: 20190407052618664198
2019-04-07 05:26:39,671 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1978.90810919s left)
2019-04-07 05:26:48,722 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052648707453
2019-04-07 05:26:48,744 [salt.minion      :1432][INFO    ][9164] Starting a new job with PID 9164
2019-04-07 05:26:48,766 [salt.minion      :1711][INFO    ][9164] Returning information for job: 20190407052648707453
2019-04-07 05:27:13,182 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1945.39691901s left)
2019-04-07 05:27:18,786 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052718779166
2019-04-07 05:27:18,802 [salt.minion      :1432][INFO    ][9392] Starting a new job with PID 9392
2019-04-07 05:27:18,821 [salt.minion      :1711][INFO    ][9392] Returning information for job: 20190407052718779166
2019-04-07 05:27:46,665 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1911.91393304s left)
2019-04-07 05:27:48,853 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052748840265
2019-04-07 05:27:48,876 [salt.minion      :1432][INFO    ][9654] Starting a new job with PID 9654
2019-04-07 05:27:48,899 [salt.minion      :1711][INFO    ][9654] Returning information for job: 20190407052748840265
2019-04-07 05:28:18,932 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052818919323
2019-04-07 05:28:18,952 [salt.minion      :1432][INFO    ][9828] Starting a new job with PID 9828
2019-04-07 05:28:18,975 [salt.minion      :1711][INFO    ][9828] Returning information for job: 20190407052818919323
2019-04-07 05:28:19,683 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1878.89608097s left)
2019-04-07 05:28:49,013 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052849000853
2019-04-07 05:28:49,036 [salt.minion      :1432][INFO    ][9873] Starting a new job with PID 9873
2019-04-07 05:28:49,059 [salt.minion      :1711][INFO    ][9873] Returning information for job: 20190407052849000853
2019-04-07 05:28:53,246 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.3329072s left)
2019-04-07 05:29:19,112 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052919100068
2019-04-07 05:29:19,135 [salt.minion      :1432][INFO    ][10055] Starting a new job with PID 10055
2019-04-07 05:29:19,160 [salt.minion      :1711][INFO    ][10055] Returning information for job: 20190407052919100068
2019-04-07 05:29:26,682 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:2250s (1811.89709306s left)
2019-04-07 05:29:49,164 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407052949151542
2019-04-07 05:29:49,187 [salt.minion      :1432][INFO    ][10196] Starting a new job with PID 10196
2019-04-07 05:29:49,211 [salt.minion      :1711][INFO    ][10196] Returning information for job: 20190407052949151542
2019-04-07 05:30:00,330 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1778.24869418s left)
2019-04-07 05:30:19,262 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053019254987
2019-04-07 05:30:19,279 [salt.minion      :1432][INFO    ][10476] Starting a new job with PID 10476
2019-04-07 05:30:19,292 [salt.minion      :1711][INFO    ][10476] Returning information for job: 20190407053019254987
2019-04-07 05:30:33,822 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1744.75714707s left)
2019-04-07 05:30:49,358 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053049345783
2019-04-07 05:30:49,380 [salt.minion      :1432][INFO    ][10637] Starting a new job with PID 10637
2019-04-07 05:30:49,404 [salt.minion      :1711][INFO    ][10637] Returning information for job: 20190407053049345783
2019-04-07 05:31:07,314 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1711.26446009s left)
2019-04-07 05:31:19,478 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053119465667
2019-04-07 05:31:19,501 [salt.minion      :1432][INFO    ][10678] Starting a new job with PID 10678
2019-04-07 05:31:19,525 [salt.minion      :1711][INFO    ][10678] Returning information for job: 20190407053119465667
2019-04-07 05:31:40,808 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1677.77045703s left)
2019-04-07 05:31:49,613 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053149599323
2019-04-07 05:31:49,635 [salt.minion      :1432][INFO    ][10714] Starting a new job with PID 10714
2019-04-07 05:31:49,659 [salt.minion      :1711][INFO    ][10714] Returning information for job: 20190407053149599323
2019-04-07 05:32:14,199 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1644.37931609s left)
2019-04-07 05:32:19,747 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053219734985
2019-04-07 05:32:19,769 [salt.minion      :1432][INFO    ][10755] Starting a new job with PID 10755
2019-04-07 05:32:19,795 [salt.minion      :1711][INFO    ][10755] Returning information for job: 20190407053219734985
2019-04-07 05:32:47,751 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1610.82765317s left)
2019-04-07 05:32:49,896 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053249881109
2019-04-07 05:32:49,919 [salt.minion      :1432][INFO    ][10790] Starting a new job with PID 10790
2019-04-07 05:32:49,944 [salt.minion      :1711][INFO    ][10790] Returning information for job: 20190407053249881109
2019-04-07 05:33:20,056 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053320039655
2019-04-07 05:33:20,075 [salt.minion      :1432][INFO    ][10830] Starting a new job with PID 10830
2019-04-07 05:33:20,098 [salt.minion      :1711][INFO    ][10830] Returning information for job: 20190407053320039655
2019-04-07 05:33:21,008 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1577.570575s left)
2019-04-07 05:33:50,213 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053350197649
2019-04-07 05:33:50,236 [salt.minion      :1432][INFO    ][10872] Starting a new job with PID 10872
2019-04-07 05:33:50,260 [salt.minion      :1711][INFO    ][10872] Returning information for job: 20190407053350197649
2019-04-07 05:33:54,577 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1544.00199103s left)
2019-04-07 05:34:20,386 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053420372775
2019-04-07 05:34:20,408 [salt.minion      :1432][INFO    ][10916] Starting a new job with PID 10916
2019-04-07 05:34:20,431 [salt.minion      :1711][INFO    ][10916] Returning information for job: 20190407053420372775
2019-04-07 05:34:27,040 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1511.53830314s left)
2019-04-07 05:34:50,575 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053450561856
2019-04-07 05:34:50,598 [salt.minion      :1432][INFO    ][10951] Starting a new job with PID 10951
2019-04-07 05:34:50,621 [salt.minion      :1711][INFO    ][10951] Returning information for job: 20190407053450561856
2019-04-07 05:35:00,578 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1478.00105s left)
2019-04-07 05:35:20,767 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053520754813
2019-04-07 05:35:20,789 [salt.minion      :1432][INFO    ][11130] Starting a new job with PID 11130
2019-04-07 05:35:20,815 [salt.minion      :1711][INFO    ][11130] Returning information for job: 20190407053520754813
2019-04-07 05:35:33,718 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1444.86083817s left)
2019-04-07 05:35:50,972 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053550958671
2019-04-07 05:35:50,994 [salt.minion      :1432][INFO    ][11174] Starting a new job with PID 11174
2019-04-07 05:35:51,017 [salt.minion      :1711][INFO    ][11174] Returning information for job: 20190407053550958671
2019-04-07 05:36:07,361 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1411.21766806s left)
2019-04-07 05:36:21,184 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053621171071
2019-04-07 05:36:21,207 [salt.minion      :1432][INFO    ][11215] Starting a new job with PID 11215
2019-04-07 05:36:21,232 [salt.minion      :1711][INFO    ][11215] Returning information for job: 20190407053621171071
2019-04-07 05:36:40,858 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1377.72053504s left)
2019-04-07 05:36:51,411 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053651398313
2019-04-07 05:36:51,434 [salt.minion      :1432][INFO    ][11253] Starting a new job with PID 11253
2019-04-07 05:36:51,458 [salt.minion      :1711][INFO    ][11253] Returning information for job: 20190407053651398313
2019-04-07 05:37:14,356 [salt.loaded.ext.module.maas:1023][INFO    ][7338] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1344.22239017s left)
2019-04-07 05:37:21,435 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053721422954
2019-04-07 05:37:21,458 [salt.minion      :1432][INFO    ][11295] Starting a new job with PID 11295
2019-04-07 05:37:21,482 [salt.minion      :1711][INFO    ][11295] Returning information for job: 20190407053721422954
2019-04-07 05:37:46,431 [salt.loaded.ext.module.maas:993 ][INFO    ][7338] Machine nggphk mark broken
2019-04-07 05:37:47,116 [salt.loaded.ext.module.maas:996 ][INFO    ][7338] Machine nggphk mark fixed
2019-04-07 05:37:47,998 [salt.loaded.ext.module.maas:684 ][INFO    ][7338] deploymachines hwe_kernel=hwe-16.04 system_id=nggphk distro_series=xenial
2019-04-07 05:37:50,808 [salt.loaded.ext.module.maas:160 ][ERROR   ][7338] Failed for object kvm01 reason Unable to change power state to 'cycle' for node kvm01: another action is already in progress for that node.
2019-04-07 05:37:50,810 [salt.state       :302 ][ERROR   ][7338] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm03', 'kvm02'], 'errors': {'kvm01': "Unable to change power state to 'cycle' for node kvm01: another action is already in progress for that node."}, 'success': []}
2019-04-07 05:37:50,810 [salt.state       :1951][INFO    ][7338] Completed state [maas.wait_for_machine_status] at time 05:37:50.810802 duration_in_ms=942243.388
2019-04-07 05:37:50,818 [salt.minion      :1711][INFO    ][7338] Returning information for job: 20190407052203207654
2019-04-07 05:38:01,586 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command pillar.get with jid 20190407053801578358
2019-04-07 05:38:01,608 [salt.minion      :1432][INFO    ][11408] Starting a new job with PID 11408
2019-04-07 05:38:01,617 [salt.minion      :1711][INFO    ][11408] Returning information for job: 20190407053801578358
2019-04-07 05:38:02,129 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command service.status with jid 20190407053802115810
2019-04-07 05:38:02,150 [salt.minion      :1432][INFO    ][11413] Starting a new job with PID 11413
2019-04-07 05:38:02,566 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][11413] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-07 05:38:02,601 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][11413] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-07 05:38:02,617 [salt.minion      :1711][INFO    ][11413] Returning information for job: 20190407053802115810
2019-04-07 05:38:03,120 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command state.apply with jid 20190407053803107177
2019-04-07 05:38:03,141 [salt.minion      :1432][INFO    ][11424] Starting a new job with PID 11424
2019-04-07 05:38:06,604 [salt.state       :915 ][INFO    ][11424] Loading fresh modules for state activity
2019-04-07 05:38:07,018 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11424] Executing command 'salt-minion --version' in directory '/root'
2019-04-07 05:38:07,347 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11424] Executing command 'salt-minion --version' in directory '/root'
2019-04-07 05:38:08,175 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11424] Executing command 'salt-minion --version' in directory '/root'
2019-04-07 05:38:08,529 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11424] Executing command 'salt-minion --version' in directory '/root'
2019-04-07 05:38:09,863 [salt.state       :1780][INFO    ][11424] Running state [salt-minion] at time 05:38:09.863861
2019-04-07 05:38:09,864 [salt.state       :1813][INFO    ][11424] Executing state pkg.installed for [salt-minion]
2019-04-07 05:38:09,864 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11424] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-07 05:38:09,952 [salt.state       :300 ][INFO    ][11424] All specified packages are already installed
2019-04-07 05:38:09,952 [salt.state       :1951][INFO    ][11424] Completed state [salt-minion] at time 05:38:09.952816 duration_in_ms=88.956
2019-04-07 05:38:09,953 [salt.state       :1780][INFO    ][11424] Running state [salt_minion_dependency_packages] at time 05:38:09.953141
2019-04-07 05:38:09,953 [salt.state       :1813][INFO    ][11424] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-07 05:38:09,960 [salt.state       :300 ][INFO    ][11424] All specified packages are already installed
2019-04-07 05:38:09,960 [salt.state       :1951][INFO    ][11424] Completed state [salt_minion_dependency_packages] at time 05:38:09.960223 duration_in_ms=7.082
2019-04-07 05:38:09,963 [salt.state       :1780][INFO    ][11424] Running state [/etc/salt/minion.d/minion.conf] at time 05:38:09.963332
2019-04-07 05:38:09,963 [salt.state       :1813][INFO    ][11424] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-07 05:38:10,168 [salt.state       :300 ][INFO    ][11424] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-07 05:38:10,168 [salt.state       :1951][INFO    ][11424] Completed state [/etc/salt/minion.d/minion.conf] at time 05:38:10.168314 duration_in_ms=204.981
2019-04-07 05:38:10,168 [salt.state       :1780][INFO    ][11424] Running state [python-netaddr] at time 05:38:10.168637
2019-04-07 05:38:10,168 [salt.state       :1813][INFO    ][11424] Executing state pkg.installed for [python-netaddr]
2019-04-07 05:38:10,177 [salt.state       :300 ][INFO    ][11424] All specified packages are already installed
2019-04-07 05:38:10,177 [salt.state       :1951][INFO    ][11424] Completed state [python-netaddr] at time 05:38:10.177755 duration_in_ms=9.118
2019-04-07 05:38:10,181 [salt.state       :1780][INFO    ][11424] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:38:10.181518
2019-04-07 05:38:10,181 [salt.state       :1813][INFO    ][11424] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-07 05:38:10,193 [salt.state       :300 ][INFO    ][11424] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-07 05:38:10,193 [salt.state       :1951][INFO    ][11424] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:38:10.193735 duration_in_ms=12.216
2019-04-07 05:38:10,194 [salt.state       :1780][INFO    ][11424] Running state [salt-minion] at time 05:38:10.194928
2019-04-07 05:38:10,195 [salt.state       :1813][INFO    ][11424] Executing state service.running for [salt-minion]
2019-04-07 05:38:10,195 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11424] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-07 05:38:10,234 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11424] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-07 05:38:10,252 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11424] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-07 05:38:10,269 [salt.state       :300 ][INFO    ][11424] The service salt-minion is already running
2019-04-07 05:38:10,270 [salt.state       :1951][INFO    ][11424] Completed state [salt-minion] at time 05:38:10.270376 duration_in_ms=75.448
2019-04-07 05:38:10,272 [salt.state       :1780][INFO    ][11424] Running state [/etc/salt/grains.d] at time 05:38:10.272553
2019-04-07 05:38:10,273 [salt.state       :1813][INFO    ][11424] Executing state file.directory for [/etc/salt/grains.d]
2019-04-07 05:38:10,274 [salt.state       :300 ][INFO    ][11424] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-07 05:38:10,274 [salt.state       :1951][INFO    ][11424] Completed state [/etc/salt/grains.d] at time 05:38:10.274600 duration_in_ms=2.046
2019-04-07 05:38:10,275 [salt.state       :1780][INFO    ][11424] Running state [/etc/salt/grains] at time 05:38:10.275546
2019-04-07 05:38:10,276 [salt.state       :1813][INFO    ][11424] Executing state file.managed for [/etc/salt/grains]
2019-04-07 05:38:10,276 [salt.state       :300 ][INFO    ][11424] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-07 05:38:10,277 [salt.state       :1951][INFO    ][11424] Completed state [/etc/salt/grains] at time 05:38:10.277002 duration_in_ms=1.456
2019-04-07 05:38:10,277 [salt.state       :1780][INFO    ][11424] Running state [/etc/salt/grains.d/placeholder] at time 05:38:10.277657
2019-04-07 05:38:10,278 [salt.state       :1813][INFO    ][11424] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-07 05:38:10,278 [salt.state       :300 ][INFO    ][11424] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-07 05:38:10,279 [salt.state       :1951][INFO    ][11424] Completed state [/etc/salt/grains.d/placeholder] at time 05:38:10.279057 duration_in_ms=1.401
2019-04-07 05:38:10,279 [salt.state       :1780][INFO    ][11424] Running state [/etc/salt/grains.d/sphinx] at time 05:38:10.279727
2019-04-07 05:38:10,280 [salt.state       :1813][INFO    ][11424] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-07 05:38:10,293 [salt.state       :300 ][INFO    ][11424] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-07 05:38:10,294 [salt.state       :1951][INFO    ][11424] Completed state [/etc/salt/grains.d/sphinx] at time 05:38:10.293934 duration_in_ms=14.207
2019-04-07 05:38:10,297 [salt.state       :1780][INFO    ][11424] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:38:10.297355
2019-04-07 05:38:10,297 [salt.state       :1813][INFO    ][11424] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-07 05:38:10,298 [salt.state       :300 ][INFO    ][11424] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-07 05:38:10,298 [salt.state       :1951][INFO    ][11424] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:38:10.298739 duration_in_ms=1.384
2019-04-07 05:38:10,299 [salt.state       :1780][INFO    ][11424] Running state [/etc/salt/grains.d/dns_records] at time 05:38:10.299438
2019-04-07 05:38:10,299 [salt.state       :1813][INFO    ][11424] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-07 05:38:10,311 [salt.state       :300 ][INFO    ][11424] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-07 05:38:10,311 [salt.state       :1951][INFO    ][11424] Completed state [/etc/salt/grains.d/dns_records] at time 05:38:10.311760 duration_in_ms=12.322
2019-04-07 05:38:10,313 [salt.state       :1780][INFO    ][11424] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:38:10.313129
2019-04-07 05:38:10,313 [salt.state       :1813][INFO    ][11424] 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-07 05:38:10,314 [salt.state       :300 ][INFO    ][11424] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-07 05:38:10,314 [salt.state       :1951][INFO    ][11424] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:38:10.314406 duration_in_ms=1.277
2019-04-07 05:38:10,315 [salt.state       :1780][INFO    ][11424] Running state [/etc/salt/grains.d/salt] at time 05:38:10.315135
2019-04-07 05:38:10,315 [salt.state       :1813][INFO    ][11424] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-07 05:38:10,329 [salt.state       :300 ][INFO    ][11424] File /etc/salt/grains.d/salt is in the correct state
2019-04-07 05:38:10,329 [salt.state       :1951][INFO    ][11424] Completed state [/etc/salt/grains.d/salt] at time 05:38:10.329671 duration_in_ms=14.535
2019-04-07 05:38:10,330 [salt.state       :1780][INFO    ][11424] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:38:10.330915
2019-04-07 05:38:10,331 [salt.state       :1813][INFO    ][11424] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-07 05:38:10,331 [salt.state       :300 ][INFO    ][11424] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-07 05:38:10,332 [salt.state       :1951][INFO    ][11424] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:38:10.332099 duration_in_ms=1.185
2019-04-07 05:38:10,334 [salt.state       :1780][INFO    ][11424] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:38:10.334771
2019-04-07 05:38:10,335 [salt.state       :1813][INFO    ][11424] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-07 05:38:10,335 [salt.state       :300 ][INFO    ][11424] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-07 05:38:10,336 [salt.state       :1951][INFO    ][11424] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:38:10.335964 duration_in_ms=1.193
2019-04-07 05:38:10,336 [salt.state       :1780][INFO    ][11424] Running state [mine.update] at time 05:38:10.336915
2019-04-07 05:38:10,337 [salt.state       :1813][INFO    ][11424] Executing state module.wait for [mine.update]
2019-04-07 05:38:10,337 [salt.state       :300 ][INFO    ][11424] No changes made for mine.update
2019-04-07 05:38:10,338 [salt.state       :1951][INFO    ][11424] Completed state [mine.update] at time 05:38:10.338018 duration_in_ms=1.102
2019-04-07 05:38:10,338 [salt.state       :1780][INFO    ][11424] Running state [ca-certificates] at time 05:38:10.338351
2019-04-07 05:38:10,338 [salt.state       :1813][INFO    ][11424] Executing state pkg.installed for [ca-certificates]
2019-04-07 05:38:10,349 [salt.state       :300 ][INFO    ][11424] All specified packages are already installed
2019-04-07 05:38:10,349 [salt.state       :1951][INFO    ][11424] Completed state [ca-certificates] at time 05:38:10.349263 duration_in_ms=10.912
2019-04-07 05:38:10,350 [salt.state       :1780][INFO    ][11424] Running state [update-ca-certificates] at time 05:38:10.350210
2019-04-07 05:38:10,350 [salt.state       :1813][INFO    ][11424] Executing state cmd.wait for [update-ca-certificates]
2019-04-07 05:38:10,351 [salt.state       :300 ][INFO    ][11424] No changes made for update-ca-certificates
2019-04-07 05:38:10,351 [salt.state       :1951][INFO    ][11424] Completed state [update-ca-certificates] at time 05:38:10.351292 duration_in_ms=1.082
2019-04-07 05:38:10,351 [salt.state       :1780][INFO    ][11424] Running state [iptables] at time 05:38:10.351652
2019-04-07 05:38:10,352 [salt.state       :1813][INFO    ][11424] Executing state pkg.installed for [iptables]
2019-04-07 05:38:10,361 [salt.state       :300 ][INFO    ][11424] All specified packages are already installed
2019-04-07 05:38:10,361 [salt.state       :1951][INFO    ][11424] Completed state [iptables] at time 05:38:10.361465 duration_in_ms=9.814
2019-04-07 05:38:10,361 [salt.state       :1780][INFO    ][11424] Running state [iptables-persistent] at time 05:38:10.361810
2019-04-07 05:38:10,362 [salt.state       :1813][INFO    ][11424] Executing state pkg.installed for [iptables-persistent]
2019-04-07 05:38:10,370 [salt.state       :300 ][INFO    ][11424] All specified packages are already installed
2019-04-07 05:38:10,371 [salt.state       :1951][INFO    ][11424] Completed state [iptables-persistent] at time 05:38:10.371194 duration_in_ms=9.383
2019-04-07 05:38:10,372 [salt.state       :1780][INFO    ][11424] Running state [iptables_modules_v4_load] at time 05:38:10.372446
2019-04-07 05:38:10,372 [salt.state       :1813][INFO    ][11424] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-07 05:38:10,373 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11424] Executing command 'lsmod' in directory '/root'
2019-04-07 05:38:10,396 [salt.state       :300 ][INFO    ][11424] Kernel modules iptable_filter, ip_tables are already present
2019-04-07 05:38:10,396 [salt.state       :1951][INFO    ][11424] Completed state [iptables_modules_v4_load] at time 05:38:10.396859 duration_in_ms=24.414
2019-04-07 05:38:10,397 [salt.state       :1780][INFO    ][11424] Running state [/etc/iptables/rules.v4] at time 05:38:10.397670
2019-04-07 05:38:10,398 [salt.state       :1813][INFO    ][11424] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-07 05:38:10,503 [salt.state       :300 ][INFO    ][11424] File /etc/iptables/rules.v4 is in the correct state
2019-04-07 05:38:10,503 [salt.state       :1951][INFO    ][11424] Completed state [/etc/iptables/rules.v4] at time 05:38:10.503422 duration_in_ms=105.752
2019-04-07 05:38:10,504 [salt.state       :1780][INFO    ][11424] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:38:10.504512
2019-04-07 05:38:10,504 [salt.state       :1813][INFO    ][11424] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-07 05:38:10,505 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11424] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-07 05:38:10,524 [salt.state       :300 ][INFO    ][11424] onlyif execution failed
2019-04-07 05:38:10,525 [salt.state       :1951][INFO    ][11424] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:38:10.525139 duration_in_ms=20.627
2019-04-07 05:38:10,526 [salt.state       :1780][INFO    ][11424] Running state [netfilter-persistent] at time 05:38:10.526115
2019-04-07 05:38:10,526 [salt.state       :1813][INFO    ][11424] Executing state service.running for [netfilter-persistent]
2019-04-07 05:38:10,527 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11424] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-07 05:38:10,546 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11424] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-07 05:38:10,563 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11424] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-07 05:38:10,580 [salt.state       :300 ][INFO    ][11424] The service netfilter-persistent is already running
2019-04-07 05:38:10,580 [salt.state       :1951][INFO    ][11424] Completed state [netfilter-persistent] at time 05:38:10.580669 duration_in_ms=54.554
2019-04-07 05:38:10,581 [salt.state       :1780][INFO    ][11424] Running state [iptables_extra.remove_stale_tables] at time 05:38:10.581655
2019-04-07 05:38:10,582 [salt.state       :1813][INFO    ][11424] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-07 05:38:10,582 [salt.state       :300 ][INFO    ][11424] No changes made for iptables_extra.remove_stale_tables
2019-04-07 05:38:10,582 [salt.state       :1951][INFO    ][11424] Completed state [iptables_extra.remove_stale_tables] at time 05:38:10.582748 duration_in_ms=1.093
2019-04-07 05:38:10,583 [salt.state       :1780][INFO    ][11424] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:38:10.583052
2019-04-07 05:38:10,583 [salt.state       :1813][INFO    ][11424] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-07 05:38:10,584 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11424] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-07 05:38:10,599 [salt.state       :300 ][INFO    ][11424] onlyif execution failed
2019-04-07 05:38:10,599 [salt.state       :1951][INFO    ][11424] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:38:10.599339 duration_in_ms=16.286
2019-04-07 05:38:10,600 [salt.state       :1780][INFO    ][11424] Running state [/etc/iptables/rules.v6] at time 05:38:10.600478
2019-04-07 05:38:10,600 [salt.state       :1813][INFO    ][11424] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-07 05:38:10,601 [salt.state       :300 ][INFO    ][11424] File /etc/iptables/rules.v6 is not present
2019-04-07 05:38:10,601 [salt.state       :1951][INFO    ][11424] Completed state [/etc/iptables/rules.v6] at time 05:38:10.601719 duration_in_ms=1.242
2019-04-07 05:38:10,602 [salt.state       :1780][INFO    ][11424] Running state [iptables_extra.flush_all] at time 05:38:10.602529
2019-04-07 05:38:10,602 [salt.state       :1813][INFO    ][11424] Executing state module.wait for [iptables_extra.flush_all]
2019-04-07 05:38:10,603 [salt.state       :300 ][INFO    ][11424] No changes made for iptables_extra.flush_all
2019-04-07 05:38:10,603 [salt.state       :1951][INFO    ][11424] Completed state [iptables_extra.flush_all] at time 05:38:10.603502 duration_in_ms=0.974
2019-04-07 05:38:10,607 [salt.minion      :1711][INFO    ][11424] Returning information for job: 20190407053803107177
2019-04-07 05:38:11,239 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command state.apply with jid 20190407053811225875
2019-04-07 05:38:11,262 [salt.minion      :1432][INFO    ][11510] Starting a new job with PID 11510
2019-04-07 05:38:12,041 [salt.state       :915 ][INFO    ][11510] Loading fresh modules for state activity
2019-04-07 05:38:12,674 [salt.state       :1780][INFO    ][11510] Running state [maas-rack-controller] at time 05:38:12.674738
2019-04-07 05:38:12,675 [salt.state       :1813][INFO    ][11510] Executing state pkg.installed for [maas-rack-controller]
2019-04-07 05:38:12,675 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11510] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-07 05:38:12,800 [salt.state       :300 ][INFO    ][11510] All specified packages are already installed
2019-04-07 05:38:12,801 [salt.state       :1951][INFO    ][11510] Completed state [maas-rack-controller] at time 05:38:12.801057 duration_in_ms=126.32
2019-04-07 05:38:12,801 [salt.state       :1780][INFO    ][11510] Running state [ipmitool] at time 05:38:12.801313
2019-04-07 05:38:12,801 [salt.state       :1813][INFO    ][11510] Executing state pkg.installed for [ipmitool]
2019-04-07 05:38:12,806 [salt.state       :300 ][INFO    ][11510] All specified packages are already installed
2019-04-07 05:38:12,806 [salt.state       :1951][INFO    ][11510] Completed state [ipmitool] at time 05:38:12.806143 duration_in_ms=4.831
2019-04-07 05:38:12,808 [salt.state       :1780][INFO    ][11510] Running state [/etc/maas/rackd.conf] at time 05:38:12.808369
2019-04-07 05:38:12,808 [salt.state       :1813][INFO    ][11510] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-07 05:38:12,809 [salt.state       :300 ][INFO    ][11510] No changes needed to be made
2019-04-07 05:38:12,809 [salt.state       :1951][INFO    ][11510] Completed state [/etc/maas/rackd.conf] at time 05:38:12.809450 duration_in_ms=1.081
2019-04-07 05:38:12,809 [salt.state       :1780][INFO    ][11510] Running state [/etc/maas/rackd.conf] at time 05:38:12.809625
2019-04-07 05:38:12,809 [salt.state       :1813][INFO    ][11510] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-07 05:38:12,810 [salt.loaded.int.states.file:2298][WARNING ][11510] 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-07 05:38:12,810 [salt.state       :300 ][INFO    ][11510] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-07 05:38:12,810 [salt.state       :1951][INFO    ][11510] Completed state [/etc/maas/rackd.conf] at time 05:38:12.810473 duration_in_ms=0.847
2019-04-07 05:38:12,811 [salt.state       :1780][INFO    ][11510] Running state [maas-rackd] at time 05:38:12.811173
2019-04-07 05:38:12,811 [salt.state       :1813][INFO    ][11510] Executing state service.running for [maas-rackd]
2019-04-07 05:38:12,811 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11510] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-07 05:38:12,842 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11510] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-07 05:38:12,857 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11510] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-07 05:38:12,872 [salt.state       :300 ][INFO    ][11510] The service maas-rackd is already running
2019-04-07 05:38:12,872 [salt.state       :1951][INFO    ][11510] Completed state [maas-rackd] at time 05:38:12.872837 duration_in_ms=61.664
2019-04-07 05:38:12,910 [salt.minion      :1711][INFO    ][11510] Returning information for job: 20190407053811225875
2019-04-07 05:38:13,504 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command state.apply with jid 20190407053813491515
2019-04-07 05:38:13,526 [salt.minion      :1432][INFO    ][11534] Starting a new job with PID 11534
2019-04-07 05:38:14,308 [salt.state       :915 ][INFO    ][11534] Loading fresh modules for state activity
2019-04-07 05:38:14,993 [salt.state       :1780][INFO    ][11534] Running state [maas-region-controller] at time 05:38:14.993217
2019-04-07 05:38:14,993 [salt.state       :1813][INFO    ][11534] Executing state pkg.installed for [maas-region-controller]
2019-04-07 05:38:14,994 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11534] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-07 05:38:15,083 [salt.state       :300 ][INFO    ][11534] All specified packages are already installed
2019-04-07 05:38:15,084 [salt.state       :1951][INFO    ][11534] Completed state [maas-region-controller] at time 05:38:15.084210 duration_in_ms=90.993
2019-04-07 05:38:15,084 [salt.state       :1780][INFO    ][11534] Running state [python-oauth] at time 05:38:15.084540
2019-04-07 05:38:15,084 [salt.state       :1813][INFO    ][11534] Executing state pkg.installed for [python-oauth]
2019-04-07 05:38:15,091 [salt.state       :300 ][INFO    ][11534] All specified packages are already installed
2019-04-07 05:38:15,091 [salt.state       :1951][INFO    ][11534] Completed state [python-oauth] at time 05:38:15.091330 duration_in_ms=6.79
2019-04-07 05:38:15,094 [salt.state       :1780][INFO    ][11534] Running state [/etc/maas/regiond.conf] at time 05:38:15.094311
2019-04-07 05:38:15,094 [salt.state       :1813][INFO    ][11534] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-07 05:38:15,133 [salt.state       :300 ][INFO    ][11534] No changes needed to be made
2019-04-07 05:38:15,133 [salt.state       :1951][INFO    ][11534] Completed state [/etc/maas/regiond.conf] at time 05:38:15.133440 duration_in_ms=39.128
2019-04-07 05:38:15,134 [salt.state       :1780][INFO    ][11534] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:38:15.134149
2019-04-07 05:38:15,134 [salt.state       :1813][INFO    ][11534] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-07 05:38:15,210 [salt.state       :300 ][INFO    ][11534] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-07 05:38:15,210 [salt.state       :1951][INFO    ][11534] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:38:15.210740 duration_in_ms=76.591
2019-04-07 05:38:15,211 [salt.state       :1780][INFO    ][11534] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:38:15.211590
2019-04-07 05:38:15,212 [salt.state       :1813][INFO    ][11534] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-07 05:38:15,230 [salt.state       :300 ][INFO    ][11534] No changes needed to be made
2019-04-07 05:38:15,230 [salt.state       :1951][INFO    ][11534] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:38:15.230908 duration_in_ms=19.318
2019-04-07 05:38:15,231 [salt.state       :1780][INFO    ][11534] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:38:15.231678
2019-04-07 05:38:15,232 [salt.state       :1813][INFO    ][11534] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-07 05:38:15,254 [salt.state       :300 ][INFO    ][11534] No changes needed to be made
2019-04-07 05:38:15,255 [salt.state       :1951][INFO    ][11534] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:38:15.255101 duration_in_ms=23.423
2019-04-07 05:38:15,256 [salt.state       :1780][INFO    ][11534] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:38:15.255927
2019-04-07 05:38:15,256 [salt.state       :1813][INFO    ][11534] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-07 05:38:15,293 [salt.state       :300 ][INFO    ][11534] No changes needed to be made
2019-04-07 05:38:15,293 [salt.state       :1951][INFO    ][11534] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:38:15.293645 duration_in_ms=37.718
2019-04-07 05:38:15,294 [salt.state       :1780][INFO    ][11534] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:38:15.294317
2019-04-07 05:38:15,294 [salt.state       :1813][INFO    ][11534] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-07 05:38:15,307 [salt.state       :300 ][INFO    ][11534] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-07 05:38:15,308 [salt.state       :1951][INFO    ][11534] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:38:15.307950 duration_in_ms=13.632
2019-04-07 05:38:15,309 [salt.state       :1780][INFO    ][11534] Running state [a2enmod headers] at time 05:38:15.309574
2019-04-07 05:38:15,309 [salt.state       :1813][INFO    ][11534] Executing state cmd.run for [a2enmod headers]
2019-04-07 05:38:15,310 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11534] Executing command 'a2enmod headers' in directory '/root'
2019-04-07 05:38:15,380 [salt.state       :300 ][INFO    ][11534] {'pid': 11554, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-07 05:38:15,381 [salt.state       :1951][INFO    ][11534] Completed state [a2enmod headers] at time 05:38:15.381335 duration_in_ms=71.759
2019-04-07 05:38:15,382 [salt.state       :1780][INFO    ][11534] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:38:15.381963
2019-04-07 05:38:15,382 [salt.state       :1813][INFO    ][11534] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-07 05:38:15,408 [salt.state       :300 ][INFO    ][11534] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-07 05:38:15,409 [salt.state       :1951][INFO    ][11534] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:38:15.409161 duration_in_ms=27.189
2019-04-07 05:38:15,410 [salt.state       :1780][INFO    ][11534] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:38:15.410318
2019-04-07 05:38:15,410 [salt.state       :1813][INFO    ][11534] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-07 05:38:15,486 [salt.state       :300 ][INFO    ][11534] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-07 05:38:15,486 [salt.state       :1951][INFO    ][11534] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:38:15.486468 duration_in_ms=76.15
2019-04-07 05:38:15,487 [salt.state       :1780][INFO    ][11534] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:38:15.487326
2019-04-07 05:38:15,487 [salt.state       :1813][INFO    ][11534] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-07 05:38:15,563 [salt.state       :300 ][INFO    ][11534] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-07 05:38:15,564 [salt.state       :1951][INFO    ][11534] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:38:15.563995 duration_in_ms=76.668
2019-04-07 05:38:15,564 [salt.state       :1780][INFO    ][11534] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:38:15.564617
2019-04-07 05:38:15,565 [salt.state       :1813][INFO    ][11534] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-07 05:38:15,635 [salt.state       :300 ][INFO    ][11534] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-07 05:38:15,635 [salt.state       :1951][INFO    ][11534] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:38:15.635739 duration_in_ms=71.122
2019-04-07 05:38:15,636 [salt.state       :1780][INFO    ][11534] Running state [/root/.pgpass] at time 05:38:15.636042
2019-04-07 05:38:15,636 [salt.state       :1813][INFO    ][11534] Executing state file.managed for [/root/.pgpass]
2019-04-07 05:38:15,689 [salt.state       :300 ][INFO    ][11534] File /root/.pgpass is in the correct state
2019-04-07 05:38:15,689 [salt.state       :1951][INFO    ][11534] Completed state [/root/.pgpass] at time 05:38:15.689708 duration_in_ms=53.666
2019-04-07 05:38:15,695 [salt.state       :1780][INFO    ][11534] Running state [maas-region syncdb --noinput] at time 05:38:15.695510
2019-04-07 05:38:15,695 [salt.state       :1813][INFO    ][11534] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-07 05:38:15,696 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11534] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-07 05:38:17,679 [salt.state       :300 ][INFO    ][11534] {'pid': 11567, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: contenttypes, maasserver, metadataserver, auth, piston3, sites, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-07 05:38:17,679 [salt.state       :1951][INFO    ][11534] Completed state [maas-region syncdb --noinput] at time 05:38:17.679640 duration_in_ms=1984.128
2019-04-07 05:38:17,680 [salt.state       :2022][WARNING ][11534] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-07 05:38:17,682 [salt.state       :1780][INFO    ][11534] Running state [maas-regiond] at time 05:38:17.682911
2019-04-07 05:38:17,683 [salt.state       :1813][INFO    ][11534] Executing state service.running for [maas-regiond]
2019-04-07 05:38:17,684 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11534] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-07 05:38:17,726 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11534] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-07 05:38:17,745 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11534] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-07 05:38:17,763 [salt.state       :300 ][INFO    ][11534] The service maas-regiond is already running
2019-04-07 05:38:17,764 [salt.state       :1951][INFO    ][11534] Completed state [maas-regiond] at time 05:38:17.763953 duration_in_ms=81.043
2019-04-07 05:38:17,766 [salt.state       :1780][INFO    ][11534] Running state [bind9] at time 05:38:17.766143
2019-04-07 05:38:17,766 [salt.state       :1813][INFO    ][11534] Executing state service.running for [bind9]
2019-04-07 05:38:17,767 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11534] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-07 05:38:17,786 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11534] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-07 05:38:17,804 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11534] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-07 05:38:17,821 [salt.state       :300 ][INFO    ][11534] The service bind9 is already running
2019-04-07 05:38:17,822 [salt.state       :1951][INFO    ][11534] Completed state [bind9] at time 05:38:17.821947 duration_in_ms=55.804
2019-04-07 05:38:17,824 [salt.state       :1780][INFO    ][11534] Running state [apache2] at time 05:38:17.824002
2019-04-07 05:38:17,824 [salt.state       :1813][INFO    ][11534] Executing state service.running for [apache2]
2019-04-07 05:38:17,825 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11534] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-07 05:38:17,844 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11534] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-07 05:38:17,862 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11534] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-07 05:38:17,883 [salt.state       :300 ][INFO    ][11534] The service apache2 is already running
2019-04-07 05:38:17,883 [salt.state       :1951][INFO    ][11534] Completed state [apache2] at time 05:38:17.883578 duration_in_ms=59.576
2019-04-07 05:38:17,885 [salt.state       :1780][INFO    ][11534] Running state [maasng.wait_for_http_code] at time 05:38:17.885232
2019-04-07 05:38:17,885 [salt.state       :1813][INFO    ][11534] Executing state module.run for [maasng.wait_for_http_code]
2019-04-07 05:38:17,886 [salt.utils.decorators:613 ][WARNING ][11534] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:38:17,895 [salt.state       :300 ][INFO    ][11534] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-07 05:38:17,896 [salt.state       :1951][INFO    ][11534] Completed state [maasng.wait_for_http_code] at time 05:38:17.896204 duration_in_ms=10.971
2019-04-07 05:38:17,897 [salt.state       :1780][INFO    ][11534] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:38:17.897105
2019-04-07 05:38:17,897 [salt.state       :1813][INFO    ][11534] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-07 05:38:17,898 [salt.state       :300 ][INFO    ][11534] /var/lib/maas/.setup_admin exists
2019-04-07 05:38:17,898 [salt.state       :1951][INFO    ][11534] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:38:17.898250 duration_in_ms=1.146
2019-04-07 05:38:17,899 [salt.state       :1780][INFO    ][11534] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:38:17.899085
2019-04-07 05:38:17,899 [salt.state       :1813][INFO    ][11534] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-07 05:38:17,900 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11534] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-07 05:38:19,314 [salt.state       :300 ][INFO    ][11534] {'pid': 11600, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-07 05:38:19,315 [salt.state       :1951][INFO    ][11534] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:38:19.315217 duration_in_ms=1416.131
2019-04-07 05:38:19,323 [salt.state       :1780][INFO    ][11534] Running state [maas_region_boot_source_resources_mirror] at time 05:38:19.323709
2019-04-07 05:38:19,324 [salt.state       :1813][INFO    ][11534] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-07 05:38:19,417 [salt.state       :300 ][INFO    ][11534] {'changes': {}}
2019-04-07 05:38:19,417 [salt.state       :1951][INFO    ][11534] Completed state [maas_region_boot_source_resources_mirror] at time 05:38:19.417599 duration_in_ms=93.89
2019-04-07 05:38:19,418 [salt.state       :1780][INFO    ][11534] Running state [maasng.boot_resources_import] at time 05:38:19.418739
2019-04-07 05:38:19,419 [salt.state       :1813][INFO    ][11534] Executing state module.run for [maasng.boot_resources_import]
2019-04-07 05:38:19,419 [salt.utils.decorators:613 ][WARNING ][11534] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:38:19,538 [salt.loaded.ext.module.maasng:1600][INFO    ][11534] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-07 05:38:24,599 [salt.loaded.ext.module.maasng:1600][INFO    ][11534] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-07 05:38:28,578 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053828565296
2019-04-07 05:38:28,602 [salt.minion      :1432][INFO    ][11646] Starting a new job with PID 11646
2019-04-07 05:38:28,627 [salt.minion      :1711][INFO    ][11646] Returning information for job: 20190407053828565296
2019-04-07 05:38:29,666 [salt.loaded.ext.module.maasng:1600][INFO    ][11534] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-04-07 05:38:34,779 [salt.state       :300 ][INFO    ][11534] {'ret': True}
2019-04-07 05:38:34,780 [salt.state       :1951][INFO    ][11534] Completed state [maasng.boot_resources_import] at time 05:38:34.779970 duration_in_ms=15361.231
2019-04-07 05:38:34,781 [salt.state       :1780][INFO    ][11534] Running state [maas_region_boot_sources_selection_xenial] at time 05:38:34.781209
2019-04-07 05:38:34,781 [salt.state       :1813][INFO    ][11534] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-07 05:38:34,988 [salt.state       :300 ][INFO    ][11534] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-07 05:38:34,989 [salt.state       :1951][INFO    ][11534] Completed state [maas_region_boot_sources_selection_xenial] at time 05:38:34.989425 duration_in_ms=208.214
2019-04-07 05:38:34,991 [salt.state       :1780][INFO    ][11534] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:38:34.991048
2019-04-07 05:38:34,991 [salt.state       :1813][INFO    ][11534] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-07 05:38:34,992 [salt.utils.decorators:613 ][WARNING ][11534] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:38:34,993 [salt.loaded.ext.module.maasng:1771][INFO    ][11534] boot-sources sync initiated for ALL Rack's
2019-04-07 05:38:35,887 [salt.state       :300 ][INFO    ][11534] {'ret': True}
2019-04-07 05:38:35,888 [salt.state       :1951][INFO    ][11534] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:38:35.887976 duration_in_ms=896.927
2019-04-07 05:38:35,890 [salt.state       :1780][INFO    ][11534] Running state [maas.process_maas_config] at time 05:38:35.890098
2019-04-07 05:38:35,890 [salt.state       :1813][INFO    ][11534] Executing state module.run for [maas.process_maas_config]
2019-04-07 05:38:35,891 [salt.utils.decorators:613 ][WARNING ][11534] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:38:35,892 [salt.loaded.ext.module.maas:92  ][INFO    ][11534] maasconfig name=enable_http_proxy value=True
2019-04-07 05:38:35,954 [salt.loaded.ext.module.maas:92  ][INFO    ][11534] maasconfig name=upstream_dns value=8.8.8.8
2019-04-07 05:38:36,019 [salt.loaded.ext.module.maas:92  ][INFO    ][11534] maasconfig name=commissioning_distro_series value=xenial
2019-04-07 05:38:36,091 [salt.loaded.ext.module.maas:92  ][INFO    ][11534] maasconfig name=default_osystem value=ubuntu
2019-04-07 05:38:36,156 [salt.loaded.ext.module.maas:92  ][INFO    ][11534] maasconfig name=active_discovery_interval value=600
2019-04-07 05:38:36,210 [salt.loaded.ext.module.maas:92  ][INFO    ][11534] maasconfig name=dnssec_validation value=no
2019-04-07 05:38:39,022 [salt.loaded.ext.module.maas:92  ][INFO    ][11534] maasconfig name=maas_name value=mas01
2019-04-07 05:38:39,069 [salt.loaded.ext.module.maas:92  ][INFO    ][11534] maasconfig name=network_discovery value=enabled
2019-04-07 05:38:39,202 [salt.loaded.ext.module.maas:92  ][INFO    ][11534] maasconfig name=enable_third_party_drivers value=True
2019-04-07 05:38:39,255 [salt.loaded.ext.module.maas:92  ][INFO    ][11534] maasconfig name=default_storage_layout value=lvm
2019-04-07 05:38:39,309 [salt.loaded.ext.module.maas:92  ][INFO    ][11534] maasconfig name=ntp_external_only value=True
2019-04-07 05:38:39,363 [salt.loaded.ext.module.maas:92  ][INFO    ][11534] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-07 05:38:39,411 [salt.loaded.ext.module.maas:92  ][INFO    ][11534] maasconfig name=default_distro_series value=xenial
2019-04-07 05:38:39,485 [salt.loaded.ext.module.maas:92  ][INFO    ][11534] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-07 05:38:39,630 [salt.state       :300 ][INFO    ][11534] {'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-07 05:38:39,631 [salt.state       :1951][INFO    ][11534] Completed state [maas.process_maas_config] at time 05:38:39.631454 duration_in_ms=3741.354
2019-04-07 05:38:39,632 [salt.state       :1780][INFO    ][11534] Running state [pxe_admin] at time 05:38:39.632721
2019-04-07 05:38:39,633 [salt.state       :1813][INFO    ][11534] Executing state maasng.fabric_present for [pxe_admin]
2019-04-07 05:38:39,710 [salt.loaded.ext.module.maasng:945 ][INFO    ][11534] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'n8tner', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-04-07 05:38:39,785 [salt.loaded.ext.module.maasng:1008][WARNING ][11534] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-07 05:38:39,786 [salt.loaded.ext.module.maasng:1011][WARNING ][11534] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-07 05:38:39,884 [salt.state       :300 ][INFO    ][11534] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-07 05:38:39,884 [salt.state       :1951][INFO    ][11534] Completed state [pxe_admin] at time 05:38:39.884705 duration_in_ms=251.983
2019-04-07 05:38:39,885 [salt.state       :1780][INFO    ][11534] Running state [vlan 0] at time 05:38:39.885287
2019-04-07 05:38:39,885 [salt.state       :1813][INFO    ][11534] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-07 05:38:39,929 [salt.loaded.ext.module.maasng:945 ][INFO    ][11534] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n8tner', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-07 05:38:40,025 [salt.loaded.ext.module.maasng:945 ][INFO    ][11534] [{u'class_type': None, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, 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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', 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'n8tner', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-07 05:38:40,236 [salt.loaded.ext.module.maasng:945 ][INFO    ][11534] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n8tner', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-07 05:38:40,321 [salt.state       :300 ][INFO    ][11534] {'new': 'Vlan untagged was updated'}
2019-04-07 05:38:40,321 [salt.state       :1951][INFO    ][11534] Completed state [vlan 0] at time 05:38:40.321436 duration_in_ms=436.15
2019-04-07 05:38:40,322 [salt.state       :1780][INFO    ][11534] Running state [192.168.11.0/24] at time 05:38:40.322083
2019-04-07 05:38:40,322 [salt.state       :1813][INFO    ][11534] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-07 05:38:40,492 [salt.loaded.ext.module.maasng:945 ][INFO    ][11534] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n8tner', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-07 05:38:40,493 [salt.loaded.ext.module.maasng:1235][WARNING ][11534] Ignoring parameter vlan:0
2019-04-07 05:38:40,584 [salt.state       :300 ][INFO    ][11534] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-07 05:38:40,585 [salt.state       :1951][INFO    ][11534] Completed state [192.168.11.0/24] at time 05:38:40.584949 duration_in_ms=262.864
2019-04-07 05:38:40,586 [salt.state       :1780][INFO    ][11534] Running state [maas_create_iprange_1] at time 05:38:40.586776
2019-04-07 05:38:40,587 [salt.state       :1813][INFO    ][11534] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-07 05:38:40,661 [salt.state       :300 ][INFO    ][11534] Iprange maas_create_iprange_1 already exist.
2019-04-07 05:38:40,661 [salt.state       :1951][INFO    ][11534] Completed state [maas_create_iprange_1] at time 05:38:40.661415 duration_in_ms=74.64
2019-04-07 05:38:40,661 [salt.state       :1780][INFO    ][11534] Running state [vlan 0] at time 05:38:40.661805
2019-04-07 05:38:40,662 [salt.state       :1813][INFO    ][11534] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-07 05:38:40,721 [salt.loaded.ext.module.maasng:945 ][INFO    ][11534] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'n8tner', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-04-07 05:38:40,816 [salt.loaded.ext.module.maasng:945 ][INFO    ][11534] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'n8tner', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-07 05:38:41,040 [salt.loaded.ext.module.maasng:945 ][INFO    ][11534] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'n8tner', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-04-07 05:38:41,130 [salt.state       :300 ][INFO    ][11534] {'new': 'Vlan untagged was updated'}
2019-04-07 05:38:41,130 [salt.state       :1951][INFO    ][11534] Completed state [vlan 0] at time 05:38:41.130700 duration_in_ms=468.894
2019-04-07 05:38:41,131 [salt.state       :1780][INFO    ][11534] Running state [opnfv] at time 05:38:41.131647
2019-04-07 05:38:41,132 [salt.state       :1813][INFO    ][11534] Executing state maasng.sshkey_present for [opnfv]
2019-04-07 05:38:41,183 [salt.loaded.ext.module.maasng:1903][INFO    ][11534] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-04-07 05:38:41,184 [salt.state       :300 ][INFO    ][11534] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-04-07 05:38:41,184 [salt.state       :1951][INFO    ][11534] Completed state [opnfv] at time 05:38:41.184722 duration_in_ms=53.073
2019-04-07 05:38:41,185 [salt.state       :1780][INFO    ][11534] Running state [maas.process_tags] at time 05:38:41.185662
2019-04-07 05:38:41,186 [salt.state       :1813][INFO    ][11534] Executing state module.run for [maas.process_tags]
2019-04-07 05:38:41,186 [salt.utils.decorators:613 ][WARNING ][11534] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:38:41,232 [salt.loaded.ext.module.maas:92  ][INFO    ][11534] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-04-07 05:38:41,279 [salt.state       :300 ][INFO    ][11534] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-04-07 05:38:41,280 [salt.state       :1951][INFO    ][11534] Completed state [maas.process_tags] at time 05:38:41.280236 duration_in_ms=94.574
2019-04-07 05:38:41,284 [salt.minion      :1711][INFO    ][11534] Returning information for job: 20190407053813491515
2019-04-07 05:38:41,816 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command state.apply with jid 20190407053841804780
2019-04-07 05:38:41,834 [salt.minion      :1432][INFO    ][12007] Starting a new job with PID 12007
2019-04-07 05:38:45,528 [salt.state       :915 ][INFO    ][12007] Loading fresh modules for state activity
2019-04-07 05:38:45,578 [salt.state       :1780][INFO    ][12007] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:38:45.578448
2019-04-07 05:38:45,578 [salt.state       :1813][INFO    ][12007] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-07 05:38:45,579 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12007] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-07 05:38:47,049 [salt.state       :300 ][INFO    ][12007] {'pid': 12030, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-07 05:38:47,050 [salt.state       :1951][INFO    ][12007] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:38:47.049979 duration_in_ms=1471.53
2019-04-07 05:38:47,052 [salt.state       :1780][INFO    ][12007] Running state [maas.process_machines] at time 05:38:47.052517
2019-04-07 05:38:47,053 [salt.state       :1813][INFO    ][12007] Executing state module.run for [maas.process_machines]
2019-04-07 05:38:47,053 [salt.utils.decorators:613 ][WARNING ][12007] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:38:47,564 [salt.loaded.ext.module.maas:412 ][WARNING ][12007] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-07 05:38:47,565 [salt.loaded.ext.module.maas:92  ][INFO    ][12007] 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=w3366c architecture=amd64/generic power_parameters_power_user=admin
2019-04-07 05:38:48,691 [salt.loaded.ext.module.maas:412 ][WARNING ][12007] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-07 05:38:48,692 [salt.loaded.ext.module.maas:92  ][INFO    ][12007] 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=tfpykq architecture=amd64/generic power_parameters_power_user=admin
2019-04-07 05:38:49,925 [salt.loaded.ext.module.maas:412 ][WARNING ][12007] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-07 05:38:49,926 [salt.loaded.ext.module.maas:92  ][INFO    ][12007] 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=nggphk architecture=amd64/generic power_parameters_power_user=admin
2019-04-07 05:38:51,176 [salt.loaded.ext.module.maas:412 ][WARNING ][12007] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-07 05:38:51,177 [salt.loaded.ext.module.maas:92  ][INFO    ][12007] 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=7ymkgb architecture=amd64/generic power_parameters_power_user=admin
2019-04-07 05:38:52,434 [salt.loaded.ext.module.maas:412 ][WARNING ][12007] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-07 05:38:52,435 [salt.loaded.ext.module.maas:92  ][INFO    ][12007] 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=qpwefc architecture=amd64/generic power_parameters_power_user=admin
2019-04-07 05:38:53,735 [salt.state       :300 ][INFO    ][12007] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-07 05:38:53,736 [salt.state       :1951][INFO    ][12007] Completed state [maas.process_machines] at time 05:38:53.736292 duration_in_ms=6683.772
2019-04-07 05:38:53,740 [salt.minion      :1711][INFO    ][12007] Returning information for job: 20190407053841804780
2019-04-07 05:39:27,161 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command state.apply with jid 20190407053927147140
2019-04-07 05:39:27,183 [salt.minion      :1432][INFO    ][12299] Starting a new job with PID 12299
2019-04-07 05:39:30,915 [salt.state       :915 ][INFO    ][12299] Loading fresh modules for state activity
2019-04-07 05:39:30,966 [salt.state       :1780][INFO    ][12299] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:30.966093
2019-04-07 05:39:30,966 [salt.state       :1813][INFO    ][12299] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-07 05:39:30,967 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12299] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-07 05:39:32,407 [salt.state       :300 ][INFO    ][12299] {'pid': 12306, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-07 05:39:32,407 [salt.state       :1951][INFO    ][12299] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:32.407793 duration_in_ms=1441.7
2019-04-07 05:39:32,409 [salt.state       :1780][INFO    ][12299] Running state [maas.wait_for_machine_status] at time 05:39:32.409011
2019-04-07 05:39:32,409 [salt.state       :1813][INFO    ][12299] Executing state module.run for [maas.wait_for_machine_status]
2019-04-07 05:39:32,409 [salt.utils.decorators:613 ][WARNING ][12299] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:39:34,358 [salt.loaded.ext.module.maas:993 ][INFO    ][12299] Machine nggphk mark broken
2019-04-07 05:39:34,978 [salt.loaded.ext.module.maas:996 ][INFO    ][12299] Machine nggphk mark fixed
2019-04-07 05:39:36,211 [salt.loaded.ext.module.maas:684 ][INFO    ][12299] deploymachines hwe_kernel=hwe-16.04 system_id=nggphk distro_series=xenial
2019-04-07 05:39:40,208 [salt.loaded.ext.module.maas:1023][INFO    ][12299] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1492.209126s left)
2019-04-07 05:39:42,209 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407053942195430
2019-04-07 05:39:42,232 [salt.minion      :1432][INFO    ][12377] Starting a new job with PID 12377
2019-04-07 05:39:42,258 [salt.minion      :1711][INFO    ][12377] Returning information for job: 20190407053942195430
2019-04-07 05:40:12,452 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407054012396305
2019-04-07 05:40:12,472 [salt.minion      :1432][INFO    ][12412] Starting a new job with PID 12412
2019-04-07 05:40:12,495 [salt.minion      :1711][INFO    ][12412] Returning information for job: 20190407054012396305
2019-04-07 05:40:13,657 [salt.loaded.ext.module.maas:1023][INFO    ][12299] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1458.75922489s left)
2019-04-07 05:40:42,543 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407054042530431
2019-04-07 05:40:42,565 [salt.minion      :1432][INFO    ][12456] Starting a new job with PID 12456
2019-04-07 05:40:42,593 [salt.minion      :1711][INFO    ][12456] Returning information for job: 20190407054042530431
2019-04-07 05:40:47,162 [salt.loaded.ext.module.maas:1023][INFO    ][12299] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1425.254668s left)
2019-04-07 05:41:12,593 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407054112580385
2019-04-07 05:41:12,616 [salt.minion      :1432][INFO    ][12484] Starting a new job with PID 12484
2019-04-07 05:41:12,642 [salt.minion      :1711][INFO    ][12484] Returning information for job: 20190407054112580385
2019-04-07 05:41:19,732 [salt.loaded.ext.module.maas:1023][INFO    ][12299] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1392.68494892s left)
2019-04-07 05:41:42,647 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407054142633408
2019-04-07 05:41:42,670 [salt.minion      :1432][INFO    ][12582] Starting a new job with PID 12582
2019-04-07 05:41:42,696 [salt.minion      :1711][INFO    ][12582] Returning information for job: 20190407054142633408
2019-04-07 05:41:53,128 [salt.loaded.ext.module.maas:1023][INFO    ][12299] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1359.28821087s left)
2019-04-07 05:42:12,700 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407054212687007
2019-04-07 05:42:12,723 [salt.minion      :1432][INFO    ][12613] Starting a new job with PID 12613
2019-04-07 05:42:12,750 [salt.minion      :1711][INFO    ][12613] Returning information for job: 20190407054212687007
2019-04-07 05:42:26,459 [salt.loaded.ext.module.maas:1023][INFO    ][12299] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1325.95788598s left)
2019-04-07 05:42:42,762 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407054242748480
2019-04-07 05:42:42,784 [salt.minion      :1432][INFO    ][12805] Starting a new job with PID 12805
2019-04-07 05:42:42,810 [salt.minion      :1711][INFO    ][12805] Returning information for job: 20190407054242748480
2019-04-07 05:42:59,587 [salt.loaded.ext.module.maas:1023][INFO    ][12299] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1292.8298769s left)
2019-04-07 05:43:12,829 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407054312812915
2019-04-07 05:43:12,856 [salt.minion      :1432][INFO    ][12836] Starting a new job with PID 12836
2019-04-07 05:43:12,891 [salt.minion      :1711][INFO    ][12836] Returning information for job: 20190407054312812915
2019-04-07 05:43:33,223 [salt.loaded.ext.module.maas:1023][INFO    ][12299] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1259.19341397s left)
2019-04-07 05:43:42,908 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407054342895522
2019-04-07 05:43:42,930 [salt.minion      :1432][INFO    ][12904] Starting a new job with PID 12904
2019-04-07 05:43:42,960 [salt.minion      :1711][INFO    ][12904] Returning information for job: 20190407054342895522
2019-04-07 05:44:06,673 [salt.loaded.ext.module.maas:1023][INFO    ][12299] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1225.74360085s left)
2019-04-07 05:44:12,977 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407054412964802
2019-04-07 05:44:13,002 [salt.minion      :1432][INFO    ][12934] Starting a new job with PID 12934
2019-04-07 05:44:13,030 [salt.minion      :1711][INFO    ][12934] Returning information for job: 20190407054412964802
2019-04-07 05:44:40,269 [salt.loaded.ext.module.maas:1023][INFO    ][12299] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1192.14797902s left)
2019-04-07 05:44:43,057 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407054443044788
2019-04-07 05:44:43,078 [salt.minion      :1432][INFO    ][13095] Starting a new job with PID 13095
2019-04-07 05:44:43,120 [salt.minion      :1711][INFO    ][13095] Returning information for job: 20190407054443044788
2019-04-07 05:45:13,150 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407054513137002
2019-04-07 05:45:13,171 [salt.minion      :1432][INFO    ][13135] Starting a new job with PID 13135
2019-04-07 05:45:13,196 [salt.minion      :1711][INFO    ][13135] Returning information for job: 20190407054513137002
2019-04-07 05:45:13,715 [salt.loaded.ext.module.maas:1023][INFO    ][12299] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1158.70136189s left)
2019-04-07 05:45:43,232 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407054543219472
2019-04-07 05:45:43,253 [salt.minion      :1432][INFO    ][13258] Starting a new job with PID 13258
2019-04-07 05:45:43,281 [salt.minion      :1711][INFO    ][13258] Returning information for job: 20190407054543219472
2019-04-07 05:45:47,057 [salt.loaded.ext.module.maas:1023][INFO    ][12299] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1125.35976291s left)
2019-04-07 05:46:13,318 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407054613304807
2019-04-07 05:46:13,341 [salt.minion      :1432][INFO    ][13287] Starting a new job with PID 13287
2019-04-07 05:46:13,366 [salt.minion      :1711][INFO    ][13287] Returning information for job: 20190407054613304807
2019-04-07 05:46:20,561 [salt.loaded.ext.module.maas:1023][INFO    ][12299] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1091.85547304s left)
2019-04-07 05:46:43,413 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407054643400092
2019-04-07 05:46:43,435 [salt.minion      :1432][INFO    ][13360] Starting a new job with PID 13360
2019-04-07 05:46:43,460 [salt.minion      :1711][INFO    ][13360] Returning information for job: 20190407054643400092
2019-04-07 05:46:54,311 [salt.loaded.ext.module.maas:1023][INFO    ][12299] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1058.10585403s left)
2019-04-07 05:47:13,631 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command saltutil.find_job with jid 20190407054713618851
2019-04-07 05:47:13,651 [salt.minion      :1432][INFO    ][13419] Starting a new job with PID 13419
2019-04-07 05:47:13,677 [salt.minion      :1711][INFO    ][13419] Returning information for job: 20190407054713618851
2019-04-07 05:47:27,869 [salt.state       :300 ][INFO    ][12299] {'ret': True}
2019-04-07 05:47:27,870 [salt.state       :1951][INFO    ][12299] Completed state [maas.wait_for_machine_status] at time 05:47:27.870275 duration_in_ms=475461.261
2019-04-07 05:47:27,874 [salt.minion      :1711][INFO    ][12299] Returning information for job: 20190407053927147140
2019-04-07 05:47:28,571 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command state.apply with jid 20190407054728557883
2019-04-07 05:47:28,593 [salt.minion      :1432][INFO    ][13592] Starting a new job with PID 13592
2019-04-07 05:47:32,276 [salt.state       :915 ][INFO    ][13592] Loading fresh modules for state activity
2019-04-07 05:47:32,416 [salt.state       :1780][INFO    ][13592] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:47:32.416307
2019-04-07 05:47:32,416 [salt.state       :1813][INFO    ][13592] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-07 05:47:32,418 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13592] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-07 05:47:33,867 [salt.state       :300 ][INFO    ][13592] {'pid': 13599, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-07 05:47:33,867 [salt.state       :1951][INFO    ][13592] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:47:33.867513 duration_in_ms=1451.206
2019-04-07 05:47:33,869 [salt.state       :1780][INFO    ][13592] Running state [maas_machines_storage_cmp002_lvm] at time 05:47:33.869006
2019-04-07 05:47:33,869 [salt.state       :1813][INFO    ][13592] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-07 05:47:34,485 [salt.state       :300 ][INFO    ][13592] Machine cmp002 is not in Ready state.
2019-04-07 05:47:34,486 [salt.state       :1951][INFO    ][13592] Completed state [maas_machines_storage_cmp002_lvm] at time 05:47:34.486338 duration_in_ms=617.33
2019-04-07 05:47:34,486 [salt.state       :1780][INFO    ][13592] Running state [maas_machines_storage_cmp001_lvm] at time 05:47:34.486796
2019-04-07 05:47:34,487 [salt.state       :1813][INFO    ][13592] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-07 05:47:35,216 [salt.state       :300 ][INFO    ][13592] Machine cmp001 is not in Ready state.
2019-04-07 05:47:35,217 [salt.state       :1951][INFO    ][13592] Completed state [maas_machines_storage_cmp001_lvm] at time 05:47:35.216994 duration_in_ms=730.197
2019-04-07 05:47:35,221 [salt.minion      :1711][INFO    ][13592] Returning information for job: 20190407054728557883
2019-04-07 05:47:35,875 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command state.apply with jid 20190407054735861601
2019-04-07 05:47:35,898 [salt.minion      :1432][INFO    ][13609] Starting a new job with PID 13609
2019-04-07 05:47:36,640 [salt.state       :915 ][INFO    ][13609] Loading fresh modules for state activity
2019-04-07 05:47:36,730 [salt.state       :1780][INFO    ][13609] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:47:36.730340
2019-04-07 05:47:36,730 [salt.state       :1813][INFO    ][13609] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-07 05:47:36,733 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13609] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-07 05:47:38,209 [salt.state       :300 ][INFO    ][13609] {'pid': 13616, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-07 05:47:38,210 [salt.state       :1951][INFO    ][13609] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:47:38.210159 duration_in_ms=1479.82
2019-04-07 05:47:38,211 [salt.state       :1780][INFO    ][13609] Running state [maas.deploy_machines] at time 05:47:38.211282
2019-04-07 05:47:38,211 [salt.state       :1813][INFO    ][13609] Executing state module.run for [maas.deploy_machines]
2019-04-07 05:47:38,212 [salt.utils.decorators:613 ][WARNING ][13609] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:47:38,947 [salt.state       :300 ][INFO    ][13609] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-07 05:47:38,947 [salt.state       :1951][INFO    ][13609] Completed state [maas.deploy_machines] at time 05:47:38.947540 duration_in_ms=736.256
2019-04-07 05:47:38,950 [salt.minion      :1711][INFO    ][13609] Returning information for job: 20190407054735861601
2019-04-07 05:47:39,599 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command state.apply with jid 20190407054739586358
2019-04-07 05:47:39,621 [salt.minion      :1432][INFO    ][13632] Starting a new job with PID 13632
2019-04-07 05:47:40,374 [salt.state       :915 ][INFO    ][13632] Loading fresh modules for state activity
2019-04-07 05:47:40,463 [salt.state       :1780][INFO    ][13632] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:47:40.463905
2019-04-07 05:47:40,464 [salt.state       :1813][INFO    ][13632] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-07 05:47:40,466 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13632] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-07 05:47:42,005 [salt.state       :300 ][INFO    ][13632] {'pid': 13639, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-07 05:47:42,006 [salt.state       :1951][INFO    ][13632] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:47:42.006726 duration_in_ms=1542.82
2019-04-07 05:47:42,010 [salt.state       :1780][INFO    ][13632] Running state [maas.wait_for_machine_status] at time 05:47:42.010107
2019-04-07 05:47:42,010 [salt.state       :1813][INFO    ][13632] Executing state module.run for [maas.wait_for_machine_status]
2019-04-07 05:47:42,011 [salt.utils.decorators:613 ][WARNING ][13632] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-07 05:47:45,641 [salt.state       :300 ][INFO    ][13632] {'ret': True}
2019-04-07 05:47:45,642 [salt.state       :1951][INFO    ][13632] Completed state [maas.wait_for_machine_status] at time 05:47:45.642101 duration_in_ms=3631.993
2019-04-07 05:47:45,645 [salt.minion      :1711][INFO    ][13632] Returning information for job: 20190407054739586358
2019-04-07 06:15:14,285 [salt.utils.schedule:1377][INFO    ][3296] Running scheduled job: __mine_interval
2019-04-07 07:15:14,285 [salt.utils.schedule:1377][INFO    ][3296] Running scheduled job: __mine_interval
2019-04-07 07:19:47,931 [salt.minion      :1308][INFO    ][3296] User sudo_ubuntu Executing command cp.push_dir with jid 20190407071947918406
2019-04-07 07:19:47,950 [salt.minion      :1432][INFO    ][20423] Starting a new job with PID 20423
