2019-05-21 02:05:43,365 [salt.utils.decorators:613 ][WARNING ][2367] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:05:44,329 [salt.utils.decorators:613 ][WARNING ][2367] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:05:47,195 [salt.loaded.int.states.file:2298][WARNING ][2610] 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-21 02:06:11,982 [salt.state       :2022][WARNING ][3046] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-21 02:06:15,064 [salt.utils.decorators:613 ][WARNING ][3046] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:06:28,505 [salt.utils.decorators:613 ][WARNING ][3046] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:06:55,415 [salt.utils.decorators:613 ][WARNING ][3046] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:06:56,436 [salt.utils.decorators:613 ][WARNING ][3046] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:06:58,797 [salt.loaded.ext.module.maasng:1008][WARNING ][3046] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-05-21 02:06:58,797 [salt.loaded.ext.module.maasng:1011][WARNING ][3046] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-05-21 02:06:59,445 [salt.loaded.ext.module.maasng:1235][WARNING ][3046] Ignoring parameter vlan:0
2019-05-21 02:07:09,190 [salt.utils.decorators:613 ][WARNING ][5559] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:07:09,241 [salt.loaded.ext.module.maas:412 ][WARNING ][5559] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 02:07:10,438 [salt.loaded.ext.module.maas:412 ][WARNING ][5559] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 02:07:11,615 [salt.loaded.ext.module.maas:412 ][WARNING ][5559] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 02:07:12,791 [salt.loaded.ext.module.maas:412 ][WARNING ][5559] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 02:07:13,855 [salt.loaded.ext.module.maas:412 ][WARNING ][5559] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 02:07:19,281 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6152] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-21 02:07:19,317 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6152] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-21 02:07:19,355 [salt.utils.parsers:1051][WARNING ][388] Minion received a SIGTERM. Exiting.
2019-05-21 02:07:20,375 [salt.cli.daemons :293 ][INFO    ][6211] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-05-21 02:07:20,497 [salt.cli.daemons :82  ][INFO    ][6211] Starting up the Salt Minion
2019-05-21 02:07:20,498 [salt.utils.event :1017][INFO    ][6211] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-05-21 02:07:21,630 [salt.minion      :976 ][INFO    ][6211] Creating minion process manager
2019-05-21 02:07:23,526 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6211] Executing command ['date', '+%z'] in directory '/root'
2019-05-21 02:07:23,550 [salt.utils.schedule:568 ][INFO    ][6211] Updating job settings for scheduled job: __mine_interval
2019-05-21 02:07:23,552 [salt.minion      :1108][INFO    ][6211] Added mine.update to scheduler
2019-05-21 02:07:23,560 [salt.minion      :1975][INFO    ][6211] Minion is starting as user 'root'
2019-05-21 02:07:23,579 [salt.minion      :2336][INFO    ][6211] Minion is ready to receive requests!
2019-05-21 02:07:46,109 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command state.apply with jid 20190521020746096895
2019-05-21 02:07:46,125 [salt.minion      :1432][INFO    ][6315] Starting a new job with PID 6315
2019-05-21 02:07:52,325 [salt.state       :915 ][INFO    ][6315] Loading fresh modules for state activity
2019-05-21 02:07:52,376 [salt.fileclient  :1219][INFO    ][6315] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-21 02:07:52,417 [salt.state       :1780][INFO    ][6315] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:07:52.417550
2019-05-21 02:07:52,418 [salt.state       :1813][INFO    ][6315] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 02:07:52,420 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6315] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 02:07:54,146 [salt.state       :300 ][INFO    ][6315] {'pid': 6322, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 02:07:54,147 [salt.state       :1951][INFO    ][6315] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:07:54.147214 duration_in_ms=1729.662
2019-05-21 02:07:54,150 [salt.state       :1780][INFO    ][6315] Running state [maas.wait_for_machine_status] at time 02:07:54.150486
2019-05-21 02:07:54,151 [salt.state       :1813][INFO    ][6315] Executing state module.run for [maas.wait_for_machine_status]
2019-05-21 02:07:54,152 [salt.utils.decorators:613 ][WARNING ][6315] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:07:55,003 [salt.loaded.ext.module.maas:1023][INFO    ][6315] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.16030097s left)
2019-05-21 02:08:01,187 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521020801172948
2019-05-21 02:08:01,216 [salt.minion      :1432][INFO    ][6333] Starting a new job with PID 6333
2019-05-21 02:08:01,239 [salt.minion      :1711][INFO    ][6333] Returning information for job: 20190521020801172948
2019-05-21 02:08:25,857 [salt.loaded.ext.module.maas:1023][INFO    ][6315] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.30605793s left)
2019-05-21 02:08:31,269 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521020831254081
2019-05-21 02:08:31,300 [salt.minion      :1432][INFO    ][6412] Starting a new job with PID 6412
2019-05-21 02:08:31,324 [salt.minion      :1711][INFO    ][6412] Returning information for job: 20190521020831254081
2019-05-21 02:08:57,091 [salt.loaded.ext.module.maas:1023][INFO    ][6315] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.07219696s left)
2019-05-21 02:09:01,363 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521020901344892
2019-05-21 02:09:01,388 [salt.minion      :1432][INFO    ][6433] Starting a new job with PID 6433
2019-05-21 02:09:01,412 [salt.minion      :1711][INFO    ][6433] Returning information for job: 20190521020901344892
2019-05-21 02:09:27,947 [salt.loaded.ext.module.maas:1023][INFO    ][6315] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.21644211s left)
2019-05-21 02:09:31,431 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521020931415436
2019-05-21 02:09:31,456 [salt.minion      :1432][INFO    ][6485] Starting a new job with PID 6485
2019-05-21 02:09:31,480 [salt.minion      :1711][INFO    ][6485] Returning information for job: 20190521020931415436
2019-05-21 02:09:59,043 [salt.loaded.ext.module.maas:1023][INFO    ][6315] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.12004399s left)
2019-05-21 02:10:01,520 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021001510086
2019-05-21 02:10:01,557 [salt.minion      :1432][INFO    ][6544] Starting a new job with PID 6544
2019-05-21 02:10:01,585 [salt.minion      :1711][INFO    ][6544] Returning information for job: 20190521021001510086
2019-05-21 02:10:30,081 [salt.loaded.ext.module.maas:1023][INFO    ][6315] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.0818429s left)
2019-05-21 02:10:31,652 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021031640772
2019-05-21 02:10:31,675 [salt.minion      :1432][INFO    ][6729] Starting a new job with PID 6729
2019-05-21 02:10:31,695 [salt.minion      :1711][INFO    ][6729] Returning information for job: 20190521021031640772
2019-05-21 02:11:01,399 [salt.loaded.ext.module.maas:1023][INFO    ][6315] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.76409698s left)
2019-05-21 02:11:01,751 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021101735845
2019-05-21 02:11:01,783 [salt.minion      :1432][INFO    ][6847] Starting a new job with PID 6847
2019-05-21 02:11:01,804 [salt.minion      :1711][INFO    ][6847] Returning information for job: 20190521021101735845
2019-05-21 02:11:31,861 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021131853805
2019-05-21 02:11:31,880 [salt.minion      :1432][INFO    ][7118] Starting a new job with PID 7118
2019-05-21 02:11:31,906 [salt.minion      :1711][INFO    ][7118] Returning information for job: 20190521021131853805
2019-05-21 02:11:32,657 [salt.loaded.ext.module.maas:1023][INFO    ][6315] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.50628805s left)
2019-05-21 02:12:01,956 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021201943911
2019-05-21 02:12:01,978 [salt.minion      :1432][INFO    ][7243] Starting a new job with PID 7243
2019-05-21 02:12:01,999 [salt.minion      :1711][INFO    ][7243] Returning information for job: 20190521021201943911
2019-05-21 02:12:04,588 [salt.loaded.ext.module.maas:1023][INFO    ][6315] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.57512188s left)
2019-05-21 02:12:32,122 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021232108927
2019-05-21 02:12:32,147 [salt.minion      :1432][INFO    ][7683] Starting a new job with PID 7683
2019-05-21 02:12:32,169 [salt.minion      :1711][INFO    ][7683] Returning information for job: 20190521021232108927
2019-05-21 02:12:36,923 [salt.loaded.ext.module.maas:1023][INFO    ][6315] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.23983192s left)
2019-05-21 02:13:02,245 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021302230801
2019-05-21 02:13:02,267 [salt.minion      :1432][INFO    ][7824] Starting a new job with PID 7824
2019-05-21 02:13:02,290 [salt.minion      :1711][INFO    ][7824] Returning information for job: 20190521021302230801
2019-05-21 02:13:09,207 [salt.loaded.ext.module.maas:1023][INFO    ][6315] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.955585s left)
2019-05-21 02:13:32,384 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021332371037
2019-05-21 02:13:32,413 [salt.minion      :1432][INFO    ][7982] Starting a new job with PID 7982
2019-05-21 02:13:32,434 [salt.minion      :1711][INFO    ][7982] Returning information for job: 20190521021332371037
2019-05-21 02:13:42,064 [salt.state       :300 ][INFO    ][6315] {'ret': True}
2019-05-21 02:13:42,064 [salt.state       :1951][INFO    ][6315] Completed state [maas.wait_for_machine_status] at time 02:13:42.064607 duration_in_ms=347914.12
2019-05-21 02:13:42,070 [salt.minion      :1711][INFO    ][6315] Returning information for job: 20190521020746096895
2019-05-21 02:13:42,691 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command state.apply with jid 20190521021342679396
2019-05-21 02:13:42,720 [salt.minion      :1432][INFO    ][7994] Starting a new job with PID 7994
2019-05-21 02:13:48,817 [salt.state       :915 ][INFO    ][7994] Loading fresh modules for state activity
2019-05-21 02:13:48,874 [salt.fileclient  :1219][INFO    ][7994] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-21 02:13:48,970 [salt.state       :1780][INFO    ][7994] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:48.970663
2019-05-21 02:13:48,970 [salt.state       :1813][INFO    ][7994] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 02:13:48,972 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7994] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 02:13:50,825 [salt.state       :300 ][INFO    ][7994] {'pid': 8030, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 02:13:50,826 [salt.state       :1951][INFO    ][7994] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:50.826400 duration_in_ms=1855.735
2019-05-21 02:13:50,830 [salt.state       :1780][INFO    ][7994] Running state [maas_machines_storage_cmp002_lvm] at time 02:13:50.830112
2019-05-21 02:13:50,830 [salt.state       :1813][INFO    ][7994] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-21 02:13:52,113 [salt.loaded.ext.module.maasng:610 ][INFO    ][7994] 8t4frm
2019-05-21 02:13:52,114 [salt.loaded.ext.module.maasng:626 ][INFO    ][7994] sda
2019-05-21 02:13:52,684 [salt.loaded.ext.module.maasng:361 ][INFO    ][7994] 8t4frm
2019-05-21 02:13:52,775 [salt.loaded.ext.module.maasng:367 ][INFO    ][7994] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'248db9f6-14d5-4d19-b4cc-d2f505ece0ef', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8t4frm', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ba34f154-d748-4214-b975-3e2897762aac', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'8t4frm', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/blockdevices/1/', u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'uuid': u'9bdc7671-0817-4c08-a3e1-06c7c3cb7c8f', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'81719ba0-ee7a-4c6f-a4cd-3792af28db42', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8t4frm', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/blockdevices/3/', u'type': u'virtual', u'id': 3, u'size': 800097042432}]
2019-05-21 02:13:52,776 [salt.loaded.ext.module.maasng:632 ][INFO    ][7994] vgroot
2019-05-21 02:13:52,776 [salt.loaded.ext.module.maasng:635 ][INFO    ][7994] lvroot
2019-05-21 02:13:52,777 [salt.loaded.ext.module.maasng:639 ][INFO    ][7994] 107374182400
2019-05-21 02:13:53,395 [salt.loaded.ext.module.maasng:645 ][INFO    ][7994] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 40, 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'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/8t4frm/', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'8t4frm', 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'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'blockdevice_set': [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/blockdevices/1/', u'system_id': u'8t4frm', u'used_size': 800106479616, u'partitions': [{u'uuid': u'83917f17-9018-485c-bf6c-097a414783b3', u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8t4frm', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ec07693c-4a09-4f5a-a19b-35d577c31ac5', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id': 1, u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/blockdevices/11/', u'system_id': u'8t4frm', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'8dc6f4fa-822b-4c89-a56c-8bd89334cf53', u'fstype': u'ext4'}, u'uuid': u'a4bc8ad3-a964-4db8-bdf5-60d29833b40f', u'used_for': u'ext4 formatted filesystem mounted at /', u'id': 11, u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/blockdevices/1/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'83917f17-9018-485c-bf6c-097a414783b3', u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8t4frm', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ec07693c-4a09-4f5a-a19b-35d577c31ac5', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'8t4frm', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'8t4frm', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'pxe_admin'}, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'8t4frm', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 13, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'8t4frm', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/interfaces/13/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 15, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'8t4frm', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/interfaces/15/'}, {u'name': u'ens2f0', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'enabled': True, u'id': 14, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'8t4frm', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'pxe_admin'}, u'enabled': True, u'id': 11, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'8t4frm', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/interfaces/11/'}, {u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'enabled': True, u'id': 12, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'8t4frm', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/interfaces/12/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/blockdevices/11/', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'8dc6f4fa-822b-4c89-a56c-8bd89334cf53', u'fstype': u'ext4'}, u'uuid': u'a4bc8ad3-a964-4db8-bdf5-60d29833b40f', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8t4frm', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'pxe_admin'}, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'8t4frm', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/interfaces/4/'}, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'boot_disk': {u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/blockdevices/1/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'83917f17-9018-485c-bf6c-097a414783b3', u'resource_uri': u'/MAAS/api/2.0/nodes/8t4frm/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8t4frm', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ec07693c-4a09-4f5a-a19b-35d577c31ac5', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'8t4frm', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}}
2019-05-21 02:13:53,398 [salt.state       :300 ][INFO    ][7994] {'new': {'storage_layout': 'lvm'}}
2019-05-21 02:13:53,398 [salt.state       :1951][INFO    ][7994] Completed state [maas_machines_storage_cmp002_lvm] at time 02:13:53.398803 duration_in_ms=2568.689
2019-05-21 02:13:53,399 [salt.state       :1780][INFO    ][7994] Running state [maas_machines_storage_cmp001_lvm] at time 02:13:53.399529
2019-05-21 02:13:53,400 [salt.state       :1813][INFO    ][7994] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-21 02:13:54,536 [salt.loaded.ext.module.maasng:610 ][INFO    ][7994] pqsct3
2019-05-21 02:13:54,537 [salt.loaded.ext.module.maasng:626 ][INFO    ][7994] sda
2019-05-21 02:13:55,136 [salt.loaded.ext.module.maasng:361 ][INFO    ][7994] pqsct3
2019-05-21 02:13:55,248 [salt.loaded.ext.module.maasng:367 ][INFO    ][7994] [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/blockdevices/2/', u'system_id': u'pqsct3', u'used_size': 800106479616, u'partitions': [{u'uuid': u'1e9f36f2-5f62-47c6-90b9-118e8772a380', u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pqsct3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'87838e50-1d55-4e3a-a2c9-b8b989513775', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}], u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id': 2, u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/blockdevices/4/', u'system_id': u'pqsct3', u'used_size': 800097042432, u'partitions': [], u'tags': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'ce477531-b5d5-4a86-bf9a-21b1015ee85d', u'fstype': u'ext4'}, u'uuid': u'a28c086f-afd4-4e7f-8ee2-6720cac846d2', u'used_for': u'ext4 formatted filesystem mounted at /', u'id': 4, u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 800097042432}]
2019-05-21 02:13:55,248 [salt.loaded.ext.module.maasng:632 ][INFO    ][7994] vgroot
2019-05-21 02:13:55,249 [salt.loaded.ext.module.maasng:635 ][INFO    ][7994] lvroot
2019-05-21 02:13:55,249 [salt.loaded.ext.module.maasng:639 ][INFO    ][7994] 107374182400
2019-05-21 02:13:55,875 [salt.loaded.ext.module.maasng:645 ][INFO    ][7994] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 40, 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'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/pqsct3/', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'pqsct3', 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'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'blockdevice_set': [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/blockdevices/2/', u'system_id': u'pqsct3', u'used_size': 800106479616, u'partitions': [{u'uuid': u'ab7f95bf-6a0c-41b9-8dcc-846c77bf2e8f', u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pqsct3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'8d206f85-718f-46b8-9a83-7ede70f93e30', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id': 2, u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/blockdevices/12/', u'system_id': u'pqsct3', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'85199c86-ce1b-4fbb-a86c-820be2349aea', u'fstype': u'ext4'}, u'uuid': u'd8d3bd3c-5d26-40c2-8577-fd3ce46dc6be', u'used_for': u'ext4 formatted filesystem mounted at /', u'id': 12, u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/blockdevices/2/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'ab7f95bf-6a0c-41b9-8dcc-846c77bf2e8f', u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pqsct3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'8d206f85-718f-46b8-9a83-7ede70f93e30', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pqsct3', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'pqsct3', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'pxe_admin'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'pqsct3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 17, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'pqsct3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/interfaces/17/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 20, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'pqsct3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/interfaces/20/'}, {u'name': u'ens2f0', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'enabled': True, u'id': 16, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'pqsct3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/interfaces/16/'}, {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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'pxe_admin'}, 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.41'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'pqsct3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'enabled': True, u'id': 19, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'pqsct3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/interfaces/19/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, 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'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/blockdevices/12/', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'85199c86-ce1b-4fbb-a86c-820be2349aea', u'fstype': u'ext4'}, u'uuid': u'd8d3bd3c-5d26-40c2-8577-fd3ce46dc6be', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pqsct3', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'pxe_admin'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'pqsct3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/interfaces/5/'}, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'boot_disk': {u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/blockdevices/2/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'ab7f95bf-6a0c-41b9-8dcc-846c77bf2e8f', u'resource_uri': u'/MAAS/api/2.0/nodes/pqsct3/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pqsct3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'8d206f85-718f-46b8-9a83-7ede70f93e30', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pqsct3', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}}
2019-05-21 02:13:55,880 [salt.state       :300 ][INFO    ][7994] {'new': {'storage_layout': 'lvm'}}
2019-05-21 02:13:55,880 [salt.state       :1951][INFO    ][7994] Completed state [maas_machines_storage_cmp001_lvm] at time 02:13:55.880431 duration_in_ms=2480.902
2019-05-21 02:13:55,884 [salt.minion      :1711][INFO    ][7994] Returning information for job: 20190521021342679396
2019-05-21 02:13:56,457 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command state.apply with jid 20190521021356441694
2019-05-21 02:13:56,488 [salt.minion      :1432][INFO    ][8055] Starting a new job with PID 8055
2019-05-21 02:13:57,462 [salt.state       :915 ][INFO    ][8055] Loading fresh modules for state activity
2019-05-21 02:13:57,521 [salt.fileclient  :1219][INFO    ][8055] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-21 02:13:57,567 [salt.state       :1780][INFO    ][8055] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:57.567651
2019-05-21 02:13:57,567 [salt.state       :1813][INFO    ][8055] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 02:13:57,569 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8055] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 02:13:59,365 [salt.state       :300 ][INFO    ][8055] {'pid': 8062, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 02:13:59,367 [salt.state       :1951][INFO    ][8055] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:59.367491 duration_in_ms=1799.838
2019-05-21 02:13:59,371 [salt.state       :1780][INFO    ][8055] Running state [maas.deploy_machines] at time 02:13:59.371450
2019-05-21 02:13:59,372 [salt.state       :1813][INFO    ][8055] Executing state module.run for [maas.deploy_machines]
2019-05-21 02:13:59,372 [salt.utils.decorators:613 ][WARNING ][8055] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:13:59,976 [salt.loaded.ext.module.maas:684 ][INFO    ][8055] deploymachines hwe_kernel=ga-16.04 system_id=8t4frm distro_series=xenial
2019-05-21 02:14:02,591 [salt.loaded.ext.module.maas:684 ][INFO    ][8055] deploymachines hwe_kernel=ga-16.04 system_id=pqsct3 distro_series=xenial
2019-05-21 02:14:05,095 [salt.loaded.ext.module.maas:684 ][INFO    ][8055] deploymachines hwe_kernel=ga-16.04 system_id=nxsaaw distro_series=xenial
2019-05-21 02:14:07,771 [salt.loaded.ext.module.maas:684 ][INFO    ][8055] deploymachines hwe_kernel=ga-16.04 system_id=mcxnwt distro_series=xenial
2019-05-21 02:14:10,396 [salt.loaded.ext.module.maas:684 ][INFO    ][8055] deploymachines hwe_kernel=ga-16.04 system_id=yfq344 distro_series=xenial
2019-05-21 02:14:11,520 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021411512164
2019-05-21 02:14:11,545 [salt.minion      :1432][INFO    ][8315] Starting a new job with PID 8315
2019-05-21 02:14:11,570 [salt.minion      :1711][INFO    ][8315] Returning information for job: 20190521021411512164
2019-05-21 02:14:12,936 [salt.state       :300 ][INFO    ][8055] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-21 02:14:12,937 [salt.state       :1951][INFO    ][8055] Completed state [maas.deploy_machines] at time 02:14:12.937146 duration_in_ms=13565.695
2019-05-21 02:14:12,943 [salt.minion      :1711][INFO    ][8055] Returning information for job: 20190521021356441694
2019-05-21 02:14:13,512 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command state.apply with jid 20190521021413495043
2019-05-21 02:14:13,542 [salt.minion      :1432][INFO    ][8342] Starting a new job with PID 8342
2019-05-21 02:14:19,627 [salt.state       :915 ][INFO    ][8342] Loading fresh modules for state activity
2019-05-21 02:14:19,678 [salt.fileclient  :1219][INFO    ][8342] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-21 02:14:19,728 [salt.state       :1780][INFO    ][8342] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:19.728690
2019-05-21 02:14:19,729 [salt.state       :1813][INFO    ][8342] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 02:14:19,731 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8342] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 02:14:21,461 [salt.state       :300 ][INFO    ][8342] {'pid': 8385, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 02:14:21,462 [salt.state       :1951][INFO    ][8342] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:21.462616 duration_in_ms=1733.925
2019-05-21 02:14:21,466 [salt.state       :1780][INFO    ][8342] Running state [maas.wait_for_machine_status] at time 02:14:21.466604
2019-05-21 02:14:21,467 [salt.state       :1813][INFO    ][8342] Executing state module.run for [maas.wait_for_machine_status]
2019-05-21 02:14:21,468 [salt.utils.decorators:613 ][WARNING ][8342] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:14:24,499 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.98010302s left)
2019-05-21 02:14:28,593 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021428580746
2019-05-21 02:14:28,625 [salt.minion      :1432][INFO    ][8397] Starting a new job with PID 8397
2019-05-21 02:14:28,648 [salt.minion      :1711][INFO    ][8397] Returning information for job: 20190521021428580746
2019-05-21 02:14:57,329 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.15052795s left)
2019-05-21 02:14:58,665 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021458654591
2019-05-21 02:14:58,693 [salt.minion      :1432][INFO    ][8422] Starting a new job with PID 8422
2019-05-21 02:14:58,716 [salt.minion      :1711][INFO    ][8422] Returning information for job: 20190521021458654591
2019-05-21 02:15:28,764 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021528751721
2019-05-21 02:15:28,786 [salt.minion      :1432][INFO    ][8487] Starting a new job with PID 8487
2019-05-21 02:15:28,809 [salt.minion      :1711][INFO    ][8487] Returning information for job: 20190521021528751721
2019-05-21 02:15:30,311 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.16825008s left)
2019-05-21 02:15:58,831 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021558818406
2019-05-21 02:15:58,848 [salt.minion      :1432][INFO    ][8508] Starting a new job with PID 8508
2019-05-21 02:15:58,871 [salt.minion      :1711][INFO    ][8508] Returning information for job: 20190521021558818406
2019-05-21 02:16:03,536 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.94345689s left)
2019-05-21 02:16:28,912 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021628895314
2019-05-21 02:16:28,939 [salt.minion      :1432][INFO    ][8581] Starting a new job with PID 8581
2019-05-21 02:16:28,962 [salt.minion      :1711][INFO    ][8581] Returning information for job: 20190521021628895314
2019-05-21 02:16:36,429 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.05039406s left)
2019-05-21 02:16:59,005 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021658997084
2019-05-21 02:16:59,027 [salt.minion      :1432][INFO    ][8670] Starting a new job with PID 8670
2019-05-21 02:16:59,047 [salt.minion      :1711][INFO    ][8670] Returning information for job: 20190521021658997084
2019-05-21 02:17:09,457 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.02268791s left)
2019-05-21 02:17:29,104 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021729086593
2019-05-21 02:17:29,135 [salt.minion      :1432][INFO    ][8853] Starting a new job with PID 8853
2019-05-21 02:17:29,161 [salt.minion      :1711][INFO    ][8853] Returning information for job: 20190521021729086593
2019-05-21 02:17:42,442 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.03754807s left)
2019-05-21 02:17:59,229 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021759212692
2019-05-21 02:17:59,259 [salt.minion      :1432][INFO    ][8971] Starting a new job with PID 8971
2019-05-21 02:17:59,280 [salt.minion      :1711][INFO    ][8971] Returning information for job: 20190521021759212692
2019-05-21 02:18:15,716 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.76358008s left)
2019-05-21 02:18:29,350 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021829337609
2019-05-21 02:18:29,372 [salt.minion      :1432][INFO    ][9200] Starting a new job with PID 9200
2019-05-21 02:18:29,395 [salt.minion      :1711][INFO    ][9200] Returning information for job: 20190521021829337609
2019-05-21 02:18:49,627 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1981.85253692s left)
2019-05-21 02:18:59,484 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021859457377
2019-05-21 02:18:59,508 [salt.minion      :1432][INFO    ][9291] Starting a new job with PID 9291
2019-05-21 02:18:59,532 [salt.minion      :1711][INFO    ][9291] Returning information for job: 20190521021859457377
2019-05-21 02:19:22,508 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1948.97161293s left)
2019-05-21 02:19:29,656 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021929640624
2019-05-21 02:19:29,681 [salt.minion      :1432][INFO    ][9682] Starting a new job with PID 9682
2019-05-21 02:19:29,706 [salt.minion      :1711][INFO    ][9682] Returning information for job: 20190521021929640624
2019-05-21 02:19:55,649 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1915.83063197s left)
2019-05-21 02:19:59,793 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521021959775129
2019-05-21 02:19:59,824 [salt.minion      :1432][INFO    ][9848] Starting a new job with PID 9848
2019-05-21 02:19:59,853 [salt.minion      :1711][INFO    ][9848] Returning information for job: 20190521021959775129
2019-05-21 02:20:28,868 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1882.61161709s left)
2019-05-21 02:20:29,946 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022029933956
2019-05-21 02:20:29,974 [salt.minion      :1432][INFO    ][10091] Starting a new job with PID 10091
2019-05-21 02:20:29,994 [salt.minion      :1711][INFO    ][10091] Returning information for job: 20190521022029933956
2019-05-21 02:21:00,097 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022100081483
2019-05-21 02:21:00,121 [salt.minion      :1432][INFO    ][10229] Starting a new job with PID 10229
2019-05-21 02:21:00,148 [salt.minion      :1711][INFO    ][10229] Returning information for job: 20190521022100081483
2019-05-21 02:21:02,019 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1849.46234298s left)
2019-05-21 02:21:30,247 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022130239535
2019-05-21 02:21:30,264 [salt.minion      :1432][INFO    ][10500] Starting a new job with PID 10500
2019-05-21 02:21:30,288 [salt.minion      :1711][INFO    ][10500] Returning information for job: 20190521022130239535
2019-05-21 02:21:35,164 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1816.31545806s left)
2019-05-21 02:22:00,391 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022200375763
2019-05-21 02:22:00,421 [salt.minion      :1432][INFO    ][10562] Starting a new job with PID 10562
2019-05-21 02:22:00,443 [salt.minion      :1711][INFO    ][10562] Returning information for job: 20190521022200375763
2019-05-21 02:22:08,083 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1783.39671993s left)
2019-05-21 02:22:30,597 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022230579347
2019-05-21 02:22:30,620 [salt.minion      :1432][INFO    ][10782] Starting a new job with PID 10782
2019-05-21 02:22:30,643 [salt.minion      :1711][INFO    ][10782] Returning information for job: 20190521022230579347
2019-05-21 02:22:40,927 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1750.5527339s left)
2019-05-21 02:23:00,735 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022300718812
2019-05-21 02:23:00,762 [salt.minion      :1432][INFO    ][10865] Starting a new job with PID 10865
2019-05-21 02:23:00,784 [salt.minion      :1711][INFO    ][10865] Returning information for job: 20190521022300718812
2019-05-21 02:23:13,924 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1717.55558801s left)
2019-05-21 02:23:30,853 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022330841341
2019-05-21 02:23:30,875 [salt.minion      :1432][INFO    ][10989] Starting a new job with PID 10989
2019-05-21 02:23:30,895 [salt.minion      :1711][INFO    ][10989] Returning information for job: 20190521022330841341
2019-05-21 02:23:47,092 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1684.38780808s left)
2019-05-21 02:24:01,012 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022400998946
2019-05-21 02:24:01,038 [salt.minion      :1432][INFO    ][11072] Starting a new job with PID 11072
2019-05-21 02:24:01,063 [salt.minion      :1711][INFO    ][11072] Returning information for job: 20190521022400998946
2019-05-21 02:24:20,070 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1651.40953207s left)
2019-05-21 02:24:31,209 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022431198517
2019-05-21 02:24:31,237 [salt.minion      :1432][INFO    ][11281] Starting a new job with PID 11281
2019-05-21 02:24:31,261 [salt.minion      :1711][INFO    ][11281] Returning information for job: 20190521022431198517
2019-05-21 02:24:53,104 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1618.375494s left)
2019-05-21 02:25:01,394 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022501375110
2019-05-21 02:25:01,426 [salt.minion      :1432][INFO    ][11316] Starting a new job with PID 11316
2019-05-21 02:25:01,449 [salt.minion      :1711][INFO    ][11316] Returning information for job: 20190521022501375110
2019-05-21 02:25:26,098 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1585.38133407s left)
2019-05-21 02:25:31,580 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022531568040
2019-05-21 02:25:31,607 [salt.minion      :1432][INFO    ][11369] Starting a new job with PID 11369
2019-05-21 02:25:31,632 [salt.minion      :1711][INFO    ][11369] Returning information for job: 20190521022531568040
2019-05-21 02:25:58,909 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1552.57045293s left)
2019-05-21 02:26:01,764 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022601752988
2019-05-21 02:26:01,788 [salt.minion      :1432][INFO    ][11410] Starting a new job with PID 11410
2019-05-21 02:26:01,813 [salt.minion      :1711][INFO    ][11410] Returning information for job: 20190521022601752988
2019-05-21 02:26:31,977 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022631966935
2019-05-21 02:26:32,001 [salt.minion      :1432][INFO    ][11666] Starting a new job with PID 11666
2019-05-21 02:26:32,035 [salt.minion      :1711][INFO    ][11666] Returning information for job: 20190521022631966935
2019-05-21 02:26:32,160 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1519.31949496s left)
2019-05-21 02:27:02,160 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022702146618
2019-05-21 02:27:02,188 [salt.minion      :1432][INFO    ][11762] Starting a new job with PID 11762
2019-05-21 02:27:02,226 [salt.minion      :1711][INFO    ][11762] Returning information for job: 20190521022702146618
2019-05-21 02:27:05,092 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1486.38761806s left)
2019-05-21 02:27:32,228 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022732217493
2019-05-21 02:27:32,250 [salt.minion      :1432][INFO    ][11991] Starting a new job with PID 11991
2019-05-21 02:27:32,277 [salt.minion      :1711][INFO    ][11991] Returning information for job: 20190521022732217493
2019-05-21 02:27:37,935 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1453.54471087s left)
2019-05-21 02:28:02,250 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022802236296
2019-05-21 02:28:02,267 [salt.minion      :1432][INFO    ][12045] Starting a new job with PID 12045
2019-05-21 02:28:02,293 [salt.minion      :1711][INFO    ][12045] Returning information for job: 20190521022802236296
2019-05-21 02:28:10,884 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1420.59596395s left)
2019-05-21 02:28:32,276 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022832263640
2019-05-21 02:28:32,307 [salt.minion      :1432][INFO    ][12089] Starting a new job with PID 12089
2019-05-21 02:28:32,330 [salt.minion      :1711][INFO    ][12089] Returning information for job: 20190521022832263640
2019-05-21 02:28:43,916 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1387.56348991s left)
2019-05-21 02:29:02,325 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022902313589
2019-05-21 02:29:02,351 [salt.minion      :1432][INFO    ][12121] Starting a new job with PID 12121
2019-05-21 02:29:02,381 [salt.minion      :1711][INFO    ][12121] Returning information for job: 20190521022902313589
2019-05-21 02:29:16,926 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1354.55387902s left)
2019-05-21 02:29:32,375 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521022932360005
2019-05-21 02:29:32,405 [salt.minion      :1432][INFO    ][12164] Starting a new job with PID 12164
2019-05-21 02:29:32,428 [salt.minion      :1711][INFO    ][12164] Returning information for job: 20190521022932360005
2019-05-21 02:29:49,929 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1321.55037189s left)
2019-05-21 02:30:02,447 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023002435101
2019-05-21 02:30:02,471 [salt.minion      :1432][INFO    ][12203] Starting a new job with PID 12203
2019-05-21 02:30:02,494 [salt.minion      :1711][INFO    ][12203] Returning information for job: 20190521023002435101
2019-05-21 02:30:22,845 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1288.6346519s left)
2019-05-21 02:30:32,496 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023032485392
2019-05-21 02:30:32,524 [salt.minion      :1432][INFO    ][12250] Starting a new job with PID 12250
2019-05-21 02:30:32,560 [salt.minion      :1711][INFO    ][12250] Returning information for job: 20190521023032485392
2019-05-21 02:30:55,846 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1255.63365889s left)
2019-05-21 02:31:02,624 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023102613379
2019-05-21 02:31:02,649 [salt.minion      :1432][INFO    ][12284] Starting a new job with PID 12284
2019-05-21 02:31:02,673 [salt.minion      :1711][INFO    ][12284] Returning information for job: 20190521023102613379
2019-05-21 02:31:28,688 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1222.7911799s left)
2019-05-21 02:31:32,707 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023132690639
2019-05-21 02:31:32,733 [salt.minion      :1432][INFO    ][12334] Starting a new job with PID 12334
2019-05-21 02:31:32,751 [salt.minion      :1711][INFO    ][12334] Returning information for job: 20190521023132690639
2019-05-21 02:32:01,607 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1189.87268209s left)
2019-05-21 02:32:02,816 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023202799471
2019-05-21 02:32:02,843 [salt.minion      :1432][INFO    ][12368] Starting a new job with PID 12368
2019-05-21 02:32:02,862 [salt.minion      :1711][INFO    ][12368] Returning information for job: 20190521023202799471
2019-05-21 02:32:32,921 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023232908564
2019-05-21 02:32:32,945 [salt.minion      :1432][INFO    ][12409] Starting a new job with PID 12409
2019-05-21 02:32:32,971 [salt.minion      :1711][INFO    ][12409] Returning information for job: 20190521023232908564
2019-05-21 02:32:34,336 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1157.143466s left)
2019-05-21 02:33:03,069 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023303053536
2019-05-21 02:33:03,097 [salt.minion      :1432][INFO    ][12443] Starting a new job with PID 12443
2019-05-21 02:33:03,126 [salt.minion      :1711][INFO    ][12443] Returning information for job: 20190521023303053536
2019-05-21 02:33:07,314 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1124.16556406s left)
2019-05-21 02:33:33,204 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023333196502
2019-05-21 02:33:33,226 [salt.minion      :1432][INFO    ][12488] Starting a new job with PID 12488
2019-05-21 02:33:33,247 [salt.minion      :1711][INFO    ][12488] Returning information for job: 20190521023333196502
2019-05-21 02:33:40,037 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1091.44240904s left)
2019-05-21 02:34:03,373 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023403358368
2019-05-21 02:34:03,399 [salt.minion      :1432][INFO    ][12521] Starting a new job with PID 12521
2019-05-21 02:34:03,423 [salt.minion      :1711][INFO    ][12521] Returning information for job: 20190521023403358368
2019-05-21 02:34:12,876 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1058.60353208s left)
2019-05-21 02:34:33,549 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023433541233
2019-05-21 02:34:33,573 [salt.minion      :1432][INFO    ][12566] Starting a new job with PID 12566
2019-05-21 02:34:33,597 [salt.minion      :1711][INFO    ][12566] Returning information for job: 20190521023433541233
2019-05-21 02:34:45,635 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1025.84502506s left)
2019-05-21 02:35:03,739 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023503721492
2019-05-21 02:35:03,766 [salt.minion      :1432][INFO    ][12599] Starting a new job with PID 12599
2019-05-21 02:35:03,789 [salt.minion      :1711][INFO    ][12599] Returning information for job: 20190521023503721492
2019-05-21 02:35:18,525 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (992.954593897s left)
2019-05-21 02:35:33,959 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023533946968
2019-05-21 02:35:33,984 [salt.minion      :1432][INFO    ][12643] Starting a new job with PID 12643
2019-05-21 02:35:34,008 [salt.minion      :1711][INFO    ][12643] Returning information for job: 20190521023533946968
2019-05-21 02:35:51,450 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (960.029247046s left)
2019-05-21 02:36:04,164 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023604149568
2019-05-21 02:36:04,191 [salt.minion      :1432][INFO    ][12682] Starting a new job with PID 12682
2019-05-21 02:36:04,212 [salt.minion      :1711][INFO    ][12682] Returning information for job: 20190521023604149568
2019-05-21 02:36:24,265 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (927.214644909s left)
2019-05-21 02:36:34,209 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023634192698
2019-05-21 02:36:34,236 [salt.minion      :1432][INFO    ][12747] Starting a new job with PID 12747
2019-05-21 02:36:34,258 [salt.minion      :1711][INFO    ][12747] Returning information for job: 20190521023634192698
2019-05-21 02:36:57,289 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (894.19046402s left)
2019-05-21 02:37:04,244 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023704232440
2019-05-21 02:37:04,275 [salt.minion      :1432][INFO    ][12781] Starting a new job with PID 12781
2019-05-21 02:37:04,296 [salt.minion      :1711][INFO    ][12781] Returning information for job: 20190521023704232440
2019-05-21 02:37:30,381 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (861.098933935s left)
2019-05-21 02:37:34,320 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023734305484
2019-05-21 02:37:34,351 [salt.minion      :1432][INFO    ][12829] Starting a new job with PID 12829
2019-05-21 02:37:34,376 [salt.minion      :1711][INFO    ][12829] Returning information for job: 20190521023734305484
2019-05-21 02:38:03,169 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (828.311074018s left)
2019-05-21 02:38:04,398 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023804384864
2019-05-21 02:38:04,422 [salt.minion      :1432][INFO    ][12866] Starting a new job with PID 12866
2019-05-21 02:38:04,449 [salt.minion      :1711][INFO    ][12866] Returning information for job: 20190521023804384864
2019-05-21 02:38:34,516 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023834506961
2019-05-21 02:38:34,536 [salt.minion      :1432][INFO    ][12905] Starting a new job with PID 12905
2019-05-21 02:38:34,560 [salt.minion      :1711][INFO    ][12905] Returning information for job: 20190521023834506961
2019-05-21 02:38:35,934 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (795.545599937s left)
2019-05-21 02:39:04,624 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023904608798
2019-05-21 02:39:04,655 [salt.minion      :1432][INFO    ][12935] Starting a new job with PID 12935
2019-05-21 02:39:04,678 [salt.minion      :1711][INFO    ][12935] Returning information for job: 20190521023904608798
2019-05-21 02:39:08,644 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (762.83574295s left)
2019-05-21 02:39:34,769 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521023934755402
2019-05-21 02:39:34,796 [salt.minion      :1432][INFO    ][12979] Starting a new job with PID 12979
2019-05-21 02:39:34,818 [salt.minion      :1711][INFO    ][12979] Returning information for job: 20190521023934755402
2019-05-21 02:39:41,493 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (729.986819983s left)
2019-05-21 02:40:04,903 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024004890711
2019-05-21 02:40:04,924 [salt.minion      :1432][INFO    ][13015] Starting a new job with PID 13015
2019-05-21 02:40:04,948 [salt.minion      :1711][INFO    ][13015] Returning information for job: 20190521024004890711
2019-05-21 02:40:14,375 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (697.104397058s left)
2019-05-21 02:40:35,070 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024035057110
2019-05-21 02:40:35,092 [salt.minion      :1432][INFO    ][13063] Starting a new job with PID 13063
2019-05-21 02:40:35,114 [salt.minion      :1711][INFO    ][13063] Returning information for job: 20190521024035057110
2019-05-21 02:40:47,423 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (664.056362867s left)
2019-05-21 02:41:05,264 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024105255060
2019-05-21 02:41:05,291 [salt.minion      :1432][INFO    ][13097] Starting a new job with PID 13097
2019-05-21 02:41:05,315 [salt.minion      :1711][INFO    ][13097] Returning information for job: 20190521024105255060
2019-05-21 02:41:20,193 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (631.286838055s left)
2019-05-21 02:41:35,447 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024135431302
2019-05-21 02:41:35,475 [salt.minion      :1432][INFO    ][13140] Starting a new job with PID 13140
2019-05-21 02:41:35,501 [salt.minion      :1711][INFO    ][13140] Returning information for job: 20190521024135431302
2019-05-21 02:41:53,033 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (598.446423054s left)
2019-05-21 02:42:05,495 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024205484713
2019-05-21 02:42:05,525 [salt.minion      :1432][INFO    ][13176] Starting a new job with PID 13176
2019-05-21 02:42:05,551 [salt.minion      :1711][INFO    ][13176] Returning information for job: 20190521024205484713
2019-05-21 02:42:25,860 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (565.61947608s left)
2019-05-21 02:42:35,710 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024235693573
2019-05-21 02:42:35,743 [salt.minion      :1432][INFO    ][13223] Starting a new job with PID 13223
2019-05-21 02:42:35,780 [salt.minion      :1711][INFO    ][13223] Returning information for job: 20190521024235693573
2019-05-21 02:42:58,631 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (532.848660946s left)
2019-05-21 02:43:05,831 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024305816088
2019-05-21 02:43:05,860 [salt.minion      :1432][INFO    ][13257] Starting a new job with PID 13257
2019-05-21 02:43:05,886 [salt.minion      :1711][INFO    ][13257] Returning information for job: 20190521024305816088
2019-05-21 02:43:31,348 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (500.131157875s left)
2019-05-21 02:43:35,925 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024335912301
2019-05-21 02:43:35,955 [salt.minion      :1432][INFO    ][13300] Starting a new job with PID 13300
2019-05-21 02:43:35,979 [salt.minion      :1711][INFO    ][13300] Returning information for job: 20190521024335912301
2019-05-21 02:44:04,371 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (467.108690977s left)
2019-05-21 02:44:06,053 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024406039550
2019-05-21 02:44:06,079 [salt.minion      :1432][INFO    ][13336] Starting a new job with PID 13336
2019-05-21 02:44:06,102 [salt.minion      :1711][INFO    ][13336] Returning information for job: 20190521024406039550
2019-05-21 02:44:36,208 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024436196487
2019-05-21 02:44:36,229 [salt.minion      :1432][INFO    ][13379] Starting a new job with PID 13379
2019-05-21 02:44:36,263 [salt.minion      :1711][INFO    ][13379] Returning information for job: 20190521024436196487
2019-05-21 02:44:37,353 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (434.126635075s left)
2019-05-21 02:45:06,407 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024506390289
2019-05-21 02:45:06,432 [salt.minion      :1432][INFO    ][13410] Starting a new job with PID 13410
2019-05-21 02:45:06,452 [salt.minion      :1711][INFO    ][13410] Returning information for job: 20190521024506390289
2019-05-21 02:45:10,263 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (401.218199015s left)
2019-05-21 02:45:36,505 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024536496838
2019-05-21 02:45:36,528 [salt.minion      :1432][INFO    ][13460] Starting a new job with PID 13460
2019-05-21 02:45:36,551 [salt.minion      :1711][INFO    ][13460] Returning information for job: 20190521024536496838
2019-05-21 02:45:43,151 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (368.328578949s left)
2019-05-21 02:46:06,712 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024606701355
2019-05-21 02:46:06,739 [salt.minion      :1432][INFO    ][13626] Starting a new job with PID 13626
2019-05-21 02:46:06,763 [salt.minion      :1711][INFO    ][13626] Returning information for job: 20190521024606701355
2019-05-21 02:46:16,081 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (335.398897886s left)
2019-05-21 02:46:36,773 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024636758451
2019-05-21 02:46:36,802 [salt.minion      :1432][INFO    ][13684] Starting a new job with PID 13684
2019-05-21 02:46:36,825 [salt.minion      :1711][INFO    ][13684] Returning information for job: 20190521024636758451
2019-05-21 02:46:48,812 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (302.667327881s left)
2019-05-21 02:47:06,834 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024706818363
2019-05-21 02:47:06,859 [salt.minion      :1432][INFO    ][13718] Starting a new job with PID 13718
2019-05-21 02:47:06,884 [salt.minion      :1711][INFO    ][13718] Returning information for job: 20190521024706818363
2019-05-21 02:47:21,716 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (269.763211012s left)
2019-05-21 02:47:36,920 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024736905515
2019-05-21 02:47:36,946 [salt.minion      :1432][INFO    ][13766] Starting a new job with PID 13766
2019-05-21 02:47:36,974 [salt.minion      :1711][INFO    ][13766] Returning information for job: 20190521024736905515
2019-05-21 02:47:54,736 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (236.743494034s left)
2019-05-21 02:48:07,156 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024807141193
2019-05-21 02:48:07,180 [salt.minion      :1432][INFO    ][13804] Starting a new job with PID 13804
2019-05-21 02:48:07,203 [salt.minion      :1711][INFO    ][13804] Returning information for job: 20190521024807141193
2019-05-21 02:48:27,492 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (203.987997055s left)
2019-05-21 02:48:37,232 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024837216914
2019-05-21 02:48:37,263 [salt.minion      :1432][INFO    ][13848] Starting a new job with PID 13848
2019-05-21 02:48:37,287 [salt.minion      :1711][INFO    ][13848] Returning information for job: 20190521024837216914
2019-05-21 02:49:00,288 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (171.191559076s left)
2019-05-21 02:49:07,268 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024907251713
2019-05-21 02:49:07,295 [salt.minion      :1432][INFO    ][13881] Starting a new job with PID 13881
2019-05-21 02:49:07,317 [salt.minion      :1711][INFO    ][13881] Returning information for job: 20190521024907251713
2019-05-21 02:49:33,053 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (138.426622868s left)
2019-05-21 02:49:37,375 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521024937360234
2019-05-21 02:49:37,403 [salt.minion      :1432][INFO    ][13925] Starting a new job with PID 13925
2019-05-21 02:49:37,426 [salt.minion      :1711][INFO    ][13925] Returning information for job: 20190521024937360234
2019-05-21 02:50:05,968 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (105.512021065s left)
2019-05-21 02:50:07,432 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025007415236
2019-05-21 02:50:07,464 [salt.minion      :1432][INFO    ][13957] Starting a new job with PID 13957
2019-05-21 02:50:07,490 [salt.minion      :1711][INFO    ][13957] Returning information for job: 20190521025007415236
2019-05-21 02:50:37,589 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025037576101
2019-05-21 02:50:37,614 [salt.minion      :1432][INFO    ][14001] Starting a new job with PID 14001
2019-05-21 02:50:37,639 [salt.minion      :1711][INFO    ][14001] Returning information for job: 20190521025037576101
2019-05-21 02:50:38,889 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (72.590364933s left)
2019-05-21 02:51:07,771 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025107758725
2019-05-21 02:51:07,799 [salt.minion      :1432][INFO    ][14031] Starting a new job with PID 14031
2019-05-21 02:51:07,822 [salt.minion      :1711][INFO    ][14031] Returning information for job: 20190521025107758725
2019-05-21 02:51:11,707 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (39.7724080086s left)
2019-05-21 02:51:37,984 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025137967952
2019-05-21 02:51:38,011 [salt.minion      :1432][INFO    ][14075] Starting a new job with PID 14075
2019-05-21 02:51:38,035 [salt.minion      :1711][INFO    ][14075] Returning information for job: 20190521025137967952
2019-05-21 02:51:44,749 [salt.loaded.ext.module.maas:1023][INFO    ][8342] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (6.73053789139s left)
2019-05-21 02:52:08,156 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025208149902
2019-05-21 02:52:08,180 [salt.minion      :1432][INFO    ][14111] Starting a new job with PID 14111
2019-05-21 02:52:08,203 [salt.minion      :1711][INFO    ][14111] Returning information for job: 20190521025208149902
2019-05-21 02:52:17,723 [salt.state       :302 ][ERROR   ][8342] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-21 02:52:17,724 [salt.state       :1951][INFO    ][8342] Completed state [maas.wait_for_machine_status] at time 02:52:17.724699 duration_in_ms=2276258.093
2019-05-21 02:52:17,732 [salt.minion      :1711][INFO    ][8342] Returning information for job: 20190521021413495043
2019-05-21 02:52:28,577 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command pillar.get with jid 20190521025228562840
2019-05-21 02:52:28,602 [salt.minion      :1432][INFO    ][14152] Starting a new job with PID 14152
2019-05-21 02:52:28,613 [salt.minion      :1711][INFO    ][14152] Returning information for job: 20190521025228562840
2019-05-21 02:52:29,170 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command service.status with jid 20190521025229158283
2019-05-21 02:52:29,195 [salt.minion      :1432][INFO    ][14157] Starting a new job with PID 14157
2019-05-21 02:52:29,749 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14157] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-21 02:52:29,788 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14157] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-21 02:52:29,807 [salt.minion      :1711][INFO    ][14157] Returning information for job: 20190521025229158283
2019-05-21 02:52:30,371 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command state.apply with jid 20190521025230355522
2019-05-21 02:52:30,400 [salt.minion      :1432][INFO    ][14168] Starting a new job with PID 14168
2019-05-21 02:52:36,411 [salt.state       :915 ][INFO    ][14168] Loading fresh modules for state activity
2019-05-21 02:52:37,020 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14168] Executing command 'salt-minion --version' in directory '/root'
2019-05-21 02:52:37,378 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14168] Executing command 'salt-minion --version' in directory '/root'
2019-05-21 02:52:38,401 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14168] Executing command 'salt-minion --version' in directory '/root'
2019-05-21 02:52:38,697 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14168] Executing command 'salt-minion --version' in directory '/root'
2019-05-21 02:52:40,559 [salt.state       :1780][INFO    ][14168] Running state [salt-minion] at time 02:52:40.559003
2019-05-21 02:52:40,559 [salt.state       :1813][INFO    ][14168] Executing state pkg.installed for [salt-minion]
2019-05-21 02:52:40,560 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14168] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-21 02:52:40,685 [salt.state       :300 ][INFO    ][14168] All specified packages are already installed
2019-05-21 02:52:40,686 [salt.state       :1951][INFO    ][14168] Completed state [salt-minion] at time 02:52:40.686027 duration_in_ms=127.025
2019-05-21 02:52:40,686 [salt.state       :1780][INFO    ][14168] Running state [salt_minion_dependency_packages] at time 02:52:40.686358
2019-05-21 02:52:40,686 [salt.state       :1813][INFO    ][14168] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-21 02:52:40,696 [salt.state       :300 ][INFO    ][14168] All specified packages are already installed
2019-05-21 02:52:40,696 [salt.state       :1951][INFO    ][14168] Completed state [salt_minion_dependency_packages] at time 02:52:40.696505 duration_in_ms=10.146
2019-05-21 02:52:40,699 [salt.state       :1780][INFO    ][14168] Running state [/etc/salt/minion.d/minion.conf] at time 02:52:40.699509
2019-05-21 02:52:40,699 [salt.state       :1813][INFO    ][14168] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-21 02:52:40,984 [salt.state       :300 ][INFO    ][14168] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-21 02:52:40,985 [salt.state       :1951][INFO    ][14168] Completed state [/etc/salt/minion.d/minion.conf] at time 02:52:40.984959 duration_in_ms=285.45
2019-05-21 02:52:40,985 [salt.state       :1780][INFO    ][14168] Running state [python-netaddr] at time 02:52:40.985214
2019-05-21 02:52:40,985 [salt.state       :1813][INFO    ][14168] Executing state pkg.installed for [python-netaddr]
2019-05-21 02:52:40,995 [salt.state       :300 ][INFO    ][14168] All specified packages are already installed
2019-05-21 02:52:40,995 [salt.state       :1951][INFO    ][14168] Completed state [python-netaddr] at time 02:52:40.995704 duration_in_ms=10.49
2019-05-21 02:52:40,998 [salt.state       :1780][INFO    ][14168] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:52:40.998803
2019-05-21 02:52:40,999 [salt.state       :1813][INFO    ][14168] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-21 02:52:41,011 [salt.state       :300 ][INFO    ][14168] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-21 02:52:41,011 [salt.state       :1951][INFO    ][14168] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:52:41.011369 duration_in_ms=12.566
2019-05-21 02:52:41,012 [salt.state       :1780][INFO    ][14168] Running state [salt-minion] at time 02:52:41.012606
2019-05-21 02:52:41,012 [salt.state       :1813][INFO    ][14168] Executing state service.running for [salt-minion]
2019-05-21 02:52:41,013 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14168] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-21 02:52:41,056 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14168] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-21 02:52:41,082 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14168] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-21 02:52:41,106 [salt.state       :300 ][INFO    ][14168] The service salt-minion is already running
2019-05-21 02:52:41,106 [salt.state       :1951][INFO    ][14168] Completed state [salt-minion] at time 02:52:41.106774 duration_in_ms=94.167
2019-05-21 02:52:41,110 [salt.state       :1780][INFO    ][14168] Running state [/etc/salt/grains.d] at time 02:52:41.109955
2019-05-21 02:52:41,110 [salt.state       :1813][INFO    ][14168] Executing state file.directory for [/etc/salt/grains.d]
2019-05-21 02:52:41,112 [salt.state       :300 ][INFO    ][14168] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-21 02:52:41,112 [salt.state       :1951][INFO    ][14168] Completed state [/etc/salt/grains.d] at time 02:52:41.112822 duration_in_ms=2.866
2019-05-21 02:52:41,116 [salt.state       :1780][INFO    ][14168] Running state [/etc/salt/grains] at time 02:52:41.116179
2019-05-21 02:52:41,116 [salt.state       :1813][INFO    ][14168] Executing state file.managed for [/etc/salt/grains]
2019-05-21 02:52:41,117 [salt.state       :300 ][INFO    ][14168] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-21 02:52:41,117 [salt.state       :1951][INFO    ][14168] Completed state [/etc/salt/grains] at time 02:52:41.117301 duration_in_ms=1.121
2019-05-21 02:52:41,117 [salt.state       :1780][INFO    ][14168] Running state [/etc/salt/grains.d/placeholder] at time 02:52:41.117838
2019-05-21 02:52:41,118 [salt.state       :1813][INFO    ][14168] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-21 02:52:41,118 [salt.state       :300 ][INFO    ][14168] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-21 02:52:41,118 [salt.state       :1951][INFO    ][14168] Completed state [/etc/salt/grains.d/placeholder] at time 02:52:41.118925 duration_in_ms=1.087
2019-05-21 02:52:41,119 [salt.state       :1780][INFO    ][14168] Running state [/etc/salt/grains.d/sphinx] at time 02:52:41.119451
2019-05-21 02:52:41,119 [salt.state       :1813][INFO    ][14168] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-21 02:52:41,121 [salt.state       :300 ][INFO    ][14168] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-21 02:52:41,121 [salt.state       :1951][INFO    ][14168] Completed state [/etc/salt/grains.d/sphinx] at time 02:52:41.121234 duration_in_ms=1.783
2019-05-21 02:52:41,123 [salt.state       :1780][INFO    ][14168] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:52:41.123593
2019-05-21 02:52:41,123 [salt.state       :1813][INFO    ][14168] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-21 02:52:41,124 [salt.state       :300 ][INFO    ][14168] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-21 02:52:41,124 [salt.state       :1951][INFO    ][14168] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:52:41.124440 duration_in_ms=0.847
2019-05-21 02:52:41,125 [salt.state       :1780][INFO    ][14168] Running state [/etc/salt/grains.d/dns_records] at time 02:52:41.124978
2019-05-21 02:52:41,125 [salt.state       :1813][INFO    ][14168] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-21 02:52:41,127 [salt.state       :300 ][INFO    ][14168] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-21 02:52:41,128 [salt.state       :1951][INFO    ][14168] Completed state [/etc/salt/grains.d/dns_records] at time 02:52:41.128140 duration_in_ms=3.162
2019-05-21 02:52:41,129 [salt.state       :1780][INFO    ][14168] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:52:41.129079
2019-05-21 02:52:41,129 [salt.state       :1813][INFO    ][14168] 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-21 02:52:41,129 [salt.state       :300 ][INFO    ][14168] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-21 02:52:41,129 [salt.state       :1951][INFO    ][14168] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:52:41.129844 duration_in_ms=0.764
2019-05-21 02:52:41,130 [salt.state       :1780][INFO    ][14168] Running state [/etc/salt/grains.d/salt] at time 02:52:41.130322
2019-05-21 02:52:41,130 [salt.state       :1813][INFO    ][14168] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-21 02:52:41,131 [salt.state       :300 ][INFO    ][14168] File /etc/salt/grains.d/salt is in the correct state
2019-05-21 02:52:41,131 [salt.state       :1951][INFO    ][14168] Completed state [/etc/salt/grains.d/salt] at time 02:52:41.131730 duration_in_ms=1.408
2019-05-21 02:52:41,132 [salt.state       :1780][INFO    ][14168] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:52:41.132618
2019-05-21 02:52:41,132 [salt.state       :1813][INFO    ][14168] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-21 02:52:41,133 [salt.state       :300 ][INFO    ][14168] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-21 02:52:41,133 [salt.state       :1951][INFO    ][14168] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:52:41.133363 duration_in_ms=0.745
2019-05-21 02:52:41,135 [salt.state       :1780][INFO    ][14168] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:52:41.135366
2019-05-21 02:52:41,135 [salt.state       :1813][INFO    ][14168] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-21 02:52:41,135 [salt.state       :300 ][INFO    ][14168] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-21 02:52:41,136 [salt.state       :1951][INFO    ][14168] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:52:41.136163 duration_in_ms=0.796
2019-05-21 02:52:41,136 [salt.state       :1780][INFO    ][14168] Running state [mine.update] at time 02:52:41.136841
2019-05-21 02:52:41,137 [salt.state       :1813][INFO    ][14168] Executing state module.wait for [mine.update]
2019-05-21 02:52:41,137 [salt.state       :300 ][INFO    ][14168] No changes made for mine.update
2019-05-21 02:52:41,137 [salt.state       :1951][INFO    ][14168] Completed state [mine.update] at time 02:52:41.137536 duration_in_ms=0.695
2019-05-21 02:52:41,138 [salt.state       :1780][INFO    ][14168] Running state [ca-certificates] at time 02:52:41.138648
2019-05-21 02:52:41,138 [salt.state       :1813][INFO    ][14168] Executing state pkg.installed for [ca-certificates]
2019-05-21 02:52:41,147 [salt.state       :300 ][INFO    ][14168] All specified packages are already installed
2019-05-21 02:52:41,148 [salt.state       :1951][INFO    ][14168] Completed state [ca-certificates] at time 02:52:41.148041 duration_in_ms=9.393
2019-05-21 02:52:41,148 [salt.state       :1780][INFO    ][14168] Running state [update-ca-certificates] at time 02:52:41.148740
2019-05-21 02:52:41,149 [salt.state       :1813][INFO    ][14168] Executing state cmd.wait for [update-ca-certificates]
2019-05-21 02:52:41,149 [salt.state       :300 ][INFO    ][14168] No changes made for update-ca-certificates
2019-05-21 02:52:41,149 [salt.state       :1951][INFO    ][14168] Completed state [update-ca-certificates] at time 02:52:41.149462 duration_in_ms=0.722
2019-05-21 02:52:41,150 [salt.state       :1780][INFO    ][14168] Running state [iptables] at time 02:52:41.149693
2019-05-21 02:52:41,151 [salt.state       :1813][INFO    ][14168] Executing state pkg.installed for [iptables]
2019-05-21 02:52:41,159 [salt.state       :300 ][INFO    ][14168] All specified packages are already installed
2019-05-21 02:52:41,159 [salt.state       :1951][INFO    ][14168] Completed state [iptables] at time 02:52:41.159773 duration_in_ms=10.08
2019-05-21 02:52:41,160 [salt.state       :1780][INFO    ][14168] Running state [iptables-persistent] at time 02:52:41.160015
2019-05-21 02:52:41,160 [salt.state       :1813][INFO    ][14168] Executing state pkg.installed for [iptables-persistent]
2019-05-21 02:52:41,169 [salt.state       :300 ][INFO    ][14168] All specified packages are already installed
2019-05-21 02:52:41,169 [salt.state       :1951][INFO    ][14168] Completed state [iptables-persistent] at time 02:52:41.169336 duration_in_ms=9.321
2019-05-21 02:52:41,170 [salt.state       :1780][INFO    ][14168] Running state [iptables_modules_v4_load] at time 02:52:41.170328
2019-05-21 02:52:41,170 [salt.state       :1813][INFO    ][14168] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-21 02:52:41,171 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14168] Executing command 'lsmod' in directory '/root'
2019-05-21 02:52:41,192 [salt.state       :300 ][INFO    ][14168] Kernel modules iptable_filter, ip_tables are already present
2019-05-21 02:52:41,193 [salt.state       :1951][INFO    ][14168] Completed state [iptables_modules_v4_load] at time 02:52:41.193008 duration_in_ms=22.679
2019-05-21 02:52:41,194 [salt.state       :1780][INFO    ][14168] Running state [/etc/iptables/rules.v4] at time 02:52:41.194429
2019-05-21 02:52:41,194 [salt.state       :1813][INFO    ][14168] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-21 02:52:41,298 [salt.state       :300 ][INFO    ][14168] File /etc/iptables/rules.v4 is in the correct state
2019-05-21 02:52:41,298 [salt.state       :1951][INFO    ][14168] Completed state [/etc/iptables/rules.v4] at time 02:52:41.298207 duration_in_ms=103.778
2019-05-21 02:52:41,299 [salt.state       :1780][INFO    ][14168] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:52:41.299340
2019-05-21 02:52:41,299 [salt.state       :1813][INFO    ][14168] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-21 02:52:41,300 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14168] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-21 02:52:41,323 [salt.state       :300 ][INFO    ][14168] onlyif execution failed
2019-05-21 02:52:41,323 [salt.state       :1951][INFO    ][14168] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:52:41.323676 duration_in_ms=24.335
2019-05-21 02:52:41,325 [salt.state       :1780][INFO    ][14168] Running state [netfilter-persistent] at time 02:52:41.325814
2019-05-21 02:52:41,326 [salt.state       :1813][INFO    ][14168] Executing state service.running for [netfilter-persistent]
2019-05-21 02:52:41,327 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14168] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-21 02:52:41,352 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14168] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-21 02:52:41,376 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14168] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-21 02:52:41,399 [salt.state       :300 ][INFO    ][14168] The service netfilter-persistent is already running
2019-05-21 02:52:41,400 [salt.state       :1951][INFO    ][14168] Completed state [netfilter-persistent] at time 02:52:41.399944 duration_in_ms=74.13
2019-05-21 02:52:41,401 [salt.state       :1780][INFO    ][14168] Running state [iptables_extra.remove_stale_tables] at time 02:52:41.401266
2019-05-21 02:52:41,403 [salt.state       :1813][INFO    ][14168] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-21 02:52:41,403 [salt.state       :300 ][INFO    ][14168] No changes made for iptables_extra.remove_stale_tables
2019-05-21 02:52:41,404 [salt.state       :1951][INFO    ][14168] Completed state [iptables_extra.remove_stale_tables] at time 02:52:41.404057 duration_in_ms=2.792
2019-05-21 02:52:41,404 [salt.state       :1780][INFO    ][14168] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:52:41.404441
2019-05-21 02:52:41,404 [salt.state       :1813][INFO    ][14168] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-21 02:52:41,406 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14168] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-21 02:52:41,423 [salt.state       :300 ][INFO    ][14168] onlyif execution failed
2019-05-21 02:52:41,423 [salt.state       :1951][INFO    ][14168] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:52:41.423808 duration_in_ms=19.366
2019-05-21 02:52:41,425 [salt.state       :1780][INFO    ][14168] Running state [/etc/iptables/rules.v6] at time 02:52:41.425015
2019-05-21 02:52:41,425 [salt.state       :1813][INFO    ][14168] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-21 02:52:41,428 [salt.state       :300 ][INFO    ][14168] File /etc/iptables/rules.v6 is not present
2019-05-21 02:52:41,429 [salt.state       :1951][INFO    ][14168] Completed state [/etc/iptables/rules.v6] at time 02:52:41.428972 duration_in_ms=3.957
2019-05-21 02:52:41,430 [salt.state       :1780][INFO    ][14168] Running state [iptables_extra.flush_all] at time 02:52:41.430023
2019-05-21 02:52:41,430 [salt.state       :1813][INFO    ][14168] Executing state module.wait for [iptables_extra.flush_all]
2019-05-21 02:52:41,430 [salt.state       :300 ][INFO    ][14168] No changes made for iptables_extra.flush_all
2019-05-21 02:52:41,431 [salt.state       :1951][INFO    ][14168] Completed state [iptables_extra.flush_all] at time 02:52:41.431126 duration_in_ms=1.103
2019-05-21 02:52:41,436 [salt.minion      :1711][INFO    ][14168] Returning information for job: 20190521025230355522
2019-05-21 02:52:41,995 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command state.apply with jid 20190521025241984030
2019-05-21 02:52:42,030 [salt.minion      :1432][INFO    ][14247] Starting a new job with PID 14247
2019-05-21 02:52:43,024 [salt.state       :915 ][INFO    ][14247] Loading fresh modules for state activity
2019-05-21 02:52:43,909 [salt.state       :1780][INFO    ][14247] Running state [maas-rack-controller] at time 02:52:43.909101
2019-05-21 02:52:43,909 [salt.state       :1813][INFO    ][14247] Executing state pkg.installed for [maas-rack-controller]
2019-05-21 02:52:43,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14247] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-21 02:52:44,016 [salt.state       :300 ][INFO    ][14247] All specified packages are already installed
2019-05-21 02:52:44,017 [salt.state       :1951][INFO    ][14247] Completed state [maas-rack-controller] at time 02:52:44.017178 duration_in_ms=108.077
2019-05-21 02:52:44,017 [salt.state       :1780][INFO    ][14247] Running state [ipmitool] at time 02:52:44.017544
2019-05-21 02:52:44,020 [salt.state       :1813][INFO    ][14247] Executing state pkg.installed for [ipmitool]
2019-05-21 02:52:44,033 [salt.state       :300 ][INFO    ][14247] All specified packages are already installed
2019-05-21 02:52:44,033 [salt.state       :1951][INFO    ][14247] Completed state [ipmitool] at time 02:52:44.033200 duration_in_ms=15.656
2019-05-21 02:52:44,037 [salt.state       :1780][INFO    ][14247] Running state [/etc/maas/rackd.conf] at time 02:52:44.037288
2019-05-21 02:52:44,037 [salt.state       :1813][INFO    ][14247] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-21 02:52:44,038 [salt.state       :300 ][INFO    ][14247] No changes needed to be made
2019-05-21 02:52:44,038 [salt.state       :1951][INFO    ][14247] Completed state [/etc/maas/rackd.conf] at time 02:52:44.038763 duration_in_ms=1.475
2019-05-21 02:52:44,039 [salt.state       :1780][INFO    ][14247] Running state [/etc/maas/rackd.conf] at time 02:52:44.039003
2019-05-21 02:52:44,039 [salt.state       :1813][INFO    ][14247] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-21 02:52:44,039 [salt.loaded.int.states.file:2298][WARNING ][14247] 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-21 02:52:44,040 [salt.state       :300 ][INFO    ][14247] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-21 02:52:44,040 [salt.state       :1951][INFO    ][14247] Completed state [/etc/maas/rackd.conf] at time 02:52:44.040509 duration_in_ms=1.506
2019-05-21 02:52:44,041 [salt.state       :1780][INFO    ][14247] Running state [maas-rackd] at time 02:52:44.041513
2019-05-21 02:52:44,044 [salt.state       :1813][INFO    ][14247] Executing state service.running for [maas-rackd]
2019-05-21 02:52:44,045 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14247] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-21 02:52:44,081 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14247] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-21 02:52:44,099 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14247] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-21 02:52:44,121 [salt.state       :300 ][INFO    ][14247] The service maas-rackd is already running
2019-05-21 02:52:44,122 [salt.state       :1951][INFO    ][14247] Completed state [maas-rackd] at time 02:52:44.122366 duration_in_ms=80.852
2019-05-21 02:52:44,124 [salt.minion      :1711][INFO    ][14247] Returning information for job: 20190521025241984030
2019-05-21 02:52:44,683 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command state.apply with jid 20190521025244672820
2019-05-21 02:52:44,713 [salt.minion      :1432][INFO    ][14274] Starting a new job with PID 14274
2019-05-21 02:52:45,749 [salt.state       :915 ][INFO    ][14274] Loading fresh modules for state activity
2019-05-21 02:52:46,823 [salt.state       :1780][INFO    ][14274] Running state [maas-region-controller] at time 02:52:46.823721
2019-05-21 02:52:46,824 [salt.state       :1813][INFO    ][14274] Executing state pkg.installed for [maas-region-controller]
2019-05-21 02:52:46,824 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-21 02:52:46,928 [salt.state       :300 ][INFO    ][14274] All specified packages are already installed
2019-05-21 02:52:46,928 [salt.state       :1951][INFO    ][14274] Completed state [maas-region-controller] at time 02:52:46.928741 duration_in_ms=105.021
2019-05-21 02:52:46,929 [salt.state       :1780][INFO    ][14274] Running state [python-oauth] at time 02:52:46.929060
2019-05-21 02:52:46,929 [salt.state       :1813][INFO    ][14274] Executing state pkg.installed for [python-oauth]
2019-05-21 02:52:46,939 [salt.state       :300 ][INFO    ][14274] All specified packages are already installed
2019-05-21 02:52:46,939 [salt.state       :1951][INFO    ][14274] Completed state [python-oauth] at time 02:52:46.939238 duration_in_ms=10.178
2019-05-21 02:52:46,942 [salt.state       :1780][INFO    ][14274] Running state [/etc/maas/regiond.conf] at time 02:52:46.942614
2019-05-21 02:52:46,942 [salt.state       :1813][INFO    ][14274] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-21 02:52:46,948 [salt.state       :300 ][INFO    ][14274] No changes needed to be made
2019-05-21 02:52:46,948 [salt.state       :1951][INFO    ][14274] Completed state [/etc/maas/regiond.conf] at time 02:52:46.948691 duration_in_ms=6.077
2019-05-21 02:52:46,949 [salt.state       :1780][INFO    ][14274] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:52:46.949187
2019-05-21 02:52:46,949 [salt.state       :1813][INFO    ][14274] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-21 02:52:47,009 [salt.state       :300 ][INFO    ][14274] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-21 02:52:47,009 [salt.state       :1951][INFO    ][14274] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:52:47.009224 duration_in_ms=60.037
2019-05-21 02:52:47,010 [salt.state       :1780][INFO    ][14274] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:52:47.009727
2019-05-21 02:52:47,010 [salt.state       :1813][INFO    ][14274] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-21 02:52:47,013 [salt.state       :300 ][INFO    ][14274] No changes needed to be made
2019-05-21 02:52:47,014 [salt.state       :1951][INFO    ][14274] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:52:47.014078 duration_in_ms=4.351
2019-05-21 02:52:47,014 [salt.state       :1780][INFO    ][14274] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:52:47.014563
2019-05-21 02:52:47,014 [salt.state       :1813][INFO    ][14274] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-21 02:52:47,019 [salt.state       :300 ][INFO    ][14274] No changes needed to be made
2019-05-21 02:52:47,019 [salt.state       :1951][INFO    ][14274] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:52:47.019408 duration_in_ms=4.845
2019-05-21 02:52:47,019 [salt.state       :1780][INFO    ][14274] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:52:47.019901
2019-05-21 02:52:47,020 [salt.state       :1813][INFO    ][14274] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-21 02:52:47,044 [salt.state       :300 ][INFO    ][14274] No changes needed to be made
2019-05-21 02:52:47,044 [salt.state       :1951][INFO    ][14274] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:52:47.044565 duration_in_ms=24.664
2019-05-21 02:52:47,045 [salt.state       :1780][INFO    ][14274] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:52:47.045058
2019-05-21 02:52:47,045 [salt.state       :1813][INFO    ][14274] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-21 02:52:47,058 [salt.state       :300 ][INFO    ][14274] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-21 02:52:47,059 [salt.state       :1951][INFO    ][14274] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:52:47.059033 duration_in_ms=13.974
2019-05-21 02:52:47,060 [salt.state       :1780][INFO    ][14274] Running state [a2enmod headers] at time 02:52:47.060465
2019-05-21 02:52:47,060 [salt.state       :1813][INFO    ][14274] Executing state cmd.run for [a2enmod headers]
2019-05-21 02:52:47,061 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command 'a2enmod headers' in directory '/root'
2019-05-21 02:52:47,124 [salt.state       :300 ][INFO    ][14274] {'pid': 14299, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-21 02:52:47,125 [salt.state       :1951][INFO    ][14274] Completed state [a2enmod headers] at time 02:52:47.124911 duration_in_ms=64.444
2019-05-21 02:52:47,125 [salt.state       :1780][INFO    ][14274] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:52:47.125564
2019-05-21 02:52:47,129 [salt.state       :1813][INFO    ][14274] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-21 02:52:47,148 [salt.state       :300 ][INFO    ][14274] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-21 02:52:47,148 [salt.state       :1951][INFO    ][14274] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:52:47.148676 duration_in_ms=23.113
2019-05-21 02:52:47,149 [salt.state       :1780][INFO    ][14274] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:52:47.149364
2019-05-21 02:52:47,152 [salt.state       :1813][INFO    ][14274] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-21 02:52:47,214 [salt.state       :300 ][INFO    ][14274] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-21 02:52:47,215 [salt.state       :1951][INFO    ][14274] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:52:47.215098 duration_in_ms=65.734
2019-05-21 02:52:47,215 [salt.state       :1780][INFO    ][14274] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:52:47.215616
2019-05-21 02:52:47,215 [salt.state       :1813][INFO    ][14274] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-21 02:52:47,271 [salt.state       :300 ][INFO    ][14274] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-21 02:52:47,272 [salt.state       :1951][INFO    ][14274] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:52:47.272261 duration_in_ms=56.644
2019-05-21 02:52:47,273 [salt.state       :1780][INFO    ][14274] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:52:47.273383
2019-05-21 02:52:47,273 [salt.state       :1813][INFO    ][14274] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-21 02:52:47,343 [salt.state       :300 ][INFO    ][14274] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-21 02:52:47,344 [salt.state       :1951][INFO    ][14274] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:52:47.344009 duration_in_ms=70.627
2019-05-21 02:52:47,344 [salt.state       :1780][INFO    ][14274] Running state [/root/.pgpass] at time 02:52:47.344271
2019-05-21 02:52:47,344 [salt.state       :1813][INFO    ][14274] Executing state file.managed for [/root/.pgpass]
2019-05-21 02:52:47,393 [salt.state       :300 ][INFO    ][14274] File /root/.pgpass is in the correct state
2019-05-21 02:52:47,393 [salt.state       :1951][INFO    ][14274] Completed state [/root/.pgpass] at time 02:52:47.393230 duration_in_ms=48.959
2019-05-21 02:52:47,398 [salt.state       :1780][INFO    ][14274] Running state [maas-region syncdb --noinput] at time 02:52:47.398784
2019-05-21 02:52:47,399 [salt.state       :1813][INFO    ][14274] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-21 02:52:47,399 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-21 02:52:50,000 [salt.state       :300 ][INFO    ][14274] {'pid': 14312, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: contenttypes, piston3, metadataserver, auth, sessions, maasserver, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-21 02:52:50,002 [salt.state       :1951][INFO    ][14274] Completed state [maas-region syncdb --noinput] at time 02:52:50.001669 duration_in_ms=2602.883
2019-05-21 02:52:50,002 [salt.state       :2022][WARNING ][14274] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-21 02:52:50,004 [salt.state       :1780][INFO    ][14274] Running state [maas-regiond] at time 02:52:50.004147
2019-05-21 02:52:50,004 [salt.state       :1813][INFO    ][14274] Executing state service.running for [maas-regiond]
2019-05-21 02:52:50,005 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-21 02:52:50,049 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-21 02:52:50,070 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-21 02:52:50,093 [salt.state       :300 ][INFO    ][14274] The service maas-regiond is already running
2019-05-21 02:52:50,094 [salt.state       :1951][INFO    ][14274] Completed state [maas-regiond] at time 02:52:50.094562 duration_in_ms=90.413
2019-05-21 02:52:50,098 [salt.state       :1780][INFO    ][14274] Running state [bind9] at time 02:52:50.097942
2019-05-21 02:52:50,098 [salt.state       :1813][INFO    ][14274] Executing state service.running for [bind9]
2019-05-21 02:52:50,099 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-21 02:52:50,123 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-21 02:52:50,143 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-21 02:52:50,162 [salt.state       :300 ][INFO    ][14274] The service bind9 is already running
2019-05-21 02:52:50,162 [salt.state       :1951][INFO    ][14274] Completed state [bind9] at time 02:52:50.162802 duration_in_ms=64.859
2019-05-21 02:52:50,165 [salt.state       :1780][INFO    ][14274] Running state [apache2] at time 02:52:50.165218
2019-05-21 02:52:50,165 [salt.state       :1813][INFO    ][14274] Executing state service.running for [apache2]
2019-05-21 02:52:50,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-21 02:52:50,191 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-21 02:52:50,209 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-21 02:52:50,240 [salt.state       :300 ][INFO    ][14274] The service apache2 is already running
2019-05-21 02:52:50,241 [salt.state       :1951][INFO    ][14274] Completed state [apache2] at time 02:52:50.241160 duration_in_ms=75.94
2019-05-21 02:52:50,243 [salt.state       :1780][INFO    ][14274] Running state [maasng.wait_for_http_code] at time 02:52:50.243614
2019-05-21 02:52:50,244 [salt.state       :1813][INFO    ][14274] Executing state module.run for [maasng.wait_for_http_code]
2019-05-21 02:52:50,245 [salt.utils.decorators:613 ][WARNING ][14274] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:52:50,409 [salt.state       :300 ][INFO    ][14274] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-21 02:52:50,410 [salt.state       :1951][INFO    ][14274] Completed state [maasng.wait_for_http_code] at time 02:52:50.410460 duration_in_ms=166.846
2019-05-21 02:52:50,411 [salt.state       :1780][INFO    ][14274] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:52:50.411852
2019-05-21 02:52:50,412 [salt.state       :1813][INFO    ][14274] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-21 02:52:50,413 [salt.state       :300 ][INFO    ][14274] /var/lib/maas/.setup_admin exists
2019-05-21 02:52:50,413 [salt.state       :1951][INFO    ][14274] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:52:50.413513 duration_in_ms=1.661
2019-05-21 02:52:50,414 [salt.state       :1780][INFO    ][14274] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:50.414923
2019-05-21 02:52:50,415 [salt.state       :1813][INFO    ][14274] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 02:52:50,415 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14274] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 02:52:52,138 [salt.state       :300 ][INFO    ][14274] {'pid': 14333, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 02:52:52,139 [salt.state       :1951][INFO    ][14274] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:52.139371 duration_in_ms=1724.447
2019-05-21 02:52:52,146 [salt.state       :1780][INFO    ][14274] Running state [maas_region_boot_source_resources_mirror] at time 02:52:52.146807
2019-05-21 02:52:52,147 [salt.state       :1813][INFO    ][14274] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-21 02:52:52,254 [salt.state       :300 ][INFO    ][14274] {'changes': {}}
2019-05-21 02:52:52,255 [salt.state       :1951][INFO    ][14274] Completed state [maas_region_boot_source_resources_mirror] at time 02:52:52.255430 duration_in_ms=108.622
2019-05-21 02:52:52,256 [salt.state       :1780][INFO    ][14274] Running state [maasng.boot_resources_import] at time 02:52:52.256719
2019-05-21 02:52:52,257 [salt.state       :1813][INFO    ][14274] Executing state module.run for [maasng.boot_resources_import]
2019-05-21 02:52:52,258 [salt.utils.decorators:613 ][WARNING ][14274] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:52:52,351 [salt.loaded.ext.module.maasng:1600][INFO    ][14274] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-21 02:52:57,395 [salt.loaded.ext.module.maasng:1600][INFO    ][14274] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-21 02:52:59,803 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025259791859
2019-05-21 02:52:59,823 [salt.minion      :1432][INFO    ][14364] Starting a new job with PID 14364
2019-05-21 02:52:59,847 [salt.minion      :1711][INFO    ][14364] Returning information for job: 20190521025259791859
2019-05-21 02:53:02,512 [salt.state       :300 ][INFO    ][14274] {'ret': True}
2019-05-21 02:53:02,512 [salt.state       :1951][INFO    ][14274] Completed state [maasng.boot_resources_import] at time 02:53:02.512674 duration_in_ms=10255.94
2019-05-21 02:53:02,515 [salt.state       :1780][INFO    ][14274] Running state [maas_region_boot_sources_selection_xenial] at time 02:53:02.514979
2019-05-21 02:53:02,515 [salt.state       :1813][INFO    ][14274] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-21 02:53:02,701 [salt.state       :300 ][INFO    ][14274] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-21 02:53:02,702 [salt.state       :1951][INFO    ][14274] Completed state [maas_region_boot_sources_selection_xenial] at time 02:53:02.702822 duration_in_ms=187.842
2019-05-21 02:53:02,704 [salt.state       :1780][INFO    ][14274] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:53:02.704429
2019-05-21 02:53:02,704 [salt.state       :1813][INFO    ][14274] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-21 02:53:02,705 [salt.utils.decorators:613 ][WARNING ][14274] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:53:02,705 [salt.loaded.ext.module.maasng:1771][INFO    ][14274] boot-sources sync initiated for ALL Rack's
2019-05-21 02:53:03,767 [salt.state       :300 ][INFO    ][14274] {'ret': True}
2019-05-21 02:53:03,767 [salt.state       :1951][INFO    ][14274] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:53:03.767433 duration_in_ms=1063.005
2019-05-21 02:53:03,769 [salt.state       :1780][INFO    ][14274] Running state [maas.process_maas_config] at time 02:53:03.769495
2019-05-21 02:53:03,771 [salt.state       :1813][INFO    ][14274] Executing state module.run for [maas.process_maas_config]
2019-05-21 02:53:03,771 [salt.utils.decorators:613 ][WARNING ][14274] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:53:03,772 [salt.loaded.ext.module.maas:92  ][INFO    ][14274] maasconfig name=enable_http_proxy value=True
2019-05-21 02:53:03,835 [salt.loaded.ext.module.maas:92  ][INFO    ][14274] maasconfig name=upstream_dns value=8.8.8.8
2019-05-21 02:53:03,888 [salt.loaded.ext.module.maas:92  ][INFO    ][14274] maasconfig name=commissioning_distro_series value=xenial
2019-05-21 02:53:05,178 [salt.loaded.ext.module.maas:92  ][INFO    ][14274] maasconfig name=default_osystem value=ubuntu
2019-05-21 02:53:05,245 [salt.loaded.ext.module.maas:92  ][INFO    ][14274] maasconfig name=active_discovery_interval value=600
2019-05-21 02:53:05,308 [salt.loaded.ext.module.maas:92  ][INFO    ][14274] maasconfig name=dnssec_validation value=no
2019-05-21 02:53:05,367 [salt.loaded.ext.module.maas:92  ][INFO    ][14274] maasconfig name=maas_name value=mas01
2019-05-21 02:53:05,425 [salt.loaded.ext.module.maas:92  ][INFO    ][14274] maasconfig name=network_discovery value=enabled
2019-05-21 02:53:05,547 [salt.loaded.ext.module.maas:92  ][INFO    ][14274] maasconfig name=enable_third_party_drivers value=True
2019-05-21 02:53:05,595 [salt.loaded.ext.module.maas:92  ][INFO    ][14274] maasconfig name=default_storage_layout value=lvm
2019-05-21 02:53:05,645 [salt.loaded.ext.module.maas:92  ][INFO    ][14274] maasconfig name=ntp_external_only value=True
2019-05-21 02:53:05,693 [salt.loaded.ext.module.maas:92  ][INFO    ][14274] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-21 02:53:05,756 [salt.loaded.ext.module.maas:92  ][INFO    ][14274] maasconfig name=default_distro_series value=xenial
2019-05-21 02:53:05,832 [salt.loaded.ext.module.maas:92  ][INFO    ][14274] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-05-21 02:53:05,948 [salt.state       :300 ][INFO    ][14274] {'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-21 02:53:05,949 [salt.state       :1951][INFO    ][14274] Completed state [maas.process_maas_config] at time 02:53:05.949130 duration_in_ms=2179.636
2019-05-21 02:53:05,950 [salt.state       :1780][INFO    ][14274] Running state [pxe_admin] at time 02:53:05.950312
2019-05-21 02:53:05,950 [salt.state       :1813][INFO    ][14274] Executing state maasng.fabric_present for [pxe_admin]
2019-05-21 02:53:06,010 [salt.loaded.ext.module.maasng:945 ][INFO    ][14274] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, 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'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin', u'class_type': u''}]
2019-05-21 02:53:06,064 [salt.loaded.ext.module.maasng:1008][WARNING ][14274] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-21 02:53:06,065 [salt.loaded.ext.module.maasng:1011][WARNING ][14274] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-21 02:53:06,124 [salt.state       :300 ][INFO    ][14274] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-21 02:53:06,125 [salt.state       :1951][INFO    ][14274] Completed state [pxe_admin] at time 02:53:06.124983 duration_in_ms=174.67
2019-05-21 02:53:06,125 [salt.state       :1780][INFO    ][14274] Running state [vlan 0] at time 02:53:06.125419
2019-05-21 02:53:06,126 [salt.state       :1813][INFO    ][14274] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-21 02:53:06,186 [salt.loaded.ext.module.maasng:945 ][INFO    ][14274] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, 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'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-21 02:53:06,308 [salt.loaded.ext.module.maasng:945 ][INFO    ][14274] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, 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'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-21 02:53:06,559 [salt.loaded.ext.module.maasng:945 ][INFO    ][14274] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, 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'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin', u'class_type': u''}]
2019-05-21 02:53:06,657 [salt.state       :300 ][INFO    ][14274] {'new': 'Vlan untagged was updated'}
2019-05-21 02:53:06,657 [salt.state       :1951][INFO    ][14274] Completed state [vlan 0] at time 02:53:06.657578 duration_in_ms=532.159
2019-05-21 02:53:06,659 [salt.state       :1780][INFO    ][14274] Running state [192.168.11.0/24] at time 02:53:06.659169
2019-05-21 02:53:06,659 [salt.state       :1813][INFO    ][14274] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-21 02:53:06,869 [salt.loaded.ext.module.maasng:945 ][INFO    ][14274] [{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': 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': None, 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'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {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': u'yxgyqt', 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'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-21 02:53:06,870 [salt.loaded.ext.module.maasng:1235][WARNING ][14274] Ignoring parameter vlan:0
2019-05-21 02:53:06,956 [salt.state       :300 ][INFO    ][14274] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-21 02:53:06,957 [salt.state       :1951][INFO    ][14274] Completed state [192.168.11.0/24] at time 02:53:06.957068 duration_in_ms=297.899
2019-05-21 02:53:06,958 [salt.state       :1780][INFO    ][14274] Running state [maas_create_iprange_1] at time 02:53:06.958739
2019-05-21 02:53:06,959 [salt.state       :1813][INFO    ][14274] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-21 02:53:07,016 [salt.state       :300 ][INFO    ][14274] Iprange maas_create_iprange_1 already exist.
2019-05-21 02:53:07,016 [salt.state       :1951][INFO    ][14274] Completed state [maas_create_iprange_1] at time 02:53:07.016870 duration_in_ms=58.13
2019-05-21 02:53:07,017 [salt.state       :1780][INFO    ][14274] Running state [vlan 0] at time 02:53:07.017146
2019-05-21 02:53:07,017 [salt.state       :1813][INFO    ][14274] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-21 02:53:07,081 [salt.loaded.ext.module.maasng:945 ][INFO    ][14274] [{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': 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': None, 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'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {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': u'yxgyqt', 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'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-21 02:53:07,180 [salt.loaded.ext.module.maasng:945 ][INFO    ][14274] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, 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'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'yxgyqt', 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'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-21 02:53:07,427 [salt.loaded.ext.module.maasng:945 ][INFO    ][14274] [{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': 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': None, 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'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {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': u'yxgyqt', 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'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-21 02:53:07,521 [salt.state       :300 ][INFO    ][14274] {'new': 'Vlan untagged was updated'}
2019-05-21 02:53:07,521 [salt.state       :1951][INFO    ][14274] Completed state [vlan 0] at time 02:53:07.521750 duration_in_ms=504.604
2019-05-21 02:53:07,522 [salt.state       :1780][INFO    ][14274] Running state [opnfv] at time 02:53:07.522763
2019-05-21 02:53:07,523 [salt.state       :1813][INFO    ][14274] Executing state maasng.sshkey_present for [opnfv]
2019-05-21 02:53:07,572 [salt.loaded.ext.module.maasng:1903][INFO    ][14274] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-05-21 02:53:07,572 [salt.state       :300 ][INFO    ][14274] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-21 02:53:07,573 [salt.state       :1951][INFO    ][14274] Completed state [opnfv] at time 02:53:07.573081 duration_in_ms=50.318
2019-05-21 02:53:07,578 [salt.minion      :1711][INFO    ][14274] Returning information for job: 20190521025244672820
2019-05-21 02:53:08,179 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command state.apply with jid 20190521025308164028
2019-05-21 02:53:08,207 [salt.minion      :1432][INFO    ][14726] Starting a new job with PID 14726
2019-05-21 02:53:14,283 [salt.state       :915 ][INFO    ][14726] Loading fresh modules for state activity
2019-05-21 02:53:14,395 [salt.state       :1780][INFO    ][14726] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:14.394978
2019-05-21 02:53:14,395 [salt.state       :1813][INFO    ][14726] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 02:53:14,397 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14726] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 02:53:16,290 [salt.state       :300 ][INFO    ][14726] {'pid': 14750, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 02:53:16,291 [salt.state       :1951][INFO    ][14726] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:16.291487 duration_in_ms=1896.509
2019-05-21 02:53:16,293 [salt.state       :1780][INFO    ][14726] Running state [maas.process_machines] at time 02:53:16.293528
2019-05-21 02:53:16,294 [salt.state       :1813][INFO    ][14726] Executing state module.run for [maas.process_machines]
2019-05-21 02:53:16,295 [salt.utils.decorators:613 ][WARNING ][14726] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:53:16,870 [salt.loaded.ext.module.maas:412 ][WARNING ][14726] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 02:53:16,870 [salt.loaded.ext.module.maas:92  ][INFO    ][14726] 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=8t4frm architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 02:53:18,021 [salt.loaded.ext.module.maas:412 ][WARNING ][14726] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 02:53:18,022 [salt.loaded.ext.module.maas:92  ][INFO    ][14726] 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=pqsct3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 02:53:19,247 [salt.loaded.ext.module.maas:412 ][WARNING ][14726] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 02:53:19,248 [salt.loaded.ext.module.maas:92  ][INFO    ][14726] machine hostname=kvm01 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=nxsaaw architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 02:53:20,496 [salt.loaded.ext.module.maas:412 ][WARNING ][14726] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 02:53:20,498 [salt.loaded.ext.module.maas:92  ][INFO    ][14726] machine hostname=kvm03 power_type=ipmi mac_addresses=['14:58:d0:54:7a:28'] power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 system_id=mcxnwt architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 02:53:21,736 [salt.loaded.ext.module.maas:412 ][WARNING ][14726] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 02:53:21,738 [salt.loaded.ext.module.maas:92  ][INFO    ][14726] machine hostname=kvm02 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=yfq344 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 02:53:22,932 [salt.state       :300 ][INFO    ][14726] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-21 02:53:22,932 [salt.state       :1951][INFO    ][14726] Completed state [maas.process_machines] at time 02:53:22.932684 duration_in_ms=6639.155
2019-05-21 02:53:22,939 [salt.minion      :1711][INFO    ][14726] Returning information for job: 20190521025308164028
2019-05-21 02:53:55,951 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command state.apply with jid 20190521025355938184
2019-05-21 02:53:55,981 [salt.minion      :1432][INFO    ][15009] Starting a new job with PID 15009
2019-05-21 02:54:01,943 [salt.state       :915 ][INFO    ][15009] Loading fresh modules for state activity
2019-05-21 02:54:02,046 [salt.state       :1780][INFO    ][15009] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:02.046703
2019-05-21 02:54:02,047 [salt.state       :1813][INFO    ][15009] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 02:54:02,048 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15009] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 02:54:03,787 [salt.state       :300 ][INFO    ][15009] {'pid': 15029, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 02:54:03,788 [salt.state       :1951][INFO    ][15009] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:03.788658 duration_in_ms=1741.955
2019-05-21 02:54:03,792 [salt.state       :1780][INFO    ][15009] Running state [maas.wait_for_machine_status] at time 02:54:03.792577
2019-05-21 02:54:03,793 [salt.state       :1813][INFO    ][15009] Executing state module.run for [maas.wait_for_machine_status]
2019-05-21 02:54:03,795 [salt.utils.decorators:613 ][WARNING ][15009] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:54:06,733 [salt.state       :300 ][INFO    ][15009] {'ret': True}
2019-05-21 02:54:06,733 [salt.state       :1951][INFO    ][15009] Completed state [maas.wait_for_machine_status] at time 02:54:06.733440 duration_in_ms=2940.862
2019-05-21 02:54:06,739 [salt.minion      :1711][INFO    ][15009] Returning information for job: 20190521025355938184
2019-05-21 02:54:07,305 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command state.apply with jid 20190521025407292272
2019-05-21 02:54:07,333 [salt.minion      :1432][INFO    ][15043] Starting a new job with PID 15043
2019-05-21 02:54:08,276 [salt.state       :915 ][INFO    ][15043] Loading fresh modules for state activity
2019-05-21 02:54:08,430 [salt.state       :1780][INFO    ][15043] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:08.430879
2019-05-21 02:54:08,431 [salt.state       :1813][INFO    ][15043] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 02:54:08,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15043] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 02:54:10,177 [salt.state       :300 ][INFO    ][15043] {'pid': 15050, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 02:54:10,180 [salt.state       :1951][INFO    ][15043] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:10.179922 duration_in_ms=1749.042
2019-05-21 02:54:10,183 [salt.state       :1780][INFO    ][15043] Running state [maas_machines_storage_cmp002_lvm] at time 02:54:10.183817
2019-05-21 02:54:10,184 [salt.state       :1813][INFO    ][15043] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-21 02:54:10,745 [salt.state       :300 ][INFO    ][15043] Machine cmp002 is not in Ready state.
2019-05-21 02:54:10,745 [salt.state       :1951][INFO    ][15043] Completed state [maas_machines_storage_cmp002_lvm] at time 02:54:10.745510 duration_in_ms=561.692
2019-05-21 02:54:10,745 [salt.state       :1780][INFO    ][15043] Running state [maas_machines_storage_cmp001_lvm] at time 02:54:10.745920
2019-05-21 02:54:10,746 [salt.state       :1813][INFO    ][15043] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-21 02:54:11,289 [salt.state       :300 ][INFO    ][15043] Machine cmp001 is not in Ready state.
2019-05-21 02:54:11,290 [salt.state       :1951][INFO    ][15043] Completed state [maas_machines_storage_cmp001_lvm] at time 02:54:11.290478 duration_in_ms=544.556
2019-05-21 02:54:11,296 [salt.minion      :1711][INFO    ][15043] Returning information for job: 20190521025407292272
2019-05-21 02:54:11,863 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command state.apply with jid 20190521025411848620
2019-05-21 02:54:11,887 [salt.minion      :1432][INFO    ][15060] Starting a new job with PID 15060
2019-05-21 02:54:12,912 [salt.state       :915 ][INFO    ][15060] Loading fresh modules for state activity
2019-05-21 02:54:13,015 [salt.state       :1780][INFO    ][15060] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:13.015585
2019-05-21 02:54:13,015 [salt.state       :1813][INFO    ][15060] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 02:54:13,017 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15060] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 02:54:14,730 [salt.state       :300 ][INFO    ][15060] {'pid': 15067, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 02:54:14,732 [salt.state       :1951][INFO    ][15060] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:14.731936 duration_in_ms=1716.35
2019-05-21 02:54:14,735 [salt.state       :1780][INFO    ][15060] Running state [maas.deploy_machines] at time 02:54:14.735473
2019-05-21 02:54:14,736 [salt.state       :1813][INFO    ][15060] Executing state module.run for [maas.deploy_machines]
2019-05-21 02:54:14,739 [salt.utils.decorators:613 ][WARNING ][15060] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:54:15,295 [salt.loaded.ext.module.maas:684 ][INFO    ][15060] deploymachines hwe_kernel=ga-16.04 system_id=yfq344 distro_series=xenial
2019-05-21 02:54:17,970 [salt.state       :300 ][INFO    ][15060] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-21 02:54:17,971 [salt.state       :1951][INFO    ][15060] Completed state [maas.deploy_machines] at time 02:54:17.971277 duration_in_ms=3235.803
2019-05-21 02:54:17,974 [salt.minion      :1711][INFO    ][15060] Returning information for job: 20190521025411848620
2019-05-21 02:54:18,532 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command state.apply with jid 20190521025418523489
2019-05-21 02:54:18,551 [salt.minion      :1432][INFO    ][15156] Starting a new job with PID 15156
2019-05-21 02:54:24,499 [salt.state       :915 ][INFO    ][15156] Loading fresh modules for state activity
2019-05-21 02:54:24,596 [salt.state       :1780][INFO    ][15156] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:24.596851
2019-05-21 02:54:24,597 [salt.state       :1813][INFO    ][15156] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 02:54:24,599 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15156] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 02:54:26,337 [salt.state       :300 ][INFO    ][15156] {'pid': 15169, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 02:54:26,338 [salt.state       :1951][INFO    ][15156] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:26.338250 duration_in_ms=1741.398
2019-05-21 02:54:26,341 [salt.state       :1780][INFO    ][15156] Running state [maas.wait_for_machine_status] at time 02:54:26.341325
2019-05-21 02:54:26,343 [salt.state       :1813][INFO    ][15156] Executing state module.run for [maas.wait_for_machine_status]
2019-05-21 02:54:26,343 [salt.utils.decorators:613 ][WARNING ][15156] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 02:54:29,216 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.14324021s left)
2019-05-21 02:54:33,641 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025433573517
2019-05-21 02:54:33,667 [salt.minion      :1432][INFO    ][15187] Starting a new job with PID 15187
2019-05-21 02:54:33,690 [salt.minion      :1711][INFO    ][15187] Returning information for job: 20190521025433573517
2019-05-21 02:55:02,033 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.32635522s left)
2019-05-21 02:55:03,725 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025503709079
2019-05-21 02:55:03,754 [salt.minion      :1432][INFO    ][15222] Starting a new job with PID 15222
2019-05-21 02:55:03,778 [salt.minion      :1711][INFO    ][15222] Returning information for job: 20190521025503709079
2019-05-21 02:55:33,816 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025533805599
2019-05-21 02:55:33,844 [salt.minion      :1432][INFO    ][15266] Starting a new job with PID 15266
2019-05-21 02:55:33,865 [salt.minion      :1711][INFO    ][15266] Returning information for job: 20190521025533805599
2019-05-21 02:55:34,800 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.55883217s left)
2019-05-21 02:56:03,896 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025603886665
2019-05-21 02:56:03,919 [salt.minion      :1432][INFO    ][15302] Starting a new job with PID 15302
2019-05-21 02:56:03,945 [salt.minion      :1711][INFO    ][15302] Returning information for job: 20190521025603886665
2019-05-21 02:56:07,885 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.47418118s left)
2019-05-21 02:56:33,966 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025633955090
2019-05-21 02:56:33,985 [salt.minion      :1432][INFO    ][15364] Starting a new job with PID 15364
2019-05-21 02:56:34,007 [salt.minion      :1711][INFO    ][15364] Returning information for job: 20190521025633955090
2019-05-21 02:56:40,685 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.67427421s left)
2019-05-21 02:57:04,040 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025704024396
2019-05-21 02:57:04,069 [salt.minion      :1432][INFO    ][15397] Starting a new job with PID 15397
2019-05-21 02:57:04,092 [salt.minion      :1711][INFO    ][15397] Returning information for job: 20190521025704024396
2019-05-21 02:57:13,458 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.90096521s left)
2019-05-21 02:57:34,135 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025734119175
2019-05-21 02:57:34,161 [salt.minion      :1432][INFO    ][15445] Starting a new job with PID 15445
2019-05-21 02:57:34,200 [salt.minion      :1711][INFO    ][15445] Returning information for job: 20190521025734119175
2019-05-21 02:57:46,436 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2049.92276216s left)
2019-05-21 02:58:04,237 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025804223109
2019-05-21 02:58:04,264 [salt.minion      :1432][INFO    ][15478] Starting a new job with PID 15478
2019-05-21 02:58:04,288 [salt.minion      :1711][INFO    ][15478] Returning information for job: 20190521025804223109
2019-05-21 02:58:19,379 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2016.97963715s left)
2019-05-21 02:58:34,318 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025834308684
2019-05-21 02:58:34,342 [salt.minion      :1432][INFO    ][15552] Starting a new job with PID 15552
2019-05-21 02:58:34,367 [salt.minion      :1711][INFO    ][15552] Returning information for job: 20190521025834308684
2019-05-21 02:58:52,212 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.14662218s left)
2019-05-21 02:59:04,397 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025904382504
2019-05-21 02:59:04,420 [salt.minion      :1432][INFO    ][15613] Starting a new job with PID 15613
2019-05-21 02:59:04,446 [salt.minion      :1711][INFO    ][15613] Returning information for job: 20190521025904382504
2019-05-21 02:59:25,023 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.33644414s left)
2019-05-21 02:59:34,512 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521025934495151
2019-05-21 02:59:34,543 [salt.minion      :1432][INFO    ][15720] Starting a new job with PID 15720
2019-05-21 02:59:34,569 [salt.minion      :1711][INFO    ][15720] Returning information for job: 20190521025934495151
2019-05-21 02:59:58,019 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.33982706s left)
2019-05-21 03:00:04,618 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521030004603905
2019-05-21 03:00:04,642 [salt.minion      :1432][INFO    ][15788] Starting a new job with PID 15788
2019-05-21 03:00:04,683 [salt.minion      :1711][INFO    ][15788] Returning information for job: 20190521030004603905
2019-05-21 03:00:30,763 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.59627509s left)
2019-05-21 03:00:34,704 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521030034696230
2019-05-21 03:00:34,733 [salt.minion      :1432][INFO    ][15903] Starting a new job with PID 15903
2019-05-21 03:00:34,767 [salt.minion      :1711][INFO    ][15903] Returning information for job: 20190521030034696230
2019-05-21 03:01:03,652 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.70721817s left)
2019-05-21 03:01:04,813 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521030104802376
2019-05-21 03:01:04,840 [salt.minion      :1432][INFO    ][15939] Starting a new job with PID 15939
2019-05-21 03:01:04,866 [salt.minion      :1711][INFO    ][15939] Returning information for job: 20190521030104802376
2019-05-21 03:01:34,941 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521030134933031
2019-05-21 03:01:34,961 [salt.minion      :1432][INFO    ][16036] Starting a new job with PID 16036
2019-05-21 03:01:34,985 [salt.minion      :1711][INFO    ][16036] Returning information for job: 20190521030134933031
2019-05-21 03:01:36,543 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.81615019s left)
2019-05-21 03:02:05,056 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521030205043434
2019-05-21 03:02:05,079 [salt.minion      :1432][INFO    ][16094] Starting a new job with PID 16094
2019-05-21 03:02:05,101 [salt.minion      :1711][INFO    ][16094] Returning information for job: 20190521030205043434
2019-05-21 03:02:09,435 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.92418599s left)
2019-05-21 03:02:35,180 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521030235168373
2019-05-21 03:02:35,202 [salt.minion      :1432][INFO    ][16190] Starting a new job with PID 16190
2019-05-21 03:02:35,225 [salt.minion      :1711][INFO    ][16190] Returning information for job: 20190521030235168373
2019-05-21 03:02:42,244 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1754.11482906s left)
2019-05-21 03:03:05,301 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521030305294481
2019-05-21 03:03:05,315 [salt.minion      :1432][INFO    ][16254] Starting a new job with PID 16254
2019-05-21 03:03:05,339 [salt.minion      :1711][INFO    ][16254] Returning information for job: 20190521030305294481
2019-05-21 03:03:14,955 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1721.40355611s left)
2019-05-21 03:03:35,425 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521030335411451
2019-05-21 03:03:35,448 [salt.minion      :1432][INFO    ][16329] Starting a new job with PID 16329
2019-05-21 03:03:35,472 [salt.minion      :1711][INFO    ][16329] Returning information for job: 20190521030335411451
2019-05-21 03:03:47,625 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1688.73408103s left)
2019-05-21 03:04:05,588 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521030405574984
2019-05-21 03:04:05,610 [salt.minion      :1432][INFO    ][16362] Starting a new job with PID 16362
2019-05-21 03:04:05,641 [salt.minion      :1711][INFO    ][16362] Returning information for job: 20190521030405574984
2019-05-21 03:04:20,392 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1655.96661401s left)
2019-05-21 03:04:35,743 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521030435732114
2019-05-21 03:04:35,766 [salt.minion      :1432][INFO    ][16409] Starting a new job with PID 16409
2019-05-21 03:04:35,790 [salt.minion      :1711][INFO    ][16409] Returning information for job: 20190521030435732114
2019-05-21 03:04:53,315 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1623.0444622s left)
2019-05-21 03:05:05,897 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521030505884841
2019-05-21 03:05:05,922 [salt.minion      :1432][INFO    ][16445] Starting a new job with PID 16445
2019-05-21 03:05:05,945 [salt.minion      :1711][INFO    ][16445] Returning information for job: 20190521030505884841
2019-05-21 03:05:26,417 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1589.94256115s left)
2019-05-21 03:05:36,033 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521030536023764
2019-05-21 03:05:36,058 [salt.minion      :1432][INFO    ][16489] Starting a new job with PID 16489
2019-05-21 03:05:36,083 [salt.minion      :1711][INFO    ][16489] Returning information for job: 20190521030536023764
2019-05-21 03:05:59,423 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1556.93616605s left)
2019-05-21 03:06:06,227 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521030606208721
2019-05-21 03:06:06,253 [salt.minion      :1432][INFO    ][16528] Starting a new job with PID 16528
2019-05-21 03:06:06,281 [salt.minion      :1711][INFO    ][16528] Returning information for job: 20190521030606208721
2019-05-21 03:06:32,470 [salt.loaded.ext.module.maas:1023][INFO    ][15156] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1523.88902521s left)
2019-05-21 03:06:36,416 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521030636400899
2019-05-21 03:06:36,441 [salt.minion      :1432][INFO    ][16751] Starting a new job with PID 16751
2019-05-21 03:06:36,464 [salt.minion      :1711][INFO    ][16751] Returning information for job: 20190521030636400899
2019-05-21 03:07:05,456 [salt.state       :300 ][INFO    ][15156] {'ret': True}
2019-05-21 03:07:05,457 [salt.state       :1951][INFO    ][15156] Completed state [maas.wait_for_machine_status] at time 03:07:05.456998 duration_in_ms=759115.666
2019-05-21 03:07:05,466 [salt.minion      :1711][INFO    ][15156] Returning information for job: 20190521025418523489
2019-05-21 03:07:24,586 [salt.utils.schedule:1377][INFO    ][6211] Running scheduled job: __mine_interval
2019-05-21 04:07:24,585 [salt.utils.schedule:1377][INFO    ][6211] Running scheduled job: __mine_interval
2019-05-21 04:36:14,353 [salt.minion      :1308][INFO    ][6211] User sudo_ubuntu Executing command cp.push_dir with jid 20190521043614339800
2019-05-21 04:36:14,383 [salt.minion      :1432][INFO    ][23068] Starting a new job with PID 23068
