2019-04-12 10:05:34,881 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-12 10:06:24,932 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-12 10:07:14,981 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-12 10:08:05,023 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-12 10:08:55,075 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-12 10:11:03,399 [salt.utils.decorators:613 ][WARNING ][2765] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 10:11:03,996 [salt.utils.decorators:613 ][WARNING ][2765] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 10:11:06,252 [salt.loaded.int.states.file:2298][WARNING ][2901] 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-12 10:11:11,834 [salt.state       :2022][WARNING ][3023] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-12 10:11:14,448 [salt.utils.decorators:613 ][WARNING ][3023] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 10:26:19,157 [salt.utils.decorators:613 ][WARNING ][3023] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 10:53:30,452 [salt.utils.decorators:613 ][WARNING ][3023] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 11:08:36,011 [salt.utils.decorators:613 ][WARNING ][3023] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 11:08:40,240 [salt.loaded.ext.module.maasng:1008][WARNING ][3023] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-04-12 11:08:40,241 [salt.loaded.ext.module.maasng:1011][WARNING ][3023] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-04-12 11:08:40,852 [salt.loaded.ext.module.maasng:1235][WARNING ][3023] Ignoring parameter vlan:0
2019-04-12 11:08:41,752 [salt.utils.decorators:613 ][WARNING ][3023] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 11:08:43,678 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9154] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-12 11:08:43,706 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9154] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-12 11:08:43,724 [salt.utils.parsers:1051][WARNING ][359] Minion received a SIGTERM. Exiting.
2019-04-12 11:08:44,721 [salt.cli.daemons :293 ][INFO    ][9222] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-04-12 11:08:44,799 [salt.cli.daemons :82  ][INFO    ][9222] Starting up the Salt Minion
2019-04-12 11:08:44,800 [salt.utils.event :1017][INFO    ][9222] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-04-12 11:08:45,558 [salt.minion      :976 ][INFO    ][9222] Creating minion process manager
2019-04-12 11:08:46,873 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][9222] Executing command ['date', '+%z'] in directory '/root'
2019-04-12 11:08:46,896 [salt.utils.schedule:568 ][INFO    ][9222] Updating job settings for scheduled job: __mine_interval
2019-04-12 11:08:46,897 [salt.minion      :1108][INFO    ][9222] Added mine.update to scheduler
2019-04-12 11:08:46,901 [salt.minion      :1975][INFO    ][9222] Minion is starting as user 'root'
2019-04-12 11:08:46,914 [salt.minion      :2336][INFO    ][9222] Minion is ready to receive requests!
2019-04-12 11:08:47,868 [salt.utils.decorators:613 ][WARNING ][9177] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 11:08:47,930 [salt.loaded.ext.module.maas:412 ][WARNING ][9177] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-12 11:08:51,902 [salt.loaded.ext.module.maas:412 ][WARNING ][9177] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-12 11:08:56,639 [salt.loaded.ext.module.maas:412 ][WARNING ][9177] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-12 11:08:57,662 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412110857650789
2019-04-12 11:08:57,687 [salt.minion      :1432][INFO    ][9424] Starting a new job with PID 9424
2019-04-12 11:08:57,708 [salt.minion      :1711][INFO    ][9424] Returning information for job: 20190412110857650789
2019-04-12 11:09:00,777 [salt.loaded.ext.module.maas:412 ][WARNING ][9177] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-12 11:09:04,861 [salt.loaded.ext.module.maas:412 ][WARNING ][9177] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-12 11:09:39,480 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command state.apply with jid 20190412110939463273
2019-04-12 11:09:39,503 [salt.minion      :1432][INFO    ][9656] Starting a new job with PID 9656
2019-04-12 11:09:43,185 [salt.state       :915 ][INFO    ][9656] Loading fresh modules for state activity
2019-04-12 11:09:43,227 [salt.fileclient  :1219][INFO    ][9656] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-12 11:09:43,262 [salt.state       :1780][INFO    ][9656] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:09:43.262782
2019-04-12 11:09:43,263 [salt.state       :1813][INFO    ][9656] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-12 11:09:43,264 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9656] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-12 11:09:44,509 [salt.state       :300 ][INFO    ][9656] {'pid': 9663, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-12 11:09:44,510 [salt.state       :1951][INFO    ][9656] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:09:44.510170 duration_in_ms=1247.39
2019-04-12 11:09:44,511 [salt.state       :1780][INFO    ][9656] Running state [maas.wait_for_machine_status] at time 11:09:44.511543
2019-04-12 11:09:44,511 [salt.state       :1813][INFO    ][9656] Executing state module.run for [maas.wait_for_machine_status]
2019-04-12 11:09:44,512 [salt.utils.decorators:613 ][WARNING ][9656] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 11:09:45,383 [salt.loaded.ext.module.maas:1023][INFO    ][9656] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.13255382s left)
2019-04-12 11:09:54,577 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412110954564085
2019-04-12 11:09:54,603 [salt.minion      :1432][INFO    ][9675] Starting a new job with PID 9675
2019-04-12 11:09:54,625 [salt.minion      :1711][INFO    ][9675] Returning information for job: 20190412110954564085
2019-04-12 11:10:16,366 [salt.loaded.ext.module.maas:1023][INFO    ][9656] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.14954495s left)
2019-04-12 11:10:24,626 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111024612799
2019-04-12 11:10:24,651 [salt.minion      :1432][INFO    ][9728] Starting a new job with PID 9728
2019-04-12 11:10:24,672 [salt.minion      :1711][INFO    ][9728] Returning information for job: 20190412111024612799
2019-04-12 11:10:47,401 [salt.loaded.ext.module.maas:1023][INFO    ][9656] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.11489701s left)
2019-04-12 11:10:54,745 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111054733076
2019-04-12 11:10:54,771 [salt.minion      :1432][INFO    ][9844] Starting a new job with PID 9844
2019-04-12 11:10:54,792 [salt.minion      :1711][INFO    ][9844] Returning information for job: 20190412111054733076
2019-04-12 11:11:18,291 [salt.loaded.ext.module.maas:1023][INFO    ][9656] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.22464585s left)
2019-04-12 11:11:24,789 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111124781945
2019-04-12 11:11:24,812 [salt.minion      :1432][INFO    ][10331] Starting a new job with PID 10331
2019-04-12 11:11:24,825 [salt.minion      :1711][INFO    ][10331] Returning information for job: 20190412111124781945
2019-04-12 11:11:51,541 [salt.loaded.ext.module.maas:1023][INFO    ][9656] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1372.97470784s left)
2019-04-12 11:11:54,847 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111154836413
2019-04-12 11:11:54,869 [salt.minion      :1432][INFO    ][10616] Starting a new job with PID 10616
2019-04-12 11:11:54,889 [salt.minion      :1711][INFO    ][10616] Returning information for job: 20190412111154836413
2019-04-12 11:12:24,924 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111224911406
2019-04-12 11:12:24,950 [salt.minion      :1432][INFO    ][10895] Starting a new job with PID 10895
2019-04-12 11:12:24,969 [salt.minion      :1711][INFO    ][10895] Returning information for job: 20190412111224911406
2019-04-12 11:12:55,001 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111254988659
2019-04-12 11:12:55,028 [salt.minion      :1432][INFO    ][10936] Starting a new job with PID 10936
2019-04-12 11:12:55,047 [salt.minion      :1711][INFO    ][10936] Returning information for job: 20190412111254988659
2019-04-12 11:13:25,077 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111325064117
2019-04-12 11:13:25,106 [salt.minion      :1432][INFO    ][10990] Starting a new job with PID 10990
2019-04-12 11:13:25,126 [salt.minion      :1711][INFO    ][10990] Returning information for job: 20190412111325064117
2019-04-12 11:13:55,160 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111355147729
2019-04-12 11:13:55,182 [salt.minion      :1432][INFO    ][11022] Starting a new job with PID 11022
2019-04-12 11:13:55,199 [salt.minion      :1711][INFO    ][11022] Returning information for job: 20190412111355147729
2019-04-12 11:14:25,244 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111425231785
2019-04-12 11:14:25,270 [salt.minion      :1432][INFO    ][11071] Starting a new job with PID 11071
2019-04-12 11:14:25,285 [salt.minion      :1711][INFO    ][11071] Returning information for job: 20190412111425231785
2019-04-12 11:14:55,326 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111455317030
2019-04-12 11:14:55,349 [salt.minion      :1432][INFO    ][11110] Starting a new job with PID 11110
2019-04-12 11:14:55,367 [salt.minion      :1711][INFO    ][11110] Returning information for job: 20190412111455317030
2019-04-12 11:15:25,426 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111525414666
2019-04-12 11:15:25,454 [salt.minion      :1432][INFO    ][11164] Starting a new job with PID 11164
2019-04-12 11:15:25,470 [salt.minion      :1711][INFO    ][11164] Returning information for job: 20190412111525414666
2019-04-12 11:15:55,536 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111555523181
2019-04-12 11:15:55,562 [salt.minion      :1432][INFO    ][11198] Starting a new job with PID 11198
2019-04-12 11:15:55,581 [salt.minion      :1711][INFO    ][11198] Returning information for job: 20190412111555523181
2019-04-12 11:16:25,639 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111625626519
2019-04-12 11:16:25,665 [salt.minion      :1432][INFO    ][11243] Starting a new job with PID 11243
2019-04-12 11:16:25,688 [salt.minion      :1711][INFO    ][11243] Returning information for job: 20190412111625626519
2019-04-12 11:16:44,885 [salt.loaded.ext.module.maas:1023][INFO    ][9656] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1079.6302309s left)
2019-04-12 11:16:55,762 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111655750063
2019-04-12 11:16:55,788 [salt.minion      :1432][INFO    ][11568] Starting a new job with PID 11568
2019-04-12 11:16:55,810 [salt.minion      :1711][INFO    ][11568] Returning information for job: 20190412111655750063
2019-04-12 11:17:17,318 [salt.loaded.ext.module.maas:1023][INFO    ][9656] Waiting status:Ready|Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1047.19760394s left)
2019-04-12 11:17:25,881 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111725868296
2019-04-12 11:17:25,908 [salt.minion      :1432][INFO    ][11882] Starting a new job with PID 11882
2019-04-12 11:17:25,929 [salt.minion      :1711][INFO    ][11882] Returning information for job: 20190412111725868296
2019-04-12 11:17:50,287 [salt.loaded.ext.module.maas:1023][INFO    ][9656] Waiting status:Ready|Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1014.22873497s left)
2019-04-12 11:17:55,990 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111755977030
2019-04-12 11:17:56,015 [salt.minion      :1432][INFO    ][11937] Starting a new job with PID 11937
2019-04-12 11:17:56,037 [salt.minion      :1711][INFO    ][11937] Returning information for job: 20190412111755977030
2019-04-12 11:18:23,331 [salt.state       :300 ][INFO    ][9656] {'ret': True}
2019-04-12 11:18:23,331 [salt.state       :1951][INFO    ][9656] Completed state [maas.wait_for_machine_status] at time 11:18:23.331610 duration_in_ms=518820.064
2019-04-12 11:18:23,335 [salt.minion      :1711][INFO    ][9656] Returning information for job: 20190412110939463273
2019-04-12 11:18:23,851 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command state.apply with jid 20190412111823839705
2019-04-12 11:18:23,871 [salt.minion      :1432][INFO    ][12089] Starting a new job with PID 12089
2019-04-12 11:18:27,370 [salt.state       :915 ][INFO    ][12089] Loading fresh modules for state activity
2019-04-12 11:18:27,422 [salt.fileclient  :1219][INFO    ][12089] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-12 11:18:27,513 [salt.state       :1780][INFO    ][12089] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:18:27.513319
2019-04-12 11:18:27,513 [salt.state       :1813][INFO    ][12089] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-12 11:18:27,515 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12089] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-12 11:18:28,922 [salt.state       :300 ][INFO    ][12089] {'pid': 12104, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-12 11:18:28,923 [salt.state       :1951][INFO    ][12089] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:18:28.923337 duration_in_ms=1410.018
2019-04-12 11:18:28,926 [salt.state       :1780][INFO    ][12089] Running state [maas_machines_storage_cmp002_lvm] at time 11:18:28.926331
2019-04-12 11:18:28,926 [salt.state       :1813][INFO    ][12089] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-12 11:18:30,331 [salt.loaded.ext.module.maasng:610 ][INFO    ][12089] dtaw3f
2019-04-12 11:18:30,332 [salt.loaded.ext.module.maasng:626 ][INFO    ][12089] sda
2019-04-12 11:18:30,834 [salt.loaded.ext.module.maasng:361 ][INFO    ][12089] dtaw3f
2019-04-12 11:18:30,954 [salt.loaded.ext.module.maasng:367 ][INFO    ][12089] [{u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'b8dcf890-3462-4cb3-bef2-8aee50ba784e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dtaw3f', u'device_id': 3, u'filesystem': {u'uuid': u'32369928-d37b-4359-8917-53db59fd6b5b', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/dtaw3f/blockdevices/3/partition/2'}], u'filesystem': None, u'uuid': None, u'id': 3, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'dtaw3f', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/dtaw3f/blockdevices/3/', u'size': 2397998940160}, {u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'018161ca-b114-45f5-aa69-d45434e0c5c0', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'f6eb97ff-99a9-4db1-bf73-93120a8c7a4a', u'id': 5, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dtaw3f', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'resource_uri': u'/MAAS/api/2.0/nodes/dtaw3f/blockdevices/5/', u'size': 2397988454400}]
2019-04-12 11:18:30,955 [salt.loaded.ext.module.maasng:632 ][INFO    ][12089] vgroot
2019-04-12 11:18:30,955 [salt.loaded.ext.module.maasng:635 ][INFO    ][12089] lvroot
2019-04-12 11:18:30,955 [salt.loaded.ext.module.maasng:639 ][INFO    ][12089] 107374182400
2019-04-12 11:18:31,633 [salt.loaded.ext.module.maasng:645 ][INFO    ][12089] {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'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/dtaw3f/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'dtaw3f', 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'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dtaw3f/blockdevices/11/', u'uuid': u'469ec8b4-52f5-43f1-bc3a-32421f74deba', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'label': u'root', u'uuid': u'f889b977-cd5d-4228-a0a0-c754d0ed1399', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dtaw3f', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/dtaw3f/blockdevices/3/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'dtaw3f', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'c020ac7e-df6a-4b38-ab5e-b2ae5c4e7fd2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dtaw3f', u'filesystem': {u'label': None, u'uuid': u'5094e41c-9220-463b-89f1-92b654b14ac2', 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/dtaw3f/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'device_id': 3}], u'used_size': 2397998940160, u'id': 3, u'size': 2397998940160}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dtaw3f/blockdevices/11/', u'uuid': u'469ec8b4-52f5-43f1-bc3a-32421f74deba', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'label': u'root', u'uuid': u'f889b977-cd5d-4228-a0a0-c754d0ed1399', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'dtaw3f', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'block_size': 4096, u'available_size': 0, u'serial': None, u'partitions': [], u'used_size': 107374182400, u'id': 11, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'ip_addresses': [u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'dtaw3f', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/dtaw3f/blockdevices/3/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'dtaw3f', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 3, u'partitions': [{u'size': 2397992648704, u'uuid': u'c020ac7e-df6a-4b38-ab5e-b2ae5c4e7fd2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dtaw3f', u'filesystem': {u'label': None, u'uuid': u'5094e41c-9220-463b-89f1-92b654b14ac2', 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/dtaw3f/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'device_id': 3}]}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/dtaw3f/blockdevices/3/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'dtaw3f', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 3, u'partitions': [{u'size': 2397992648704, u'uuid': u'c020ac7e-df6a-4b38-ab5e-b2ae5c4e7fd2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dtaw3f', u'filesystem': {u'label': None, u'uuid': u'5094e41c-9220-463b-89f1-92b654b14ac2', 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/dtaw3f/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'device_id': 3}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'et7prd', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'et7prd', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'mode': u'dhcp', u'id': 21}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'et7prd', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'system_id': u'dtaw3f', u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dtaw3f/interfaces/4/'}, u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'et7prd', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'et7prd', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'mode': u'dhcp', u'id': 21}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'et7prd', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'system_id': u'dtaw3f', u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dtaw3f/interfaces/4/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 22}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 12, u'discovered': None, u'parents': [], u'system_id': u'dtaw3f', u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dtaw3f/interfaces/12/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 23}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 13, u'discovered': None, u'parents': [], u'system_id': u'dtaw3f', u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dtaw3f/interfaces/13/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 24}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 14, u'discovered': None, u'parents': [], u'system_id': u'dtaw3f', u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dtaw3f/interfaces/14/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-04-12 11:18:31,635 [salt.state       :300 ][INFO    ][12089] {'new': {'storage_layout': 'lvm'}}
2019-04-12 11:18:31,636 [salt.state       :1951][INFO    ][12089] Completed state [maas_machines_storage_cmp002_lvm] at time 11:18:31.636190 duration_in_ms=2709.858
2019-04-12 11:18:31,636 [salt.state       :1780][INFO    ][12089] Running state [maas_machines_storage_cmp001_lvm] at time 11:18:31.636798
2019-04-12 11:18:31,637 [salt.state       :1813][INFO    ][12089] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-12 11:18:33,056 [salt.loaded.ext.module.maasng:610 ][INFO    ][12089] n4ebyg
2019-04-12 11:18:33,057 [salt.loaded.ext.module.maasng:626 ][INFO    ][12089] sda
2019-04-12 11:18:33,731 [salt.loaded.ext.module.maasng:361 ][INFO    ][12089] n4ebyg
2019-04-12 11:18:33,853 [salt.loaded.ext.module.maasng:367 ][INFO    ][12089] [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/n4ebyg/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'system_id': u'n4ebyg', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'aba39e9b-b589-4256-9f17-eebb61622de7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n4ebyg', u'filesystem': {u'label': None, u'uuid': u'49be8413-33ca-4b5c-a41f-576476068ecd', 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/n4ebyg/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}], u'used_size': 2397998940160, u'id': 1, u'size': 2397998940160}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/n4ebyg/blockdevices/2/', u'uuid': u'984d373c-0da2-4696-83cd-da2e9d57e62b', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'label': u'root', u'uuid': u'7b28990b-4fcc-467b-9689-08298fa4dc87', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'n4ebyg', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'block_size': 4096, u'available_size': 0, u'serial': None, u'partitions': [], u'used_size': 2397988454400, u'id': 2, u'size': 2397988454400}]
2019-04-12 11:18:33,853 [salt.loaded.ext.module.maasng:632 ][INFO    ][12089] vgroot
2019-04-12 11:18:33,853 [salt.loaded.ext.module.maasng:635 ][INFO    ][12089] lvroot
2019-04-12 11:18:33,854 [salt.loaded.ext.module.maasng:639 ][INFO    ][12089] 107374182400
2019-04-12 11:18:34,601 [salt.loaded.ext.module.maasng:645 ][INFO    ][12089] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'status_action': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.38'], u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'50f161a1-ace9-401c-9f5f-8492216da7bc', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n4ebyg', u'device_id': 1, u'filesystem': {u'uuid': u'156c607b-0073-436e-b019-4525a3b1aa2c', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/n4ebyg/blockdevices/1/partition/7'}], u'filesystem': None, u'id': 1, u'name': u'sda', u'system_id': u'n4ebyg', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/n4ebyg/blockdevices/1/', u'size': 2397998940160}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/n4ebyg/', u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'n4ebyg', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'uuid': u'32fb1f46-826c-4617-a175-47a3e37297a2', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'5ed6c067-6df3-4d90-ab9f-3e08abd46c06', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id': 12, u'name': u'vgroot-lvroot', u'system_id': u'n4ebyg', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/n4ebyg/blockdevices/12/', u'size': 107374182400}], u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'50f161a1-ace9-401c-9f5f-8492216da7bc', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n4ebyg', u'device_id': 1, u'filesystem': {u'uuid': u'156c607b-0073-436e-b019-4525a3b1aa2c', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/n4ebyg/blockdevices/1/partition/7'}], u'filesystem': None, u'uuid': None, u'id': 1, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'n4ebyg', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/n4ebyg/blockdevices/1/', u'size': 2397998940160}, {u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'5ed6c067-6df3-4d90-ab9f-3e08abd46c06', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'32fb1f46-826c-4617-a175-47a3e37297a2', u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'n4ebyg', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/n4ebyg/blockdevices/12/', u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'n4ebyg', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'et7prd', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 15}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'et7prd', 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'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'et7prd', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'n4ebyg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n4ebyg/interfaces/5/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'50f161a1-ace9-401c-9f5f-8492216da7bc', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n4ebyg', u'device_id': 1, u'filesystem': {u'uuid': u'156c607b-0073-436e-b019-4525a3b1aa2c', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/n4ebyg/blockdevices/1/partition/7'}], u'filesystem': None, u'id': 1, u'name': u'sda', u'system_id': u'n4ebyg', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/n4ebyg/blockdevices/1/', u'size': 2397998940160}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'et7prd', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 15}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'et7prd', 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'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'et7prd', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'n4ebyg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n4ebyg/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 16}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'enabled': True, u'id': 9, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'n4ebyg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n4ebyg/interfaces/9/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 17}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'enabled': True, u'id': 10, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'n4ebyg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n4ebyg/interfaces/10/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 18}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'enabled': True, u'id': 11, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'n4ebyg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n4ebyg/interfaces/11/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-04-12 11:18:34,604 [salt.state       :300 ][INFO    ][12089] {'new': {'storage_layout': 'lvm'}}
2019-04-12 11:18:34,604 [salt.state       :1951][INFO    ][12089] Completed state [maas_machines_storage_cmp001_lvm] at time 11:18:34.604633 duration_in_ms=2967.834
2019-04-12 11:18:34,608 [salt.minion      :1711][INFO    ][12089] Returning information for job: 20190412111823839705
2019-04-12 11:18:35,239 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command state.apply with jid 20190412111835227220
2019-04-12 11:18:35,265 [salt.minion      :1432][INFO    ][12122] Starting a new job with PID 12122
2019-04-12 11:18:35,999 [salt.state       :915 ][INFO    ][12122] Loading fresh modules for state activity
2019-04-12 11:18:36,025 [salt.fileclient  :1219][INFO    ][12122] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-12 11:18:36,050 [salt.state       :1780][INFO    ][12122] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:18:36.050385
2019-04-12 11:18:36,050 [salt.state       :1813][INFO    ][12122] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-12 11:18:36,051 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12122] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-12 11:18:37,476 [salt.state       :300 ][INFO    ][12122] {'pid': 12129, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-12 11:18:37,477 [salt.state       :1951][INFO    ][12122] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:18:37.477265 duration_in_ms=1426.88
2019-04-12 11:18:37,478 [salt.state       :1780][INFO    ][12122] Running state [maas.deploy_machines] at time 11:18:37.478420
2019-04-12 11:18:37,478 [salt.state       :1813][INFO    ][12122] Executing state module.run for [maas.deploy_machines]
2019-04-12 11:18:37,478 [salt.utils.decorators:613 ][WARNING ][12122] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 11:18:38,080 [salt.loaded.ext.module.maas:684 ][INFO    ][12122] deploymachines hwe_kernel=hwe-16.04 system_id=dtaw3f distro_series=xenial
2019-04-12 11:18:40,681 [salt.loaded.ext.module.maas:684 ][INFO    ][12122] deploymachines hwe_kernel=hwe-16.04 system_id=n4ebyg distro_series=xenial
2019-04-12 11:18:43,442 [salt.loaded.ext.module.maas:684 ][INFO    ][12122] deploymachines hwe_kernel=hwe-16.04 system_id=egyhbt distro_series=xenial
2019-04-12 11:18:46,010 [salt.loaded.ext.module.maas:684 ][INFO    ][12122] deploymachines hwe_kernel=hwe-16.04 system_id=m8brsg distro_series=xenial
2019-04-12 11:18:48,794 [salt.loaded.ext.module.maas:684 ][INFO    ][12122] deploymachines hwe_kernel=hwe-16.04 system_id=r7dgww distro_series=xenial
2019-04-12 11:18:50,280 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111850270326
2019-04-12 11:18:50,308 [salt.minion      :1432][INFO    ][12392] Starting a new job with PID 12392
2019-04-12 11:18:50,326 [salt.minion      :1711][INFO    ][12392] Returning information for job: 20190412111850270326
2019-04-12 11:18:51,257 [salt.state       :300 ][INFO    ][12122] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-12 11:18:51,258 [salt.state       :1951][INFO    ][12122] Completed state [maas.deploy_machines] at time 11:18:51.258346 duration_in_ms=13779.923
2019-04-12 11:18:51,262 [salt.minion      :1711][INFO    ][12122] Returning information for job: 20190412111835227220
2019-04-12 11:18:51,906 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command state.apply with jid 20190412111851892549
2019-04-12 11:18:51,931 [salt.minion      :1432][INFO    ][12410] Starting a new job with PID 12410
2019-04-12 11:18:55,520 [salt.state       :915 ][INFO    ][12410] Loading fresh modules for state activity
2019-04-12 11:18:55,568 [salt.fileclient  :1219][INFO    ][12410] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-12 11:18:55,609 [salt.state       :1780][INFO    ][12410] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:18:55.609178
2019-04-12 11:18:55,609 [salt.state       :1813][INFO    ][12410] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-12 11:18:55,611 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12410] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-12 11:18:57,054 [salt.state       :300 ][INFO    ][12410] {'pid': 12423, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-12 11:18:57,055 [salt.state       :1951][INFO    ][12410] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:18:57.055222 duration_in_ms=1446.044
2019-04-12 11:18:57,056 [salt.state       :1780][INFO    ][12410] Running state [maas.wait_for_machine_status] at time 11:18:57.056389
2019-04-12 11:18:57,056 [salt.state       :1813][INFO    ][12410] Executing state module.run for [maas.wait_for_machine_status]
2019-04-12 11:18:57,056 [salt.utils.decorators:613 ][WARNING ][12410] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 11:19:00,275 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.7853899s left)
2019-04-12 11:19:07,029 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111907016543
2019-04-12 11:19:07,056 [salt.minion      :1432][INFO    ][12437] Starting a new job with PID 12437
2019-04-12 11:19:07,078 [salt.minion      :1711][INFO    ][12437] Returning information for job: 20190412111907016543
2019-04-12 11:19:33,378 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.68309593s left)
2019-04-12 11:19:37,083 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412111937070509
2019-04-12 11:19:37,110 [salt.minion      :1432][INFO    ][12490] Starting a new job with PID 12490
2019-04-12 11:19:37,130 [salt.minion      :1711][INFO    ][12490] Returning information for job: 20190412111937070509
2019-04-12 11:20:06,715 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.3457489s left)
2019-04-12 11:20:07,179 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112007166417
2019-04-12 11:20:07,206 [salt.minion      :1432][INFO    ][12510] Starting a new job with PID 12510
2019-04-12 11:20:07,226 [salt.minion      :1711][INFO    ][12510] Returning information for job: 20190412112007166417
2019-04-12 11:20:37,214 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112037208183
2019-04-12 11:20:37,237 [salt.minion      :1432][INFO    ][12653] Starting a new job with PID 12653
2019-04-12 11:20:37,257 [salt.minion      :1711][INFO    ][12653] Returning information for job: 20190412112037208183
2019-04-12 11:20:39,577 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.48371911s left)
2019-04-12 11:21:07,261 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112107248191
2019-04-12 11:21:07,286 [salt.minion      :1432][INFO    ][12814] Starting a new job with PID 12814
2019-04-12 11:21:07,307 [salt.minion      :1711][INFO    ][12814] Returning information for job: 20190412112107248191
2019-04-12 11:21:13,087 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.97380304s left)
2019-04-12 11:21:37,315 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112137303682
2019-04-12 11:21:37,341 [salt.minion      :1432][INFO    ][13479] Starting a new job with PID 13479
2019-04-12 11:21:37,363 [salt.minion      :1711][INFO    ][13479] Returning information for job: 20190412112137303682
2019-04-12 11:21:46,460 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.60049009s left)
2019-04-12 11:22:07,374 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112207361248
2019-04-12 11:22:07,401 [salt.minion      :1432][INFO    ][13501] Starting a new job with PID 13501
2019-04-12 11:22:07,422 [salt.minion      :1711][INFO    ][13501] Returning information for job: 20190412112207361248
2019-04-12 11:22:20,067 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.99416614s left)
2019-04-12 11:22:37,444 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112237432940
2019-04-12 11:22:37,469 [salt.minion      :1432][INFO    ][13799] Starting a new job with PID 13799
2019-04-12 11:22:37,489 [salt.minion      :1711][INFO    ][13799] Returning information for job: 20190412112237432940
2019-04-12 11:22:53,365 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.69583201s left)
2019-04-12 11:23:07,504 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112307491018
2019-04-12 11:23:07,530 [salt.minion      :1432][INFO    ][13824] Starting a new job with PID 13824
2019-04-12 11:23:07,551 [salt.minion      :1711][INFO    ][13824] Returning information for job: 20190412112307491018
2019-04-12 11:23:26,529 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.53186512s left)
2019-04-12 11:23:37,583 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112337570738
2019-04-12 11:23:37,610 [salt.minion      :1432][INFO    ][14364] Starting a new job with PID 14364
2019-04-12 11:23:37,631 [salt.minion      :1711][INFO    ][14364] Returning information for job: 20190412112337570738
2019-04-12 11:23:59,822 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.23848391s left)
2019-04-12 11:24:07,659 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112407647409
2019-04-12 11:24:07,680 [salt.minion      :1432][INFO    ][14424] Starting a new job with PID 14424
2019-04-12 11:24:07,695 [salt.minion      :1711][INFO    ][14424] Returning information for job: 20190412112407647409
2019-04-12 11:24:33,178 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.883044s left)
2019-04-12 11:24:37,737 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112437725207
2019-04-12 11:24:37,763 [salt.minion      :1432][INFO    ][14843] Starting a new job with PID 14843
2019-04-12 11:24:37,785 [salt.minion      :1711][INFO    ][14843] Returning information for job: 20190412112437725207
2019-04-12 11:25:06,583 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.47748709s left)
2019-04-12 11:25:07,817 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112507804080
2019-04-12 11:25:07,843 [salt.minion      :1432][INFO    ][14872] Starting a new job with PID 14872
2019-04-12 11:25:07,865 [salt.minion      :1711][INFO    ][14872] Returning information for job: 20190412112507804080
2019-04-12 11:25:37,904 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112537895948
2019-04-12 11:25:37,929 [salt.minion      :1432][INFO    ][14957] Starting a new job with PID 14957
2019-04-12 11:25:37,951 [salt.minion      :1711][INFO    ][14957] Returning information for job: 20190412112537895948
2019-04-12 11:25:40,184 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.87671804s left)
2019-04-12 11:26:07,963 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112607948127
2019-04-12 11:26:07,990 [salt.minion      :1432][INFO    ][15126] Starting a new job with PID 15126
2019-04-12 11:26:08,011 [salt.minion      :1711][INFO    ][15126] Returning information for job: 20190412112607948127
2019-04-12 11:26:13,302 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1813.75871706s left)
2019-04-12 11:26:38,146 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112638133331
2019-04-12 11:26:38,171 [salt.minion      :1432][INFO    ][15545] Starting a new job with PID 15545
2019-04-12 11:26:38,192 [salt.minion      :1711][INFO    ][15545] Returning information for job: 20190412112638133331
2019-04-12 11:26:46,721 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1780.33989406s left)
2019-04-12 11:27:08,249 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112708236179
2019-04-12 11:27:08,276 [salt.minion      :1432][INFO    ][15574] Starting a new job with PID 15574
2019-04-12 11:27:08,297 [salt.minion      :1711][INFO    ][15574] Returning information for job: 20190412112708236179
2019-04-12 11:27:20,241 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1746.82009506s left)
2019-04-12 11:27:38,360 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112738349042
2019-04-12 11:27:38,386 [salt.minion      :1432][INFO    ][15761] Starting a new job with PID 15761
2019-04-12 11:27:38,408 [salt.minion      :1711][INFO    ][15761] Returning information for job: 20190412112738349042
2019-04-12 11:27:53,860 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1713.20078897s left)
2019-04-12 11:28:08,480 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112808467736
2019-04-12 11:28:08,506 [salt.minion      :1432][INFO    ][15791] Starting a new job with PID 15791
2019-04-12 11:28:08,528 [salt.minion      :1711][INFO    ][15791] Returning information for job: 20190412112808467736
2019-04-12 11:28:27,221 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1679.83997893s left)
2019-04-12 11:28:38,617 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112838603947
2019-04-12 11:28:38,643 [salt.minion      :1432][INFO    ][15836] Starting a new job with PID 15836
2019-04-12 11:28:38,665 [salt.minion      :1711][INFO    ][15836] Returning information for job: 20190412112838603947
2019-04-12 11:29:00,677 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1646.38338614s left)
2019-04-12 11:29:08,760 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112908747876
2019-04-12 11:29:08,786 [salt.minion      :1432][INFO    ][15867] Starting a new job with PID 15867
2019-04-12 11:29:08,808 [salt.minion      :1711][INFO    ][15867] Returning information for job: 20190412112908747876
2019-04-12 11:29:34,163 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1612.89800596s left)
2019-04-12 11:29:38,909 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412112938896108
2019-04-12 11:29:38,934 [salt.minion      :1432][INFO    ][15914] Starting a new job with PID 15914
2019-04-12 11:29:38,955 [salt.minion      :1711][INFO    ][15914] Returning information for job: 20190412112938896108
2019-04-12 11:30:07,192 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1579.86867404s left)
2019-04-12 11:30:09,061 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113009048963
2019-04-12 11:30:09,085 [salt.minion      :1432][INFO    ][15947] Starting a new job with PID 15947
2019-04-12 11:30:09,104 [salt.minion      :1711][INFO    ][15947] Returning information for job: 20190412113009048963
2019-04-12 11:30:39,221 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113039207862
2019-04-12 11:30:39,246 [salt.minion      :1432][INFO    ][16004] Starting a new job with PID 16004
2019-04-12 11:30:39,266 [salt.minion      :1711][INFO    ][16004] Returning information for job: 20190412113039207862
2019-04-12 11:30:40,611 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1546.45024705s left)
2019-04-12 11:31:09,394 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113109379172
2019-04-12 11:31:09,420 [salt.minion      :1432][INFO    ][16039] Starting a new job with PID 16039
2019-04-12 11:31:09,442 [salt.minion      :1711][INFO    ][16039] Returning information for job: 20190412113109379172
2019-04-12 11:31:13,463 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1513.59816909s left)
2019-04-12 11:31:39,579 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113139566304
2019-04-12 11:31:39,606 [salt.minion      :1432][INFO    ][16105] Starting a new job with PID 16105
2019-04-12 11:31:39,629 [salt.minion      :1711][INFO    ][16105] Returning information for job: 20190412113139566304
2019-04-12 11:31:46,980 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1480.08038402s left)
2019-04-12 11:32:09,774 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113209762447
2019-04-12 11:32:09,801 [salt.minion      :1432][INFO    ][16133] Starting a new job with PID 16133
2019-04-12 11:32:09,823 [salt.minion      :1711][INFO    ][16133] Returning information for job: 20190412113209762447
2019-04-12 11:32:19,701 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1447.35979009s left)
2019-04-12 11:32:39,974 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113239962080
2019-04-12 11:32:40,000 [salt.minion      :1432][INFO    ][16179] Starting a new job with PID 16179
2019-04-12 11:32:40,022 [salt.minion      :1711][INFO    ][16179] Returning information for job: 20190412113239962080
2019-04-12 11:32:53,038 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1414.02270007s left)
2019-04-12 11:33:10,185 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113310172674
2019-04-12 11:33:10,213 [salt.minion      :1432][INFO    ][16210] Starting a new job with PID 16210
2019-04-12 11:33:10,231 [salt.minion      :1711][INFO    ][16210] Returning information for job: 20190412113310172674
2019-04-12 11:33:26,148 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1380.91240001s left)
2019-04-12 11:33:40,412 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113340399045
2019-04-12 11:33:40,439 [salt.minion      :1432][INFO    ][16256] Starting a new job with PID 16256
2019-04-12 11:33:40,458 [salt.minion      :1711][INFO    ][16256] Returning information for job: 20190412113340399045
2019-04-12 11:33:59,602 [salt.loaded.ext.module.maas:1023][INFO    ][12410] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1347.45867395s left)
2019-04-12 11:34:10,435 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113410422367
2019-04-12 11:34:10,461 [salt.minion      :1432][INFO    ][16282] Starting a new job with PID 16282
2019-04-12 11:34:10,483 [salt.minion      :1711][INFO    ][16282] Returning information for job: 20190412113410422367
2019-04-12 11:34:31,855 [salt.loaded.ext.module.maas:993 ][INFO    ][12410] Machine egyhbt mark broken
2019-04-12 11:34:32,597 [salt.loaded.ext.module.maas:996 ][INFO    ][12410] Machine egyhbt mark fixed
2019-04-12 11:34:33,758 [salt.loaded.ext.module.maas:684 ][INFO    ][12410] deploymachines hwe_kernel=hwe-16.04 system_id=egyhbt distro_series=xenial
2019-04-12 11:34:36,548 [salt.loaded.ext.module.maas:160 ][ERROR   ][12410] 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-12 11:34:36,550 [salt.state       :302 ][ERROR   ][12410] 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-12 11:34:36,551 [salt.state       :1951][INFO    ][12410] Completed state [maas.wait_for_machine_status] at time 11:34:36.551409 duration_in_ms=939495.013
2019-04-12 11:34:36,560 [salt.minion      :1711][INFO    ][12410] Returning information for job: 20190412111851892549
2019-04-12 11:34:47,304 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command pillar.get with jid 20190412113447292644
2019-04-12 11:34:47,327 [salt.minion      :1432][INFO    ][16399] Starting a new job with PID 16399
2019-04-12 11:34:47,332 [salt.minion      :1711][INFO    ][16399] Returning information for job: 20190412113447292644
2019-04-12 11:34:47,845 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command service.status with jid 20190412113447832597
2019-04-12 11:34:47,869 [salt.minion      :1432][INFO    ][16404] Starting a new job with PID 16404
2019-04-12 11:34:48,240 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][16404] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-12 11:34:48,274 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][16404] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-12 11:34:48,290 [salt.minion      :1711][INFO    ][16404] Returning information for job: 20190412113447832597
2019-04-12 11:34:48,875 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command state.apply with jid 20190412113448866220
2019-04-12 11:34:48,901 [salt.minion      :1432][INFO    ][16415] Starting a new job with PID 16415
2019-04-12 11:34:52,675 [salt.state       :915 ][INFO    ][16415] Loading fresh modules for state activity
2019-04-12 11:34:53,120 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16415] Executing command 'salt-minion --version' in directory '/root'
2019-04-12 11:34:53,414 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16415] Executing command 'salt-minion --version' in directory '/root'
2019-04-12 11:34:54,339 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16415] Executing command 'salt-minion --version' in directory '/root'
2019-04-12 11:34:54,690 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16415] Executing command 'salt-minion --version' in directory '/root'
2019-04-12 11:34:56,155 [salt.state       :1780][INFO    ][16415] Running state [salt-minion] at time 11:34:56.155609
2019-04-12 11:34:56,155 [salt.state       :1813][INFO    ][16415] Executing state pkg.installed for [salt-minion]
2019-04-12 11:34:56,156 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16415] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-12 11:34:56,257 [salt.state       :300 ][INFO    ][16415] All specified packages are already installed
2019-04-12 11:34:56,258 [salt.state       :1951][INFO    ][16415] Completed state [salt-minion] at time 11:34:56.258010 duration_in_ms=102.401
2019-04-12 11:34:56,258 [salt.state       :1780][INFO    ][16415] Running state [salt_minion_dependency_packages] at time 11:34:56.258408
2019-04-12 11:34:56,258 [salt.state       :1813][INFO    ][16415] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-12 11:34:56,266 [salt.state       :300 ][INFO    ][16415] All specified packages are already installed
2019-04-12 11:34:56,266 [salt.state       :1951][INFO    ][16415] Completed state [salt_minion_dependency_packages] at time 11:34:56.266869 duration_in_ms=8.461
2019-04-12 11:34:56,270 [salt.state       :1780][INFO    ][16415] Running state [/etc/salt/minion.d/minion.conf] at time 11:34:56.270883
2019-04-12 11:34:56,271 [salt.state       :1813][INFO    ][16415] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-12 11:34:56,528 [salt.state       :300 ][INFO    ][16415] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-12 11:34:56,528 [salt.state       :1951][INFO    ][16415] Completed state [/etc/salt/minion.d/minion.conf] at time 11:34:56.528578 duration_in_ms=257.695
2019-04-12 11:34:56,528 [salt.state       :1780][INFO    ][16415] Running state [python-netaddr] at time 11:34:56.528796
2019-04-12 11:34:56,529 [salt.state       :1813][INFO    ][16415] Executing state pkg.installed for [python-netaddr]
2019-04-12 11:34:56,534 [salt.state       :300 ][INFO    ][16415] All specified packages are already installed
2019-04-12 11:34:56,534 [salt.state       :1951][INFO    ][16415] Completed state [python-netaddr] at time 11:34:56.534143 duration_in_ms=5.348
2019-04-12 11:34:56,536 [salt.state       :1780][INFO    ][16415] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 11:34:56.536713
2019-04-12 11:34:56,536 [salt.state       :1813][INFO    ][16415] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-12 11:34:56,547 [salt.state       :300 ][INFO    ][16415] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-12 11:34:56,547 [salt.state       :1951][INFO    ][16415] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 11:34:56.547646 duration_in_ms=10.933
2019-04-12 11:34:56,548 [salt.state       :1780][INFO    ][16415] Running state [salt-minion] at time 11:34:56.548382
2019-04-12 11:34:56,548 [salt.state       :1813][INFO    ][16415] Executing state service.running for [salt-minion]
2019-04-12 11:34:56,549 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16415] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-12 11:34:56,580 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16415] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-12 11:34:56,594 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16415] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-12 11:34:56,609 [salt.state       :300 ][INFO    ][16415] The service salt-minion is already running
2019-04-12 11:34:56,609 [salt.state       :1951][INFO    ][16415] Completed state [salt-minion] at time 11:34:56.609285 duration_in_ms=60.903
2019-04-12 11:34:56,610 [salt.state       :1780][INFO    ][16415] Running state [/etc/salt/grains.d] at time 11:34:56.610356
2019-04-12 11:34:56,610 [salt.state       :1813][INFO    ][16415] Executing state file.directory for [/etc/salt/grains.d]
2019-04-12 11:34:56,611 [salt.state       :300 ][INFO    ][16415] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-12 11:34:56,611 [salt.state       :1951][INFO    ][16415] Completed state [/etc/salt/grains.d] at time 11:34:56.611469 duration_in_ms=1.112
2019-04-12 11:34:56,611 [salt.state       :1780][INFO    ][16415] Running state [/etc/salt/grains] at time 11:34:56.611959
2019-04-12 11:34:56,612 [salt.state       :1813][INFO    ][16415] Executing state file.managed for [/etc/salt/grains]
2019-04-12 11:34:56,612 [salt.state       :300 ][INFO    ][16415] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-12 11:34:56,612 [salt.state       :1951][INFO    ][16415] Completed state [/etc/salt/grains] at time 11:34:56.612735 duration_in_ms=0.775
2019-04-12 11:34:56,613 [salt.state       :1780][INFO    ][16415] Running state [/etc/salt/grains.d/placeholder] at time 11:34:56.613060
2019-04-12 11:34:56,613 [salt.state       :1813][INFO    ][16415] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-12 11:34:56,613 [salt.state       :300 ][INFO    ][16415] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-12 11:34:56,613 [salt.state       :1951][INFO    ][16415] Completed state [/etc/salt/grains.d/placeholder] at time 11:34:56.613795 duration_in_ms=0.735
2019-04-12 11:34:56,614 [salt.state       :1780][INFO    ][16415] Running state [/etc/salt/grains.d/sphinx] at time 11:34:56.614119
2019-04-12 11:34:56,614 [salt.state       :1813][INFO    ][16415] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-12 11:34:56,624 [salt.state       :300 ][INFO    ][16415] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-12 11:34:56,624 [salt.state       :1951][INFO    ][16415] Completed state [/etc/salt/grains.d/sphinx] at time 11:34:56.624210 duration_in_ms=10.091
2019-04-12 11:34:56,625 [salt.state       :1780][INFO    ][16415] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 11:34:56.625820
2019-04-12 11:34:56,626 [salt.state       :1813][INFO    ][16415] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-12 11:34:56,626 [salt.state       :300 ][INFO    ][16415] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-12 11:34:56,626 [salt.state       :1951][INFO    ][16415] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 11:34:56.626423 duration_in_ms=0.603
2019-04-12 11:34:56,626 [salt.state       :1780][INFO    ][16415] Running state [/etc/salt/grains.d/dns_records] at time 11:34:56.626773
2019-04-12 11:34:56,626 [salt.state       :1813][INFO    ][16415] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-12 11:34:56,636 [salt.state       :300 ][INFO    ][16415] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-12 11:34:56,636 [salt.state       :1951][INFO    ][16415] Completed state [/etc/salt/grains.d/dns_records] at time 11:34:56.636548 duration_in_ms=9.774
2019-04-12 11:34:56,638 [salt.state       :1780][INFO    ][16415] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 11:34:56.638103
2019-04-12 11:34:56,638 [salt.state       :1813][INFO    ][16415] 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-12 11:34:56,639 [salt.state       :300 ][INFO    ][16415] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-12 11:34:56,639 [salt.state       :1951][INFO    ][16415] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 11:34:56.639439 duration_in_ms=1.336
2019-04-12 11:34:56,640 [salt.state       :1780][INFO    ][16415] Running state [/etc/salt/grains.d/salt] at time 11:34:56.640225
2019-04-12 11:34:56,640 [salt.state       :1813][INFO    ][16415] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-12 11:34:56,660 [salt.state       :300 ][INFO    ][16415] File /etc/salt/grains.d/salt is in the correct state
2019-04-12 11:34:56,660 [salt.state       :1951][INFO    ][16415] Completed state [/etc/salt/grains.d/salt] at time 11:34:56.660516 duration_in_ms=20.291
2019-04-12 11:34:56,661 [salt.state       :1780][INFO    ][16415] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 11:34:56.661919
2019-04-12 11:34:56,662 [salt.state       :1813][INFO    ][16415] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-12 11:34:56,662 [salt.state       :300 ][INFO    ][16415] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-12 11:34:56,663 [salt.state       :1951][INFO    ][16415] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 11:34:56.663184 duration_in_ms=1.265
2019-04-12 11:34:56,666 [salt.state       :1780][INFO    ][16415] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 11:34:56.666226
2019-04-12 11:34:56,666 [salt.state       :1813][INFO    ][16415] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-12 11:34:56,667 [salt.state       :300 ][INFO    ][16415] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-12 11:34:56,667 [salt.state       :1951][INFO    ][16415] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 11:34:56.667522 duration_in_ms=1.296
2019-04-12 11:34:56,668 [salt.state       :1780][INFO    ][16415] Running state [mine.update] at time 11:34:56.668590
2019-04-12 11:34:56,669 [salt.state       :1813][INFO    ][16415] Executing state module.wait for [mine.update]
2019-04-12 11:34:56,669 [salt.state       :300 ][INFO    ][16415] No changes made for mine.update
2019-04-12 11:34:56,669 [salt.state       :1951][INFO    ][16415] Completed state [mine.update] at time 11:34:56.669765 duration_in_ms=1.176
2019-04-12 11:34:56,670 [salt.state       :1780][INFO    ][16415] Running state [ca-certificates] at time 11:34:56.670128
2019-04-12 11:34:56,670 [salt.state       :1813][INFO    ][16415] Executing state pkg.installed for [ca-certificates]
2019-04-12 11:34:56,681 [salt.state       :300 ][INFO    ][16415] All specified packages are already installed
2019-04-12 11:34:56,682 [salt.state       :1951][INFO    ][16415] Completed state [ca-certificates] at time 11:34:56.682051 duration_in_ms=11.922
2019-04-12 11:34:56,683 [salt.state       :1780][INFO    ][16415] Running state [update-ca-certificates] at time 11:34:56.683083
2019-04-12 11:34:56,683 [salt.state       :1813][INFO    ][16415] Executing state cmd.wait for [update-ca-certificates]
2019-04-12 11:34:56,684 [salt.state       :300 ][INFO    ][16415] No changes made for update-ca-certificates
2019-04-12 11:34:56,684 [salt.state       :1951][INFO    ][16415] Completed state [update-ca-certificates] at time 11:34:56.684279 duration_in_ms=1.196
2019-04-12 11:34:56,684 [salt.state       :1780][INFO    ][16415] Running state [iptables] at time 11:34:56.684628
2019-04-12 11:34:56,684 [salt.state       :1813][INFO    ][16415] Executing state pkg.installed for [iptables]
2019-04-12 11:34:56,695 [salt.state       :300 ][INFO    ][16415] All specified packages are already installed
2019-04-12 11:34:56,695 [salt.state       :1951][INFO    ][16415] Completed state [iptables] at time 11:34:56.695281 duration_in_ms=10.653
2019-04-12 11:34:56,695 [salt.state       :1780][INFO    ][16415] Running state [iptables-persistent] at time 11:34:56.695633
2019-04-12 11:34:56,695 [salt.state       :1813][INFO    ][16415] Executing state pkg.installed for [iptables-persistent]
2019-04-12 11:34:56,705 [salt.state       :300 ][INFO    ][16415] All specified packages are already installed
2019-04-12 11:34:56,705 [salt.state       :1951][INFO    ][16415] Completed state [iptables-persistent] at time 11:34:56.705657 duration_in_ms=10.023
2019-04-12 11:34:56,707 [salt.state       :1780][INFO    ][16415] Running state [iptables_modules_v4_load] at time 11:34:56.707082
2019-04-12 11:34:56,707 [salt.state       :1813][INFO    ][16415] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-12 11:34:56,708 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16415] Executing command 'lsmod' in directory '/root'
2019-04-12 11:34:56,731 [salt.state       :300 ][INFO    ][16415] Kernel modules iptable_filter, ip_tables are already present
2019-04-12 11:34:56,732 [salt.state       :1951][INFO    ][16415] Completed state [iptables_modules_v4_load] at time 11:34:56.732014 duration_in_ms=24.932
2019-04-12 11:34:56,732 [salt.state       :1780][INFO    ][16415] Running state [/etc/iptables/rules.v4] at time 11:34:56.732856
2019-04-12 11:34:56,733 [salt.state       :1813][INFO    ][16415] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-12 11:34:56,858 [salt.state       :300 ][INFO    ][16415] File /etc/iptables/rules.v4 is in the correct state
2019-04-12 11:34:56,858 [salt.state       :1951][INFO    ][16415] Completed state [/etc/iptables/rules.v4] at time 11:34:56.858629 duration_in_ms=125.772
2019-04-12 11:34:56,860 [salt.state       :1780][INFO    ][16415] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 11:34:56.860246
2019-04-12 11:34:56,860 [salt.state       :1813][INFO    ][16415] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-12 11:34:56,861 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16415] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-12 11:34:56,882 [salt.state       :300 ][INFO    ][16415] onlyif execution failed
2019-04-12 11:34:56,882 [salt.state       :1951][INFO    ][16415] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 11:34:56.882732 duration_in_ms=22.486
2019-04-12 11:34:56,884 [salt.state       :1780][INFO    ][16415] Running state [netfilter-persistent] at time 11:34:56.884424
2019-04-12 11:34:56,885 [salt.state       :1813][INFO    ][16415] Executing state service.running for [netfilter-persistent]
2019-04-12 11:34:56,886 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16415] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-12 11:34:56,907 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16415] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-12 11:34:56,926 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16415] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-12 11:34:56,943 [salt.state       :300 ][INFO    ][16415] The service netfilter-persistent is already running
2019-04-12 11:34:56,944 [salt.state       :1951][INFO    ][16415] Completed state [netfilter-persistent] at time 11:34:56.943971 duration_in_ms=59.546
2019-04-12 11:34:56,945 [salt.state       :1780][INFO    ][16415] Running state [iptables_extra.remove_stale_tables] at time 11:34:56.945237
2019-04-12 11:34:56,945 [salt.state       :1813][INFO    ][16415] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-12 11:34:56,946 [salt.state       :300 ][INFO    ][16415] No changes made for iptables_extra.remove_stale_tables
2019-04-12 11:34:56,946 [salt.state       :1951][INFO    ][16415] Completed state [iptables_extra.remove_stale_tables] at time 11:34:56.946620 duration_in_ms=1.384
2019-04-12 11:34:56,947 [salt.state       :1780][INFO    ][16415] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 11:34:56.947004
2019-04-12 11:34:56,947 [salt.state       :1813][INFO    ][16415] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-12 11:34:56,948 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16415] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-12 11:34:56,963 [salt.state       :300 ][INFO    ][16415] onlyif execution failed
2019-04-12 11:34:56,964 [salt.state       :1951][INFO    ][16415] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 11:34:56.964118 duration_in_ms=17.114
2019-04-12 11:34:56,965 [salt.state       :1780][INFO    ][16415] Running state [/etc/iptables/rules.v6] at time 11:34:56.965378
2019-04-12 11:34:56,965 [salt.state       :1813][INFO    ][16415] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-12 11:34:56,966 [salt.state       :300 ][INFO    ][16415] File /etc/iptables/rules.v6 is not present
2019-04-12 11:34:56,966 [salt.state       :1951][INFO    ][16415] Completed state [/etc/iptables/rules.v6] at time 11:34:56.966709 duration_in_ms=1.331
2019-04-12 11:34:56,967 [salt.state       :1780][INFO    ][16415] Running state [iptables_extra.flush_all] at time 11:34:56.967610
2019-04-12 11:34:56,968 [salt.state       :1813][INFO    ][16415] Executing state module.wait for [iptables_extra.flush_all]
2019-04-12 11:34:56,968 [salt.state       :300 ][INFO    ][16415] No changes made for iptables_extra.flush_all
2019-04-12 11:34:56,968 [salt.state       :1951][INFO    ][16415] Completed state [iptables_extra.flush_all] at time 11:34:56.968697 duration_in_ms=1.087
2019-04-12 11:34:56,972 [salt.minion      :1711][INFO    ][16415] Returning information for job: 20190412113448866220
2019-04-12 11:34:57,610 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command state.apply with jid 20190412113457597902
2019-04-12 11:34:57,637 [salt.minion      :1432][INFO    ][16491] Starting a new job with PID 16491
2019-04-12 11:34:58,395 [salt.state       :915 ][INFO    ][16491] Loading fresh modules for state activity
2019-04-12 11:34:59,072 [salt.state       :1780][INFO    ][16491] Running state [maas-rack-controller] at time 11:34:59.072504
2019-04-12 11:34:59,072 [salt.state       :1813][INFO    ][16491] Executing state pkg.installed for [maas-rack-controller]
2019-04-12 11:34:59,073 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16491] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-12 11:34:59,161 [salt.state       :300 ][INFO    ][16491] All specified packages are already installed
2019-04-12 11:34:59,161 [salt.state       :1951][INFO    ][16491] Completed state [maas-rack-controller] at time 11:34:59.161614 duration_in_ms=89.11
2019-04-12 11:34:59,161 [salt.state       :1780][INFO    ][16491] Running state [ipmitool] at time 11:34:59.161925
2019-04-12 11:34:59,162 [salt.state       :1813][INFO    ][16491] Executing state pkg.installed for [ipmitool]
2019-04-12 11:34:59,168 [salt.state       :300 ][INFO    ][16491] All specified packages are already installed
2019-04-12 11:34:59,168 [salt.state       :1951][INFO    ][16491] Completed state [ipmitool] at time 11:34:59.168281 duration_in_ms=6.356
2019-04-12 11:34:59,171 [salt.state       :1780][INFO    ][16491] Running state [/etc/maas/rackd.conf] at time 11:34:59.170954
2019-04-12 11:34:59,171 [salt.state       :1813][INFO    ][16491] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-12 11:34:59,172 [salt.state       :300 ][INFO    ][16491] No changes needed to be made
2019-04-12 11:34:59,172 [salt.state       :1951][INFO    ][16491] Completed state [/etc/maas/rackd.conf] at time 11:34:59.172361 duration_in_ms=1.407
2019-04-12 11:34:59,172 [salt.state       :1780][INFO    ][16491] Running state [/etc/maas/rackd.conf] at time 11:34:59.172588
2019-04-12 11:34:59,172 [salt.state       :1813][INFO    ][16491] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-12 11:34:59,173 [salt.loaded.int.states.file:2298][WARNING ][16491] 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-12 11:34:59,173 [salt.state       :300 ][INFO    ][16491] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-12 11:34:59,173 [salt.state       :1951][INFO    ][16491] Completed state [/etc/maas/rackd.conf] at time 11:34:59.173720 duration_in_ms=1.132
2019-04-12 11:34:59,174 [salt.state       :1780][INFO    ][16491] Running state [maas-rackd] at time 11:34:59.174567
2019-04-12 11:34:59,174 [salt.state       :1813][INFO    ][16491] Executing state service.running for [maas-rackd]
2019-04-12 11:34:59,175 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16491] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-12 11:34:59,210 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16491] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-12 11:34:59,227 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16491] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-12 11:34:59,248 [salt.state       :300 ][INFO    ][16491] The service maas-rackd is already running
2019-04-12 11:34:59,248 [salt.state       :1951][INFO    ][16491] Completed state [maas-rackd] at time 11:34:59.248625 duration_in_ms=74.057
2019-04-12 11:34:59,251 [salt.minion      :1711][INFO    ][16491] Returning information for job: 20190412113457597902
2019-04-12 11:34:59,819 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command state.apply with jid 20190412113459805970
2019-04-12 11:34:59,844 [salt.minion      :1432][INFO    ][16515] Starting a new job with PID 16515
2019-04-12 11:35:00,616 [salt.state       :915 ][INFO    ][16515] Loading fresh modules for state activity
2019-04-12 11:35:01,292 [salt.state       :1780][INFO    ][16515] Running state [maas-region-controller] at time 11:35:01.292401
2019-04-12 11:35:01,292 [salt.state       :1813][INFO    ][16515] Executing state pkg.installed for [maas-region-controller]
2019-04-12 11:35:01,293 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16515] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-12 11:35:01,387 [salt.state       :300 ][INFO    ][16515] All specified packages are already installed
2019-04-12 11:35:01,388 [salt.state       :1951][INFO    ][16515] Completed state [maas-region-controller] at time 11:35:01.387958 duration_in_ms=95.557
2019-04-12 11:35:01,388 [salt.state       :1780][INFO    ][16515] Running state [python-oauth] at time 11:35:01.388287
2019-04-12 11:35:01,388 [salt.state       :1813][INFO    ][16515] Executing state pkg.installed for [python-oauth]
2019-04-12 11:35:01,395 [salt.state       :300 ][INFO    ][16515] All specified packages are already installed
2019-04-12 11:35:01,395 [salt.state       :1951][INFO    ][16515] Completed state [python-oauth] at time 11:35:01.395266 duration_in_ms=6.979
2019-04-12 11:35:01,398 [salt.state       :1780][INFO    ][16515] Running state [/etc/maas/regiond.conf] at time 11:35:01.398439
2019-04-12 11:35:01,398 [salt.state       :1813][INFO    ][16515] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-12 11:35:01,450 [salt.state       :300 ][INFO    ][16515] No changes needed to be made
2019-04-12 11:35:01,450 [salt.state       :1951][INFO    ][16515] Completed state [/etc/maas/regiond.conf] at time 11:35:01.450616 duration_in_ms=52.176
2019-04-12 11:35:01,451 [salt.state       :1780][INFO    ][16515] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 11:35:01.451202
2019-04-12 11:35:01,451 [salt.state       :1813][INFO    ][16515] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-12 11:35:01,521 [salt.state       :300 ][INFO    ][16515] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-12 11:35:01,521 [salt.state       :1951][INFO    ][16515] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 11:35:01.521643 duration_in_ms=70.442
2019-04-12 11:35:01,522 [salt.state       :1780][INFO    ][16515] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:35:01.522218
2019-04-12 11:35:01,522 [salt.state       :1813][INFO    ][16515] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-12 11:35:01,535 [salt.state       :300 ][INFO    ][16515] No changes needed to be made
2019-04-12 11:35:01,535 [salt.state       :1951][INFO    ][16515] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:35:01.535285 duration_in_ms=13.067
2019-04-12 11:35:01,535 [salt.state       :1780][INFO    ][16515] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:35:01.535872
2019-04-12 11:35:01,536 [salt.state       :1813][INFO    ][16515] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-12 11:35:01,559 [salt.state       :300 ][INFO    ][16515] No changes needed to be made
2019-04-12 11:35:01,559 [salt.state       :1951][INFO    ][16515] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:35:01.559239 duration_in_ms=23.366
2019-04-12 11:35:01,559 [salt.state       :1780][INFO    ][16515] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 11:35:01.559814
2019-04-12 11:35:01,560 [salt.state       :1813][INFO    ][16515] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-12 11:35:01,586 [salt.state       :300 ][INFO    ][16515] No changes needed to be made
2019-04-12 11:35:01,587 [salt.state       :1951][INFO    ][16515] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 11:35:01.587194 duration_in_ms=27.38
2019-04-12 11:35:01,587 [salt.state       :1780][INFO    ][16515] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 11:35:01.587725
2019-04-12 11:35:01,588 [salt.state       :1813][INFO    ][16515] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-12 11:35:01,599 [salt.state       :300 ][INFO    ][16515] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-12 11:35:01,600 [salt.state       :1951][INFO    ][16515] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 11:35:01.600171 duration_in_ms=12.447
2019-04-12 11:35:01,601 [salt.state       :1780][INFO    ][16515] Running state [a2enmod headers] at time 11:35:01.601444
2019-04-12 11:35:01,601 [salt.state       :1813][INFO    ][16515] Executing state cmd.run for [a2enmod headers]
2019-04-12 11:35:01,602 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16515] Executing command 'a2enmod headers' in directory '/root'
2019-04-12 11:35:01,676 [salt.state       :300 ][INFO    ][16515] {'pid': 16534, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-12 11:35:01,677 [salt.state       :1951][INFO    ][16515] Completed state [a2enmod headers] at time 11:35:01.677410 duration_in_ms=75.966
2019-04-12 11:35:01,678 [salt.state       :1780][INFO    ][16515] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 11:35:01.678042
2019-04-12 11:35:01,678 [salt.state       :1813][INFO    ][16515] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-12 11:35:01,697 [salt.state       :300 ][INFO    ][16515] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-12 11:35:01,698 [salt.state       :1951][INFO    ][16515] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 11:35:01.697935 duration_in_ms=19.894
2019-04-12 11:35:01,698 [salt.state       :1780][INFO    ][16515] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 11:35:01.698763
2019-04-12 11:35:01,699 [salt.state       :1813][INFO    ][16515] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-12 11:35:01,953 [salt.state       :300 ][INFO    ][16515] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-12 11:35:01,953 [salt.state       :1951][INFO    ][16515] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 11:35:01.953679 duration_in_ms=254.916
2019-04-12 11:35:01,954 [salt.state       :1780][INFO    ][16515] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 11:35:01.954777
2019-04-12 11:35:01,955 [salt.state       :1813][INFO    ][16515] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-12 11:35:02,024 [salt.state       :300 ][INFO    ][16515] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-12 11:35:02,025 [salt.state       :1951][INFO    ][16515] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 11:35:02.024968 duration_in_ms=70.19
2019-04-12 11:35:02,025 [salt.state       :1780][INFO    ][16515] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 11:35:02.025610
2019-04-12 11:35:02,025 [salt.state       :1813][INFO    ][16515] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-12 11:35:02,090 [salt.state       :300 ][INFO    ][16515] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-12 11:35:02,090 [salt.state       :1951][INFO    ][16515] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 11:35:02.090832 duration_in_ms=65.223
2019-04-12 11:35:02,091 [salt.state       :1780][INFO    ][16515] Running state [/root/.pgpass] at time 11:35:02.091134
2019-04-12 11:35:02,091 [salt.state       :1813][INFO    ][16515] Executing state file.managed for [/root/.pgpass]
2019-04-12 11:35:02,138 [salt.state       :300 ][INFO    ][16515] File /root/.pgpass is in the correct state
2019-04-12 11:35:02,138 [salt.state       :1951][INFO    ][16515] Completed state [/root/.pgpass] at time 11:35:02.138616 duration_in_ms=47.482
2019-04-12 11:35:02,144 [salt.state       :1780][INFO    ][16515] Running state [maas-region syncdb --noinput] at time 11:35:02.144438
2019-04-12 11:35:02,144 [salt.state       :1813][INFO    ][16515] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-12 11:35:02,145 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16515] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-12 11:35:04,303 [salt.state       :300 ][INFO    ][16515] {'pid': 16547, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, piston3, contenttypes, sites, sessions, maasserver, auth\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-12 11:35:04,304 [salt.state       :1951][INFO    ][16515] Completed state [maas-region syncdb --noinput] at time 11:35:04.304453 duration_in_ms=2160.015
2019-04-12 11:35:04,304 [salt.state       :2022][WARNING ][16515] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-12 11:35:04,307 [salt.state       :1780][INFO    ][16515] Running state [maas-regiond] at time 11:35:04.307823
2019-04-12 11:35:04,308 [salt.state       :1813][INFO    ][16515] Executing state service.running for [maas-regiond]
2019-04-12 11:35:04,309 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16515] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-12 11:35:04,341 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16515] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-12 11:35:04,355 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16515] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-12 11:35:04,369 [salt.state       :300 ][INFO    ][16515] The service maas-regiond is already running
2019-04-12 11:35:04,370 [salt.state       :1951][INFO    ][16515] Completed state [maas-regiond] at time 11:35:04.369990 duration_in_ms=62.166
2019-04-12 11:35:04,372 [salt.state       :1780][INFO    ][16515] Running state [bind9] at time 11:35:04.372363
2019-04-12 11:35:04,372 [salt.state       :1813][INFO    ][16515] Executing state service.running for [bind9]
2019-04-12 11:35:04,373 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16515] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-12 11:35:04,388 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16515] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-12 11:35:04,402 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16515] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-12 11:35:04,415 [salt.state       :300 ][INFO    ][16515] The service bind9 is already running
2019-04-12 11:35:04,415 [salt.state       :1951][INFO    ][16515] Completed state [bind9] at time 11:35:04.415775 duration_in_ms=43.412
2019-04-12 11:35:04,418 [salt.state       :1780][INFO    ][16515] Running state [apache2] at time 11:35:04.418004
2019-04-12 11:35:04,418 [salt.state       :1813][INFO    ][16515] Executing state service.running for [apache2]
2019-04-12 11:35:04,419 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16515] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-12 11:35:04,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16515] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-12 11:35:04,446 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16515] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-12 11:35:04,463 [salt.state       :300 ][INFO    ][16515] The service apache2 is already running
2019-04-12 11:35:04,463 [salt.state       :1951][INFO    ][16515] Completed state [apache2] at time 11:35:04.463562 duration_in_ms=45.559
2019-04-12 11:35:04,465 [salt.state       :1780][INFO    ][16515] Running state [maasng.wait_for_http_code] at time 11:35:04.465187
2019-04-12 11:35:04,465 [salt.state       :1813][INFO    ][16515] Executing state module.run for [maasng.wait_for_http_code]
2019-04-12 11:35:04,466 [salt.utils.decorators:613 ][WARNING ][16515] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 11:35:04,577 [salt.state       :300 ][INFO    ][16515] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-12 11:35:04,577 [salt.state       :1951][INFO    ][16515] Completed state [maasng.wait_for_http_code] at time 11:35:04.577656 duration_in_ms=112.469
2019-04-12 11:35:04,579 [salt.state       :1780][INFO    ][16515] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 11:35:04.578973
2019-04-12 11:35:04,579 [salt.state       :1813][INFO    ][16515] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-12 11:35:04,580 [salt.state       :300 ][INFO    ][16515] /var/lib/maas/.setup_admin exists
2019-04-12 11:35:04,580 [salt.state       :1951][INFO    ][16515] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 11:35:04.580426 duration_in_ms=1.454
2019-04-12 11:35:04,581 [salt.state       :1780][INFO    ][16515] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:35:04.581426
2019-04-12 11:35:04,581 [salt.state       :1813][INFO    ][16515] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-12 11:35:04,582 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16515] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-12 11:35:05,999 [salt.state       :300 ][INFO    ][16515] {'pid': 16568, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-12 11:35:05,999 [salt.state       :1951][INFO    ][16515] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:35:05.999834 duration_in_ms=1418.408
2019-04-12 11:35:06,010 [salt.state       :1780][INFO    ][16515] Running state [maas_region_boot_source_resources_mirror] at time 11:35:06.010683
2019-04-12 11:35:06,011 [salt.state       :1813][INFO    ][16515] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-12 11:35:06,123 [salt.state       :300 ][INFO    ][16515] {'changes': {}}
2019-04-12 11:35:06,124 [salt.state       :1951][INFO    ][16515] Completed state [maas_region_boot_source_resources_mirror] at time 11:35:06.124022 duration_in_ms=113.338
2019-04-12 11:35:06,125 [salt.state       :1780][INFO    ][16515] Running state [maasng.boot_resources_import] at time 11:35:06.125083
2019-04-12 11:35:06,125 [salt.state       :1813][INFO    ][16515] Executing state module.run for [maasng.boot_resources_import]
2019-04-12 11:35:06,126 [salt.utils.decorators:613 ][WARNING ][16515] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 11:35:06,226 [salt.loaded.ext.module.maasng:1600][INFO    ][16515] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-12 11:35:11,275 [salt.loaded.ext.module.maasng:1600][INFO    ][16515] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-12 11:35:14,894 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113514881997
2019-04-12 11:35:14,920 [salt.minion      :1432][INFO    ][16636] Starting a new job with PID 16636
2019-04-12 11:35:14,942 [salt.minion      :1711][INFO    ][16636] Returning information for job: 20190412113514881997
2019-04-12 11:35:16,336 [salt.loaded.ext.module.maasng:1600][INFO    ][16515] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-04-12 11:35:21,457 [salt.state       :300 ][INFO    ][16515] {'ret': True}
2019-04-12 11:35:21,458 [salt.state       :1951][INFO    ][16515] Completed state [maasng.boot_resources_import] at time 11:35:21.458051 duration_in_ms=15332.968
2019-04-12 11:35:21,459 [salt.state       :1780][INFO    ][16515] Running state [maas_region_boot_sources_selection_xenial] at time 11:35:21.459277
2019-04-12 11:35:21,459 [salt.state       :1813][INFO    ][16515] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-12 11:35:21,661 [salt.state       :300 ][INFO    ][16515] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-12 11:35:21,661 [salt.state       :1951][INFO    ][16515] Completed state [maas_region_boot_sources_selection_xenial] at time 11:35:21.661710 duration_in_ms=202.433
2019-04-12 11:35:21,663 [salt.state       :1780][INFO    ][16515] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 11:35:21.663040
2019-04-12 11:35:21,663 [salt.state       :1813][INFO    ][16515] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-12 11:35:21,664 [salt.utils.decorators:613 ][WARNING ][16515] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 11:35:21,664 [salt.loaded.ext.module.maasng:1771][INFO    ][16515] boot-sources sync initiated for ALL Rack's
2019-04-12 11:35:22,726 [salt.state       :300 ][INFO    ][16515] {'ret': True}
2019-04-12 11:35:22,727 [salt.state       :1951][INFO    ][16515] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 11:35:22.727237 duration_in_ms=1064.196
2019-04-12 11:35:22,729 [salt.state       :1780][INFO    ][16515] Running state [maas.process_maas_config] at time 11:35:22.729299
2019-04-12 11:35:22,729 [salt.state       :1813][INFO    ][16515] Executing state module.run for [maas.process_maas_config]
2019-04-12 11:35:22,730 [salt.utils.decorators:613 ][WARNING ][16515] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 11:35:22,731 [salt.loaded.ext.module.maas:92  ][INFO    ][16515] maasconfig name=enable_http_proxy value=True
2019-04-12 11:35:22,795 [salt.loaded.ext.module.maas:92  ][INFO    ][16515] maasconfig name=upstream_dns value=8.8.8.8
2019-04-12 11:35:22,859 [salt.loaded.ext.module.maas:92  ][INFO    ][16515] maasconfig name=commissioning_distro_series value=xenial
2019-04-12 11:35:22,930 [salt.loaded.ext.module.maas:92  ][INFO    ][16515] maasconfig name=default_osystem value=ubuntu
2019-04-12 11:35:22,996 [salt.loaded.ext.module.maas:92  ][INFO    ][16515] maasconfig name=active_discovery_interval value=600
2019-04-12 11:35:23,050 [salt.loaded.ext.module.maas:92  ][INFO    ][16515] maasconfig name=dnssec_validation value=no
2019-04-12 11:35:23,110 [salt.loaded.ext.module.maas:92  ][INFO    ][16515] maasconfig name=maas_name value=mas01
2019-04-12 11:35:23,170 [salt.loaded.ext.module.maas:92  ][INFO    ][16515] maasconfig name=network_discovery value=enabled
2019-04-12 11:35:23,296 [salt.loaded.ext.module.maas:92  ][INFO    ][16515] maasconfig name=enable_third_party_drivers value=True
2019-04-12 11:35:26,076 [salt.loaded.ext.module.maas:92  ][INFO    ][16515] maasconfig name=default_storage_layout value=lvm
2019-04-12 11:35:26,140 [salt.loaded.ext.module.maas:92  ][INFO    ][16515] maasconfig name=ntp_external_only value=True
2019-04-12 11:35:26,208 [salt.loaded.ext.module.maas:92  ][INFO    ][16515] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-12 11:35:26,266 [salt.loaded.ext.module.maas:92  ][INFO    ][16515] maasconfig name=default_distro_series value=xenial
2019-04-12 11:35:26,332 [salt.loaded.ext.module.maas:92  ][INFO    ][16515] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-12 11:35:26,476 [salt.state       :300 ][INFO    ][16515] {'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-12 11:35:26,476 [salt.state       :1951][INFO    ][16515] Completed state [maas.process_maas_config] at time 11:35:26.476775 duration_in_ms=3747.475
2019-04-12 11:35:26,477 [salt.state       :1780][INFO    ][16515] Running state [pxe_admin] at time 11:35:26.477603
2019-04-12 11:35:26,478 [salt.state       :1813][INFO    ][16515] Executing state maasng.fabric_present for [pxe_admin]
2019-04-12 11:35:26,547 [salt.loaded.ext.module.maasng:945 ][INFO    ][16515] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'et7prd', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-04-12 11:35:26,648 [salt.loaded.ext.module.maasng:1008][WARNING ][16515] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-12 11:35:26,649 [salt.loaded.ext.module.maasng:1011][WARNING ][16515] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-12 11:35:26,719 [salt.state       :300 ][INFO    ][16515] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-12 11:35:26,720 [salt.state       :1951][INFO    ][16515] Completed state [pxe_admin] at time 11:35:26.720239 duration_in_ms=242.634
2019-04-12 11:35:26,720 [salt.state       :1780][INFO    ][16515] Running state [vlan 0] at time 11:35:26.720743
2019-04-12 11:35:26,721 [salt.state       :1813][INFO    ][16515] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-12 11:35:26,785 [salt.loaded.ext.module.maasng:945 ][INFO    ][16515] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'et7prd', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-04-12 11:35:27,080 [salt.loaded.ext.module.maasng:945 ][INFO    ][16515] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'et7prd', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-04-12 11:35:27,373 [salt.loaded.ext.module.maasng:945 ][INFO    ][16515] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'et7prd', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-04-12 11:35:27,468 [salt.state       :300 ][INFO    ][16515] {'new': 'Vlan untagged was updated'}
2019-04-12 11:35:27,469 [salt.state       :1951][INFO    ][16515] Completed state [vlan 0] at time 11:35:27.469220 duration_in_ms=748.477
2019-04-12 11:35:27,470 [salt.state       :1780][INFO    ][16515] Running state [192.168.11.0/24] at time 11:35:27.470743
2019-04-12 11:35:27,471 [salt.state       :1813][INFO    ][16515] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-12 11:35:27,690 [salt.loaded.ext.module.maasng:945 ][INFO    ][16515] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'et7prd', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-04-12 11:35:27,691 [salt.loaded.ext.module.maasng:1235][WARNING ][16515] Ignoring parameter vlan:0
2019-04-12 11:35:27,768 [salt.state       :300 ][INFO    ][16515] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-12 11:35:27,769 [salt.state       :1951][INFO    ][16515] Completed state [192.168.11.0/24] at time 11:35:27.769013 duration_in_ms=298.269
2019-04-12 11:35:27,770 [salt.state       :1780][INFO    ][16515] Running state [maas_create_iprange_1] at time 11:35:27.770292
2019-04-12 11:35:27,770 [salt.state       :1813][INFO    ][16515] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-12 11:35:27,834 [salt.state       :300 ][INFO    ][16515] Iprange maas_create_iprange_1 already exist.
2019-04-12 11:35:27,835 [salt.state       :1951][INFO    ][16515] Completed state [maas_create_iprange_1] at time 11:35:27.835002 duration_in_ms=64.71
2019-04-12 11:35:27,835 [salt.state       :1780][INFO    ][16515] Running state [vlan 0] at time 11:35:27.835414
2019-04-12 11:35:27,835 [salt.state       :1813][INFO    ][16515] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-12 11:35:27,888 [salt.loaded.ext.module.maasng:945 ][INFO    ][16515] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'et7prd', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-12 11:35:27,972 [salt.loaded.ext.module.maasng:945 ][INFO    ][16515] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'et7prd', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-12 11:35:28,126 [salt.loaded.ext.module.maasng:945 ][INFO    ][16515] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'et7prd', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-12 11:35:28,200 [salt.state       :300 ][INFO    ][16515] {'new': 'Vlan untagged was updated'}
2019-04-12 11:35:28,200 [salt.state       :1951][INFO    ][16515] Completed state [vlan 0] at time 11:35:28.200552 duration_in_ms=365.137
2019-04-12 11:35:28,201 [salt.state       :1780][INFO    ][16515] Running state [opnfv] at time 11:35:28.201513
2019-04-12 11:35:28,202 [salt.state       :1813][INFO    ][16515] Executing state maasng.sshkey_present for [opnfv]
2019-04-12 11:35:28,246 [salt.loaded.ext.module.maasng:1903][INFO    ][16515] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-04-12 11:35:28,247 [salt.state       :300 ][INFO    ][16515] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-04-12 11:35:28,247 [salt.state       :1951][INFO    ][16515] Completed state [opnfv] at time 11:35:28.247830 duration_in_ms=46.316
2019-04-12 11:35:28,248 [salt.state       :1780][INFO    ][16515] Running state [maas.process_tags] at time 11:35:28.248718
2019-04-12 11:35:28,249 [salt.state       :1813][INFO    ][16515] Executing state module.run for [maas.process_tags]
2019-04-12 11:35:28,249 [salt.utils.decorators:613 ][WARNING ][16515] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 11:35:28,330 [salt.loaded.ext.module.maas:92  ][INFO    ][16515] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-04-12 11:35:28,402 [salt.state       :300 ][INFO    ][16515] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-04-12 11:35:28,403 [salt.state       :1951][INFO    ][16515] Completed state [maas.process_tags] at time 11:35:28.403301 duration_in_ms=154.582
2019-04-12 11:35:28,406 [salt.minion      :1711][INFO    ][16515] Returning information for job: 20190412113459805970
2019-04-12 11:35:28,893 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command state.apply with jid 20190412113528881588
2019-04-12 11:35:28,914 [salt.minion      :1432][INFO    ][16995] Starting a new job with PID 16995
2019-04-12 11:35:32,435 [salt.state       :915 ][INFO    ][16995] Loading fresh modules for state activity
2019-04-12 11:35:32,533 [salt.state       :1780][INFO    ][16995] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:35:32.533085
2019-04-12 11:35:32,533 [salt.state       :1813][INFO    ][16995] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-12 11:35:32,535 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16995] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-12 11:35:33,976 [salt.state       :300 ][INFO    ][16995] {'pid': 17018, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-12 11:35:33,977 [salt.state       :1951][INFO    ][16995] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:35:33.977632 duration_in_ms=1444.547
2019-04-12 11:35:33,980 [salt.state       :1780][INFO    ][16995] Running state [maas.process_machines] at time 11:35:33.980061
2019-04-12 11:35:33,980 [salt.state       :1813][INFO    ][16995] Executing state module.run for [maas.process_machines]
2019-04-12 11:35:33,981 [salt.utils.decorators:613 ][WARNING ][16995] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 11:35:34,607 [salt.loaded.ext.module.maas:412 ][WARNING ][16995] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-12 11:35:34,607 [salt.loaded.ext.module.maas:92  ][INFO    ][16995] 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=dtaw3f architecture=amd64/generic power_parameters_power_user=admin
2019-04-12 11:35:35,722 [salt.loaded.ext.module.maas:412 ][WARNING ][16995] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-12 11:35:35,723 [salt.loaded.ext.module.maas:92  ][INFO    ][16995] 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=n4ebyg architecture=amd64/generic power_parameters_power_user=admin
2019-04-12 11:35:36,921 [salt.loaded.ext.module.maas:412 ][WARNING ][16995] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-12 11:35:36,922 [salt.loaded.ext.module.maas:92  ][INFO    ][16995] 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=egyhbt architecture=amd64/generic power_parameters_power_user=admin
2019-04-12 11:35:38,033 [salt.loaded.ext.module.maas:412 ][WARNING ][16995] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-12 11:35:38,034 [salt.loaded.ext.module.maas:92  ][INFO    ][16995] 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=m8brsg architecture=amd64/generic power_parameters_power_user=admin
2019-04-12 11:35:39,327 [salt.loaded.ext.module.maas:412 ][WARNING ][16995] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-12 11:35:39,328 [salt.loaded.ext.module.maas:92  ][INFO    ][16995] 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=r7dgww architecture=amd64/generic power_parameters_power_user=admin
2019-04-12 11:35:40,537 [salt.state       :300 ][INFO    ][16995] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-12 11:35:40,538 [salt.state       :1951][INFO    ][16995] Completed state [maas.process_machines] at time 11:35:40.538147 duration_in_ms=6558.085
2019-04-12 11:35:40,541 [salt.minion      :1711][INFO    ][16995] Returning information for job: 20190412113528881588
2019-04-12 11:36:13,786 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command state.apply with jid 20190412113613772943
2019-04-12 11:36:13,811 [salt.minion      :1432][INFO    ][17262] Starting a new job with PID 17262
2019-04-12 11:36:17,420 [salt.state       :915 ][INFO    ][17262] Loading fresh modules for state activity
2019-04-12 11:36:17,468 [salt.state       :1780][INFO    ][17262] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:36:17.468777
2019-04-12 11:36:17,469 [salt.state       :1813][INFO    ][17262] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-12 11:36:17,470 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17262] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-12 11:36:18,916 [salt.state       :300 ][INFO    ][17262] {'pid': 17298, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-12 11:36:18,917 [salt.state       :1951][INFO    ][17262] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:36:18.917034 duration_in_ms=1448.257
2019-04-12 11:36:18,918 [salt.state       :1780][INFO    ][17262] Running state [maas.wait_for_machine_status] at time 11:36:18.918178
2019-04-12 11:36:18,918 [salt.state       :1813][INFO    ][17262] Executing state module.run for [maas.wait_for_machine_status]
2019-04-12 11:36:18,918 [salt.utils.decorators:613 ][WARNING ][17262] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 11:36:22,387 [salt.state       :300 ][INFO    ][17262] {'ret': True}
2019-04-12 11:36:22,388 [salt.state       :1951][INFO    ][17262] Completed state [maas.wait_for_machine_status] at time 11:36:22.388356 duration_in_ms=3470.176
2019-04-12 11:36:22,392 [salt.minion      :1711][INFO    ][17262] Returning information for job: 20190412113613772943
2019-04-12 11:36:23,020 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command state.apply with jid 20190412113623007731
2019-04-12 11:36:23,046 [salt.minion      :1432][INFO    ][17313] Starting a new job with PID 17313
2019-04-12 11:36:23,786 [salt.state       :915 ][INFO    ][17313] Loading fresh modules for state activity
2019-04-12 11:36:23,926 [salt.state       :1780][INFO    ][17313] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:36:23.926296
2019-04-12 11:36:23,926 [salt.state       :1813][INFO    ][17313] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-12 11:36:23,928 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17313] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-12 11:36:25,308 [salt.state       :300 ][INFO    ][17313] {'pid': 17320, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-12 11:36:25,309 [salt.state       :1951][INFO    ][17313] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:36:25.309292 duration_in_ms=1382.997
2019-04-12 11:36:25,310 [salt.state       :1780][INFO    ][17313] Running state [maas_machines_storage_cmp002_lvm] at time 11:36:25.310537
2019-04-12 11:36:25,310 [salt.state       :1813][INFO    ][17313] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-12 11:36:25,959 [salt.state       :300 ][INFO    ][17313] Machine cmp002 is not in Ready state.
2019-04-12 11:36:25,960 [salt.state       :1951][INFO    ][17313] Completed state [maas_machines_storage_cmp002_lvm] at time 11:36:25.960226 duration_in_ms=649.688
2019-04-12 11:36:25,960 [salt.state       :1780][INFO    ][17313] Running state [maas_machines_storage_cmp001_lvm] at time 11:36:25.960506
2019-04-12 11:36:25,960 [salt.state       :1813][INFO    ][17313] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-12 11:36:26,565 [salt.state       :300 ][INFO    ][17313] Machine cmp001 is not in Ready state.
2019-04-12 11:36:26,566 [salt.state       :1951][INFO    ][17313] Completed state [maas_machines_storage_cmp001_lvm] at time 11:36:26.566129 duration_in_ms=605.622
2019-04-12 11:36:26,569 [salt.minion      :1711][INFO    ][17313] Returning information for job: 20190412113623007731
2019-04-12 11:36:27,177 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command state.apply with jid 20190412113627166698
2019-04-12 11:36:27,200 [salt.minion      :1432][INFO    ][17330] Starting a new job with PID 17330
2019-04-12 11:36:27,921 [salt.state       :915 ][INFO    ][17330] Loading fresh modules for state activity
2019-04-12 11:36:28,003 [salt.state       :1780][INFO    ][17330] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:36:28.003621
2019-04-12 11:36:28,004 [salt.state       :1813][INFO    ][17330] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-12 11:36:28,005 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17330] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-12 11:36:29,490 [salt.state       :300 ][INFO    ][17330] {'pid': 17337, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-12 11:36:29,491 [salt.state       :1951][INFO    ][17330] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:36:29.491412 duration_in_ms=1487.791
2019-04-12 11:36:29,492 [salt.state       :1780][INFO    ][17330] Running state [maas.deploy_machines] at time 11:36:29.492650
2019-04-12 11:36:29,492 [salt.state       :1813][INFO    ][17330] Executing state module.run for [maas.deploy_machines]
2019-04-12 11:36:29,493 [salt.utils.decorators:613 ][WARNING ][17330] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 11:36:30,215 [salt.loaded.ext.module.maas:684 ][INFO    ][17330] deploymachines hwe_kernel=hwe-16.04 system_id=egyhbt distro_series=xenial
2019-04-12 11:36:32,880 [salt.state       :300 ][INFO    ][17330] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm03', 'kvm02'], 'errors': {}, 'success': ['kvm01']}}
2019-04-12 11:36:32,880 [salt.state       :1951][INFO    ][17330] Completed state [maas.deploy_machines] at time 11:36:32.880561 duration_in_ms=3387.909
2019-04-12 11:36:32,884 [salt.minion      :1711][INFO    ][17330] Returning information for job: 20190412113627166698
2019-04-12 11:36:33,444 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command state.apply with jid 20190412113633435705
2019-04-12 11:36:33,466 [salt.minion      :1432][INFO    ][17396] Starting a new job with PID 17396
2019-04-12 11:36:34,167 [salt.state       :915 ][INFO    ][17396] Loading fresh modules for state activity
2019-04-12 11:36:34,250 [salt.state       :1780][INFO    ][17396] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:36:34.250862
2019-04-12 11:36:34,251 [salt.state       :1813][INFO    ][17396] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-12 11:36:34,253 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17396] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-12 11:36:35,504 [salt.state       :300 ][INFO    ][17396] {'pid': 17403, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-12 11:36:35,504 [salt.state       :1951][INFO    ][17396] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:36:35.504858 duration_in_ms=1253.996
2019-04-12 11:36:35,506 [salt.state       :1780][INFO    ][17396] Running state [maas.wait_for_machine_status] at time 11:36:35.506126
2019-04-12 11:36:35,506 [salt.state       :1813][INFO    ][17396] Executing state module.run for [maas.wait_for_machine_status]
2019-04-12 11:36:35,506 [salt.utils.decorators:613 ][WARNING ][17396] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-12 11:36:38,649 [salt.loaded.ext.module.maas:1023][INFO    ][17396] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (2246.86252904s left)
2019-04-12 11:36:48,559 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113648503111
2019-04-12 11:36:48,583 [salt.minion      :1432][INFO    ][17429] Starting a new job with PID 17429
2019-04-12 11:36:48,608 [salt.minion      :1711][INFO    ][17429] Returning information for job: 20190412113648503111
2019-04-12 11:37:12,039 [salt.loaded.ext.module.maas:1023][INFO    ][17396] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (2213.47211909s left)
2019-04-12 11:37:18,607 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113718594884
2019-04-12 11:37:18,633 [salt.minion      :1432][INFO    ][17485] Starting a new job with PID 17485
2019-04-12 11:37:18,657 [salt.minion      :1711][INFO    ][17485] Returning information for job: 20190412113718594884
2019-04-12 11:37:45,414 [salt.loaded.ext.module.maas:1023][INFO    ][17396] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (2180.09764194s left)
2019-04-12 11:37:48,659 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113748646017
2019-04-12 11:37:48,686 [salt.minion      :1432][INFO    ][17505] Starting a new job with PID 17505
2019-04-12 11:37:48,711 [salt.minion      :1711][INFO    ][17505] Returning information for job: 20190412113748646017
2019-04-12 11:38:18,723 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113818710075
2019-04-12 11:38:18,750 [salt.minion      :1432][INFO    ][17589] Starting a new job with PID 17589
2019-04-12 11:38:18,773 [salt.minion      :1711][INFO    ][17589] Returning information for job: 20190412113818710075
2019-04-12 11:38:19,124 [salt.loaded.ext.module.maas:1023][INFO    ][17396] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (2146.38793111s left)
2019-04-12 11:38:48,776 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113848763653
2019-04-12 11:38:48,803 [salt.minion      :1432][INFO    ][17638] Starting a new job with PID 17638
2019-04-12 11:38:48,826 [salt.minion      :1711][INFO    ][17638] Returning information for job: 20190412113848763653
2019-04-12 11:38:52,820 [salt.loaded.ext.module.maas:1023][INFO    ][17396] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (2112.6917181s left)
2019-04-12 11:39:18,832 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113918820422
2019-04-12 11:39:18,858 [salt.minion      :1432][INFO    ][17843] Starting a new job with PID 17843
2019-04-12 11:39:18,884 [salt.minion      :1711][INFO    ][17843] Returning information for job: 20190412113918820422
2019-04-12 11:39:26,131 [salt.loaded.ext.module.maas:1023][INFO    ][17396] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (2079.38021994s left)
2019-04-12 11:39:48,892 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412113948879153
2019-04-12 11:39:48,917 [salt.minion      :1432][INFO    ][17868] Starting a new job with PID 17868
2019-04-12 11:39:48,941 [salt.minion      :1711][INFO    ][17868] Returning information for job: 20190412113948879153
2019-04-12 11:39:59,600 [salt.loaded.ext.module.maas:1023][INFO    ][17396] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (2045.91139197s left)
2019-04-12 11:40:18,957 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412114018944377
2019-04-12 11:40:18,983 [salt.minion      :1432][INFO    ][17945] Starting a new job with PID 17945
2019-04-12 11:40:19,006 [salt.minion      :1711][INFO    ][17945] Returning information for job: 20190412114018944377
2019-04-12 11:40:33,061 [salt.loaded.ext.module.maas:1023][INFO    ][17396] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (2012.45076704s left)
2019-04-12 11:40:49,022 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412114049009159
2019-04-12 11:40:49,047 [salt.minion      :1432][INFO    ][17967] Starting a new job with PID 17967
2019-04-12 11:40:49,068 [salt.minion      :1711][INFO    ][17967] Returning information for job: 20190412114049009159
2019-04-12 11:41:06,804 [salt.loaded.ext.module.maas:1023][INFO    ][17396] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1978.70749092s left)
2019-04-12 11:41:19,092 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412114119080528
2019-04-12 11:41:19,118 [salt.minion      :1432][INFO    ][18150] Starting a new job with PID 18150
2019-04-12 11:41:19,142 [salt.minion      :1711][INFO    ][18150] Returning information for job: 20190412114119080528
2019-04-12 11:41:40,354 [salt.loaded.ext.module.maas:1023][INFO    ][17396] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1945.15766597s left)
2019-04-12 11:41:49,166 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412114149153637
2019-04-12 11:41:49,192 [salt.minion      :1432][INFO    ][18186] Starting a new job with PID 18186
2019-04-12 11:41:49,216 [salt.minion      :1711][INFO    ][18186] Returning information for job: 20190412114149153637
2019-04-12 11:42:13,973 [salt.loaded.ext.module.maas:1023][INFO    ][17396] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1911.53908801s left)
2019-04-12 11:42:19,250 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412114219237974
2019-04-12 11:42:19,276 [salt.minion      :1432][INFO    ][18293] Starting a new job with PID 18293
2019-04-12 11:42:19,301 [salt.minion      :1711][INFO    ][18293] Returning information for job: 20190412114219237974
2019-04-12 11:42:47,404 [salt.loaded.ext.module.maas:1023][INFO    ][17396] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1878.10746098s left)
2019-04-12 11:42:49,340 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412114249323272
2019-04-12 11:42:49,369 [salt.minion      :1432][INFO    ][18313] Starting a new job with PID 18313
2019-04-12 11:42:49,404 [salt.minion      :1711][INFO    ][18313] Returning information for job: 20190412114249323272
2019-04-12 11:43:19,442 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412114319430259
2019-04-12 11:43:19,467 [salt.minion      :1432][INFO    ][18371] Starting a new job with PID 18371
2019-04-12 11:43:19,492 [salt.minion      :1711][INFO    ][18371] Returning information for job: 20190412114319430259
2019-04-12 11:43:20,386 [salt.loaded.ext.module.maas:1023][INFO    ][17396] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1845.12561011s left)
2019-04-12 11:43:49,506 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command saltutil.find_job with jid 20190412114349494274
2019-04-12 11:43:49,532 [salt.minion      :1432][INFO    ][18427] Starting a new job with PID 18427
2019-04-12 11:43:49,556 [salt.minion      :1711][INFO    ][18427] Returning information for job: 20190412114349494274
2019-04-12 11:43:54,038 [salt.state       :300 ][INFO    ][17396] {'ret': True}
2019-04-12 11:43:54,039 [salt.state       :1951][INFO    ][17396] Completed state [maas.wait_for_machine_status] at time 11:43:54.039346 duration_in_ms=438533.216
2019-04-12 11:43:54,043 [salt.minion      :1711][INFO    ][17396] Returning information for job: 20190412113633435705
2019-04-12 12:08:47,916 [salt.utils.schedule:1377][INFO    ][9222] Running scheduled job: __mine_interval
2019-04-12 13:08:47,916 [salt.utils.schedule:1377][INFO    ][9222] Running scheduled job: __mine_interval
2019-04-12 13:16:16,546 [salt.minion      :1308][INFO    ][9222] User sudo_ubuntu Executing command cp.push_dir with jid 20190412131616533987
2019-04-12 13:16:16,573 [salt.minion      :1432][INFO    ][25204] Starting a new job with PID 25204
