2019-05-15 20:06:51,493 [salt.utils.decorators:613 ][WARNING ][2300] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:06:52,540 [salt.utils.decorators:613 ][WARNING ][2300] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:06:55,433 [salt.loaded.int.states.file:2298][WARNING ][2489] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-15 20:07:19,072 [salt.state       :2022][WARNING ][2828] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-15 20:07:22,247 [salt.utils.decorators:613 ][WARNING ][2828] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:07:35,456 [salt.utils.decorators:613 ][WARNING ][2828] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:08:02,479 [salt.utils.decorators:613 ][WARNING ][2828] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:08:03,580 [salt.utils.decorators:613 ][WARNING ][2828] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:08:05,696 [salt.loaded.ext.module.maasng:1008][WARNING ][2828] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-05-15 20:08:05,696 [salt.loaded.ext.module.maasng:1011][WARNING ][2828] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-05-15 20:08:06,443 [salt.loaded.ext.module.maasng:1235][WARNING ][2828] Ignoring parameter vlan:0
2019-05-15 20:08:07,320 [salt.utils.decorators:613 ][WARNING ][2828] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:08:16,456 [salt.utils.decorators:613 ][WARNING ][5424] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:08:16,512 [salt.loaded.ext.module.maas:412 ][WARNING ][5424] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-15 20:08:18,020 [salt.loaded.ext.module.maas:412 ][WARNING ][5424] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-15 20:08:19,496 [salt.loaded.ext.module.maas:412 ][WARNING ][5424] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-15 20:08:20,699 [salt.loaded.ext.module.maas:412 ][WARNING ][5424] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-15 20:08:24,811 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5916] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-15 20:08:24,845 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5916] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-15 20:08:24,897 [salt.utils.parsers:1051][WARNING ][386] Minion received a SIGTERM. Exiting.
2019-05-15 20:08:25,927 [salt.cli.daemons :293 ][INFO    ][5982] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2019-05-15 20:08:26,062 [salt.cli.daemons :82  ][INFO    ][5982] Starting up the Salt Minion
2019-05-15 20:08:26,063 [salt.utils.event :1017][INFO    ][5982] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2019-05-15 20:08:27,291 [salt.minion      :976 ][INFO    ][5982] Creating minion process manager
2019-05-15 20:08:29,195 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5982] Executing command ['date', '+%z'] in directory '/root'
2019-05-15 20:08:29,224 [salt.utils.schedule:568 ][INFO    ][5982] Updating job settings for scheduled job: __mine_interval
2019-05-15 20:08:29,227 [salt.minion      :1108][INFO    ][5982] Added mine.update to scheduler
2019-05-15 20:08:29,231 [salt.minion      :1975][INFO    ][5982] Minion is starting as user 'root'
2019-05-15 20:08:29,251 [salt.minion      :2336][INFO    ][5982] Minion is ready to receive requests!
2019-05-15 20:08:52,892 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command state.apply with jid 20190515200852877848
2019-05-15 20:08:52,917 [salt.minion      :1432][INFO    ][6088] Starting a new job with PID 6088
2019-05-15 20:08:58,777 [salt.state       :915 ][INFO    ][6088] Loading fresh modules for state activity
2019-05-15 20:08:58,842 [salt.fileclient  :1219][INFO    ][6088] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-15 20:08:58,891 [salt.state       :1780][INFO    ][6088] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:08:58.891099
2019-05-15 20:08:58,891 [salt.state       :1813][INFO    ][6088] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-15 20:08:58,893 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6088] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-15 20:09:00,601 [salt.state       :300 ][INFO    ][6088] {'pid': 6097, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-15 20:09:00,602 [salt.state       :1951][INFO    ][6088] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:09:00.602294 duration_in_ms=1711.196
2019-05-15 20:09:00,603 [salt.state       :1780][INFO    ][6088] Running state [maas.wait_for_machine_status] at time 20:09:00.603853
2019-05-15 20:09:00,604 [salt.state       :1813][INFO    ][6088] Executing state module.run for [maas.wait_for_machine_status]
2019-05-15 20:09:00,604 [salt.utils.decorators:613 ][WARNING ][6088] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:09:01,192 [salt.loaded.ext.module.maas:1023][INFO    ][6088] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.42007923s left)
2019-05-15 20:09:07,985 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515200907966018
2019-05-15 20:09:08,010 [salt.minion      :1432][INFO    ][6111] Starting a new job with PID 6111
2019-05-15 20:09:08,038 [salt.minion      :1711][INFO    ][6111] Returning information for job: 20190515200907966018
2019-05-15 20:09:32,207 [salt.loaded.ext.module.maas:1023][INFO    ][6088] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.4049561s left)
2019-05-15 20:09:38,075 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515200938060355
2019-05-15 20:09:38,101 [salt.minion      :1432][INFO    ][6187] Starting a new job with PID 6187
2019-05-15 20:09:38,123 [salt.minion      :1711][INFO    ][6187] Returning information for job: 20190515200938060355
2019-05-15 20:10:02,844 [salt.loaded.ext.module.maas:1023][INFO    ][6088] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1437.76799107s left)
2019-05-15 20:10:08,158 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201008144174
2019-05-15 20:10:08,182 [salt.minion      :1432][INFO    ][6217] Starting a new job with PID 6217
2019-05-15 20:10:08,205 [salt.minion      :1711][INFO    ][6217] Returning information for job: 20190515201008144174
2019-05-15 20:10:33,365 [salt.loaded.ext.module.maas:1023][INFO    ][6088] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1407.24688101s left)
2019-05-15 20:10:38,248 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201038227054
2019-05-15 20:10:38,274 [salt.minion      :1432][INFO    ][6261] Starting a new job with PID 6261
2019-05-15 20:10:38,296 [salt.minion      :1711][INFO    ][6261] Returning information for job: 20190515201038227054
2019-05-15 20:11:04,189 [salt.loaded.ext.module.maas:1023][INFO    ][6088] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1376.42309523s left)
2019-05-15 20:11:08,327 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201108313256
2019-05-15 20:11:08,351 [salt.minion      :1432][INFO    ][6323] Starting a new job with PID 6323
2019-05-15 20:11:08,372 [salt.minion      :1711][INFO    ][6323] Returning information for job: 20190515201108313256
2019-05-15 20:11:35,047 [salt.loaded.ext.module.maas:1023][INFO    ][6088] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1345.56474113s left)
2019-05-15 20:11:38,423 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201138411283
2019-05-15 20:11:38,445 [salt.minion      :1432][INFO    ][6446] Starting a new job with PID 6446
2019-05-15 20:11:38,466 [salt.minion      :1711][INFO    ][6446] Returning information for job: 20190515201138411283
2019-05-15 20:12:05,925 [salt.loaded.ext.module.maas:1023][INFO    ][6088] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1314.68714809s left)
2019-05-15 20:12:08,527 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201208514976
2019-05-15 20:12:08,554 [salt.minion      :1432][INFO    ][6562] Starting a new job with PID 6562
2019-05-15 20:12:08,576 [salt.minion      :1711][INFO    ][6562] Returning information for job: 20190515201208514976
2019-05-15 20:12:36,939 [salt.loaded.ext.module.maas:1023][INFO    ][6088] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1283.67315722s left)
2019-05-15 20:12:38,650 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201238640894
2019-05-15 20:12:38,671 [salt.minion      :1432][INFO    ][6826] Starting a new job with PID 6826
2019-05-15 20:12:38,693 [salt.minion      :1711][INFO    ][6826] Returning information for job: 20190515201238640894
2019-05-15 20:13:08,167 [salt.loaded.ext.module.maas:1023][INFO    ][6088] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1252.44508123s left)
2019-05-15 20:13:08,723 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201308716016
2019-05-15 20:13:08,740 [salt.minion      :1432][INFO    ][6905] Starting a new job with PID 6905
2019-05-15 20:13:08,759 [salt.minion      :1711][INFO    ][6905] Returning information for job: 20190515201308716016
2019-05-15 20:13:38,854 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201338840868
2019-05-15 20:13:38,873 [salt.minion      :1432][INFO    ][7173] Starting a new job with PID 7173
2019-05-15 20:13:38,896 [salt.minion      :1711][INFO    ][7173] Returning information for job: 20190515201338840868
2019-05-15 20:13:39,681 [salt.loaded.ext.module.maas:1023][INFO    ][6088] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1220.93118s left)
2019-05-15 20:14:08,960 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201408944393
2019-05-15 20:14:08,988 [salt.minion      :1432][INFO    ][7344] Starting a new job with PID 7344
2019-05-15 20:14:09,008 [salt.minion      :1711][INFO    ][7344] Returning information for job: 20190515201408944393
2019-05-15 20:14:11,377 [salt.loaded.ext.module.maas:1023][INFO    ][6088] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1189.23534322s left)
2019-05-15 20:14:39,085 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201439074274
2019-05-15 20:14:39,113 [salt.minion      :1432][INFO    ][7452] Starting a new job with PID 7452
2019-05-15 20:14:39,136 [salt.minion      :1711][INFO    ][7452] Returning information for job: 20190515201439074274
2019-05-15 20:14:42,992 [salt.loaded.ext.module.maas:1023][INFO    ][6088] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1157.61995721s left)
2019-05-15 20:15:09,201 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201509185524
2019-05-15 20:15:09,226 [salt.minion      :1432][INFO    ][7566] Starting a new job with PID 7566
2019-05-15 20:15:09,246 [salt.minion      :1711][INFO    ][7566] Returning information for job: 20190515201509185524
2019-05-15 20:15:15,116 [salt.state       :300 ][INFO    ][6088] {'ret': True}
2019-05-15 20:15:15,116 [salt.state       :1951][INFO    ][6088] Completed state [maas.wait_for_machine_status] at time 20:15:15.116532 duration_in_ms=374512.677
2019-05-15 20:15:15,121 [salt.minion      :1711][INFO    ][6088] Returning information for job: 20190515200852877848
2019-05-15 20:15:15,695 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command state.apply with jid 20190515201515684387
2019-05-15 20:15:15,726 [salt.minion      :1432][INFO    ][7577] Starting a new job with PID 7577
2019-05-15 20:15:21,678 [salt.state       :915 ][INFO    ][7577] Loading fresh modules for state activity
2019-05-15 20:15:21,739 [salt.fileclient  :1219][INFO    ][7577] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-15 20:15:21,835 [salt.state       :1780][INFO    ][7577] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:21.835322
2019-05-15 20:15:21,835 [salt.state       :1813][INFO    ][7577] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-15 20:15:21,837 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7577] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-15 20:15:23,633 [salt.state       :300 ][INFO    ][7577] {'pid': 7600, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-15 20:15:23,634 [salt.state       :1951][INFO    ][7577] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:23.634618 duration_in_ms=1799.295
2019-05-15 20:15:23,639 [salt.state       :1780][INFO    ][7577] Running state [maas_machines_storage_cmp002_lvm] at time 20:15:23.639525
2019-05-15 20:15:23,640 [salt.state       :1813][INFO    ][7577] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-15 20:15:24,628 [salt.loaded.ext.module.maasng:610 ][INFO    ][7577] ppbrtq
2019-05-15 20:15:24,629 [salt.loaded.ext.module.maasng:626 ][INFO    ][7577] sda
2019-05-15 20:15:25,294 [salt.loaded.ext.module.maasng:361 ][INFO    ][7577] ppbrtq
2019-05-15 20:15:25,385 [salt.loaded.ext.module.maasng:367 ][INFO    ][7577] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/blockdevices/1/', u'type': u'physical', u'tags': [u'ssd'], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ppbrtq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'used_for': u'MBR partitioned with 1 partition', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'cfbca1f2-1df2-4322-98f0-7dbd28dddb1e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ppbrtq', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'7f65e15c-ca61-4f82-9790-267b23267e8a', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}]}, {u'size': 800097042432, u'model': None, u'available_size': 0, u'uuid': u'f880ad33-6370-453b-954e-7c9cdc853c41', u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/blockdevices/3/', u'type': u'virtual', u'tags': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'ppbrtq', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'af8fac36-613a-4cd9-bfbc-e660978c9281', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 3, u'partitions': []}]
2019-05-15 20:15:25,386 [salt.loaded.ext.module.maasng:632 ][INFO    ][7577] vgroot
2019-05-15 20:15:25,386 [salt.loaded.ext.module.maasng:635 ][INFO    ][7577] lvroot
2019-05-15 20:15:25,386 [salt.loaded.ext.module.maasng:639 ][INFO    ][7577] 107374182400
2019-05-15 20:15:25,991 [salt.loaded.ext.module.maasng:645 ][INFO    ][7577] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/blockdevices/1/', u'type': u'physical', u'tags': [u'ssd'], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ppbrtq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'used_for': u'MBR partitioned with 1 partition', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'6ce886a5-0d70-45ea-93e2-595eb7ee9f78', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ppbrtq', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'7174a7fb-c120-4c4a-a12d-417df587f40c', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/blockdevices/1/partition/5', u'type': u'partition', u'id': 5, u'size': 800101236736}]}, {u'size': 107374182400, u'model': None, u'available_size': 0, u'uuid': u'23730ac6-52a7-43ec-bb34-877f13f56763', u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/blockdevices/9/', u'type': u'virtual', u'tags': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'ppbrtq', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'63303727-0160-4493-ae84-1ef0e3cfff20', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 9, u'partitions': []}], 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/ppbrtq/', u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ppbrtq', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/blockdevices/1/', u'type': u'physical', u'tags': [u'ssd'], u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ppbrtq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'used_for': u'MBR partitioned with 1 partition', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'6ce886a5-0d70-45ea-93e2-595eb7ee9f78', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ppbrtq', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'7174a7fb-c120-4c4a-a12d-417df587f40c', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/blockdevices/1/partition/5', u'type': u'partition', u'id': 5, u'size': 800101236736}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ppbrtq', u'id': 5}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'size': 107374182400, u'available_size': 0, u'uuid': u'23730ac6-52a7-43ec-bb34-877f13f56763', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/blockdevices/9/', u'type': u'virtual', u'tags': [], u'id': 9, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'ppbrtq', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'63303727-0160-4493-ae84-1ef0e3cfff20', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'eno1', 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'reqmdg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], 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'reqmdg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'reqmdg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'ppbrtq', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/interfaces/5/'}, u'boot_disk': {u'size': 800109715456, u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/blockdevices/1/', u'type': u'physical', u'tags': [u'ssd'], u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ppbrtq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'used_for': u'MBR partitioned with 1 partition', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'6ce886a5-0d70-45ea-93e2-595eb7ee9f78', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ppbrtq', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'7174a7fb-c120-4c4a-a12d-417df587f40c', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/blockdevices/1/partition/5', u'type': u'partition', u'id': 5, u'size': 800101236736}]}, u'interface_set': [{u'name': u'eno1', 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'reqmdg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], 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'reqmdg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'reqmdg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'ppbrtq', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/interfaces/5/'}, {u'name': u'ens2f0', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'ppbrtq', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/interfaces/14/'}, {u'name': u'eno2', 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'reqmdg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'reqmdg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'reqmdg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'system_id': u'ppbrtq', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/interfaces/11/'}, {u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'ppbrtq', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/interfaces/12/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'ppbrtq', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/interfaces/10/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'ppbrtq', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/ppbrtq/interfaces/13/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-05-15 20:15:25,993 [salt.state       :300 ][INFO    ][7577] {'new': {'storage_layout': 'lvm'}}
2019-05-15 20:15:25,993 [salt.state       :1951][INFO    ][7577] Completed state [maas_machines_storage_cmp002_lvm] at time 20:15:25.993623 duration_in_ms=2354.098
2019-05-15 20:15:25,996 [salt.state       :1780][INFO    ][7577] Running state [maas_machines_storage_cmp001_lvm] at time 20:15:25.996044
2019-05-15 20:15:25,996 [salt.state       :1813][INFO    ][7577] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-15 20:15:27,006 [salt.loaded.ext.module.maasng:610 ][INFO    ][7577] bkac7a
2019-05-15 20:15:27,006 [salt.loaded.ext.module.maasng:626 ][INFO    ][7577] sda
2019-05-15 20:15:27,493 [salt.loaded.ext.module.maasng:361 ][INFO    ][7577] bkac7a
2019-05-15 20:15:27,586 [salt.loaded.ext.module.maasng:367 ][INFO    ][7577] [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/blockdevices/2/', u'id': 2, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'system_id': u'bkac7a', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'5f8cbc34-52a1-4d8f-896d-4447966ba409', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bkac7a', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'92bf8c2c-68e6-4410-abab-cb95b023be63', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'device_id': 2}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'block_size': 4096, u'uuid': u'18587911-b2fa-41a4-a82b-24fd36a30f7a', u'name': u'vgroot-lvroot', u'tags': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/blockdevices/4/', u'id': 4, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'5ef7664d-c855-404f-bd37-3d087b798e8f', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'system_id': u'bkac7a', 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'partitions': [], u'type': u'virtual', u'model': None, u'size': 800097042432}]
2019-05-15 20:15:27,587 [salt.loaded.ext.module.maasng:632 ][INFO    ][7577] vgroot
2019-05-15 20:15:27,587 [salt.loaded.ext.module.maasng:635 ][INFO    ][7577] lvroot
2019-05-15 20:15:27,587 [salt.loaded.ext.module.maasng:639 ][INFO    ][7577] 107374182400
2019-05-15 20:15:28,208 [salt.loaded.ext.module.maasng:645 ][INFO    ][7577] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'reqmdg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'reqmdg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'reqmdg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'bkac7a', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/interfaces/6/'}, u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], 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'disable_ipv4': False, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'bkac7a', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'bkac7a', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/blockdevices/2/', u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'a2d0c230-5808-4ab5-96ec-751378a2b4d8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bkac7a', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'dfedaef6-a70b-4824-88bb-ac983e3d9efb', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/blockdevices/2/partition/6'}]}], u'blockdevice_set': [{u'size': 800109715456, u'partition_table_type': u'MBR', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'bkac7a', u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'a2d0c230-5808-4ab5-96ec-751378a2b4d8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bkac7a', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'dfedaef6-a70b-4824-88bb-ac983e3d9efb', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/blockdevices/2/partition/6'}]}, {u'size': 107374182400, u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 10, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'e5b1c154-8061-465a-b8fb-8460c876c910', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'65ae55c7-1bfe-4b34-ab3a-a7e8e916233a', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'bkac7a', u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/blockdevices/10/', u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'bkac7a', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 6, u'node_type_name': u'Machine', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'reqmdg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'reqmdg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'reqmdg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'bkac7a', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/interfaces/6/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d5', u'enabled': True, u'id': 15, u'discovered': None, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'bkac7a', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/interfaces/15/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d4', u'enabled': True, u'id': 17, u'discovered': None, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'bkac7a', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/interfaces/17/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'id': 16, u'discovered': None, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'bkac7a', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/interfaces/16/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'reqmdg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'reqmdg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', u'enabled': True, u'id': 18, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'reqmdg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.43'}], u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'bkac7a', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/interfaces/18/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'id': 19, u'discovered': None, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'bkac7a', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/interfaces/19/'}], u'current_testing_result_id': 7, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 10, u'uuid': u'65ae55c7-1bfe-4b34-ab3a-a7e8e916233a', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'bkac7a', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'e5b1c154-8061-465a-b8fb-8460c876c910', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/blockdevices/10/', u'used_size': 107374182400, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/bkac7a/', u'distro_series': u'', u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'bkac7a', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/blockdevices/2/', u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'a2d0c230-5808-4ab5-96ec-751378a2b4d8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bkac7a', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'dfedaef6-a70b-4824-88bb-ac983e3d9efb', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/bkac7a/blockdevices/2/partition/6'}]}}
2019-05-15 20:15:28,210 [salt.state       :300 ][INFO    ][7577] {'new': {'storage_layout': 'lvm'}}
2019-05-15 20:15:28,210 [salt.state       :1951][INFO    ][7577] Completed state [maas_machines_storage_cmp001_lvm] at time 20:15:28.210311 duration_in_ms=2214.265
2019-05-15 20:15:28,213 [salt.minion      :1711][INFO    ][7577] Returning information for job: 20190515201515684387
2019-05-15 20:15:28,772 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command state.apply with jid 20190515201528756842
2019-05-15 20:15:28,799 [salt.minion      :1432][INFO    ][7647] Starting a new job with PID 7647
2019-05-15 20:15:29,659 [salt.state       :915 ][INFO    ][7647] Loading fresh modules for state activity
2019-05-15 20:15:29,719 [salt.fileclient  :1219][INFO    ][7647] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-15 20:15:29,768 [salt.state       :1780][INFO    ][7647] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:29.768583
2019-05-15 20:15:29,768 [salt.state       :1813][INFO    ][7647] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-15 20:15:29,770 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7647] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-15 20:15:31,531 [salt.state       :300 ][INFO    ][7647] {'pid': 7654, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-15 20:15:31,532 [salt.state       :1951][INFO    ][7647] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:31.532770 duration_in_ms=1764.186
2019-05-15 20:15:31,536 [salt.state       :1780][INFO    ][7647] Running state [maas.deploy_machines] at time 20:15:31.536345
2019-05-15 20:15:31,537 [salt.state       :1813][INFO    ][7647] Executing state module.run for [maas.deploy_machines]
2019-05-15 20:15:31,539 [salt.utils.decorators:613 ][WARNING ][7647] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:15:32,044 [salt.loaded.ext.module.maas:684 ][INFO    ][7647] deploymachines hwe_kernel=hwe-16.04 system_id=ecqxsq distro_series=xenial
2019-05-15 20:15:34,569 [salt.loaded.ext.module.maas:684 ][INFO    ][7647] deploymachines hwe_kernel=hwe-16.04 system_id=ppbrtq distro_series=xenial
2019-05-15 20:15:37,185 [salt.loaded.ext.module.maas:684 ][INFO    ][7647] deploymachines hwe_kernel=hwe-16.04 system_id=bkac7a distro_series=xenial
2019-05-15 20:15:39,682 [salt.loaded.ext.module.maas:684 ][INFO    ][7647] deploymachines hwe_kernel=hwe-16.04 system_id=sn4mpn distro_series=xenial
2019-05-15 20:15:42,176 [salt.state       :300 ][INFO    ][7647] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-05-15 20:15:42,176 [salt.state       :1951][INFO    ][7647] Completed state [maas.deploy_machines] at time 20:15:42.176714 duration_in_ms=10640.369
2019-05-15 20:15:42,180 [salt.minion      :1711][INFO    ][7647] Returning information for job: 20190515201528756842
2019-05-15 20:15:42,752 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command state.apply with jid 20190515201542738685
2019-05-15 20:15:42,778 [salt.minion      :1432][INFO    ][7871] Starting a new job with PID 7871
2019-05-15 20:15:48,759 [salt.state       :915 ][INFO    ][7871] Loading fresh modules for state activity
2019-05-15 20:15:48,826 [salt.fileclient  :1219][INFO    ][7871] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-15 20:15:48,878 [salt.state       :1780][INFO    ][7871] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:48.878385
2019-05-15 20:15:48,878 [salt.state       :1813][INFO    ][7871] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-15 20:15:48,880 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7871] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-15 20:15:50,616 [salt.state       :300 ][INFO    ][7871] {'pid': 7886, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-15 20:15:50,617 [salt.state       :1951][INFO    ][7871] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:50.617357 duration_in_ms=1738.971
2019-05-15 20:15:50,621 [salt.state       :1780][INFO    ][7871] Running state [maas.wait_for_machine_status] at time 20:15:50.621339
2019-05-15 20:15:50,622 [salt.state       :1813][INFO    ][7871] Executing state module.run for [maas.wait_for_machine_status]
2019-05-15 20:15:50,623 [salt.utils.decorators:613 ][WARNING ][7871] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:15:52,656 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2247.98186493s left)
2019-05-15 20:15:57,835 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201557823282
2019-05-15 20:15:57,861 [salt.minion      :1432][INFO    ][7908] Starting a new job with PID 7908
2019-05-15 20:15:57,888 [salt.minion      :1711][INFO    ][7908] Returning information for job: 20190515201557823282
2019-05-15 20:16:24,664 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2215.97472596s left)
2019-05-15 20:16:27,916 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201627898620
2019-05-15 20:16:27,947 [salt.minion      :1432][INFO    ][7961] Starting a new job with PID 7961
2019-05-15 20:16:27,975 [salt.minion      :1711][INFO    ][7961] Returning information for job: 20190515201627898620
2019-05-15 20:16:56,677 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2183.961339s left)
2019-05-15 20:16:58,008 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201657996190
2019-05-15 20:16:58,036 [salt.minion      :1432][INFO    ][7984] Starting a new job with PID 7984
2019-05-15 20:16:58,065 [salt.minion      :1711][INFO    ][7984] Returning information for job: 20190515201657996190
2019-05-15 20:17:28,098 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201728085168
2019-05-15 20:17:28,123 [salt.minion      :1432][INFO    ][8049] Starting a new job with PID 8049
2019-05-15 20:17:28,156 [salt.minion      :1711][INFO    ][8049] Returning information for job: 20190515201728085168
2019-05-15 20:17:28,843 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2151.79509187s left)
2019-05-15 20:17:58,197 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201758180768
2019-05-15 20:17:58,229 [salt.minion      :1432][INFO    ][8075] Starting a new job with PID 8075
2019-05-15 20:17:58,260 [salt.minion      :1711][INFO    ][8075] Returning information for job: 20190515201758180768
2019-05-15 20:18:00,912 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2119.72621393s left)
2019-05-15 20:18:28,280 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201828267849
2019-05-15 20:18:28,301 [salt.minion      :1432][INFO    ][8144] Starting a new job with PID 8144
2019-05-15 20:18:28,333 [salt.minion      :1711][INFO    ][8144] Returning information for job: 20190515201828267849
2019-05-15 20:18:32,921 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2087.71764398s left)
2019-05-15 20:18:58,397 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201858378964
2019-05-15 20:18:58,425 [salt.minion      :1432][INFO    ][8236] Starting a new job with PID 8236
2019-05-15 20:18:58,454 [salt.minion      :1711][INFO    ][8236] Returning information for job: 20190515201858378964
2019-05-15 20:19:05,064 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2055.5738709s left)
2019-05-15 20:19:28,517 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201928499873
2019-05-15 20:19:28,547 [salt.minion      :1432][INFO    ][8474] Starting a new job with PID 8474
2019-05-15 20:19:28,576 [salt.minion      :1711][INFO    ][8474] Returning information for job: 20190515201928499873
2019-05-15 20:19:37,251 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2023.38735795s left)
2019-05-15 20:19:58,631 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515201958619380
2019-05-15 20:19:58,655 [salt.minion      :1432][INFO    ][8593] Starting a new job with PID 8593
2019-05-15 20:19:58,683 [salt.minion      :1711][INFO    ][8593] Returning information for job: 20190515201958619380
2019-05-15 20:20:09,271 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1991.36770797s left)
2019-05-15 20:20:28,729 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202028713776
2019-05-15 20:20:28,758 [salt.minion      :1432][INFO    ][8802] Starting a new job with PID 8802
2019-05-15 20:20:28,785 [salt.minion      :1711][INFO    ][8802] Returning information for job: 20190515202028713776
2019-05-15 20:20:41,403 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1959.23482585s left)
2019-05-15 20:20:58,844 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202058836277
2019-05-15 20:20:58,864 [salt.minion      :1432][INFO    ][8898] Starting a new job with PID 8898
2019-05-15 20:20:58,891 [salt.minion      :1711][INFO    ][8898] Returning information for job: 20190515202058836277
2019-05-15 20:21:13,368 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1927.27051592s left)
2019-05-15 20:21:28,988 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202128971516
2019-05-15 20:21:29,012 [salt.minion      :1432][INFO    ][9155] Starting a new job with PID 9155
2019-05-15 20:21:29,040 [salt.minion      :1711][INFO    ][9155] Returning information for job: 20190515202128971516
2019-05-15 20:21:45,378 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1895.26029706s left)
2019-05-15 20:21:59,120 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202159105294
2019-05-15 20:21:59,147 [salt.minion      :1432][INFO    ][9263] Starting a new job with PID 9263
2019-05-15 20:21:59,175 [salt.minion      :1711][INFO    ][9263] Returning information for job: 20190515202159105294
2019-05-15 20:22:17,288 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1863.3503809s left)
2019-05-15 20:22:29,286 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202229274511
2019-05-15 20:22:29,313 [salt.minion      :1432][INFO    ][9499] Starting a new job with PID 9499
2019-05-15 20:22:29,343 [salt.minion      :1711][INFO    ][9499] Returning information for job: 20190515202229274511
2019-05-15 20:22:49,294 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1831.34446192s left)
2019-05-15 20:22:59,420 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202259407206
2019-05-15 20:22:59,448 [salt.minion      :1432][INFO    ][9589] Starting a new job with PID 9589
2019-05-15 20:22:59,480 [salt.minion      :1711][INFO    ][9589] Returning information for job: 20190515202259407206
2019-05-15 20:23:21,280 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1799.35843205s left)
2019-05-15 20:23:29,588 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202329576165
2019-05-15 20:23:29,611 [salt.minion      :1432][INFO    ][9746] Starting a new job with PID 9746
2019-05-15 20:23:29,640 [salt.minion      :1711][INFO    ][9746] Returning information for job: 20190515202329576165
2019-05-15 20:23:53,496 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1767.14239287s left)
2019-05-15 20:23:59,729 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202359721122
2019-05-15 20:23:59,748 [salt.minion      :1432][INFO    ][9817] Starting a new job with PID 9817
2019-05-15 20:23:59,780 [salt.minion      :1711][INFO    ][9817] Returning information for job: 20190515202359721122
2019-05-15 20:24:25,597 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1735.04162288s left)
2019-05-15 20:24:29,903 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202429891900
2019-05-15 20:24:29,933 [salt.minion      :1432][INFO    ][9926] Starting a new job with PID 9926
2019-05-15 20:24:29,961 [salt.minion      :1711][INFO    ][9926] Returning information for job: 20190515202429891900
2019-05-15 20:24:57,723 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1702.9149189s left)
2019-05-15 20:25:00,081 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202500066877
2019-05-15 20:25:00,108 [salt.minion      :1432][INFO    ][9993] Starting a new job with PID 9993
2019-05-15 20:25:00,138 [salt.minion      :1711][INFO    ][9993] Returning information for job: 20190515202500066877
2019-05-15 20:25:29,772 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1670.86598802s left)
2019-05-15 20:25:30,276 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202530264830
2019-05-15 20:25:30,300 [salt.minion      :1432][INFO    ][10156] Starting a new job with PID 10156
2019-05-15 20:25:30,333 [salt.minion      :1711][INFO    ][10156] Returning information for job: 20190515202530264830
2019-05-15 20:26:00,313 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202600297642
2019-05-15 20:26:00,343 [salt.minion      :1432][INFO    ][10248] Starting a new job with PID 10248
2019-05-15 20:26:00,378 [salt.minion      :1711][INFO    ][10248] Returning information for job: 20190515202600297642
2019-05-15 20:26:01,788 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1638.85043406s left)
2019-05-15 20:26:30,531 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202630516065
2019-05-15 20:26:30,556 [salt.minion      :1432][INFO    ][10389] Starting a new job with PID 10389
2019-05-15 20:26:30,587 [salt.minion      :1711][INFO    ][10389] Returning information for job: 20190515202630516065
2019-05-15 20:26:33,827 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1606.81177807s left)
2019-05-15 20:27:00,706 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202700699269
2019-05-15 20:27:00,728 [salt.minion      :1432][INFO    ][10415] Starting a new job with PID 10415
2019-05-15 20:27:00,759 [salt.minion      :1711][INFO    ][10415] Returning information for job: 20190515202700699269
2019-05-15 20:27:05,796 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1574.84193897s left)
2019-05-15 20:27:30,915 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202730898898
2019-05-15 20:27:30,942 [salt.minion      :1432][INFO    ][10611] Starting a new job with PID 10611
2019-05-15 20:27:30,989 [salt.minion      :1711][INFO    ][10611] Returning information for job: 20190515202730898898
2019-05-15 20:27:37,786 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1542.85216403s left)
2019-05-15 20:28:01,135 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202801120644
2019-05-15 20:28:01,162 [salt.minion      :1432][INFO    ][10638] Starting a new job with PID 10638
2019-05-15 20:28:01,193 [salt.minion      :1711][INFO    ][10638] Returning information for job: 20190515202801120644
2019-05-15 20:28:09,971 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1510.66719794s left)
2019-05-15 20:28:31,355 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202831345638
2019-05-15 20:28:31,375 [salt.minion      :1432][INFO    ][10712] Starting a new job with PID 10712
2019-05-15 20:28:31,403 [salt.minion      :1711][INFO    ][10712] Returning information for job: 20190515202831345638
2019-05-15 20:28:42,107 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1478.53132606s left)
2019-05-15 20:29:01,447 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202901428349
2019-05-15 20:29:01,476 [salt.minion      :1432][INFO    ][10780] Starting a new job with PID 10780
2019-05-15 20:29:01,507 [salt.minion      :1711][INFO    ][10780] Returning information for job: 20190515202901428349
2019-05-15 20:29:14,007 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1446.6309309s left)
2019-05-15 20:29:31,481 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515202931469980
2019-05-15 20:29:31,510 [salt.minion      :1432][INFO    ][10924] Starting a new job with PID 10924
2019-05-15 20:29:31,539 [salt.minion      :1711][INFO    ][10924] Returning information for job: 20190515202931469980
2019-05-15 20:29:46,110 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1414.52828693s left)
2019-05-15 20:30:01,528 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203001511537
2019-05-15 20:30:01,554 [salt.minion      :1432][INFO    ][10958] Starting a new job with PID 10958
2019-05-15 20:30:01,583 [salt.minion      :1711][INFO    ][10958] Returning information for job: 20190515203001511537
2019-05-15 20:30:18,031 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1382.60684896s left)
2019-05-15 20:30:31,569 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203031556982
2019-05-15 20:30:31,596 [salt.minion      :1432][INFO    ][11009] Starting a new job with PID 11009
2019-05-15 20:30:31,628 [salt.minion      :1711][INFO    ][11009] Returning information for job: 20190515203031556982
2019-05-15 20:30:49,987 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1350.65149188s left)
2019-05-15 20:31:01,638 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203101626906
2019-05-15 20:31:01,656 [salt.minion      :1432][INFO    ][11039] Starting a new job with PID 11039
2019-05-15 20:31:01,693 [salt.minion      :1711][INFO    ][11039] Returning information for job: 20190515203101626906
2019-05-15 20:31:21,814 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1318.82406592s left)
2019-05-15 20:31:31,685 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203131671193
2019-05-15 20:31:31,712 [salt.minion      :1432][INFO    ][11090] Starting a new job with PID 11090
2019-05-15 20:31:31,741 [salt.minion      :1711][INFO    ][11090] Returning information for job: 20190515203131671193
2019-05-15 20:31:53,829 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1286.80879903s left)
2019-05-15 20:32:01,780 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203201764062
2019-05-15 20:32:01,811 [salt.minion      :1432][INFO    ][11114] Starting a new job with PID 11114
2019-05-15 20:32:01,839 [salt.minion      :1711][INFO    ][11114] Returning information for job: 20190515203201764062
2019-05-15 20:32:25,851 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1254.78743601s left)
2019-05-15 20:32:31,856 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203231840477
2019-05-15 20:32:31,885 [salt.minion      :1432][INFO    ][11162] Starting a new job with PID 11162
2019-05-15 20:32:31,918 [salt.minion      :1711][INFO    ][11162] Returning information for job: 20190515203231840477
2019-05-15 20:32:58,019 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1222.61969185s left)
2019-05-15 20:33:01,991 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203301972373
2019-05-15 20:33:02,017 [salt.minion      :1432][INFO    ][11184] Starting a new job with PID 11184
2019-05-15 20:33:02,049 [salt.minion      :1711][INFO    ][11184] Returning information for job: 20190515203301972373
2019-05-15 20:33:29,867 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1190.77177286s left)
2019-05-15 20:33:32,118 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203332107950
2019-05-15 20:33:32,143 [salt.minion      :1432][INFO    ][11238] Starting a new job with PID 11238
2019-05-15 20:33:32,172 [salt.minion      :1711][INFO    ][11238] Returning information for job: 20190515203332107950
2019-05-15 20:34:01,896 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1158.74249697s left)
2019-05-15 20:34:02,245 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203402230426
2019-05-15 20:34:02,266 [salt.minion      :1432][INFO    ][11264] Starting a new job with PID 11264
2019-05-15 20:34:02,296 [salt.minion      :1711][INFO    ][11264] Returning information for job: 20190515203402230426
2019-05-15 20:34:32,403 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203432385838
2019-05-15 20:34:32,429 [salt.minion      :1432][INFO    ][11313] Starting a new job with PID 11313
2019-05-15 20:34:32,458 [salt.minion      :1711][INFO    ][11313] Returning information for job: 20190515203432385838
2019-05-15 20:34:33,828 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1126.81007195s left)
2019-05-15 20:35:02,588 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203502570998
2019-05-15 20:35:02,616 [salt.minion      :1432][INFO    ][11338] Starting a new job with PID 11338
2019-05-15 20:35:02,647 [salt.minion      :1711][INFO    ][11338] Returning information for job: 20190515203502570998
2019-05-15 20:35:05,716 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1094.92216587s left)
2019-05-15 20:35:32,783 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203532767309
2019-05-15 20:35:32,813 [salt.minion      :1432][INFO    ][11388] Starting a new job with PID 11388
2019-05-15 20:35:32,840 [salt.minion      :1711][INFO    ][11388] Returning information for job: 20190515203532767309
2019-05-15 20:35:37,577 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1063.06138301s left)
2019-05-15 20:36:02,959 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203602945362
2019-05-15 20:36:02,984 [salt.minion      :1432][INFO    ][11414] Starting a new job with PID 11414
2019-05-15 20:36:03,016 [salt.minion      :1711][INFO    ][11414] Returning information for job: 20190515203602945362
2019-05-15 20:36:09,462 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1031.17623091s left)
2019-05-15 20:36:33,175 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203633159122
2019-05-15 20:36:33,203 [salt.minion      :1432][INFO    ][11463] Starting a new job with PID 11463
2019-05-15 20:36:33,234 [salt.minion      :1711][INFO    ][11463] Returning information for job: 20190515203633159122
2019-05-15 20:36:41,315 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (999.323117971s left)
2019-05-15 20:37:03,372 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203703356551
2019-05-15 20:37:03,400 [salt.minion      :1432][INFO    ][11487] Starting a new job with PID 11487
2019-05-15 20:37:03,431 [salt.minion      :1711][INFO    ][11487] Returning information for job: 20190515203703356551
2019-05-15 20:37:13,306 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (967.332643986s left)
2019-05-15 20:37:33,411 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203733394609
2019-05-15 20:37:33,439 [salt.minion      :1432][INFO    ][11561] Starting a new job with PID 11561
2019-05-15 20:37:33,469 [salt.minion      :1711][INFO    ][11561] Returning information for job: 20190515203733394609
2019-05-15 20:37:45,160 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (935.478142023s left)
2019-05-15 20:38:03,631 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203803619013
2019-05-15 20:38:03,659 [salt.minion      :1432][INFO    ][11586] Starting a new job with PID 11586
2019-05-15 20:38:03,692 [salt.minion      :1711][INFO    ][11586] Returning information for job: 20190515203803619013
2019-05-15 20:38:17,237 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (903.401778936s left)
2019-05-15 20:38:33,689 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203833678773
2019-05-15 20:38:33,714 [salt.minion      :1432][INFO    ][11641] Starting a new job with PID 11641
2019-05-15 20:38:33,746 [salt.minion      :1711][INFO    ][11641] Returning information for job: 20190515203833678773
2019-05-15 20:38:49,391 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (871.247282982s left)
2019-05-15 20:39:03,744 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203903731149
2019-05-15 20:39:03,767 [salt.minion      :1432][INFO    ][11671] Starting a new job with PID 11671
2019-05-15 20:39:03,800 [salt.minion      :1711][INFO    ][11671] Returning information for job: 20190515203903731149
2019-05-15 20:39:21,294 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (839.34383297s left)
2019-05-15 20:39:33,861 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515203933844002
2019-05-15 20:39:33,892 [salt.minion      :1432][INFO    ][11722] Starting a new job with PID 11722
2019-05-15 20:39:33,925 [salt.minion      :1711][INFO    ][11722] Returning information for job: 20190515203933844002
2019-05-15 20:39:53,255 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (807.382865906s left)
2019-05-15 20:40:03,988 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204003973738
2019-05-15 20:40:04,018 [salt.minion      :1432][INFO    ][11744] Starting a new job with PID 11744
2019-05-15 20:40:04,048 [salt.minion      :1711][INFO    ][11744] Returning information for job: 20190515204003973738
2019-05-15 20:40:25,160 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (775.477820873s left)
2019-05-15 20:40:34,134 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204034119273
2019-05-15 20:40:34,163 [salt.minion      :1432][INFO    ][11792] Starting a new job with PID 11792
2019-05-15 20:40:34,193 [salt.minion      :1711][INFO    ][11792] Returning information for job: 20190515204034119273
2019-05-15 20:40:57,062 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (743.577081919s left)
2019-05-15 20:41:04,307 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204104292804
2019-05-15 20:41:04,330 [salt.minion      :1432][INFO    ][11814] Starting a new job with PID 11814
2019-05-15 20:41:04,360 [salt.minion      :1711][INFO    ][11814] Returning information for job: 20190515204104292804
2019-05-15 20:41:28,967 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (711.67155385s left)
2019-05-15 20:41:34,432 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204134413099
2019-05-15 20:41:34,462 [salt.minion      :1432][INFO    ][11864] Starting a new job with PID 11864
2019-05-15 20:41:34,491 [salt.minion      :1711][INFO    ][11864] Returning information for job: 20190515204134413099
2019-05-15 20:42:00,863 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (679.774908066s left)
2019-05-15 20:42:04,648 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204204630622
2019-05-15 20:42:04,677 [salt.minion      :1432][INFO    ][11887] Starting a new job with PID 11887
2019-05-15 20:42:04,708 [salt.minion      :1711][INFO    ][11887] Returning information for job: 20190515204204630622
2019-05-15 20:42:33,059 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (647.579314947s left)
2019-05-15 20:42:34,849 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204234835440
2019-05-15 20:42:34,876 [salt.minion      :1432][INFO    ][11936] Starting a new job with PID 11936
2019-05-15 20:42:34,904 [salt.minion      :1711][INFO    ][11936] Returning information for job: 20190515204234835440
2019-05-15 20:43:04,938 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (615.70053196s left)
2019-05-15 20:43:05,071 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204305058571
2019-05-15 20:43:05,098 [salt.minion      :1432][INFO    ][11962] Starting a new job with PID 11962
2019-05-15 20:43:05,129 [salt.minion      :1711][INFO    ][11962] Returning information for job: 20190515204305058571
2019-05-15 20:43:35,119 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204335109555
2019-05-15 20:43:35,140 [salt.minion      :1432][INFO    ][12010] Starting a new job with PID 12010
2019-05-15 20:43:35,168 [salt.minion      :1711][INFO    ][12010] Returning information for job: 20190515204335109555
2019-05-15 20:43:36,949 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (583.689467907s left)
2019-05-15 20:44:05,184 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204405167411
2019-05-15 20:44:05,214 [salt.minion      :1432][INFO    ][12032] Starting a new job with PID 12032
2019-05-15 20:44:05,245 [salt.minion      :1711][INFO    ][12032] Returning information for job: 20190515204405167411
2019-05-15 20:44:09,022 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (551.616247892s left)
2019-05-15 20:44:35,279 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204435265313
2019-05-15 20:44:35,308 [salt.minion      :1432][INFO    ][12080] Starting a new job with PID 12080
2019-05-15 20:44:35,339 [salt.minion      :1711][INFO    ][12080] Returning information for job: 20190515204435265313
2019-05-15 20:44:40,945 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (519.69330287s left)
2019-05-15 20:45:05,394 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204505379301
2019-05-15 20:45:05,423 [salt.minion      :1432][INFO    ][12105] Starting a new job with PID 12105
2019-05-15 20:45:05,453 [salt.minion      :1711][INFO    ][12105] Returning information for job: 20190515204505379301
2019-05-15 20:45:12,816 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (487.821825027s left)
2019-05-15 20:45:35,542 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204535529021
2019-05-15 20:45:35,564 [salt.minion      :1432][INFO    ][12155] Starting a new job with PID 12155
2019-05-15 20:45:35,596 [salt.minion      :1711][INFO    ][12155] Returning information for job: 20190515204535529021
2019-05-15 20:45:44,695 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (455.942982912s left)
2019-05-15 20:46:05,687 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204605673417
2019-05-15 20:46:05,718 [salt.minion      :1432][INFO    ][12181] Starting a new job with PID 12181
2019-05-15 20:46:05,758 [salt.minion      :1711][INFO    ][12181] Returning information for job: 20190515204605673417
2019-05-15 20:46:16,559 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (424.07900691s left)
2019-05-15 20:46:35,880 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204635860739
2019-05-15 20:46:35,910 [salt.minion      :1432][INFO    ][12235] Starting a new job with PID 12235
2019-05-15 20:46:35,945 [salt.minion      :1711][INFO    ][12235] Returning information for job: 20190515204635860739
2019-05-15 20:46:48,447 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (392.191407919s left)
2019-05-15 20:47:06,005 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204705989544
2019-05-15 20:47:06,036 [salt.minion      :1432][INFO    ][12260] Starting a new job with PID 12260
2019-05-15 20:47:06,067 [salt.minion      :1711][INFO    ][12260] Returning information for job: 20190515204705989544
2019-05-15 20:47:20,485 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (360.153511047s left)
2019-05-15 20:47:36,059 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204736042644
2019-05-15 20:47:36,091 [salt.minion      :1432][INFO    ][12457] Starting a new job with PID 12457
2019-05-15 20:47:36,131 [salt.minion      :1711][INFO    ][12457] Returning information for job: 20190515204736042644
2019-05-15 20:47:52,506 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (328.132113934s left)
2019-05-15 20:48:06,101 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204806085112
2019-05-15 20:48:06,130 [salt.minion      :1432][INFO    ][12482] Starting a new job with PID 12482
2019-05-15 20:48:06,160 [salt.minion      :1711][INFO    ][12482] Returning information for job: 20190515204806085112
2019-05-15 20:48:24,427 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (296.211636066s left)
2019-05-15 20:48:36,157 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204836145534
2019-05-15 20:48:36,181 [salt.minion      :1432][INFO    ][12532] Starting a new job with PID 12532
2019-05-15 20:48:36,211 [salt.minion      :1711][INFO    ][12532] Returning information for job: 20190515204836145534
2019-05-15 20:48:56,185 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (264.453256845s left)
2019-05-15 20:49:06,391 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204906376302
2019-05-15 20:49:06,419 [salt.minion      :1432][INFO    ][12561] Starting a new job with PID 12561
2019-05-15 20:49:06,450 [salt.minion      :1711][INFO    ][12561] Returning information for job: 20190515204906376302
2019-05-15 20:49:28,116 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (232.522330999s left)
2019-05-15 20:49:36,477 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515204936460589
2019-05-15 20:49:36,509 [salt.minion      :1432][INFO    ][12611] Starting a new job with PID 12611
2019-05-15 20:49:36,548 [salt.minion      :1711][INFO    ][12611] Returning information for job: 20190515204936460589
2019-05-15 20:49:59,855 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (200.78332901s left)
2019-05-15 20:50:06,545 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205006530870
2019-05-15 20:50:06,573 [salt.minion      :1432][INFO    ][12635] Starting a new job with PID 12635
2019-05-15 20:50:06,605 [salt.minion      :1711][INFO    ][12635] Returning information for job: 20190515205006530870
2019-05-15 20:50:31,737 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (168.901685953s left)
2019-05-15 20:50:36,665 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205036651393
2019-05-15 20:50:36,688 [salt.minion      :1432][INFO    ][12687] Starting a new job with PID 12687
2019-05-15 20:50:36,719 [salt.minion      :1711][INFO    ][12687] Returning information for job: 20190515205036651393
2019-05-15 20:51:03,728 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (136.91031003s left)
2019-05-15 20:51:06,775 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205106767612
2019-05-15 20:51:06,793 [salt.minion      :1432][INFO    ][12712] Starting a new job with PID 12712
2019-05-15 20:51:06,828 [salt.minion      :1711][INFO    ][12712] Returning information for job: 20190515205106767612
2019-05-15 20:51:35,758 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (104.880048037s left)
2019-05-15 20:51:36,909 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205136892785
2019-05-15 20:51:36,939 [salt.minion      :1432][INFO    ][12765] Starting a new job with PID 12765
2019-05-15 20:51:36,975 [salt.minion      :1711][INFO    ][12765] Returning information for job: 20190515205136892785
2019-05-15 20:52:07,074 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205207060690
2019-05-15 20:52:07,100 [salt.minion      :1432][INFO    ][12790] Starting a new job with PID 12790
2019-05-15 20:52:07,131 [salt.minion      :1711][INFO    ][12790] Returning information for job: 20190515205207060690
2019-05-15 20:52:07,653 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (72.9852290154s left)
2019-05-15 20:52:37,207 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205237198164
2019-05-15 20:52:37,237 [salt.minion      :1432][INFO    ][12839] Starting a new job with PID 12839
2019-05-15 20:52:37,267 [salt.minion      :1711][INFO    ][12839] Returning information for job: 20190515205237198164
2019-05-15 20:52:39,565 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (41.0734908581s left)
2019-05-15 20:53:07,400 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205307383640
2019-05-15 20:53:07,428 [salt.minion      :1432][INFO    ][12861] Starting a new job with PID 12861
2019-05-15 20:53:07,461 [salt.minion      :1711][INFO    ][12861] Returning information for job: 20190515205307383640
2019-05-15 20:53:11,459 [salt.loaded.ext.module.maas:1023][INFO    ][7871] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (9.17972397804s left)
2019-05-15 20:53:37,592 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205337580632
2019-05-15 20:53:37,618 [salt.minion      :1432][INFO    ][12909] Starting a new job with PID 12909
2019-05-15 20:53:37,645 [salt.minion      :1711][INFO    ][12909] Returning information for job: 20190515205337580632
2019-05-15 20:53:43,488 [salt.state       :302 ][ERROR   ][7871] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['gtw01']not in Deployed state
2019-05-15 20:53:43,488 [salt.state       :1951][INFO    ][7871] Completed state [maas.wait_for_machine_status] at time 20:53:43.488825 duration_in_ms=2272867.483
2019-05-15 20:53:43,498 [salt.minion      :1711][INFO    ][7871] Returning information for job: 20190515201542738685
2019-05-15 20:53:54,424 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command pillar.get with jid 20190515205354410973
2019-05-15 20:53:54,456 [salt.minion      :1432][INFO    ][12932] Starting a new job with PID 12932
2019-05-15 20:53:54,465 [salt.minion      :1711][INFO    ][12932] Returning information for job: 20190515205354410973
2019-05-15 20:53:55,143 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command service.status with jid 20190515205355131049
2019-05-15 20:53:55,170 [salt.minion      :1432][INFO    ][12937] Starting a new job with PID 12937
2019-05-15 20:53:55,922 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12937] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-15 20:53:55,971 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12937] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-15 20:53:55,989 [salt.minion      :1711][INFO    ][12937] Returning information for job: 20190515205355131049
2019-05-15 20:53:56,684 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command state.apply with jid 20190515205356671272
2019-05-15 20:53:56,711 [salt.minion      :1432][INFO    ][12948] Starting a new job with PID 12948
2019-05-15 20:54:02,573 [salt.state       :915 ][INFO    ][12948] Loading fresh modules for state activity
2019-05-15 20:54:03,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12948] Executing command 'salt-minion --version' in directory '/root'
2019-05-15 20:54:03,514 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12948] Executing command 'salt-minion --version' in directory '/root'
2019-05-15 20:54:04,547 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12948] Executing command 'salt-minion --version' in directory '/root'
2019-05-15 20:54:04,905 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12948] Executing command 'salt-minion --version' in directory '/root'
2019-05-15 20:54:07,072 [salt.state       :1780][INFO    ][12948] Running state [salt-minion] at time 20:54:07.071931
2019-05-15 20:54:07,072 [salt.state       :1813][INFO    ][12948] Executing state pkg.installed for [salt-minion]
2019-05-15 20:54:07,073 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12948] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-15 20:54:07,204 [salt.state       :300 ][INFO    ][12948] All specified packages are already installed
2019-05-15 20:54:07,205 [salt.state       :1951][INFO    ][12948] Completed state [salt-minion] at time 20:54:07.205298 duration_in_ms=133.366
2019-05-15 20:54:07,207 [salt.state       :1780][INFO    ][12948] Running state [salt_minion_dependency_packages] at time 20:54:07.207038
2019-05-15 20:54:07,207 [salt.state       :1813][INFO    ][12948] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-15 20:54:07,222 [salt.state       :300 ][INFO    ][12948] All specified packages are already installed
2019-05-15 20:54:07,222 [salt.state       :1951][INFO    ][12948] Completed state [salt_minion_dependency_packages] at time 20:54:07.222293 duration_in_ms=15.256
2019-05-15 20:54:07,227 [salt.state       :1780][INFO    ][12948] Running state [/etc/salt/minion.d/minion.conf] at time 20:54:07.227079
2019-05-15 20:54:07,227 [salt.state       :1813][INFO    ][12948] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-15 20:54:07,502 [salt.state       :300 ][INFO    ][12948] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-15 20:54:07,503 [salt.state       :1951][INFO    ][12948] Completed state [/etc/salt/minion.d/minion.conf] at time 20:54:07.503187 duration_in_ms=276.108
2019-05-15 20:54:07,506 [salt.state       :1780][INFO    ][12948] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:54:07.506702
2019-05-15 20:54:07,507 [salt.state       :1813][INFO    ][12948] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-15 20:54:07,520 [salt.state       :300 ][INFO    ][12948] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-15 20:54:07,520 [salt.state       :1951][INFO    ][12948] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:54:07.520524 duration_in_ms=13.822
2019-05-15 20:54:07,522 [salt.state       :1780][INFO    ][12948] Running state [salt-minion] at time 20:54:07.522187
2019-05-15 20:54:07,522 [salt.state       :1813][INFO    ][12948] Executing state service.running for [salt-minion]
2019-05-15 20:54:07,523 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12948] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-15 20:54:07,566 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12948] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-15 20:54:07,589 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12948] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-15 20:54:07,614 [salt.state       :300 ][INFO    ][12948] The service salt-minion is already running
2019-05-15 20:54:07,615 [salt.state       :1951][INFO    ][12948] Completed state [salt-minion] at time 20:54:07.615579 duration_in_ms=93.391
2019-05-15 20:54:07,618 [salt.state       :1780][INFO    ][12948] Running state [/etc/salt/grains.d] at time 20:54:07.618866
2019-05-15 20:54:07,619 [salt.state       :1813][INFO    ][12948] Executing state file.directory for [/etc/salt/grains.d]
2019-05-15 20:54:07,623 [salt.state       :300 ][INFO    ][12948] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-15 20:54:07,624 [salt.state       :1951][INFO    ][12948] Completed state [/etc/salt/grains.d] at time 20:54:07.624082 duration_in_ms=5.217
2019-05-15 20:54:07,624 [salt.state       :1780][INFO    ][12948] Running state [/etc/salt/grains] at time 20:54:07.624888
2019-05-15 20:54:07,625 [salt.state       :1813][INFO    ][12948] Executing state file.managed for [/etc/salt/grains]
2019-05-15 20:54:07,626 [salt.state       :300 ][INFO    ][12948] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-15 20:54:07,626 [salt.state       :1951][INFO    ][12948] Completed state [/etc/salt/grains] at time 20:54:07.626519 duration_in_ms=1.631
2019-05-15 20:54:07,627 [salt.state       :1780][INFO    ][12948] Running state [/etc/salt/grains.d/placeholder] at time 20:54:07.627104
2019-05-15 20:54:07,627 [salt.state       :1813][INFO    ][12948] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-15 20:54:07,628 [salt.state       :300 ][INFO    ][12948] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-15 20:54:07,628 [salt.state       :1951][INFO    ][12948] Completed state [/etc/salt/grains.d/placeholder] at time 20:54:07.628392 duration_in_ms=1.288
2019-05-15 20:54:07,629 [salt.state       :1780][INFO    ][12948] Running state [/etc/salt/grains.d/sphinx] at time 20:54:07.628974
2019-05-15 20:54:07,629 [salt.state       :1813][INFO    ][12948] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-15 20:54:07,630 [salt.state       :300 ][INFO    ][12948] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-15 20:54:07,631 [salt.state       :1951][INFO    ][12948] Completed state [/etc/salt/grains.d/sphinx] at time 20:54:07.631068 duration_in_ms=2.094
2019-05-15 20:54:07,633 [salt.state       :1780][INFO    ][12948] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:54:07.633455
2019-05-15 20:54:07,635 [salt.state       :1813][INFO    ][12948] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-15 20:54:07,635 [salt.state       :300 ][INFO    ][12948] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-15 20:54:07,635 [salt.state       :1951][INFO    ][12948] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:54:07.635582 duration_in_ms=2.127
2019-05-15 20:54:07,636 [salt.state       :1780][INFO    ][12948] Running state [/etc/salt/grains.d/dns_records] at time 20:54:07.636113
2019-05-15 20:54:07,636 [salt.state       :1813][INFO    ][12948] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-15 20:54:07,637 [salt.state       :300 ][INFO    ][12948] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-15 20:54:07,637 [salt.state       :1951][INFO    ][12948] Completed state [/etc/salt/grains.d/dns_records] at time 20:54:07.637920 duration_in_ms=1.807
2019-05-15 20:54:07,638 [salt.state       :1780][INFO    ][12948] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:54:07.638878
2019-05-15 20:54:07,639 [salt.state       :1813][INFO    ][12948] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-15 20:54:07,639 [salt.state       :300 ][INFO    ][12948] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-15 20:54:07,639 [salt.state       :1951][INFO    ][12948] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:54:07.639725 duration_in_ms=0.847
2019-05-15 20:54:07,640 [salt.state       :1780][INFO    ][12948] Running state [/etc/salt/grains.d/salt] at time 20:54:07.640234
2019-05-15 20:54:07,640 [salt.state       :1813][INFO    ][12948] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-15 20:54:07,641 [salt.state       :300 ][INFO    ][12948] File /etc/salt/grains.d/salt is in the correct state
2019-05-15 20:54:07,642 [salt.state       :1951][INFO    ][12948] Completed state [/etc/salt/grains.d/salt] at time 20:54:07.642102 duration_in_ms=1.868
2019-05-15 20:54:07,643 [salt.state       :1780][INFO    ][12948] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:54:07.643006
2019-05-15 20:54:07,643 [salt.state       :1813][INFO    ][12948] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-15 20:54:07,643 [salt.state       :300 ][INFO    ][12948] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-15 20:54:07,643 [salt.state       :1951][INFO    ][12948] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:54:07.643846 duration_in_ms=0.84
2019-05-15 20:54:07,647 [salt.state       :1780][INFO    ][12948] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:54:07.647238
2019-05-15 20:54:07,647 [salt.state       :1813][INFO    ][12948] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-15 20:54:07,647 [salt.state       :300 ][INFO    ][12948] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-15 20:54:07,648 [salt.state       :1951][INFO    ][12948] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:54:07.648096 duration_in_ms=0.86
2019-05-15 20:54:07,648 [salt.state       :1780][INFO    ][12948] Running state [mine.update] at time 20:54:07.648798
2019-05-15 20:54:07,649 [salt.state       :1813][INFO    ][12948] Executing state module.wait for [mine.update]
2019-05-15 20:54:07,649 [salt.state       :300 ][INFO    ][12948] No changes made for mine.update
2019-05-15 20:54:07,649 [salt.state       :1951][INFO    ][12948] Completed state [mine.update] at time 20:54:07.649613 duration_in_ms=0.815
2019-05-15 20:54:07,650 [salt.state       :1780][INFO    ][12948] Running state [ca-certificates] at time 20:54:07.650199
2019-05-15 20:54:07,650 [salt.state       :1813][INFO    ][12948] Executing state pkg.installed for [ca-certificates]
2019-05-15 20:54:07,660 [salt.state       :300 ][INFO    ][12948] All specified packages are already installed
2019-05-15 20:54:07,661 [salt.state       :1951][INFO    ][12948] Completed state [ca-certificates] at time 20:54:07.661099 duration_in_ms=10.9
2019-05-15 20:54:07,662 [salt.state       :1780][INFO    ][12948] Running state [update-ca-certificates] at time 20:54:07.662543
2019-05-15 20:54:07,662 [salt.state       :1813][INFO    ][12948] Executing state cmd.wait for [update-ca-certificates]
2019-05-15 20:54:07,663 [salt.state       :300 ][INFO    ][12948] No changes made for update-ca-certificates
2019-05-15 20:54:07,663 [salt.state       :1951][INFO    ][12948] Completed state [update-ca-certificates] at time 20:54:07.663365 duration_in_ms=0.822
2019-05-15 20:54:07,663 [salt.state       :1780][INFO    ][12948] Running state [iptables] at time 20:54:07.663637
2019-05-15 20:54:07,663 [salt.state       :1813][INFO    ][12948] Executing state pkg.installed for [iptables]
2019-05-15 20:54:07,673 [salt.state       :300 ][INFO    ][12948] All specified packages are already installed
2019-05-15 20:54:07,673 [salt.state       :1951][INFO    ][12948] Completed state [iptables] at time 20:54:07.673217 duration_in_ms=9.58
2019-05-15 20:54:07,673 [salt.state       :1780][INFO    ][12948] Running state [iptables-persistent] at time 20:54:07.673488
2019-05-15 20:54:07,674 [salt.state       :1813][INFO    ][12948] Executing state pkg.installed for [iptables-persistent]
2019-05-15 20:54:07,683 [salt.state       :300 ][INFO    ][12948] All specified packages are already installed
2019-05-15 20:54:07,683 [salt.state       :1951][INFO    ][12948] Completed state [iptables-persistent] at time 20:54:07.683479 duration_in_ms=9.99
2019-05-15 20:54:07,684 [salt.state       :1780][INFO    ][12948] Running state [iptables_modules_v4_load] at time 20:54:07.684490
2019-05-15 20:54:07,684 [salt.state       :1813][INFO    ][12948] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-15 20:54:07,685 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12948] Executing command 'lsmod' in directory '/root'
2019-05-15 20:54:07,710 [salt.state       :300 ][INFO    ][12948] Kernel modules iptable_filter, ip_tables are already present
2019-05-15 20:54:07,710 [salt.state       :1951][INFO    ][12948] Completed state [iptables_modules_v4_load] at time 20:54:07.710495 duration_in_ms=26.004
2019-05-15 20:54:07,711 [salt.state       :1780][INFO    ][12948] Running state [/etc/iptables/rules.v4] at time 20:54:07.711659
2019-05-15 20:54:07,712 [salt.state       :1813][INFO    ][12948] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-15 20:54:07,815 [salt.state       :300 ][INFO    ][12948] File /etc/iptables/rules.v4 is in the correct state
2019-05-15 20:54:07,815 [salt.state       :1951][INFO    ][12948] Completed state [/etc/iptables/rules.v4] at time 20:54:07.815798 duration_in_ms=104.14
2019-05-15 20:54:07,816 [salt.state       :1780][INFO    ][12948] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:54:07.816905
2019-05-15 20:54:07,817 [salt.state       :1813][INFO    ][12948] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-15 20:54:07,818 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12948] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-15 20:54:07,836 [salt.state       :300 ][INFO    ][12948] onlyif execution failed
2019-05-15 20:54:07,837 [salt.state       :1951][INFO    ][12948] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:54:07.837130 duration_in_ms=20.224
2019-05-15 20:54:07,841 [salt.state       :1780][INFO    ][12948] Running state [netfilter-persistent] at time 20:54:07.841158
2019-05-15 20:54:07,841 [salt.state       :1813][INFO    ][12948] Executing state service.running for [netfilter-persistent]
2019-05-15 20:54:07,843 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12948] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-15 20:54:07,867 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12948] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-15 20:54:07,891 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12948] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-15 20:54:07,913 [salt.state       :300 ][INFO    ][12948] The service netfilter-persistent is already running
2019-05-15 20:54:07,914 [salt.state       :1951][INFO    ][12948] Completed state [netfilter-persistent] at time 20:54:07.914009 duration_in_ms=72.852
2019-05-15 20:54:07,915 [salt.state       :1780][INFO    ][12948] Running state [iptables_extra.remove_stale_tables] at time 20:54:07.915588
2019-05-15 20:54:07,916 [salt.state       :1813][INFO    ][12948] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-15 20:54:07,916 [salt.state       :300 ][INFO    ][12948] No changes made for iptables_extra.remove_stale_tables
2019-05-15 20:54:07,917 [salt.state       :1951][INFO    ][12948] Completed state [iptables_extra.remove_stale_tables] at time 20:54:07.917227 duration_in_ms=1.638
2019-05-15 20:54:07,917 [salt.state       :1780][INFO    ][12948] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:54:07.917829
2019-05-15 20:54:07,918 [salt.state       :1813][INFO    ][12948] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-15 20:54:07,919 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12948] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-15 20:54:07,940 [salt.state       :300 ][INFO    ][12948] onlyif execution failed
2019-05-15 20:54:07,941 [salt.state       :1951][INFO    ][12948] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:54:07.941206 duration_in_ms=23.376
2019-05-15 20:54:07,943 [salt.state       :1780][INFO    ][12948] Running state [/etc/iptables/rules.v6] at time 20:54:07.943179
2019-05-15 20:54:07,943 [salt.state       :1813][INFO    ][12948] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-15 20:54:07,944 [salt.state       :300 ][INFO    ][12948] File /etc/iptables/rules.v6 is not present
2019-05-15 20:54:07,945 [salt.state       :1951][INFO    ][12948] Completed state [/etc/iptables/rules.v6] at time 20:54:07.944925 duration_in_ms=1.745
2019-05-15 20:54:07,948 [salt.state       :1780][INFO    ][12948] Running state [iptables_extra.flush_all] at time 20:54:07.948107
2019-05-15 20:54:07,948 [salt.state       :1813][INFO    ][12948] Executing state module.wait for [iptables_extra.flush_all]
2019-05-15 20:54:07,948 [salt.state       :300 ][INFO    ][12948] No changes made for iptables_extra.flush_all
2019-05-15 20:54:07,949 [salt.state       :1951][INFO    ][12948] Completed state [iptables_extra.flush_all] at time 20:54:07.949113 duration_in_ms=1.006
2019-05-15 20:54:07,952 [salt.minion      :1711][INFO    ][12948] Returning information for job: 20190515205356671272
2019-05-15 20:54:08,661 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command state.apply with jid 20190515205408646118
2019-05-15 20:54:08,686 [salt.minion      :1432][INFO    ][13037] Starting a new job with PID 13037
2019-05-15 20:54:09,568 [salt.state       :915 ][INFO    ][13037] Loading fresh modules for state activity
2019-05-15 20:54:10,839 [salt.state       :1780][INFO    ][13037] Running state [maas-rack-controller] at time 20:54:10.838967
2019-05-15 20:54:10,839 [salt.state       :1813][INFO    ][13037] Executing state pkg.installed for [maas-rack-controller]
2019-05-15 20:54:10,840 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13037] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-15 20:54:10,960 [salt.state       :300 ][INFO    ][13037] All specified packages are already installed
2019-05-15 20:54:10,961 [salt.state       :1951][INFO    ][13037] Completed state [maas-rack-controller] at time 20:54:10.961071 duration_in_ms=122.104
2019-05-15 20:54:10,961 [salt.state       :1780][INFO    ][13037] Running state [ipmitool] at time 20:54:10.961466
2019-05-15 20:54:10,961 [salt.state       :1813][INFO    ][13037] Executing state pkg.installed for [ipmitool]
2019-05-15 20:54:10,971 [salt.state       :300 ][INFO    ][13037] All specified packages are already installed
2019-05-15 20:54:10,972 [salt.state       :1951][INFO    ][13037] Completed state [ipmitool] at time 20:54:10.972053 duration_in_ms=10.588
2019-05-15 20:54:10,975 [salt.state       :1780][INFO    ][13037] Running state [/etc/maas/rackd.conf] at time 20:54:10.975140
2019-05-15 20:54:10,975 [salt.state       :1813][INFO    ][13037] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-15 20:54:10,976 [salt.state       :300 ][INFO    ][13037] No changes needed to be made
2019-05-15 20:54:10,976 [salt.state       :1951][INFO    ][13037] Completed state [/etc/maas/rackd.conf] at time 20:54:10.976640 duration_in_ms=1.501
2019-05-15 20:54:10,976 [salt.state       :1780][INFO    ][13037] Running state [/etc/maas/rackd.conf] at time 20:54:10.976925
2019-05-15 20:54:10,977 [salt.state       :1813][INFO    ][13037] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-15 20:54:10,977 [salt.loaded.int.states.file:2298][WARNING ][13037] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-15 20:54:10,978 [salt.state       :300 ][INFO    ][13037] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-15 20:54:10,978 [salt.state       :1951][INFO    ][13037] Completed state [/etc/maas/rackd.conf] at time 20:54:10.978546 duration_in_ms=1.622
2019-05-15 20:54:10,979 [salt.state       :1780][INFO    ][13037] Running state [maas-rackd] at time 20:54:10.979591
2019-05-15 20:54:10,979 [salt.state       :1813][INFO    ][13037] Executing state service.running for [maas-rackd]
2019-05-15 20:54:10,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13037] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-15 20:54:11,024 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13037] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-15 20:54:11,045 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13037] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-15 20:54:11,068 [salt.state       :300 ][INFO    ][13037] The service maas-rackd is already running
2019-05-15 20:54:11,069 [salt.state       :1951][INFO    ][13037] Completed state [maas-rackd] at time 20:54:11.069176 duration_in_ms=89.584
2019-05-15 20:54:11,071 [salt.minion      :1711][INFO    ][13037] Returning information for job: 20190515205408646118
2019-05-15 20:54:11,767 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command state.apply with jid 20190515205411756056
2019-05-15 20:54:11,794 [salt.minion      :1432][INFO    ][13060] Starting a new job with PID 13060
2019-05-15 20:54:12,701 [salt.state       :915 ][INFO    ][13060] Loading fresh modules for state activity
2019-05-15 20:54:14,077 [salt.state       :1780][INFO    ][13060] Running state [maas-region-controller] at time 20:54:14.077065
2019-05-15 20:54:14,077 [salt.state       :1813][INFO    ][13060] Executing state pkg.installed for [maas-region-controller]
2019-05-15 20:54:14,079 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13060] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-15 20:54:14,195 [salt.state       :300 ][INFO    ][13060] All specified packages are already installed
2019-05-15 20:54:14,195 [salt.state       :1951][INFO    ][13060] Completed state [maas-region-controller] at time 20:54:14.195736 duration_in_ms=118.671
2019-05-15 20:54:14,196 [salt.state       :1780][INFO    ][13060] Running state [python-oauth] at time 20:54:14.196107
2019-05-15 20:54:14,196 [salt.state       :1813][INFO    ][13060] Executing state pkg.installed for [python-oauth]
2019-05-15 20:54:14,214 [salt.state       :300 ][INFO    ][13060] All specified packages are already installed
2019-05-15 20:54:14,215 [salt.state       :1951][INFO    ][13060] Completed state [python-oauth] at time 20:54:14.215252 duration_in_ms=19.144
2019-05-15 20:54:14,222 [salt.state       :1780][INFO    ][13060] Running state [/etc/maas/regiond.conf] at time 20:54:14.221684
2019-05-15 20:54:14,223 [salt.state       :1813][INFO    ][13060] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-15 20:54:14,228 [salt.state       :300 ][INFO    ][13060] No changes needed to be made
2019-05-15 20:54:14,229 [salt.state       :1951][INFO    ][13060] Completed state [/etc/maas/regiond.conf] at time 20:54:14.229080 duration_in_ms=7.396
2019-05-15 20:54:14,230 [salt.state       :1780][INFO    ][13060] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:54:14.229676
2019-05-15 20:54:14,230 [salt.state       :1813][INFO    ][13060] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-15 20:54:14,299 [salt.state       :300 ][INFO    ][13060] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-15 20:54:14,299 [salt.state       :1951][INFO    ][13060] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:54:14.299383 duration_in_ms=69.707
2019-05-15 20:54:14,300 [salt.state       :1780][INFO    ][13060] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:54:14.299952
2019-05-15 20:54:14,300 [salt.state       :1813][INFO    ][13060] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-15 20:54:14,304 [salt.state       :300 ][INFO    ][13060] No changes needed to be made
2019-05-15 20:54:14,305 [salt.state       :1951][INFO    ][13060] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:54:14.305072 duration_in_ms=5.119
2019-05-15 20:54:14,305 [salt.state       :1780][INFO    ][13060] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:54:14.305592
2019-05-15 20:54:14,306 [salt.state       :1813][INFO    ][13060] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-15 20:54:14,311 [salt.state       :300 ][INFO    ][13060] No changes needed to be made
2019-05-15 20:54:14,311 [salt.state       :1951][INFO    ][13060] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:54:14.311328 duration_in_ms=5.735
2019-05-15 20:54:14,311 [salt.state       :1780][INFO    ][13060] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:54:14.311853
2019-05-15 20:54:14,312 [salt.state       :1813][INFO    ][13060] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-15 20:54:14,348 [salt.state       :300 ][INFO    ][13060] No changes needed to be made
2019-05-15 20:54:14,348 [salt.state       :1951][INFO    ][13060] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:54:14.348559 duration_in_ms=36.706
2019-05-15 20:54:14,349 [salt.state       :1780][INFO    ][13060] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:54:14.349093
2019-05-15 20:54:14,349 [salt.state       :1813][INFO    ][13060] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-15 20:54:14,367 [salt.state       :300 ][INFO    ][13060] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-15 20:54:14,368 [salt.state       :1951][INFO    ][13060] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:54:14.368372 duration_in_ms=19.278
2019-05-15 20:54:14,370 [salt.state       :1780][INFO    ][13060] Running state [a2enmod headers] at time 20:54:14.370891
2019-05-15 20:54:14,371 [salt.state       :1813][INFO    ][13060] Executing state cmd.run for [a2enmod headers]
2019-05-15 20:54:14,371 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13060] Executing command 'a2enmod headers' in directory '/root'
2019-05-15 20:54:14,444 [salt.state       :300 ][INFO    ][13060] {'pid': 13079, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-15 20:54:14,444 [salt.state       :1951][INFO    ][13060] Completed state [a2enmod headers] at time 20:54:14.444833 duration_in_ms=73.941
2019-05-15 20:54:14,445 [salt.state       :1780][INFO    ][13060] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:54:14.445547
2019-05-15 20:54:14,446 [salt.state       :1813][INFO    ][13060] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-15 20:54:14,471 [salt.state       :300 ][INFO    ][13060] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-15 20:54:14,471 [salt.state       :1951][INFO    ][13060] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:54:14.471351 duration_in_ms=25.805
2019-05-15 20:54:14,472 [salt.state       :1780][INFO    ][13060] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:54:14.472189
2019-05-15 20:54:14,472 [salt.state       :1813][INFO    ][13060] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-15 20:54:14,527 [salt.state       :300 ][INFO    ][13060] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-15 20:54:14,528 [salt.state       :1951][INFO    ][13060] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:54:14.528141 duration_in_ms=55.953
2019-05-15 20:54:14,528 [salt.state       :1780][INFO    ][13060] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:54:14.528679
2019-05-15 20:54:14,529 [salt.state       :1813][INFO    ][13060] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-15 20:54:14,596 [salt.state       :300 ][INFO    ][13060] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-15 20:54:14,596 [salt.state       :1951][INFO    ][13060] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:54:14.596629 duration_in_ms=67.95
2019-05-15 20:54:14,597 [salt.state       :1780][INFO    ][13060] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:54:14.597155
2019-05-15 20:54:14,597 [salt.state       :1813][INFO    ][13060] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-15 20:54:14,677 [salt.state       :300 ][INFO    ][13060] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-15 20:54:14,677 [salt.state       :1951][INFO    ][13060] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:54:14.677428 duration_in_ms=80.271
2019-05-15 20:54:14,678 [salt.state       :1780][INFO    ][13060] Running state [/root/.pgpass] at time 20:54:14.678434
2019-05-15 20:54:14,678 [salt.state       :1813][INFO    ][13060] Executing state file.managed for [/root/.pgpass]
2019-05-15 20:54:14,723 [salt.state       :300 ][INFO    ][13060] File /root/.pgpass is in the correct state
2019-05-15 20:54:14,723 [salt.state       :1951][INFO    ][13060] Completed state [/root/.pgpass] at time 20:54:14.723840 duration_in_ms=45.406
2019-05-15 20:54:14,733 [salt.state       :1780][INFO    ][13060] Running state [maas-region syncdb --noinput] at time 20:54:14.732876
2019-05-15 20:54:14,733 [salt.state       :1813][INFO    ][13060] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-15 20:54:14,735 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13060] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-15 20:54:17,524 [salt.state       :300 ][INFO    ][13060] {'pid': 13092, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: auth, contenttypes, maasserver, piston3, metadataserver, sites, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-15 20:54:17,525 [salt.state       :1951][INFO    ][13060] Completed state [maas-region syncdb --noinput] at time 20:54:17.525294 duration_in_ms=2792.418
2019-05-15 20:54:17,525 [salt.state       :2022][WARNING ][13060] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-15 20:54:17,527 [salt.state       :1780][INFO    ][13060] Running state [maas-regiond] at time 20:54:17.527404
2019-05-15 20:54:17,527 [salt.state       :1813][INFO    ][13060] Executing state service.running for [maas-regiond]
2019-05-15 20:54:17,528 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13060] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-15 20:54:17,576 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13060] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-15 20:54:17,600 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13060] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-15 20:54:17,625 [salt.state       :300 ][INFO    ][13060] The service maas-regiond is already running
2019-05-15 20:54:17,626 [salt.state       :1951][INFO    ][13060] Completed state [maas-regiond] at time 20:54:17.626188 duration_in_ms=98.784
2019-05-15 20:54:17,629 [salt.state       :1780][INFO    ][13060] Running state [bind9] at time 20:54:17.629572
2019-05-15 20:54:17,632 [salt.state       :1813][INFO    ][13060] Executing state service.running for [bind9]
2019-05-15 20:54:17,633 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13060] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-15 20:54:17,659 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13060] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-15 20:54:17,682 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13060] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-15 20:54:17,703 [salt.state       :300 ][INFO    ][13060] The service bind9 is already running
2019-05-15 20:54:17,703 [salt.state       :1951][INFO    ][13060] Completed state [bind9] at time 20:54:17.703663 duration_in_ms=74.092
2019-05-15 20:54:17,707 [salt.state       :1780][INFO    ][13060] Running state [apache2] at time 20:54:17.707819
2019-05-15 20:54:17,708 [salt.state       :1813][INFO    ][13060] Executing state service.running for [apache2]
2019-05-15 20:54:17,709 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13060] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-15 20:54:17,732 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13060] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-15 20:54:17,752 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13060] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-15 20:54:17,782 [salt.state       :300 ][INFO    ][13060] The service apache2 is already running
2019-05-15 20:54:17,782 [salt.state       :1951][INFO    ][13060] Completed state [apache2] at time 20:54:17.782727 duration_in_ms=74.907
2019-05-15 20:54:17,784 [salt.state       :1780][INFO    ][13060] Running state [maasng.wait_for_http_code] at time 20:54:17.784762
2019-05-15 20:54:17,785 [salt.state       :1813][INFO    ][13060] Executing state module.run for [maasng.wait_for_http_code]
2019-05-15 20:54:17,788 [salt.utils.decorators:613 ][WARNING ][13060] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:54:17,902 [salt.state       :300 ][INFO    ][13060] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-15 20:54:17,903 [salt.state       :1951][INFO    ][13060] Completed state [maasng.wait_for_http_code] at time 20:54:17.902905 duration_in_ms=118.143
2019-05-15 20:54:17,904 [salt.state       :1780][INFO    ][13060] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:54:17.904436
2019-05-15 20:54:17,905 [salt.state       :1813][INFO    ][13060] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-15 20:54:17,907 [salt.state       :300 ][INFO    ][13060] /var/lib/maas/.setup_admin exists
2019-05-15 20:54:17,907 [salt.state       :1951][INFO    ][13060] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:54:17.907815 duration_in_ms=3.38
2019-05-15 20:54:17,908 [salt.state       :1780][INFO    ][13060] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:54:17.908527
2019-05-15 20:54:17,908 [salt.state       :1813][INFO    ][13060] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-15 20:54:17,909 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13060] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-15 20:54:19,720 [salt.state       :300 ][INFO    ][13060] {'pid': 13114, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-15 20:54:19,721 [salt.state       :1951][INFO    ][13060] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:54:19.721369 duration_in_ms=1812.841
2019-05-15 20:54:19,726 [salt.state       :1780][INFO    ][13060] Running state [maas_region_boot_source_resources_mirror] at time 20:54:19.726838
2019-05-15 20:54:19,727 [salt.state       :1813][INFO    ][13060] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-15 20:54:19,821 [salt.state       :300 ][INFO    ][13060] {'changes': {}}
2019-05-15 20:54:19,822 [salt.state       :1951][INFO    ][13060] Completed state [maas_region_boot_source_resources_mirror] at time 20:54:19.822162 duration_in_ms=95.323
2019-05-15 20:54:19,823 [salt.state       :1780][INFO    ][13060] Running state [maasng.boot_resources_import] at time 20:54:19.823451
2019-05-15 20:54:19,824 [salt.state       :1813][INFO    ][13060] Executing state module.run for [maasng.boot_resources_import]
2019-05-15 20:54:19,824 [salt.utils.decorators:613 ][WARNING ][13060] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:54:21,380 [salt.loaded.ext.module.maasng:1600][INFO    ][13060] Waiting boot-resources import done
sleep for:5s Left:898.0/900s
2019-05-15 20:54:26,424 [salt.loaded.ext.module.maasng:1600][INFO    ][13060] Waiting boot-resources import done
sleep for:5s Left:893.0/900s
2019-05-15 20:54:26,863 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205426850466
2019-05-15 20:54:26,891 [salt.minion      :1432][INFO    ][13168] Starting a new job with PID 13168
2019-05-15 20:54:26,924 [salt.minion      :1711][INFO    ][13168] Returning information for job: 20190515205426850466
2019-05-15 20:54:31,521 [salt.state       :300 ][INFO    ][13060] {'ret': True}
2019-05-15 20:54:31,522 [salt.state       :1951][INFO    ][13060] Completed state [maasng.boot_resources_import] at time 20:54:31.522661 duration_in_ms=11699.209
2019-05-15 20:54:31,524 [salt.state       :1780][INFO    ][13060] Running state [maas_region_boot_sources_selection_xenial] at time 20:54:31.524168
2019-05-15 20:54:31,524 [salt.state       :1813][INFO    ][13060] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-15 20:54:31,702 [salt.state       :300 ][INFO    ][13060] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-15 20:54:31,703 [salt.state       :1951][INFO    ][13060] Completed state [maas_region_boot_sources_selection_xenial] at time 20:54:31.703066 duration_in_ms=178.899
2019-05-15 20:54:31,704 [salt.state       :1780][INFO    ][13060] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 20:54:31.704413
2019-05-15 20:54:31,705 [salt.state       :1813][INFO    ][13060] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-15 20:54:31,707 [salt.utils.decorators:613 ][WARNING ][13060] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:54:31,707 [salt.loaded.ext.module.maasng:1771][INFO    ][13060] boot-sources sync initiated for ALL Rack's
2019-05-15 20:54:32,792 [salt.state       :300 ][INFO    ][13060] {'ret': True}
2019-05-15 20:54:32,793 [salt.state       :1951][INFO    ][13060] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 20:54:32.793335 duration_in_ms=1088.922
2019-05-15 20:54:32,796 [salt.state       :1780][INFO    ][13060] Running state [maas.process_maas_config] at time 20:54:32.795948
2019-05-15 20:54:32,796 [salt.state       :1813][INFO    ][13060] Executing state module.run for [maas.process_maas_config]
2019-05-15 20:54:32,797 [salt.utils.decorators:613 ][WARNING ][13060] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:54:32,799 [salt.loaded.ext.module.maas:92  ][INFO    ][13060] maasconfig name=enable_http_proxy value=True
2019-05-15 20:54:32,854 [salt.loaded.ext.module.maas:92  ][INFO    ][13060] maasconfig name=upstream_dns value=8.8.8.8
2019-05-15 20:54:32,908 [salt.loaded.ext.module.maas:92  ][INFO    ][13060] maasconfig name=commissioning_distro_series value=xenial
2019-05-15 20:54:32,969 [salt.loaded.ext.module.maas:92  ][INFO    ][13060] maasconfig name=default_osystem value=ubuntu
2019-05-15 20:54:34,617 [salt.loaded.ext.module.maas:92  ][INFO    ][13060] maasconfig name=active_discovery_interval value=600
2019-05-15 20:54:34,672 [salt.loaded.ext.module.maas:92  ][INFO    ][13060] maasconfig name=dnssec_validation value=no
2019-05-15 20:54:34,723 [salt.loaded.ext.module.maas:92  ][INFO    ][13060] maasconfig name=maas_name value=mas01
2019-05-15 20:54:34,771 [salt.loaded.ext.module.maas:92  ][INFO    ][13060] maasconfig name=network_discovery value=enabled
2019-05-15 20:54:34,911 [salt.loaded.ext.module.maas:92  ][INFO    ][13060] maasconfig name=enable_third_party_drivers value=True
2019-05-15 20:54:34,987 [salt.loaded.ext.module.maas:92  ][INFO    ][13060] maasconfig name=default_storage_layout value=lvm
2019-05-15 20:54:35,046 [salt.loaded.ext.module.maas:92  ][INFO    ][13060] maasconfig name=ntp_external_only value=True
2019-05-15 20:54:35,115 [salt.loaded.ext.module.maas:92  ][INFO    ][13060] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-15 20:54:35,167 [salt.loaded.ext.module.maas:92  ][INFO    ][13060] maasconfig name=default_distro_series value=xenial
2019-05-15 20:54:35,223 [salt.loaded.ext.module.maas:92  ][INFO    ][13060] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-15 20:54:35,334 [salt.state       :300 ][INFO    ][13060] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-15 20:54:35,335 [salt.state       :1951][INFO    ][13060] Completed state [maas.process_maas_config] at time 20:54:35.335239 duration_in_ms=2539.29
2019-05-15 20:54:35,336 [salt.state       :1780][INFO    ][13060] Running state [pxe_admin] at time 20:54:35.336246
2019-05-15 20:54:35,336 [salt.state       :1813][INFO    ][13060] Executing state maasng.fabric_present for [pxe_admin]
2019-05-15 20:54:35,395 [salt.loaded.ext.module.maasng:945 ][INFO    ][13060] [{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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'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'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': True, u'mtu': 1500, u'primary_rack': u'reqmdg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-15 20:54:35,459 [salt.loaded.ext.module.maasng:1008][WARNING ][13060] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-15 20:54:35,460 [salt.loaded.ext.module.maasng:1011][WARNING ][13060] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-15 20:54:35,559 [salt.state       :300 ][INFO    ][13060] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-15 20:54:35,559 [salt.state       :1951][INFO    ][13060] Completed state [pxe_admin] at time 20:54:35.559654 duration_in_ms=223.405
2019-05-15 20:54:35,560 [salt.state       :1780][INFO    ][13060] Running state [vlan 0] at time 20:54:35.560162
2019-05-15 20:54:35,560 [salt.state       :1813][INFO    ][13060] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-15 20:54:35,631 [salt.loaded.ext.module.maasng:945 ][INFO    ][13060] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'reqmdg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-15 20:54:35,741 [salt.loaded.ext.module.maasng:945 ][INFO    ][13060] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', 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'reqmdg', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-05-15 20:54:35,995 [salt.loaded.ext.module.maasng:945 ][INFO    ][13060] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'reqmdg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-15 20:54:36,081 [salt.state       :300 ][INFO    ][13060] {'new': 'Vlan untagged was updated'}
2019-05-15 20:54:36,082 [salt.state       :1951][INFO    ][13060] Completed state [vlan 0] at time 20:54:36.081673 duration_in_ms=521.51
2019-05-15 20:54:36,083 [salt.state       :1780][INFO    ][13060] Running state [192.168.11.0/24] at time 20:54:36.083512
2019-05-15 20:54:36,084 [salt.state       :1813][INFO    ][13060] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-15 20:54:36,292 [salt.loaded.ext.module.maasng:945 ][INFO    ][13060] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'reqmdg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-15 20:54:36,293 [salt.loaded.ext.module.maasng:1235][WARNING ][13060] Ignoring parameter vlan:0
2019-05-15 20:54:36,359 [salt.state       :300 ][INFO    ][13060] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-15 20:54:36,359 [salt.state       :1951][INFO    ][13060] Completed state [192.168.11.0/24] at time 20:54:36.359837 duration_in_ms=276.325
2019-05-15 20:54:36,360 [salt.state       :1780][INFO    ][13060] Running state [maas_create_iprange_1] at time 20:54:36.360702
2019-05-15 20:54:36,361 [salt.state       :1813][INFO    ][13060] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-15 20:54:36,404 [salt.state       :300 ][INFO    ][13060] Iprange maas_create_iprange_1 already exist.
2019-05-15 20:54:36,404 [salt.state       :1951][INFO    ][13060] Completed state [maas_create_iprange_1] at time 20:54:36.404871 duration_in_ms=44.168
2019-05-15 20:54:36,405 [salt.state       :1780][INFO    ][13060] Running state [vlan 0] at time 20:54:36.405199
2019-05-15 20:54:36,405 [salt.state       :1813][INFO    ][13060] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-15 20:54:36,450 [salt.loaded.ext.module.maasng:945 ][INFO    ][13060] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'reqmdg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-15 20:54:36,536 [salt.loaded.ext.module.maasng:945 ][INFO    ][13060] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'reqmdg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-15 20:54:36,742 [salt.loaded.ext.module.maasng:945 ][INFO    ][13060] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'reqmdg', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-05-15 20:54:36,827 [salt.state       :300 ][INFO    ][13060] {'new': 'Vlan untagged was updated'}
2019-05-15 20:54:36,827 [salt.state       :1951][INFO    ][13060] Completed state [vlan 0] at time 20:54:36.827366 duration_in_ms=422.166
2019-05-15 20:54:36,828 [salt.state       :1780][INFO    ][13060] Running state [opnfv] at time 20:54:36.828167
2019-05-15 20:54:36,828 [salt.state       :1813][INFO    ][13060] Executing state maasng.sshkey_present for [opnfv]
2019-05-15 20:54:36,876 [salt.loaded.ext.module.maasng:1903][INFO    ][13060] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-05-15 20:54:36,876 [salt.state       :300 ][INFO    ][13060] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-15 20:54:36,876 [salt.state       :1951][INFO    ][13060] Completed state [opnfv] at time 20:54:36.876751 duration_in_ms=48.584
2019-05-15 20:54:36,877 [salt.state       :1780][INFO    ][13060] Running state [maas.process_tags] at time 20:54:36.877498
2019-05-15 20:54:36,881 [salt.state       :1813][INFO    ][13060] Executing state module.run for [maas.process_tags]
2019-05-15 20:54:36,882 [salt.utils.decorators:613 ][WARNING ][13060] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:54:36,934 [salt.loaded.ext.module.maas:92  ][INFO    ][13060] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-15 20:54:36,992 [salt.state       :300 ][INFO    ][13060] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-15 20:54:36,993 [salt.state       :1951][INFO    ][13060] Completed state [maas.process_tags] at time 20:54:36.992948 duration_in_ms=115.449
2019-05-15 20:54:37,004 [salt.minion      :1711][INFO    ][13060] Returning information for job: 20190515205411756056
2019-05-15 20:54:37,747 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command state.apply with jid 20190515205437735915
2019-05-15 20:54:37,765 [salt.minion      :1432][INFO    ][13518] Starting a new job with PID 13518
2019-05-15 20:54:43,765 [salt.state       :915 ][INFO    ][13518] Loading fresh modules for state activity
2019-05-15 20:54:43,877 [salt.state       :1780][INFO    ][13518] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:54:43.877155
2019-05-15 20:54:43,877 [salt.state       :1813][INFO    ][13518] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-15 20:54:43,879 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13518] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-15 20:54:45,791 [salt.state       :300 ][INFO    ][13518] {'pid': 13547, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-15 20:54:45,792 [salt.state       :1951][INFO    ][13518] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:54:45.792284 duration_in_ms=1915.129
2019-05-15 20:54:45,794 [salt.state       :1780][INFO    ][13518] Running state [maas.process_machines] at time 20:54:45.794704
2019-05-15 20:54:45,795 [salt.state       :1813][INFO    ][13518] Executing state module.run for [maas.process_machines]
2019-05-15 20:54:45,795 [salt.utils.decorators:613 ][WARNING ][13518] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:54:46,273 [salt.loaded.ext.module.maas:412 ][WARNING ][13518] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-15 20:54:46,276 [salt.loaded.ext.module.maas:92  ][INFO    ][13518] machine hostname=gtw01 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=ecqxsq architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-15 20:54:47,494 [salt.loaded.ext.module.maas:412 ][WARNING ][13518] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-15 20:54:47,495 [salt.loaded.ext.module.maas:92  ][INFO    ][13518] machine hostname=cmp002 power_type=ipmi mac_addresses=['9c:b6:54:8a:10:18'] power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 system_id=ppbrtq architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-15 20:54:48,765 [salt.loaded.ext.module.maas:412 ][WARNING ][13518] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-15 20:54:48,767 [salt.loaded.ext.module.maas:92  ][INFO    ][13518] machine hostname=cmp001 power_type=ipmi mac_addresses=['9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=bkac7a architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-15 20:54:50,005 [salt.loaded.ext.module.maas:412 ][WARNING ][13518] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-15 20:54:50,007 [salt.loaded.ext.module.maas:92  ][INFO    ][13518] machine hostname=ctl01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=sn4mpn architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-15 20:54:51,333 [salt.state       :300 ][INFO    ][13518] {'ret': {'updated': ['gtw01', 'cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-05-15 20:54:51,334 [salt.state       :1951][INFO    ][13518] Completed state [maas.process_machines] at time 20:54:51.334688 duration_in_ms=5539.983
2019-05-15 20:54:51,340 [salt.minion      :1711][INFO    ][13518] Returning information for job: 20190515205437735915
2019-05-15 20:55:24,640 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command state.apply with jid 20190515205524624902
2019-05-15 20:55:24,669 [salt.minion      :1432][INFO    ][13775] Starting a new job with PID 13775
2019-05-15 20:55:30,558 [salt.state       :915 ][INFO    ][13775] Loading fresh modules for state activity
2019-05-15 20:55:30,654 [salt.state       :1780][INFO    ][13775] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:30.654647
2019-05-15 20:55:30,655 [salt.state       :1813][INFO    ][13775] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-15 20:55:30,657 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13775] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-15 20:55:32,482 [salt.state       :300 ][INFO    ][13775] {'pid': 13782, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-15 20:55:32,483 [salt.state       :1951][INFO    ][13775] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:32.483507 duration_in_ms=1828.859
2019-05-15 20:55:32,490 [salt.state       :1780][INFO    ][13775] Running state [maas.wait_for_machine_status] at time 20:55:32.490169
2019-05-15 20:55:32,490 [salt.state       :1813][INFO    ][13775] Executing state module.run for [maas.wait_for_machine_status]
2019-05-15 20:55:32,491 [salt.utils.decorators:613 ][WARNING ][13775] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:55:34,500 [salt.state       :300 ][INFO    ][13775] {'ret': True}
2019-05-15 20:55:34,500 [salt.state       :1951][INFO    ][13775] Completed state [maas.wait_for_machine_status] at time 20:55:34.500638 duration_in_ms=2010.467
2019-05-15 20:55:34,507 [salt.minion      :1711][INFO    ][13775] Returning information for job: 20190515205524624902
2019-05-15 20:55:35,231 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command state.apply with jid 20190515205535180607
2019-05-15 20:55:35,253 [salt.minion      :1432][INFO    ][13796] Starting a new job with PID 13796
2019-05-15 20:55:36,118 [salt.state       :915 ][INFO    ][13796] Loading fresh modules for state activity
2019-05-15 20:55:36,292 [salt.state       :1780][INFO    ][13796] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:36.292775
2019-05-15 20:55:36,293 [salt.state       :1813][INFO    ][13796] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-15 20:55:36,294 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13796] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-15 20:55:38,122 [salt.state       :300 ][INFO    ][13796] {'pid': 13803, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-15 20:55:38,123 [salt.state       :1951][INFO    ][13796] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:38.123642 duration_in_ms=1830.866
2019-05-15 20:55:38,127 [salt.state       :1780][INFO    ][13796] Running state [maas_machines_storage_cmp002_lvm] at time 20:55:38.127668
2019-05-15 20:55:38,128 [salt.state       :1813][INFO    ][13796] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-15 20:55:38,602 [salt.state       :300 ][INFO    ][13796] Machine cmp002 is not in Ready state.
2019-05-15 20:55:38,603 [salt.state       :1951][INFO    ][13796] Completed state [maas_machines_storage_cmp002_lvm] at time 20:55:38.602998 duration_in_ms=475.329
2019-05-15 20:55:38,603 [salt.state       :1780][INFO    ][13796] Running state [maas_machines_storage_cmp001_lvm] at time 20:55:38.603639
2019-05-15 20:55:38,604 [salt.state       :1813][INFO    ][13796] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-15 20:55:39,065 [salt.state       :300 ][INFO    ][13796] Machine cmp001 is not in Ready state.
2019-05-15 20:55:39,066 [salt.state       :1951][INFO    ][13796] Completed state [maas_machines_storage_cmp001_lvm] at time 20:55:39.066194 duration_in_ms=462.556
2019-05-15 20:55:39,070 [salt.minion      :1711][INFO    ][13796] Returning information for job: 20190515205535180607
2019-05-15 20:55:39,761 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command state.apply with jid 20190515205539747795
2019-05-15 20:55:39,786 [salt.minion      :1432][INFO    ][13818] Starting a new job with PID 13818
2019-05-15 20:55:40,728 [salt.state       :915 ][INFO    ][13818] Loading fresh modules for state activity
2019-05-15 20:55:40,826 [salt.state       :1780][INFO    ][13818] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:40.826812
2019-05-15 20:55:40,827 [salt.state       :1813][INFO    ][13818] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-15 20:55:40,829 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13818] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-15 20:55:42,779 [salt.state       :300 ][INFO    ][13818] {'pid': 13825, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-15 20:55:42,780 [salt.state       :1951][INFO    ][13818] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:42.780809 duration_in_ms=1953.998
2019-05-15 20:55:42,783 [salt.state       :1780][INFO    ][13818] Running state [maas.deploy_machines] at time 20:55:42.783796
2019-05-15 20:55:42,784 [salt.state       :1813][INFO    ][13818] Executing state module.run for [maas.deploy_machines]
2019-05-15 20:55:42,785 [salt.utils.decorators:613 ][WARNING ][13818] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:55:43,276 [salt.loaded.ext.module.maas:684 ][INFO    ][13818] deploymachines hwe_kernel=hwe-16.04 system_id=ecqxsq distro_series=xenial
2019-05-15 20:55:45,732 [salt.state       :300 ][INFO    ][13818] {'ret': {'updated': ['cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': ['gtw01']}}
2019-05-15 20:55:45,733 [salt.state       :1951][INFO    ][13818] Completed state [maas.deploy_machines] at time 20:55:45.733013 duration_in_ms=2949.216
2019-05-15 20:55:45,739 [salt.minion      :1711][INFO    ][13818] Returning information for job: 20190515205539747795
2019-05-15 20:55:46,444 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command state.apply with jid 20190515205546433925
2019-05-15 20:55:46,468 [salt.minion      :1432][INFO    ][13885] Starting a new job with PID 13885
2019-05-15 20:55:52,432 [salt.state       :915 ][INFO    ][13885] Loading fresh modules for state activity
2019-05-15 20:55:52,542 [salt.state       :1780][INFO    ][13885] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:52.542465
2019-05-15 20:55:52,542 [salt.state       :1813][INFO    ][13885] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-15 20:55:52,544 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13885] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-15 20:55:54,382 [salt.state       :300 ][INFO    ][13885] {'pid': 13902, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-15 20:55:54,383 [salt.state       :1951][INFO    ][13885] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:55:54.383013 duration_in_ms=1840.547
2019-05-15 20:55:54,388 [salt.state       :1780][INFO    ][13885] Running state [maas.wait_for_machine_status] at time 20:55:54.387935
2019-05-15 20:55:54,388 [salt.state       :1813][INFO    ][13885] Executing state module.run for [maas.wait_for_machine_status]
2019-05-15 20:55:54,388 [salt.utils.decorators:613 ][WARNING ][13885] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 20:55:56,463 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2247.94527817s left)
2019-05-15 20:56:01,509 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205601496507
2019-05-15 20:56:01,535 [salt.minion      :1432][INFO    ][13919] Starting a new job with PID 13919
2019-05-15 20:56:01,562 [salt.minion      :1711][INFO    ][13919] Returning information for job: 20190515205601496507
2019-05-15 20:56:28,498 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2215.90968204s left)
2019-05-15 20:56:31,585 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205631568302
2019-05-15 20:56:31,618 [salt.minion      :1432][INFO    ][13971] Starting a new job with PID 13971
2019-05-15 20:56:31,654 [salt.minion      :1711][INFO    ][13971] Returning information for job: 20190515205631568302
2019-05-15 20:57:00,395 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2184.01317406s left)
2019-05-15 20:57:01,664 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205701652358
2019-05-15 20:57:01,692 [salt.minion      :1432][INFO    ][13995] Starting a new job with PID 13995
2019-05-15 20:57:01,721 [salt.minion      :1711][INFO    ][13995] Returning information for job: 20190515205701652358
2019-05-15 20:57:31,753 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205731739393
2019-05-15 20:57:31,777 [salt.minion      :1432][INFO    ][14065] Starting a new job with PID 14065
2019-05-15 20:57:31,808 [salt.minion      :1711][INFO    ][14065] Returning information for job: 20190515205731739393
2019-05-15 20:57:32,308 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2152.09967995s left)
2019-05-15 20:58:01,828 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205801816898
2019-05-15 20:58:01,852 [salt.minion      :1432][INFO    ][14089] Starting a new job with PID 14089
2019-05-15 20:58:01,881 [salt.minion      :1711][INFO    ][14089] Returning information for job: 20190515205801816898
2019-05-15 20:58:04,194 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2120.21465516s left)
2019-05-15 20:58:31,907 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205831897457
2019-05-15 20:58:31,938 [salt.minion      :1432][INFO    ][14142] Starting a new job with PID 14142
2019-05-15 20:58:31,968 [salt.minion      :1711][INFO    ][14142] Returning information for job: 20190515205831897457
2019-05-15 20:58:36,068 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2088.34026098s left)
2019-05-15 20:59:02,020 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205902003098
2019-05-15 20:59:02,049 [salt.minion      :1432][INFO    ][14168] Starting a new job with PID 14168
2019-05-15 20:59:02,081 [salt.minion      :1711][INFO    ][14168] Returning information for job: 20190515205902003098
2019-05-15 20:59:07,956 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2056.45231605s left)
2019-05-15 20:59:32,127 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515205932111490
2019-05-15 20:59:32,154 [salt.minion      :1432][INFO    ][14225] Starting a new job with PID 14225
2019-05-15 20:59:32,185 [salt.minion      :1711][INFO    ][14225] Returning information for job: 20190515205932111490
2019-05-15 20:59:40,080 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (2024.32797813s left)
2019-05-15 21:00:02,231 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210002215063
2019-05-15 21:00:02,260 [salt.minion      :1432][INFO    ][14248] Starting a new job with PID 14248
2019-05-15 21:00:02,290 [salt.minion      :1711][INFO    ][14248] Returning information for job: 20190515210002215063
2019-05-15 21:00:11,948 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1992.45958114s left)
2019-05-15 21:00:32,332 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210032314358
2019-05-15 21:00:32,360 [salt.minion      :1432][INFO    ][14324] Starting a new job with PID 14324
2019-05-15 21:00:32,389 [salt.minion      :1711][INFO    ][14324] Returning information for job: 20190515210032314358
2019-05-15 21:00:44,085 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1960.32287598s left)
2019-05-15 21:01:02,451 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210102440435
2019-05-15 21:01:02,476 [salt.minion      :1432][INFO    ][14394] Starting a new job with PID 14394
2019-05-15 21:01:02,506 [salt.minion      :1711][INFO    ][14394] Returning information for job: 20190515210102440435
2019-05-15 21:01:16,076 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1928.33212709s left)
2019-05-15 21:01:32,580 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210132564459
2019-05-15 21:01:32,610 [salt.minion      :1432][INFO    ][14555] Starting a new job with PID 14555
2019-05-15 21:01:32,642 [salt.minion      :1711][INFO    ][14555] Returning information for job: 20190515210132564459
2019-05-15 21:01:48,060 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1896.34736204s left)
2019-05-15 21:02:02,712 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210202698977
2019-05-15 21:02:02,737 [salt.minion      :1432][INFO    ][14599] Starting a new job with PID 14599
2019-05-15 21:02:02,768 [salt.minion      :1711][INFO    ][14599] Returning information for job: 20190515210202698977
2019-05-15 21:02:20,004 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1864.40432s left)
2019-05-15 21:02:32,833 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210232818318
2019-05-15 21:02:32,863 [salt.minion      :1432][INFO    ][14665] Starting a new job with PID 14665
2019-05-15 21:02:32,894 [salt.minion      :1711][INFO    ][14665] Returning information for job: 20190515210232818318
2019-05-15 21:02:52,246 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1832.1616931s left)
2019-05-15 21:03:02,982 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210302966982
2019-05-15 21:03:03,013 [salt.minion      :1432][INFO    ][14696] Starting a new job with PID 14696
2019-05-15 21:03:03,047 [salt.minion      :1711][INFO    ][14696] Returning information for job: 20190515210302966982
2019-05-15 21:03:24,463 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1799.9445231s left)
2019-05-15 21:03:33,151 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210333135826
2019-05-15 21:03:33,180 [salt.minion      :1432][INFO    ][14817] Starting a new job with PID 14817
2019-05-15 21:03:33,213 [salt.minion      :1711][INFO    ][14817] Returning information for job: 20190515210333135826
2019-05-15 21:03:56,437 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1767.97079301s left)
2019-05-15 21:04:03,307 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210403298429
2019-05-15 21:04:03,335 [salt.minion      :1432][INFO    ][14886] Starting a new job with PID 14886
2019-05-15 21:04:03,369 [salt.minion      :1711][INFO    ][14886] Returning information for job: 20190515210403298429
2019-05-15 21:04:28,426 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1735.98226714s left)
2019-05-15 21:04:33,492 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210433475799
2019-05-15 21:04:33,521 [salt.minion      :1432][INFO    ][15005] Starting a new job with PID 15005
2019-05-15 21:04:33,556 [salt.minion      :1711][INFO    ][15005] Returning information for job: 20190515210433475799
2019-05-15 21:05:00,340 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1704.06807399s left)
2019-05-15 21:05:03,669 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210503654188
2019-05-15 21:05:03,695 [salt.minion      :1432][INFO    ][15035] Starting a new job with PID 15035
2019-05-15 21:05:03,728 [salt.minion      :1711][INFO    ][15035] Returning information for job: 20190515210503654188
2019-05-15 21:05:32,434 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1671.97458506s left)
2019-05-15 21:05:33,848 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210533830605
2019-05-15 21:05:33,879 [salt.minion      :1432][INFO    ][15085] Starting a new job with PID 15085
2019-05-15 21:05:33,910 [salt.minion      :1711][INFO    ][15085] Returning information for job: 20190515210533830605
2019-05-15 21:06:04,039 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210604026420
2019-05-15 21:06:04,062 [salt.minion      :1432][INFO    ][15110] Starting a new job with PID 15110
2019-05-15 21:06:04,096 [salt.minion      :1711][INFO    ][15110] Returning information for job: 20190515210604026420
2019-05-15 21:06:04,555 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1639.85302615s left)
2019-05-15 21:06:34,240 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210634222937
2019-05-15 21:06:34,270 [salt.minion      :1432][INFO    ][15156] Starting a new job with PID 15156
2019-05-15 21:06:34,300 [salt.minion      :1711][INFO    ][15156] Returning information for job: 20190515210634222937
2019-05-15 21:06:36,535 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1607.87245202s left)
2019-05-15 21:07:04,440 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210704423732
2019-05-15 21:07:04,471 [salt.minion      :1432][INFO    ][15182] Starting a new job with PID 15182
2019-05-15 21:07:04,505 [salt.minion      :1711][INFO    ][15182] Returning information for job: 20190515210704423732
2019-05-15 21:07:08,595 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1575.81261396s left)
2019-05-15 21:07:34,641 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210734634636
2019-05-15 21:07:34,661 [salt.minion      :1432][INFO    ][15397] Starting a new job with PID 15397
2019-05-15 21:07:34,691 [salt.minion      :1711][INFO    ][15397] Returning information for job: 20190515210734634636
2019-05-15 21:07:40,551 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1543.85644412s left)
2019-05-15 21:08:04,828 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210804819044
2019-05-15 21:08:04,856 [salt.minion      :1432][INFO    ][15444] Starting a new job with PID 15444
2019-05-15 21:08:04,885 [salt.minion      :1711][INFO    ][15444] Returning information for job: 20190515210804819044
2019-05-15 21:08:12,620 [salt.loaded.ext.module.maas:1023][INFO    ][13885] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1511.78741598s left)
2019-05-15 21:08:30,256 [salt.utils.schedule:1377][INFO    ][5982] Running scheduled job: __mine_interval
2019-05-15 21:08:34,968 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515210834953180
2019-05-15 21:08:34,995 [salt.minion      :1432][INFO    ][15562] Starting a new job with PID 15562
2019-05-15 21:08:35,027 [salt.minion      :1711][INFO    ][15562] Returning information for job: 20190515210834953180
2019-05-15 21:08:44,758 [salt.state       :300 ][INFO    ][13885] {'ret': True}
2019-05-15 21:08:44,759 [salt.state       :1951][INFO    ][13885] Completed state [maas.wait_for_machine_status] at time 21:08:44.759537 duration_in_ms=770371.595
2019-05-15 21:08:44,765 [salt.minion      :1711][INFO    ][13885] Returning information for job: 20190515205546433925
2019-05-15 22:00:53,987 [salt.minion      :1308][INFO    ][5982] User sudo_ubuntu Executing command cp.push_dir with jid 20190515220053973069
2019-05-15 22:00:54,019 [salt.minion      :1432][INFO    ][19311] Starting a new job with PID 19311
