2019-11-20 00:26:01,550 [salt.utils.decorators:613 ][WARNING ][2141] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:26:02,072 [salt.utils.decorators:613 ][WARNING ][2141] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:26:04,263 [salt.loaded.int.states.file:2298][WARNING ][2371] 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-11-20 00:26:29,393 [salt.state       :2022][WARNING ][2960] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-11-20 00:26:31,894 [salt.utils.decorators:613 ][WARNING ][2960] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:26:48,831 [salt.utils.decorators:613 ][WARNING ][2960] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:28:07,965 [salt.utils.decorators:613 ][WARNING ][2960] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:28:19,638 [salt.utils.decorators:613 ][WARNING ][2960] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:28:23,344 [salt.loaded.ext.module.maasng:1008][WARNING ][2960] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-11-20 00:28:23,344 [salt.loaded.ext.module.maasng:1011][WARNING ][2960] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-11-20 00:28:23,942 [salt.loaded.ext.module.maasng:1235][WARNING ][2960] Ignoring parameter vlan:0
2019-11-20 00:28:26,556 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7260] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-11-20 00:28:26,587 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7260] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-11-20 00:28:26,608 [salt.utils.parsers:1051][WARNING ][359] Minion received a SIGTERM. Exiting.
2019-11-20 00:28:27,596 [salt.cli.daemons :293 ][INFO    ][7332] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-11-20 00:28:27,709 [salt.cli.daemons :82  ][INFO    ][7332] Starting up the Salt Minion
2019-11-20 00:28:27,709 [salt.utils.event :1017][INFO    ][7332] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-11-20 00:28:28,471 [salt.minion      :976 ][INFO    ][7332] Creating minion process manager
2019-11-20 00:28:30,009 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][7332] Executing command ['date', '+%z'] in directory '/root'
2019-11-20 00:28:30,029 [salt.utils.schedule:568 ][INFO    ][7332] Updating job settings for scheduled job: __mine_interval
2019-11-20 00:28:30,062 [salt.minion      :1108][INFO    ][7332] Added mine.update to scheduler
2019-11-20 00:28:30,067 [salt.minion      :1975][INFO    ][7332] Minion is starting as user 'root'
2019-11-20 00:28:30,078 [salt.minion      :2336][INFO    ][7332] Minion is ready to receive requests!
2019-11-20 00:28:30,838 [salt.utils.decorators:613 ][WARNING ][7281] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:28:30,902 [salt.loaded.ext.module.maas:412 ][WARNING ][7281] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-20 00:28:32,556 [salt.loaded.ext.module.maas:412 ][WARNING ][7281] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-20 00:28:34,065 [salt.loaded.ext.module.maas:412 ][WARNING ][7281] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-20 00:28:35,292 [salt.loaded.ext.module.maas:412 ][WARNING ][7281] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-20 00:28:36,646 [salt.loaded.ext.module.maas:412 ][WARNING ][7281] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-20 00:29:09,271 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command state.apply with jid 20191120002909258288
2019-11-20 00:29:09,293 [salt.minion      :1432][INFO    ][7744] Starting a new job with PID 7744
2019-11-20 00:29:13,261 [salt.state       :915 ][INFO    ][7744] Loading fresh modules for state activity
2019-11-20 00:29:13,313 [salt.fileclient  :1219][INFO    ][7744] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-11-20 00:29:13,358 [salt.state       :1780][INFO    ][7744] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:29:13.358250
2019-11-20 00:29:13,358 [salt.state       :1813][INFO    ][7744] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-20 00:29:13,360 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7744] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-20 00:29:14,815 [salt.state       :300 ][INFO    ][7744] {'pid': 7751, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-20 00:29:14,816 [salt.state       :1951][INFO    ][7744] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:29:14.816761 duration_in_ms=1458.512
2019-11-20 00:29:14,819 [salt.state       :1780][INFO    ][7744] Running state [maas.wait_for_machine_status] at time 00:29:14.819917
2019-11-20 00:29:14,820 [salt.state       :1813][INFO    ][7744] Executing state module.run for [maas.wait_for_machine_status]
2019-11-20 00:29:14,821 [salt.utils.decorators:613 ][WARNING ][7744] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:29:15,825 [salt.loaded.ext.module.maas:1023][INFO    ][7744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.00546694s left)
2019-11-20 00:29:24,320 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120002924306913
2019-11-20 00:29:24,341 [salt.minion      :1432][INFO    ][7777] Starting a new job with PID 7777
2019-11-20 00:29:24,365 [salt.minion      :1711][INFO    ][7777] Returning information for job: 20191120002924306913
2019-11-20 00:29:46,781 [salt.loaded.ext.module.maas:1023][INFO    ][7744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.0491159s left)
2019-11-20 00:29:54,370 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120002954356785
2019-11-20 00:29:54,392 [salt.minion      :1432][INFO    ][7823] Starting a new job with PID 7823
2019-11-20 00:29:54,417 [salt.minion      :1711][INFO    ][7823] Returning information for job: 20191120002954356785
2019-11-20 00:30:17,792 [salt.loaded.ext.module.maas:1023][INFO    ][7744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.03893209s left)
2019-11-20 00:30:24,411 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003024398892
2019-11-20 00:30:24,429 [salt.minion      :1432][INFO    ][7964] Starting a new job with PID 7964
2019-11-20 00:30:24,451 [salt.minion      :1711][INFO    ][7964] Returning information for job: 20191120003024398892
2019-11-20 00:30:48,921 [salt.loaded.ext.module.maas:1023][INFO    ][7744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.90949297s left)
2019-11-20 00:30:54,455 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003054443245
2019-11-20 00:30:54,478 [salt.minion      :1432][INFO    ][8238] Starting a new job with PID 8238
2019-11-20 00:30:54,500 [salt.minion      :1711][INFO    ][8238] Returning information for job: 20191120003054443245
2019-11-20 00:31:20,713 [salt.loaded.ext.module.maas:1023][INFO    ][7744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.1178689s left)
2019-11-20 00:31:24,521 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003124505877
2019-11-20 00:31:24,544 [salt.minion      :1432][INFO    ][8397] Starting a new job with PID 8397
2019-11-20 00:31:24,567 [salt.minion      :1711][INFO    ][8397] Returning information for job: 20191120003124505877
2019-11-20 00:31:52,195 [salt.loaded.ext.module.maas:1023][INFO    ][7744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.63527203s left)
2019-11-20 00:31:54,576 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003154559873
2019-11-20 00:31:54,598 [salt.minion      :1432][INFO    ][8855] Starting a new job with PID 8855
2019-11-20 00:31:54,623 [salt.minion      :1711][INFO    ][8855] Returning information for job: 20191120003154559873
2019-11-20 00:32:24,640 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003224623540
2019-11-20 00:32:24,661 [salt.minion      :1432][INFO    ][8985] Starting a new job with PID 8985
2019-11-20 00:32:24,685 [salt.minion      :1711][INFO    ][8985] Returning information for job: 20191120003224623540
2019-11-20 00:32:25,432 [salt.loaded.ext.module.maas:1023][INFO    ][7744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1309.39869595s left)
2019-11-20 00:32:54,701 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003254692748
2019-11-20 00:32:54,721 [salt.minion      :1432][INFO    ][9086] Starting a new job with PID 9086
2019-11-20 00:32:54,745 [salt.minion      :1711][INFO    ][9086] Returning information for job: 20191120003254692748
2019-11-20 00:32:58,552 [salt.state       :300 ][INFO    ][7744] {'ret': True}
2019-11-20 00:32:58,553 [salt.state       :1951][INFO    ][7744] Completed state [maas.wait_for_machine_status] at time 00:32:58.552961 duration_in_ms=223733.043
2019-11-20 00:32:58,556 [salt.minion      :1711][INFO    ][7744] Returning information for job: 20191120002909258288
2019-11-20 00:32:59,214 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command state.apply with jid 20191120003259201306
2019-11-20 00:32:59,234 [salt.minion      :1432][INFO    ][9099] Starting a new job with PID 9099
2019-11-20 00:33:02,859 [salt.state       :915 ][INFO    ][9099] Loading fresh modules for state activity
2019-11-20 00:33:02,915 [salt.fileclient  :1219][INFO    ][9099] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-11-20 00:33:03,009 [salt.state       :1780][INFO    ][9099] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:33:03.009453
2019-11-20 00:33:03,009 [salt.state       :1813][INFO    ][9099] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-20 00:33:03,011 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9099] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-20 00:33:04,395 [salt.state       :300 ][INFO    ][9099] {'pid': 9158, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-20 00:33:04,396 [salt.state       :1951][INFO    ][9099] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:33:04.396671 duration_in_ms=1387.217
2019-11-20 00:33:04,400 [salt.state       :1780][INFO    ][9099] Running state [maas_machines_storage_cmp002_lvm] at time 00:33:04.399942
2019-11-20 00:33:04,400 [salt.state       :1813][INFO    ][9099] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-11-20 00:33:05,724 [salt.loaded.ext.module.maasng:610 ][INFO    ][9099] 6dqkn3
2019-11-20 00:33:05,725 [salt.loaded.ext.module.maasng:626 ][INFO    ][9099] sda
2019-11-20 00:33:06,584 [salt.loaded.ext.module.maasng:361 ][INFO    ][9099] 6dqkn3
2019-11-20 00:33:06,715 [salt.loaded.ext.module.maasng:367 ][INFO    ][9099] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'359219ea-5a11-44f6-91ea-18e4690a85f2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6dqkn3', u'filesystem': {u'mount_options': None, u'uuid': u'c1d625c0-107f-4bf8-8566-3058baabf817', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/6dqkn3/blockdevices/2/partition/4'}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6dqkn3', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/6dqkn3/blockdevices/2/'}, {u'size': 2397988454400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'72293157-8381-4841-8ce1-5afe93a072b8', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'uuid': u'82610eb7-c20e-442c-a3f4-d17a54397487', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6dqkn3', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/6dqkn3/blockdevices/9/'}]
2019-11-20 00:33:06,716 [salt.loaded.ext.module.maasng:632 ][INFO    ][9099] vgroot
2019-11-20 00:33:06,716 [salt.loaded.ext.module.maasng:635 ][INFO    ][9099] lvroot
2019-11-20 00:33:06,717 [salt.loaded.ext.module.maasng:639 ][INFO    ][9099] 107374182400
2019-11-20 00:33:07,435 [salt.loaded.ext.module.maasng:645 ][INFO    ][9099] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-18.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 2, u'partitions': [{u'size': 2397992648704, u'uuid': u'6303f270-7318-4270-85eb-c817c6bac2ae', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6dqkn3', u'filesystem': {u'uuid': u'7da00fcf-40de-4a01-8371-446f01dbe4cb', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/6dqkn3/blockdevices/2/partition/8'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6dqkn3', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/6dqkn3/blockdevices/2/'}, u'fqdn': u'cmp002.maas', u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'6dqkn3', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 13, u'partitions': [], u'uuid': u'282460b0-48ae-4dc5-b88a-c8a189e9f82a', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6dqkn3', u'partition_table_type': None, u'filesystem': {u'uuid': u'ce6f0ed7-3b39-4d79-acae-571751ae6bdf', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/6dqkn3/blockdevices/13/'}], u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 2, u'partitions': [{u'size': 2397992648704, u'uuid': u'6303f270-7318-4270-85eb-c817c6bac2ae', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6dqkn3', u'filesystem': {u'uuid': u'7da00fcf-40de-4a01-8371-446f01dbe4cb', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/6dqkn3/blockdevices/2/partition/8'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6dqkn3', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/6dqkn3/blockdevices/2/'}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 13, u'partitions': [], u'uuid': u'282460b0-48ae-4dc5-b88a-c8a189e9f82a', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6dqkn3', u'partition_table_type': None, u'filesystem': {u'uuid': u'ce6f0ed7-3b39-4d79-acae-571751ae6bdf', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/6dqkn3/blockdevices/13/'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.42'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'6dqkn3', u'id': 8}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 2, u'partitions': [{u'size': 2397992648704, u'uuid': u'6303f270-7318-4270-85eb-c817c6bac2ae', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6dqkn3', u'filesystem': {u'uuid': u'7da00fcf-40de-4a01-8371-446f01dbe4cb', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/6dqkn3/blockdevices/2/partition/8'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6dqkn3', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/6dqkn3/blockdevices/2/'}], u'netboot': True, u'osystem': u'', u'status_action': u'', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'mrxnpr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 31, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'mrxnpr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'mrxnpr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'6dqkn3', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/6dqkn3/interfaces/4/'}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'mrxnpr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 31, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'mrxnpr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'mrxnpr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'6dqkn3', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/6dqkn3/interfaces/4/'}, {u'name': u'enp9s0', u'links': [{u'id': 33, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'system_id': u'6dqkn3', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/6dqkn3/interfaces/12/'}, {u'name': u'enp8s0', u'links': [{u'id': 35, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'system_id': u'6dqkn3', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/6dqkn3/interfaces/13/'}, {u'name': u'enp7s0', u'links': [{u'id': 38, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'system_id': u'6dqkn3', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/6dqkn3/interfaces/14/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/6dqkn3/'}
2019-11-20 00:33:07,437 [salt.state       :300 ][INFO    ][9099] {'new': {'storage_layout': 'lvm'}}
2019-11-20 00:33:07,437 [salt.state       :1951][INFO    ][9099] Completed state [maas_machines_storage_cmp002_lvm] at time 00:33:07.437860 duration_in_ms=3037.917
2019-11-20 00:33:07,438 [salt.state       :1780][INFO    ][9099] Running state [maas_machines_storage_cmp001_lvm] at time 00:33:07.438394
2019-11-20 00:33:07,438 [salt.state       :1813][INFO    ][9099] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-11-20 00:33:08,780 [salt.loaded.ext.module.maasng:610 ][INFO    ][9099] spshdm
2019-11-20 00:33:08,780 [salt.loaded.ext.module.maasng:626 ][INFO    ][9099] sda
2019-11-20 00:33:09,338 [salt.loaded.ext.module.maasng:361 ][INFO    ][9099] spshdm
2019-11-20 00:33:09,428 [salt.loaded.ext.module.maasng:367 ][INFO    ][9099] [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 4, u'partitions': [{u'size': 2397992648704, u'uuid': u'd8e34163-9fd8-42e4-aabd-649e6291c573', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'spshdm', u'filesystem': {u'uuid': u'e0ff9a68-545b-4271-bc50-1f2aee5396b8', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/spshdm/blockdevices/4/partition/7'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'spshdm', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/spshdm/blockdevices/4/'}, {u'size': 2397988454400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'id': 12, u'partitions': [], u'uuid': u'272650cc-f467-4dd6-a107-4fb7c35fc83c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'spshdm', u'partition_table_type': None, u'filesystem': {u'uuid': u'ceeea821-c45b-4209-b9f6-7ddaecd0854c', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/spshdm/blockdevices/12/'}]
2019-11-20 00:33:09,428 [salt.loaded.ext.module.maasng:632 ][INFO    ][9099] vgroot
2019-11-20 00:33:09,429 [salt.loaded.ext.module.maasng:635 ][INFO    ][9099] lvroot
2019-11-20 00:33:09,429 [salt.loaded.ext.module.maasng:639 ][INFO    ][9099] 107374182400
2019-11-20 00:33:09,998 [salt.loaded.ext.module.maasng:645 ][INFO    ][9099] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'status_action': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38'], u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-18.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/spshdm/blockdevices/4/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'ac097b3c-d85a-41a9-8802-e19184da9b2a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'spshdm', u'filesystem': {u'uuid': u'b8ff47aa-2b3f-4c25-bb94-2140482c662e', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/spshdm/blockdevices/4/partition/9'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'spshdm', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 4, u'size': 2397998940160}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/spshdm/', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'owner_data': {}, u'system_id': u'spshdm', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/spshdm/blockdevices/4/', u'available_size': 0, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'ac097b3c-d85a-41a9-8802-e19184da9b2a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'spshdm', u'filesystem': {u'uuid': u'b8ff47aa-2b3f-4c25-bb94-2140482c662e', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/spshdm/blockdevices/4/partition/9'}], u'uuid': None, u'name': u'sda', u'system_id': u'spshdm', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 4, u'size': 2397998940160}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/spshdm/blockdevices/14/', u'available_size': 0, u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'uuid': u'11a3dd97-701d-4d63-be48-4763324b6472', u'name': u'vgroot-lvroot', u'system_id': u'spshdm', u'partition_table_type': None, u'filesystem': {u'uuid': u'5a2cf74b-3849-4f24-be21-628fbb3b85af', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 14, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/spshdm/blockdevices/4/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'ac097b3c-d85a-41a9-8802-e19184da9b2a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'spshdm', u'filesystem': {u'uuid': u'b8ff47aa-2b3f-4c25-bb94-2140482c662e', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/spshdm/blockdevices/4/partition/9'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'spshdm', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 4, u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'spshdm', u'id': 9}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mrxnpr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 43, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mrxnpr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mrxnpr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'spshdm', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/spshdm/interfaces/5/'}, {u'name': u'enp9s0', u'links': [{u'id': 44, u'mode': u'link_up'}], u'tags': [], 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'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'spshdm', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/spshdm/interfaces/21/'}, {u'name': u'enp7s0', u'links': [{u'id': 45, u'mode': u'link_up'}], u'tags': [], 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'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'spshdm', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/spshdm/interfaces/22/'}, {u'name': u'enp8s0', u'links': [{u'id': 46, u'mode': u'link_up'}], u'tags': [], 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'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'spshdm', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/spshdm/interfaces/23/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'virtualblockdevice_set': [{u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/spshdm/blockdevices/14/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'11a3dd97-701d-4d63-be48-4763324b6472', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'spshdm', u'partition_table_type': None, u'filesystem': {u'uuid': u'5a2cf74b-3849-4f24-be21-628fbb3b85af', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 14, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mrxnpr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 43, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mrxnpr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mrxnpr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'spshdm', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/spshdm/interfaces/5/'}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-11-20 00:33:10,000 [salt.state       :300 ][INFO    ][9099] {'new': {'storage_layout': 'lvm'}}
2019-11-20 00:33:10,001 [salt.state       :1951][INFO    ][9099] Completed state [maas_machines_storage_cmp001_lvm] at time 00:33:10.000972 duration_in_ms=2562.578
2019-11-20 00:33:10,004 [salt.minion      :1711][INFO    ][9099] Returning information for job: 20191120003259201306
2019-11-20 00:33:10,600 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command state.apply with jid 20191120003310582637
2019-11-20 00:33:10,621 [salt.minion      :1432][INFO    ][9177] Starting a new job with PID 9177
2019-11-20 00:33:11,299 [salt.state       :915 ][INFO    ][9177] Loading fresh modules for state activity
2019-11-20 00:33:11,329 [salt.fileclient  :1219][INFO    ][9177] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-11-20 00:33:11,354 [salt.state       :1780][INFO    ][9177] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:33:11.354829
2019-11-20 00:33:11,355 [salt.state       :1813][INFO    ][9177] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-20 00:33:11,356 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9177] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-20 00:33:12,756 [salt.state       :300 ][INFO    ][9177] {'pid': 9184, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-20 00:33:12,757 [salt.state       :1951][INFO    ][9177] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:33:12.757303 duration_in_ms=1402.473
2019-11-20 00:33:12,758 [salt.state       :1780][INFO    ][9177] Running state [maas.deploy_machines] at time 00:33:12.758391
2019-11-20 00:33:12,758 [salt.state       :1813][INFO    ][9177] Executing state module.run for [maas.deploy_machines]
2019-11-20 00:33:12,759 [salt.utils.decorators:613 ][WARNING ][9177] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:33:13,494 [salt.loaded.ext.module.maas:684 ][INFO    ][9177] deploymachines hwe_kernel=ga-18.04 system_id=6dqkn3 distro_series=bionic
2019-11-20 00:33:16,111 [salt.loaded.ext.module.maas:684 ][INFO    ][9177] deploymachines hwe_kernel=ga-18.04 system_id=spshdm distro_series=bionic
2019-11-20 00:33:18,543 [salt.loaded.ext.module.maas:684 ][INFO    ][9177] deploymachines hwe_kernel=ga-18.04 system_id=pwm7q3 distro_series=bionic
2019-11-20 00:33:21,262 [salt.loaded.ext.module.maas:684 ][INFO    ][9177] deploymachines hwe_kernel=ga-18.04 system_id=67r7rs distro_series=bionic
2019-11-20 00:33:23,814 [salt.loaded.ext.module.maas:684 ][INFO    ][9177] deploymachines hwe_kernel=ga-18.04 system_id=nb8cqf distro_series=bionic
2019-11-20 00:33:25,690 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003325677465
2019-11-20 00:33:25,710 [salt.minion      :1432][INFO    ][9455] Starting a new job with PID 9455
2019-11-20 00:33:25,733 [salt.minion      :1711][INFO    ][9455] Returning information for job: 20191120003325677465
2019-11-20 00:33:26,383 [salt.state       :300 ][INFO    ][9177] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-11-20 00:33:26,384 [salt.state       :1951][INFO    ][9177] Completed state [maas.deploy_machines] at time 00:33:26.384554 duration_in_ms=13626.16
2019-11-20 00:33:26,388 [salt.minion      :1711][INFO    ][9177] Returning information for job: 20191120003310582637
2019-11-20 00:33:27,017 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command state.apply with jid 20191120003327004516
2019-11-20 00:33:27,039 [salt.minion      :1432][INFO    ][9470] Starting a new job with PID 9470
2019-11-20 00:33:30,714 [salt.state       :915 ][INFO    ][9470] Loading fresh modules for state activity
2019-11-20 00:33:30,766 [salt.fileclient  :1219][INFO    ][9470] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-11-20 00:33:30,809 [salt.state       :1780][INFO    ][9470] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:33:30.809707
2019-11-20 00:33:30,810 [salt.state       :1813][INFO    ][9470] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-20 00:33:30,812 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9470] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-20 00:33:32,224 [salt.state       :300 ][INFO    ][9470] {'pid': 9484, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-20 00:33:32,225 [salt.state       :1951][INFO    ][9470] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:33:32.225533 duration_in_ms=1415.826
2019-11-20 00:33:32,228 [salt.state       :1780][INFO    ][9470] Running state [maas.wait_for_machine_status] at time 00:33:32.227980
2019-11-20 00:33:32,228 [salt.state       :1813][INFO    ][9470] Executing state module.run for [maas.wait_for_machine_status]
2019-11-20 00:33:32,228 [salt.utils.decorators:613 ][WARNING ][9470] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:33:35,189 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.04691601s left)
2019-11-20 00:33:42,111 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003342096509
2019-11-20 00:33:42,135 [salt.minion      :1432][INFO    ][9512] Starting a new job with PID 9512
2019-11-20 00:33:42,160 [salt.minion      :1711][INFO    ][9512] Returning information for job: 20191120003342096509
2019-11-20 00:34:08,749 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.48732805s left)
2019-11-20 00:34:12,169 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003412154822
2019-11-20 00:34:12,191 [salt.minion      :1432][INFO    ][9543] Starting a new job with PID 9543
2019-11-20 00:34:12,214 [salt.minion      :1711][INFO    ][9543] Returning information for job: 20191120003412154822
2019-11-20 00:34:41,836 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.40024996s left)
2019-11-20 00:34:42,264 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003442251229
2019-11-20 00:34:42,287 [salt.minion      :1432][INFO    ][9581] Starting a new job with PID 9581
2019-11-20 00:34:42,312 [salt.minion      :1711][INFO    ][9581] Returning information for job: 20191120003442251229
2019-11-20 00:35:12,302 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003512289327
2019-11-20 00:35:12,318 [salt.minion      :1432][INFO    ][9698] Starting a new job with PID 9698
2019-11-20 00:35:12,337 [salt.minion      :1711][INFO    ][9698] Returning information for job: 20191120003512289327
2019-11-20 00:35:14,923 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.31328988s left)
2019-11-20 00:35:42,347 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003542334332
2019-11-20 00:35:42,370 [salt.minion      :1432][INFO    ][10256] Starting a new job with PID 10256
2019-11-20 00:35:42,395 [salt.minion      :1711][INFO    ][10256] Returning information for job: 20191120003542334332
2019-11-20 00:35:48,216 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.01979589s left)
2019-11-20 00:36:12,403 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003612390717
2019-11-20 00:36:12,424 [salt.minion      :1432][INFO    ][10454] Starting a new job with PID 10454
2019-11-20 00:36:12,447 [salt.minion      :1711][INFO    ][10454] Returning information for job: 20191120003612390717
2019-11-20 00:36:22,388 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.84785604s left)
2019-11-20 00:36:42,459 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003642446321
2019-11-20 00:36:42,480 [salt.minion      :1432][INFO    ][10800] Starting a new job with PID 10800
2019-11-20 00:36:42,503 [salt.minion      :1711][INFO    ][10800] Returning information for job: 20191120003642446321
2019-11-20 00:36:56,082 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.15442991s left)
2019-11-20 00:37:12,520 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003712508878
2019-11-20 00:37:12,539 [salt.minion      :1432][INFO    ][10881] Starting a new job with PID 10881
2019-11-20 00:37:12,561 [salt.minion      :1711][INFO    ][10881] Returning information for job: 20191120003712508878
2019-11-20 00:37:29,767 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.46867299s left)
2019-11-20 00:37:42,580 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003742567531
2019-11-20 00:37:42,603 [salt.minion      :1432][INFO    ][10971] Starting a new job with PID 10971
2019-11-20 00:37:42,627 [salt.minion      :1711][INFO    ][10971] Returning information for job: 20191120003742567531
2019-11-20 00:38:03,269 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1978.96671104s left)
2019-11-20 00:38:12,649 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003812635897
2019-11-20 00:38:12,671 [salt.minion      :1432][INFO    ][11038] Starting a new job with PID 11038
2019-11-20 00:38:12,693 [salt.minion      :1711][INFO    ][11038] Returning information for job: 20191120003812635897
2019-11-20 00:38:36,577 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1945.65935493s left)
2019-11-20 00:38:42,723 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003842709581
2019-11-20 00:38:42,746 [salt.minion      :1432][INFO    ][11211] Starting a new job with PID 11211
2019-11-20 00:38:42,769 [salt.minion      :1711][INFO    ][11211] Returning information for job: 20191120003842709581
2019-11-20 00:39:10,229 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.00653887s left)
2019-11-20 00:39:12,808 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003912795308
2019-11-20 00:39:12,831 [salt.minion      :1432][INFO    ][11382] Starting a new job with PID 11382
2019-11-20 00:39:12,855 [salt.minion      :1711][INFO    ][11382] Returning information for job: 20191120003912795308
2019-11-20 00:39:42,891 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120003942878914
2019-11-20 00:39:42,913 [salt.minion      :1432][INFO    ][11789] Starting a new job with PID 11789
2019-11-20 00:39:42,938 [salt.minion      :1711][INFO    ][11789] Returning information for job: 20191120003942878914
2019-11-20 00:39:43,315 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1878.92118001s left)
2019-11-20 00:40:12,977 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004012964395
2019-11-20 00:40:13,000 [salt.minion      :1432][INFO    ][11836] Starting a new job with PID 11836
2019-11-20 00:40:13,024 [salt.minion      :1711][INFO    ][11836] Returning information for job: 20191120004012964395
2019-11-20 00:40:16,761 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.47460699s left)
2019-11-20 00:40:43,074 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004043060680
2019-11-20 00:40:43,097 [salt.minion      :1432][INFO    ][11967] Starting a new job with PID 11967
2019-11-20 00:40:43,121 [salt.minion      :1711][INFO    ][11967] Returning information for job: 20191120004043060680
2019-11-20 00:40:50,589 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['cmp002', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1811.64674091s left)
2019-11-20 00:41:13,282 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004113269078
2019-11-20 00:41:13,304 [salt.minion      :1432][INFO    ][12107] Starting a new job with PID 12107
2019-11-20 00:41:13,328 [salt.minion      :1711][INFO    ][12107] Returning information for job: 20191120004113269078
2019-11-20 00:41:23,853 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1778.38318086s left)
2019-11-20 00:41:43,391 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004143379456
2019-11-20 00:41:43,413 [salt.minion      :1432][INFO    ][12398] Starting a new job with PID 12398
2019-11-20 00:41:43,437 [salt.minion      :1711][INFO    ][12398] Returning information for job: 20191120004143379456
2019-11-20 00:41:57,135 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1745.10137296s left)
2019-11-20 00:42:13,507 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004213495030
2019-11-20 00:42:13,530 [salt.minion      :1432][INFO    ][12535] Starting a new job with PID 12535
2019-11-20 00:42:13,554 [salt.minion      :1711][INFO    ][12535] Returning information for job: 20191120004213495030
2019-11-20 00:42:30,560 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1711.67583895s left)
2019-11-20 00:42:43,628 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004243612701
2019-11-20 00:42:43,650 [salt.minion      :1432][INFO    ][12577] Starting a new job with PID 12577
2019-11-20 00:42:43,675 [salt.minion      :1711][INFO    ][12577] Returning information for job: 20191120004243612701
2019-11-20 00:43:03,823 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1678.41273689s left)
2019-11-20 00:43:13,761 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004313744956
2019-11-20 00:43:13,783 [salt.minion      :1432][INFO    ][12612] Starting a new job with PID 12612
2019-11-20 00:43:13,806 [salt.minion      :1711][INFO    ][12612] Returning information for job: 20191120004313744956
2019-11-20 00:43:37,185 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1645.05113006s left)
2019-11-20 00:43:43,891 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004343878304
2019-11-20 00:43:43,915 [salt.minion      :1432][INFO    ][12654] Starting a new job with PID 12654
2019-11-20 00:43:43,939 [salt.minion      :1711][INFO    ][12654] Returning information for job: 20191120004343878304
2019-11-20 00:44:10,555 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1611.68130708s left)
2019-11-20 00:44:14,035 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004414022226
2019-11-20 00:44:14,058 [salt.minion      :1432][INFO    ][12689] Starting a new job with PID 12689
2019-11-20 00:44:14,082 [salt.minion      :1711][INFO    ][12689] Returning information for job: 20191120004414022226
2019-11-20 00:44:42,744 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1579.49172401s left)
2019-11-20 00:44:44,185 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004444172273
2019-11-20 00:44:44,208 [salt.minion      :1432][INFO    ][12731] Starting a new job with PID 12731
2019-11-20 00:44:44,234 [salt.minion      :1711][INFO    ][12731] Returning information for job: 20191120004444172273
2019-11-20 00:45:14,355 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004514339469
2019-11-20 00:45:14,377 [salt.minion      :1432][INFO    ][12773] Starting a new job with PID 12773
2019-11-20 00:45:14,400 [salt.minion      :1711][INFO    ][12773] Returning information for job: 20191120004514339469
2019-11-20 00:45:16,117 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1546.11956906s left)
2019-11-20 00:45:44,526 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004544510965
2019-11-20 00:45:44,546 [salt.minion      :1432][INFO    ][12818] Starting a new job with PID 12818
2019-11-20 00:45:44,567 [salt.minion      :1711][INFO    ][12818] Returning information for job: 20191120004544510965
2019-11-20 00:45:48,743 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1513.49281096s left)
2019-11-20 00:46:14,701 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004614688575
2019-11-20 00:46:14,724 [salt.minion      :1432][INFO    ][12853] Starting a new job with PID 12853
2019-11-20 00:46:14,747 [salt.minion      :1711][INFO    ][12853] Returning information for job: 20191120004614688575
2019-11-20 00:46:22,318 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1479.91811585s left)
2019-11-20 00:46:44,889 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004644875617
2019-11-20 00:46:44,912 [salt.minion      :1432][INFO    ][12992] Starting a new job with PID 12992
2019-11-20 00:46:44,935 [salt.minion      :1711][INFO    ][12992] Returning information for job: 20191120004644875617
2019-11-20 00:46:55,736 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1446.49969006s left)
2019-11-20 00:47:15,084 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004715071546
2019-11-20 00:47:15,108 [salt.minion      :1432][INFO    ][13035] Starting a new job with PID 13035
2019-11-20 00:47:15,133 [salt.minion      :1711][INFO    ][13035] Returning information for job: 20191120004715071546
2019-11-20 00:47:29,030 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1413.20565104s left)
2019-11-20 00:47:45,296 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004745284513
2019-11-20 00:47:45,319 [salt.minion      :1432][INFO    ][13077] Starting a new job with PID 13077
2019-11-20 00:47:45,343 [salt.minion      :1711][INFO    ][13077] Returning information for job: 20191120004745284513
2019-11-20 00:48:02,522 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1379.71427298s left)
2019-11-20 00:48:15,517 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004815504250
2019-11-20 00:48:15,542 [salt.minion      :1432][INFO    ][13112] Starting a new job with PID 13112
2019-11-20 00:48:15,567 [salt.minion      :1711][INFO    ][13112] Returning information for job: 20191120004815504250
2019-11-20 00:48:36,082 [salt.loaded.ext.module.maas:1023][INFO    ][9470] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1346.15358186s left)
2019-11-20 00:48:45,544 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004845531412
2019-11-20 00:48:45,567 [salt.minion      :1432][INFO    ][13153] Starting a new job with PID 13153
2019-11-20 00:48:45,590 [salt.minion      :1711][INFO    ][13153] Returning information for job: 20191120004845531412
2019-11-20 00:49:08,856 [salt.loaded.ext.module.maas:993 ][INFO    ][9470] Machine 67r7rs mark broken
2019-11-20 00:49:09,380 [salt.loaded.ext.module.maas:996 ][INFO    ][9470] Machine 67r7rs mark fixed
2019-11-20 00:49:10,283 [salt.loaded.ext.module.maas:684 ][INFO    ][9470] deploymachines hwe_kernel=ga-18.04 system_id=67r7rs distro_series=bionic
2019-11-20 00:49:12,862 [salt.loaded.ext.module.maas:160 ][ERROR   ][9470] Failed for object kvm03 reason Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node.
2019-11-20 00:49:12,864 [salt.state       :302 ][ERROR   ][9470] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm02'], 'errors': {'kvm03': "Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node."}, 'success': []}
2019-11-20 00:49:12,864 [salt.state       :1951][INFO    ][9470] Completed state [maas.wait_for_machine_status] at time 00:49:12.864652 duration_in_ms=940636.664
2019-11-20 00:49:12,874 [salt.minion      :1711][INFO    ][9470] Returning information for job: 20191120003327004516
2019-11-20 00:49:23,591 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command pillar.get with jid 20191120004923584375
2019-11-20 00:49:23,612 [salt.minion      :1432][INFO    ][13275] Starting a new job with PID 13275
2019-11-20 00:49:23,618 [salt.minion      :1711][INFO    ][13275] Returning information for job: 20191120004923584375
2019-11-20 00:49:24,110 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command service.status with jid 20191120004924098070
2019-11-20 00:49:24,131 [salt.minion      :1432][INFO    ][13280] Starting a new job with PID 13280
2019-11-20 00:49:24,575 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13280] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-11-20 00:49:24,609 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13280] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-11-20 00:49:24,626 [salt.minion      :1711][INFO    ][13280] Returning information for job: 20191120004924098070
2019-11-20 00:49:25,125 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command state.apply with jid 20191120004925113396
2019-11-20 00:49:25,146 [salt.minion      :1432][INFO    ][13291] Starting a new job with PID 13291
2019-11-20 00:49:29,138 [salt.state       :915 ][INFO    ][13291] Loading fresh modules for state activity
2019-11-20 00:49:29,602 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13291] Executing command 'salt-minion --version' in directory '/root'
2019-11-20 00:49:29,966 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13291] Executing command 'salt-minion --version' in directory '/root'
2019-11-20 00:49:30,891 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13291] Executing command 'salt-minion --version' in directory '/root'
2019-11-20 00:49:31,263 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13291] Executing command 'salt-minion --version' in directory '/root'
2019-11-20 00:49:32,663 [salt.state       :1780][INFO    ][13291] Running state [salt-minion] at time 00:49:32.663639
2019-11-20 00:49:32,663 [salt.state       :1813][INFO    ][13291] Executing state pkg.installed for [salt-minion]
2019-11-20 00:49:32,664 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13291] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-20 00:49:32,719 [salt.state       :300 ][INFO    ][13291] All specified packages are already installed
2019-11-20 00:49:32,719 [salt.state       :1951][INFO    ][13291] Completed state [salt-minion] at time 00:49:32.719674 duration_in_ms=56.036
2019-11-20 00:49:32,719 [salt.state       :1780][INFO    ][13291] Running state [salt_minion_dependency_packages] at time 00:49:32.719906
2019-11-20 00:49:32,720 [salt.state       :1813][INFO    ][13291] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-11-20 00:49:32,724 [salt.state       :300 ][INFO    ][13291] All specified packages are already installed
2019-11-20 00:49:32,724 [salt.state       :1951][INFO    ][13291] Completed state [salt_minion_dependency_packages] at time 00:49:32.724740 duration_in_ms=4.833
2019-11-20 00:49:32,727 [salt.state       :1780][INFO    ][13291] Running state [/etc/salt/minion.d/minion.conf] at time 00:49:32.727068
2019-11-20 00:49:32,727 [salt.state       :1813][INFO    ][13291] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-11-20 00:49:32,907 [salt.state       :300 ][INFO    ][13291] File /etc/salt/minion.d/minion.conf is in the correct state
2019-11-20 00:49:32,907 [salt.state       :1951][INFO    ][13291] Completed state [/etc/salt/minion.d/minion.conf] at time 00:49:32.907551 duration_in_ms=180.483
2019-11-20 00:49:32,907 [salt.state       :1780][INFO    ][13291] Running state [python-netaddr] at time 00:49:32.907860
2019-11-20 00:49:32,908 [salt.state       :1813][INFO    ][13291] Executing state pkg.installed for [python-netaddr]
2019-11-20 00:49:32,916 [salt.state       :300 ][INFO    ][13291] All specified packages are already installed
2019-11-20 00:49:32,916 [salt.state       :1951][INFO    ][13291] Completed state [python-netaddr] at time 00:49:32.916844 duration_in_ms=8.984
2019-11-20 00:49:32,920 [salt.state       :1780][INFO    ][13291] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 00:49:32.920504
2019-11-20 00:49:32,920 [salt.state       :1813][INFO    ][13291] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-11-20 00:49:32,932 [salt.state       :300 ][INFO    ][13291] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-11-20 00:49:32,932 [salt.state       :1951][INFO    ][13291] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 00:49:32.932385 duration_in_ms=11.881
2019-11-20 00:49:32,933 [salt.state       :1780][INFO    ][13291] Running state [salt-minion] at time 00:49:32.933536
2019-11-20 00:49:32,933 [salt.state       :1813][INFO    ][13291] Executing state service.running for [salt-minion]
2019-11-20 00:49:32,934 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13291] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-11-20 00:49:32,970 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13291] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-11-20 00:49:32,987 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13291] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-11-20 00:49:33,003 [salt.state       :300 ][INFO    ][13291] The service salt-minion is already running
2019-11-20 00:49:33,003 [salt.state       :1951][INFO    ][13291] Completed state [salt-minion] at time 00:49:33.003840 duration_in_ms=70.304
2019-11-20 00:49:33,005 [salt.state       :1780][INFO    ][13291] Running state [/etc/salt/grains.d] at time 00:49:33.005630
2019-11-20 00:49:33,006 [salt.state       :1813][INFO    ][13291] Executing state file.directory for [/etc/salt/grains.d]
2019-11-20 00:49:33,007 [salt.state       :300 ][INFO    ][13291] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-11-20 00:49:33,007 [salt.state       :1951][INFO    ][13291] Completed state [/etc/salt/grains.d] at time 00:49:33.007367 duration_in_ms=1.737
2019-11-20 00:49:33,008 [salt.state       :1780][INFO    ][13291] Running state [/etc/salt/grains] at time 00:49:33.008170
2019-11-20 00:49:33,008 [salt.state       :1813][INFO    ][13291] Executing state file.managed for [/etc/salt/grains]
2019-11-20 00:49:33,009 [salt.state       :300 ][INFO    ][13291] File /etc/salt/grains exists with proper permissions. No changes made.
2019-11-20 00:49:33,009 [salt.state       :1951][INFO    ][13291] Completed state [/etc/salt/grains] at time 00:49:33.009406 duration_in_ms=1.236
2019-11-20 00:49:33,009 [salt.state       :1780][INFO    ][13291] Running state [/etc/salt/grains.d/placeholder] at time 00:49:33.009945
2019-11-20 00:49:33,010 [salt.state       :1813][INFO    ][13291] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-11-20 00:49:33,010 [salt.state       :300 ][INFO    ][13291] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-11-20 00:49:33,011 [salt.state       :1951][INFO    ][13291] Completed state [/etc/salt/grains.d/placeholder] at time 00:49:33.011081 duration_in_ms=1.137
2019-11-20 00:49:33,011 [salt.state       :1780][INFO    ][13291] Running state [/etc/salt/grains.d/sphinx] at time 00:49:33.011608
2019-11-20 00:49:33,011 [salt.state       :1813][INFO    ][13291] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-11-20 00:49:33,020 [salt.state       :300 ][INFO    ][13291] File /etc/salt/grains.d/sphinx is in the correct state
2019-11-20 00:49:33,021 [salt.state       :1951][INFO    ][13291] Completed state [/etc/salt/grains.d/sphinx] at time 00:49:33.021244 duration_in_ms=9.635
2019-11-20 00:49:33,024 [salt.state       :1780][INFO    ][13291] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 00:49:33.024769
2019-11-20 00:49:33,025 [salt.state       :1813][INFO    ][13291] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-11-20 00:49:33,025 [salt.state       :300 ][INFO    ][13291] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-11-20 00:49:33,026 [salt.state       :1951][INFO    ][13291] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 00:49:33.026127 duration_in_ms=1.358
2019-11-20 00:49:33,026 [salt.state       :1780][INFO    ][13291] Running state [/etc/salt/grains.d/dns_records] at time 00:49:33.026878
2019-11-20 00:49:33,027 [salt.state       :1813][INFO    ][13291] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-11-20 00:49:33,038 [salt.state       :300 ][INFO    ][13291] File /etc/salt/grains.d/dns_records is in the correct state
2019-11-20 00:49:33,039 [salt.state       :1951][INFO    ][13291] Completed state [/etc/salt/grains.d/dns_records] at time 00:49:33.039137 duration_in_ms=12.259
2019-11-20 00:49:33,040 [salt.state       :1780][INFO    ][13291] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 00:49:33.040621
2019-11-20 00:49:33,041 [salt.state       :1813][INFO    ][13291] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-11-20 00:49:33,041 [salt.state       :300 ][INFO    ][13291] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-11-20 00:49:33,042 [salt.state       :1951][INFO    ][13291] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 00:49:33.041947 duration_in_ms=1.326
2019-11-20 00:49:33,042 [salt.state       :1780][INFO    ][13291] Running state [/etc/salt/grains.d/salt] at time 00:49:33.042717
2019-11-20 00:49:33,043 [salt.state       :1813][INFO    ][13291] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-11-20 00:49:33,056 [salt.state       :300 ][INFO    ][13291] File /etc/salt/grains.d/salt is in the correct state
2019-11-20 00:49:33,057 [salt.state       :1951][INFO    ][13291] Completed state [/etc/salt/grains.d/salt] at time 00:49:33.057020 duration_in_ms=14.303
2019-11-20 00:49:33,058 [salt.state       :1780][INFO    ][13291] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 00:49:33.058323
2019-11-20 00:49:33,058 [salt.state       :1813][INFO    ][13291] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-11-20 00:49:33,059 [salt.state       :300 ][INFO    ][13291] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-11-20 00:49:33,059 [salt.state       :1951][INFO    ][13291] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 00:49:33.059570 duration_in_ms=1.248
2019-11-20 00:49:33,062 [salt.state       :1780][INFO    ][13291] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 00:49:33.062340
2019-11-20 00:49:33,062 [salt.state       :1813][INFO    ][13291] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-11-20 00:49:33,063 [salt.state       :300 ][INFO    ][13291] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-11-20 00:49:33,063 [salt.state       :1951][INFO    ][13291] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 00:49:33.063547 duration_in_ms=1.208
2019-11-20 00:49:33,064 [salt.state       :1780][INFO    ][13291] Running state [mine.update] at time 00:49:33.064480
2019-11-20 00:49:33,064 [salt.state       :1813][INFO    ][13291] Executing state module.wait for [mine.update]
2019-11-20 00:49:33,065 [salt.state       :300 ][INFO    ][13291] No changes made for mine.update
2019-11-20 00:49:33,065 [salt.state       :1951][INFO    ][13291] Completed state [mine.update] at time 00:49:33.065607 duration_in_ms=1.127
2019-11-20 00:49:33,066 [salt.state       :1780][INFO    ][13291] Running state [ca-certificates] at time 00:49:33.065950
2019-11-20 00:49:33,066 [salt.state       :1813][INFO    ][13291] Executing state pkg.installed for [ca-certificates]
2019-11-20 00:49:33,076 [salt.state       :300 ][INFO    ][13291] All specified packages are already installed
2019-11-20 00:49:33,076 [salt.state       :1951][INFO    ][13291] Completed state [ca-certificates] at time 00:49:33.076770 duration_in_ms=10.82
2019-11-20 00:49:33,077 [salt.state       :1780][INFO    ][13291] Running state [update-ca-certificates] at time 00:49:33.077703
2019-11-20 00:49:33,078 [salt.state       :1813][INFO    ][13291] Executing state cmd.wait for [update-ca-certificates]
2019-11-20 00:49:33,078 [salt.state       :300 ][INFO    ][13291] No changes made for update-ca-certificates
2019-11-20 00:49:33,078 [salt.state       :1951][INFO    ][13291] Completed state [update-ca-certificates] at time 00:49:33.078785 duration_in_ms=1.081
2019-11-20 00:49:33,079 [salt.state       :1780][INFO    ][13291] Running state [iptables] at time 00:49:33.079106
2019-11-20 00:49:33,079 [salt.state       :1813][INFO    ][13291] Executing state pkg.installed for [iptables]
2019-11-20 00:49:33,088 [salt.state       :300 ][INFO    ][13291] All specified packages are already installed
2019-11-20 00:49:33,088 [salt.state       :1951][INFO    ][13291] Completed state [iptables] at time 00:49:33.088823 duration_in_ms=9.716
2019-11-20 00:49:33,089 [salt.state       :1780][INFO    ][13291] Running state [iptables-persistent] at time 00:49:33.089133
2019-11-20 00:49:33,089 [salt.state       :1813][INFO    ][13291] Executing state pkg.installed for [iptables-persistent]
2019-11-20 00:49:33,098 [salt.state       :300 ][INFO    ][13291] All specified packages are already installed
2019-11-20 00:49:33,098 [salt.state       :1951][INFO    ][13291] Completed state [iptables-persistent] at time 00:49:33.098380 duration_in_ms=9.247
2019-11-20 00:49:33,099 [salt.state       :1780][INFO    ][13291] Running state [iptables_modules_v4_load] at time 00:49:33.099632
2019-11-20 00:49:33,100 [salt.state       :1813][INFO    ][13291] Executing state kmod.present for [iptables_modules_v4_load]
2019-11-20 00:49:33,100 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13291] Executing command 'lsmod' in directory '/root'
2019-11-20 00:49:33,125 [salt.state       :300 ][INFO    ][13291] Kernel modules iptable_filter, ip_tables are already present
2019-11-20 00:49:33,125 [salt.state       :1951][INFO    ][13291] Completed state [iptables_modules_v4_load] at time 00:49:33.125802 duration_in_ms=26.17
2019-11-20 00:49:33,126 [salt.state       :1780][INFO    ][13291] Running state [/etc/iptables/rules.v4] at time 00:49:33.126766
2019-11-20 00:49:33,127 [salt.state       :1813][INFO    ][13291] Executing state file.managed for [/etc/iptables/rules.v4]
2019-11-20 00:49:33,242 [salt.state       :300 ][INFO    ][13291] File /etc/iptables/rules.v4 is in the correct state
2019-11-20 00:49:33,242 [salt.state       :1951][INFO    ][13291] Completed state [/etc/iptables/rules.v4] at time 00:49:33.242811 duration_in_ms=116.045
2019-11-20 00:49:33,244 [salt.state       :1780][INFO    ][13291] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 00:49:33.243987
2019-11-20 00:49:33,244 [salt.state       :1813][INFO    ][13291] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-11-20 00:49:33,245 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13291] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-11-20 00:49:33,265 [salt.state       :300 ][INFO    ][13291] onlyif execution failed
2019-11-20 00:49:33,266 [salt.state       :1951][INFO    ][13291] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 00:49:33.266034 duration_in_ms=22.045
2019-11-20 00:49:33,267 [salt.state       :1780][INFO    ][13291] Running state [netfilter-persistent] at time 00:49:33.267850
2019-11-20 00:49:33,268 [salt.state       :1813][INFO    ][13291] Executing state service.running for [netfilter-persistent]
2019-11-20 00:49:33,269 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13291] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-11-20 00:49:33,290 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13291] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-11-20 00:49:33,308 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13291] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-11-20 00:49:33,326 [salt.state       :300 ][INFO    ][13291] The service netfilter-persistent is already running
2019-11-20 00:49:33,327 [salt.state       :1951][INFO    ][13291] Completed state [netfilter-persistent] at time 00:49:33.326992 duration_in_ms=59.142
2019-11-20 00:49:33,328 [salt.state       :1780][INFO    ][13291] Running state [iptables_extra.remove_stale_tables] at time 00:49:33.328379
2019-11-20 00:49:33,328 [salt.state       :1813][INFO    ][13291] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-11-20 00:49:33,329 [salt.state       :300 ][INFO    ][13291] No changes made for iptables_extra.remove_stale_tables
2019-11-20 00:49:33,329 [salt.state       :1951][INFO    ][13291] Completed state [iptables_extra.remove_stale_tables] at time 00:49:33.329809 duration_in_ms=1.43
2019-11-20 00:49:33,330 [salt.state       :1780][INFO    ][13291] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 00:49:33.330205
2019-11-20 00:49:33,330 [salt.state       :1813][INFO    ][13291] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-11-20 00:49:33,331 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13291] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-11-20 00:49:33,346 [salt.state       :300 ][INFO    ][13291] onlyif execution failed
2019-11-20 00:49:33,347 [salt.state       :1951][INFO    ][13291] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 00:49:33.347179 duration_in_ms=16.972
2019-11-20 00:49:33,348 [salt.state       :1780][INFO    ][13291] Running state [/etc/iptables/rules.v6] at time 00:49:33.348673
2019-11-20 00:49:33,349 [salt.state       :1813][INFO    ][13291] Executing state file.absent for [/etc/iptables/rules.v6]
2019-11-20 00:49:33,349 [salt.state       :300 ][INFO    ][13291] File /etc/iptables/rules.v6 is not present
2019-11-20 00:49:33,350 [salt.state       :1951][INFO    ][13291] Completed state [/etc/iptables/rules.v6] at time 00:49:33.350168 duration_in_ms=1.496
2019-11-20 00:49:33,351 [salt.state       :1780][INFO    ][13291] Running state [iptables_extra.flush_all] at time 00:49:33.351206
2019-11-20 00:49:33,351 [salt.state       :1813][INFO    ][13291] Executing state module.wait for [iptables_extra.flush_all]
2019-11-20 00:49:33,352 [salt.state       :300 ][INFO    ][13291] No changes made for iptables_extra.flush_all
2019-11-20 00:49:33,352 [salt.state       :1951][INFO    ][13291] Completed state [iptables_extra.flush_all] at time 00:49:33.352402 duration_in_ms=1.197
2019-11-20 00:49:33,356 [salt.minion      :1711][INFO    ][13291] Returning information for job: 20191120004925113396
2019-11-20 00:49:33,977 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command state.apply with jid 20191120004933963923
2019-11-20 00:49:34,000 [salt.minion      :1432][INFO    ][13381] Starting a new job with PID 13381
2019-11-20 00:49:34,758 [salt.state       :915 ][INFO    ][13381] Loading fresh modules for state activity
2019-11-20 00:49:35,379 [salt.state       :1780][INFO    ][13381] Running state [maas-rack-controller] at time 00:49:35.379090
2019-11-20 00:49:35,379 [salt.state       :1813][INFO    ][13381] Executing state pkg.installed for [maas-rack-controller]
2019-11-20 00:49:35,380 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-20 00:49:35,474 [salt.state       :300 ][INFO    ][13381] All specified packages are already installed
2019-11-20 00:49:35,475 [salt.state       :1951][INFO    ][13381] Completed state [maas-rack-controller] at time 00:49:35.474919 duration_in_ms=95.829
2019-11-20 00:49:35,475 [salt.state       :1780][INFO    ][13381] Running state [ipmitool] at time 00:49:35.475431
2019-11-20 00:49:35,475 [salt.state       :1813][INFO    ][13381] Executing state pkg.installed for [ipmitool]
2019-11-20 00:49:35,483 [salt.state       :300 ][INFO    ][13381] All specified packages are already installed
2019-11-20 00:49:35,483 [salt.state       :1951][INFO    ][13381] Completed state [ipmitool] at time 00:49:35.483729 duration_in_ms=8.299
2019-11-20 00:49:35,487 [salt.state       :1780][INFO    ][13381] Running state [/etc/maas/rackd.conf] at time 00:49:35.487101
2019-11-20 00:49:35,487 [salt.state       :1813][INFO    ][13381] Executing state file.line for [/etc/maas/rackd.conf]
2019-11-20 00:49:35,488 [salt.state       :300 ][INFO    ][13381] No changes needed to be made
2019-11-20 00:49:35,488 [salt.state       :1951][INFO    ][13381] Completed state [/etc/maas/rackd.conf] at time 00:49:35.488839 duration_in_ms=1.737
2019-11-20 00:49:35,489 [salt.state       :1780][INFO    ][13381] Running state [/etc/maas/rackd.conf] at time 00:49:35.489178
2019-11-20 00:49:35,489 [salt.state       :1813][INFO    ][13381] Executing state file.managed for [/etc/maas/rackd.conf]
2019-11-20 00:49:35,489 [salt.loaded.int.states.file:2298][WARNING ][13381] 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-11-20 00:49:35,490 [salt.state       :300 ][INFO    ][13381] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-11-20 00:49:35,490 [salt.state       :1951][INFO    ][13381] Completed state [/etc/maas/rackd.conf] at time 00:49:35.490814 duration_in_ms=1.635
2019-11-20 00:49:35,491 [salt.state       :1780][INFO    ][13381] Running state [maas-rackd] at time 00:49:35.491873
2019-11-20 00:49:35,492 [salt.state       :1813][INFO    ][13381] Executing state service.running for [maas-rackd]
2019-11-20 00:49:35,492 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-11-20 00:49:35,527 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-11-20 00:49:35,544 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-11-20 00:49:35,562 [salt.state       :300 ][INFO    ][13381] The service maas-rackd is already running
2019-11-20 00:49:35,563 [salt.state       :1951][INFO    ][13381] Completed state [maas-rackd] at time 00:49:35.562970 duration_in_ms=71.097
2019-11-20 00:49:35,564 [salt.minion      :1711][INFO    ][13381] Returning information for job: 20191120004933963923
2019-11-20 00:49:36,116 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command state.apply with jid 20191120004936104272
2019-11-20 00:49:36,136 [salt.minion      :1432][INFO    ][13404] Starting a new job with PID 13404
2019-11-20 00:49:36,918 [salt.state       :915 ][INFO    ][13404] Loading fresh modules for state activity
2019-11-20 00:49:37,616 [salt.state       :1780][INFO    ][13404] Running state [maas-region-controller] at time 00:49:37.616904
2019-11-20 00:49:37,617 [salt.state       :1813][INFO    ][13404] Executing state pkg.installed for [maas-region-controller]
2019-11-20 00:49:37,617 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13404] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-20 00:49:37,698 [salt.state       :300 ][INFO    ][13404] All specified packages are already installed
2019-11-20 00:49:37,698 [salt.state       :1951][INFO    ][13404] Completed state [maas-region-controller] at time 00:49:37.698566 duration_in_ms=81.661
2019-11-20 00:49:37,698 [salt.state       :1780][INFO    ][13404] Running state [python-oauth] at time 00:49:37.698863
2019-11-20 00:49:37,699 [salt.state       :1813][INFO    ][13404] Executing state pkg.installed for [python-oauth]
2019-11-20 00:49:37,704 [salt.state       :300 ][INFO    ][13404] All specified packages are already installed
2019-11-20 00:49:37,704 [salt.state       :1951][INFO    ][13404] Completed state [python-oauth] at time 00:49:37.704867 duration_in_ms=6.004
2019-11-20 00:49:37,707 [salt.state       :1780][INFO    ][13404] Running state [/etc/maas/regiond.conf] at time 00:49:37.707739
2019-11-20 00:49:37,708 [salt.state       :1813][INFO    ][13404] Executing state file.replace for [/etc/maas/regiond.conf]
2019-11-20 00:49:37,746 [salt.state       :300 ][INFO    ][13404] No changes needed to be made
2019-11-20 00:49:37,746 [salt.state       :1951][INFO    ][13404] Completed state [/etc/maas/regiond.conf] at time 00:49:37.746305 duration_in_ms=38.566
2019-11-20 00:49:37,746 [salt.state       :1780][INFO    ][13404] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 00:49:37.746738
2019-11-20 00:49:37,747 [salt.state       :1813][INFO    ][13404] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-11-20 00:49:37,823 [salt.state       :300 ][INFO    ][13404] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-11-20 00:49:37,824 [salt.state       :1951][INFO    ][13404] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 00:49:37.824266 duration_in_ms=77.525
2019-11-20 00:49:37,825 [salt.state       :1780][INFO    ][13404] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 00:49:37.825238
2019-11-20 00:49:37,825 [salt.state       :1813][INFO    ][13404] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-11-20 00:49:37,844 [salt.state       :300 ][INFO    ][13404] No changes needed to be made
2019-11-20 00:49:37,844 [salt.state       :1951][INFO    ][13404] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 00:49:37.844643 duration_in_ms=19.403
2019-11-20 00:49:37,845 [salt.state       :1780][INFO    ][13404] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 00:49:37.845503
2019-11-20 00:49:37,846 [salt.state       :1813][INFO    ][13404] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-11-20 00:49:37,880 [salt.state       :300 ][INFO    ][13404] No changes needed to be made
2019-11-20 00:49:37,880 [salt.state       :1951][INFO    ][13404] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 00:49:37.880532 duration_in_ms=35.027
2019-11-20 00:49:37,881 [salt.state       :1780][INFO    ][13404] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 00:49:37.881431
2019-11-20 00:49:37,881 [salt.state       :1813][INFO    ][13404] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-11-20 00:49:37,924 [salt.state       :300 ][INFO    ][13404] No changes needed to be made
2019-11-20 00:49:37,925 [salt.state       :1951][INFO    ][13404] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 00:49:37.925294 duration_in_ms=43.862
2019-11-20 00:49:37,926 [salt.state       :1780][INFO    ][13404] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 00:49:37.926827
2019-11-20 00:49:37,927 [salt.state       :1813][INFO    ][13404] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-11-20 00:49:37,945 [salt.state       :300 ][INFO    ][13404] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-11-20 00:49:37,945 [salt.state       :1951][INFO    ][13404] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 00:49:37.945786 duration_in_ms=18.958
2019-11-20 00:49:37,949 [salt.state       :1780][INFO    ][13404] Running state [a2enmod headers] at time 00:49:37.949355
2019-11-20 00:49:37,949 [salt.state       :1813][INFO    ][13404] Executing state cmd.run for [a2enmod headers]
2019-11-20 00:49:37,950 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13404] Executing command 'a2enmod headers' in directory '/root'
2019-11-20 00:49:38,026 [salt.state       :300 ][INFO    ][13404] {'pid': 13424, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-11-20 00:49:38,026 [salt.state       :1951][INFO    ][13404] Completed state [a2enmod headers] at time 00:49:38.026741 duration_in_ms=77.384
2019-11-20 00:49:38,027 [salt.state       :1780][INFO    ][13404] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 00:49:38.027389
2019-11-20 00:49:38,028 [salt.state       :1813][INFO    ][13404] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-11-20 00:49:38,047 [salt.state       :300 ][INFO    ][13404] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-11-20 00:49:38,048 [salt.state       :1951][INFO    ][13404] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 00:49:38.047965 duration_in_ms=20.575
2019-11-20 00:49:38,048 [salt.state       :1780][INFO    ][13404] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 00:49:38.048903
2019-11-20 00:49:38,049 [salt.state       :1813][INFO    ][13404] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-11-20 00:49:38,129 [salt.state       :300 ][INFO    ][13404] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-11-20 00:49:38,130 [salt.state       :1951][INFO    ][13404] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 00:49:38.130043 duration_in_ms=81.138
2019-11-20 00:49:38,131 [salt.state       :1780][INFO    ][13404] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 00:49:38.131007
2019-11-20 00:49:38,131 [salt.state       :1813][INFO    ][13404] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-11-20 00:49:38,207 [salt.state       :300 ][INFO    ][13404] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-11-20 00:49:38,207 [salt.state       :1951][INFO    ][13404] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 00:49:38.207448 duration_in_ms=76.442
2019-11-20 00:49:38,208 [salt.state       :1780][INFO    ][13404] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 00:49:38.208183
2019-11-20 00:49:38,208 [salt.state       :1813][INFO    ][13404] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-11-20 00:49:38,266 [salt.state       :300 ][INFO    ][13404] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-11-20 00:49:38,267 [salt.state       :1951][INFO    ][13404] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 00:49:38.266998 duration_in_ms=58.815
2019-11-20 00:49:38,267 [salt.state       :1780][INFO    ][13404] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 00:49:38.267606
2019-11-20 00:49:38,267 [salt.state       :1813][INFO    ][13404] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2019-11-20 00:49:38,320 [salt.state       :300 ][INFO    ][13404] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2019-11-20 00:49:38,321 [salt.state       :1951][INFO    ][13404] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 00:49:38.320970 duration_in_ms=53.364
2019-11-20 00:49:38,321 [salt.state       :1780][INFO    ][13404] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 00:49:38.321470
2019-11-20 00:49:38,321 [salt.state       :1813][INFO    ][13404] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2019-11-20 00:49:38,368 [salt.state       :300 ][INFO    ][13404] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2019-11-20 00:49:38,368 [salt.state       :1951][INFO    ][13404] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 00:49:38.368453 duration_in_ms=46.983
2019-11-20 00:49:38,368 [salt.state       :1780][INFO    ][13404] Running state [/root/.pgpass] at time 00:49:38.368725
2019-11-20 00:49:38,368 [salt.state       :1813][INFO    ][13404] Executing state file.managed for [/root/.pgpass]
2019-11-20 00:49:38,403 [salt.state       :300 ][INFO    ][13404] File /root/.pgpass is in the correct state
2019-11-20 00:49:38,404 [salt.state       :1951][INFO    ][13404] Completed state [/root/.pgpass] at time 00:49:38.404165 duration_in_ms=35.439
2019-11-20 00:49:38,407 [salt.state       :1780][INFO    ][13404] Running state [maas-region syncdb --noinput] at time 00:49:38.407846
2019-11-20 00:49:38,408 [salt.state       :1813][INFO    ][13404] Executing state cmd.run for [maas-region syncdb --noinput]
2019-11-20 00:49:38,408 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13404] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-11-20 00:49:40,623 [salt.state       :300 ][INFO    ][13404] {'pid': 13442, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, metadataserver, sites, maasserver, piston3, auth, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-11-20 00:49:40,624 [salt.state       :1951][INFO    ][13404] Completed state [maas-region syncdb --noinput] at time 00:49:40.623960 duration_in_ms=2216.112
2019-11-20 00:49:40,624 [salt.state       :2022][WARNING ][13404] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-11-20 00:49:40,627 [salt.state       :1780][INFO    ][13404] Running state [maas-regiond] at time 00:49:40.627280
2019-11-20 00:49:40,627 [salt.state       :1813][INFO    ][13404] Executing state service.running for [maas-regiond]
2019-11-20 00:49:40,629 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13404] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-11-20 00:49:40,667 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13404] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-11-20 00:49:40,684 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13404] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-11-20 00:49:40,701 [salt.state       :300 ][INFO    ][13404] The service maas-regiond is already running
2019-11-20 00:49:40,701 [salt.state       :1951][INFO    ][13404] Completed state [maas-regiond] at time 00:49:40.701494 duration_in_ms=74.197
2019-11-20 00:49:40,703 [salt.state       :1780][INFO    ][13404] Running state [bind9] at time 00:49:40.703815
2019-11-20 00:49:40,704 [salt.state       :1813][INFO    ][13404] Executing state service.running for [bind9]
2019-11-20 00:49:40,705 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13404] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-11-20 00:49:40,723 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13404] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-11-20 00:49:40,739 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13404] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-11-20 00:49:40,754 [salt.state       :300 ][INFO    ][13404] The service bind9 is already running
2019-11-20 00:49:40,755 [salt.state       :1951][INFO    ][13404] Completed state [bind9] at time 00:49:40.755188 duration_in_ms=51.372
2019-11-20 00:49:40,757 [salt.state       :1780][INFO    ][13404] Running state [apache2] at time 00:49:40.757213
2019-11-20 00:49:40,757 [salt.state       :1813][INFO    ][13404] Executing state service.running for [apache2]
2019-11-20 00:49:40,758 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13404] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-11-20 00:49:40,775 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13404] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-11-20 00:49:40,791 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13404] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-11-20 00:49:40,811 [salt.state       :300 ][INFO    ][13404] The service apache2 is already running
2019-11-20 00:49:40,811 [salt.state       :1951][INFO    ][13404] Completed state [apache2] at time 00:49:40.811584 duration_in_ms=54.371
2019-11-20 00:49:40,813 [salt.state       :1780][INFO    ][13404] Running state [maasng.wait_for_http_code] at time 00:49:40.813245
2019-11-20 00:49:40,813 [salt.state       :1813][INFO    ][13404] Executing state module.run for [maasng.wait_for_http_code]
2019-11-20 00:49:40,814 [salt.utils.decorators:613 ][WARNING ][13404] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:49:40,821 [salt.state       :300 ][INFO    ][13404] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-11-20 00:49:40,822 [salt.state       :1951][INFO    ][13404] Completed state [maasng.wait_for_http_code] at time 00:49:40.822094 duration_in_ms=8.848
2019-11-20 00:49:40,823 [salt.state       :1780][INFO    ][13404] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 00:49:40.823539
2019-11-20 00:49:40,824 [salt.state       :1813][INFO    ][13404] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-11-20 00:49:40,824 [salt.state       :300 ][INFO    ][13404] /var/lib/maas/.setup_admin exists
2019-11-20 00:49:40,825 [salt.state       :1951][INFO    ][13404] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 00:49:40.825148 duration_in_ms=1.609
2019-11-20 00:49:40,826 [salt.state       :1780][INFO    ][13404] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:49:40.826315
2019-11-20 00:49:40,826 [salt.state       :1813][INFO    ][13404] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-20 00:49:40,827 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13404] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-20 00:49:42,280 [salt.state       :300 ][INFO    ][13404] {'pid': 13461, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-20 00:49:42,281 [salt.state       :1951][INFO    ][13404] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:49:42.281341 duration_in_ms=1455.025
2019-11-20 00:49:42,290 [salt.state       :1780][INFO    ][13404] Running state [maas_region_boot_source_resources_mirror] at time 00:49:42.290562
2019-11-20 00:49:42,291 [salt.state       :1813][INFO    ][13404] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-11-20 00:49:42,396 [salt.state       :300 ][INFO    ][13404] {'changes': {}}
2019-11-20 00:49:42,397 [salt.state       :1951][INFO    ][13404] Completed state [maas_region_boot_source_resources_mirror] at time 00:49:42.396940 duration_in_ms=106.378
2019-11-20 00:49:42,398 [salt.state       :1780][INFO    ][13404] Running state [maasng.boot_resources_import] at time 00:49:42.398102
2019-11-20 00:49:42,398 [salt.state       :1813][INFO    ][13404] Executing state module.run for [maasng.boot_resources_import]
2019-11-20 00:49:42,399 [salt.utils.decorators:613 ][WARNING ][13404] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:49:42,517 [salt.loaded.ext.module.maasng:1600][INFO    ][13404] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-11-20 00:49:47,575 [salt.loaded.ext.module.maasng:1600][INFO    ][13404] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-11-20 00:49:51,201 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120004951186784
2019-11-20 00:49:51,223 [salt.minion      :1432][INFO    ][13502] Starting a new job with PID 13502
2019-11-20 00:49:51,249 [salt.minion      :1711][INFO    ][13502] Returning information for job: 20191120004951186784
2019-11-20 00:49:52,641 [salt.loaded.ext.module.maasng:1600][INFO    ][13404] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-11-20 00:49:57,773 [salt.state       :300 ][INFO    ][13404] {'ret': True}
2019-11-20 00:49:57,773 [salt.state       :1951][INFO    ][13404] Completed state [maasng.boot_resources_import] at time 00:49:57.773598 duration_in_ms=15375.495
2019-11-20 00:49:57,774 [salt.state       :1780][INFO    ][13404] Running state [maas_region_boot_sources_selection_bionic] at time 00:49:57.774846
2019-11-20 00:49:57,775 [salt.state       :1813][INFO    ][13404] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2019-11-20 00:49:57,994 [salt.state       :300 ][INFO    ][13404] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-11-20 00:49:57,994 [salt.state       :1951][INFO    ][13404] Completed state [maas_region_boot_sources_selection_bionic] at time 00:49:57.994854 duration_in_ms=220.007
2019-11-20 00:49:57,996 [salt.state       :1780][INFO    ][13404] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 00:49:57.996789
2019-11-20 00:49:57,997 [salt.state       :1813][INFO    ][13404] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-11-20 00:49:57,997 [salt.utils.decorators:613 ][WARNING ][13404] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:49:57,998 [salt.loaded.ext.module.maasng:1771][INFO    ][13404] boot-sources sync initiated for ALL Rack's
2019-11-20 00:49:58,957 [salt.state       :300 ][INFO    ][13404] {'ret': True}
2019-11-20 00:49:58,958 [salt.state       :1951][INFO    ][13404] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 00:49:58.958287 duration_in_ms=961.496
2019-11-20 00:49:58,961 [salt.state       :1780][INFO    ][13404] Running state [maas.process_maas_config] at time 00:49:58.960956
2019-11-20 00:49:58,961 [salt.state       :1813][INFO    ][13404] Executing state module.run for [maas.process_maas_config]
2019-11-20 00:49:58,962 [salt.utils.decorators:613 ][WARNING ][13404] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:49:58,963 [salt.loaded.ext.module.maas:92  ][INFO    ][13404] maasconfig name=enable_http_proxy value=True
2019-11-20 00:49:59,025 [salt.loaded.ext.module.maas:92  ][INFO    ][13404] maasconfig name=upstream_dns value=8.8.8.8
2019-11-20 00:49:59,089 [salt.loaded.ext.module.maas:92  ][INFO    ][13404] maasconfig name=commissioning_distro_series value=bionic
2019-11-20 00:50:02,164 [salt.loaded.ext.module.maas:92  ][INFO    ][13404] maasconfig name=default_osystem value=ubuntu
2019-11-20 00:50:02,219 [salt.loaded.ext.module.maas:92  ][INFO    ][13404] maasconfig name=active_discovery_interval value=600
2019-11-20 00:50:02,267 [salt.loaded.ext.module.maas:92  ][INFO    ][13404] maasconfig name=dnssec_validation value=no
2019-11-20 00:50:02,309 [salt.loaded.ext.module.maas:92  ][INFO    ][13404] maasconfig name=maas_name value=mas01
2019-11-20 00:50:02,357 [salt.loaded.ext.module.maas:92  ][INFO    ][13404] maasconfig name=network_discovery value=enabled
2019-11-20 00:50:02,454 [salt.loaded.ext.module.maas:92  ][INFO    ][13404] maasconfig name=enable_third_party_drivers value=True
2019-11-20 00:50:02,509 [salt.loaded.ext.module.maas:92  ][INFO    ][13404] maasconfig name=default_storage_layout value=lvm
2019-11-20 00:50:02,550 [salt.loaded.ext.module.maas:92  ][INFO    ][13404] maasconfig name=ntp_external_only value=True
2019-11-20 00:50:02,611 [salt.loaded.ext.module.maas:92  ][INFO    ][13404] maasconfig name=disk_erase_with_secure_erase value=False
2019-11-20 00:50:02,659 [salt.loaded.ext.module.maas:92  ][INFO    ][13404] maasconfig name=default_distro_series value=bionic
2019-11-20 00:50:02,725 [salt.loaded.ext.module.maas:92  ][INFO    ][13404] maasconfig name=default_min_hwe_kernel value=ga-18.04
2019-11-20 00:50:02,827 [salt.state       :300 ][INFO    ][13404] {'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-11-20 00:50:02,828 [salt.state       :1951][INFO    ][13404] Completed state [maas.process_maas_config] at time 00:50:02.828034 duration_in_ms=3867.077
2019-11-20 00:50:02,828 [salt.state       :1780][INFO    ][13404] Running state [pxe_admin] at time 00:50:02.828902
2019-11-20 00:50:02,829 [salt.state       :1813][INFO    ][13404] Executing state maasng.fabric_present for [pxe_admin]
2019-11-20 00:50:02,880 [salt.loaded.ext.module.maasng:945 ][INFO    ][13404] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'primary_rack': u'mrxnpr', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-11-20 00:50:02,926 [salt.loaded.ext.module.maasng:1008][WARNING ][13404] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-11-20 00:50:02,926 [salt.loaded.ext.module.maasng:1011][WARNING ][13404] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-11-20 00:50:02,974 [salt.state       :300 ][INFO    ][13404] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-11-20 00:50:02,975 [salt.state       :1951][INFO    ][13404] Completed state [pxe_admin] at time 00:50:02.975027 duration_in_ms=146.123
2019-11-20 00:50:02,975 [salt.state       :1780][INFO    ][13404] Running state [vlan 0] at time 00:50:02.975397
2019-11-20 00:50:02,975 [salt.state       :1813][INFO    ][13404] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-11-20 00:50:03,035 [salt.loaded.ext.module.maasng:945 ][INFO    ][13404] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'mrxnpr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-20 00:50:03,129 [salt.loaded.ext.module.maasng:945 ][INFO    ][13404] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mrxnpr', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-11-20 00:50:03,371 [salt.loaded.ext.module.maasng:945 ][INFO    ][13404] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/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': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mrxnpr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-11-20 00:50:03,457 [salt.state       :300 ][INFO    ][13404] {'new': 'Vlan untagged was updated'}
2019-11-20 00:50:03,457 [salt.state       :1951][INFO    ][13404] Completed state [vlan 0] at time 00:50:03.457422 duration_in_ms=482.024
2019-11-20 00:50:03,459 [salt.state       :1780][INFO    ][13404] Running state [192.168.11.0/24] at time 00:50:03.459103
2019-11-20 00:50:03,459 [salt.state       :1813][INFO    ][13404] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-11-20 00:50:03,615 [salt.loaded.ext.module.maasng:945 ][INFO    ][13404] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mrxnpr', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-11-20 00:50:03,616 [salt.loaded.ext.module.maasng:1235][WARNING ][13404] Ignoring parameter vlan:0
2019-11-20 00:50:03,681 [salt.state       :300 ][INFO    ][13404] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-11-20 00:50:03,681 [salt.state       :1951][INFO    ][13404] Completed state [192.168.11.0/24] at time 00:50:03.681784 duration_in_ms=222.683
2019-11-20 00:50:03,682 [salt.state       :1780][INFO    ][13404] Running state [maas_create_iprange_1] at time 00:50:03.682349
2019-11-20 00:50:03,682 [salt.state       :1813][INFO    ][13404] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-11-20 00:50:03,736 [salt.state       :300 ][INFO    ][13404] Iprange maas_create_iprange_1 already exist.
2019-11-20 00:50:03,736 [salt.state       :1951][INFO    ][13404] Completed state [maas_create_iprange_1] at time 00:50:03.736877 duration_in_ms=54.527
2019-11-20 00:50:03,737 [salt.state       :1780][INFO    ][13404] Running state [vlan 0] at time 00:50:03.737141
2019-11-20 00:50:03,737 [salt.state       :1813][INFO    ][13404] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-11-20 00:50:03,784 [salt.loaded.ext.module.maasng:945 ][INFO    ][13404] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/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': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'mrxnpr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-11-20 00:50:03,862 [salt.loaded.ext.module.maasng:945 ][INFO    ][13404] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mrxnpr', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-11-20 00:50:04,072 [salt.loaded.ext.module.maasng:945 ][INFO    ][13404] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mrxnpr', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-11-20 00:50:04,132 [salt.state       :300 ][INFO    ][13404] {'new': 'Vlan untagged was updated'}
2019-11-20 00:50:04,133 [salt.state       :1951][INFO    ][13404] Completed state [vlan 0] at time 00:50:04.133448 duration_in_ms=396.304
2019-11-20 00:50:04,134 [salt.state       :1780][INFO    ][13404] Running state [opnfv] at time 00:50:04.134862
2019-11-20 00:50:04,135 [salt.state       :1813][INFO    ][13404] Executing state maasng.sshkey_present for [opnfv]
2019-11-20 00:50:04,178 [salt.loaded.ext.module.maasng:1903][INFO    ][13404] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-11-20 00:50:04,179 [salt.state       :300 ][INFO    ][13404] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-11-20 00:50:04,179 [salt.state       :1951][INFO    ][13404] Completed state [opnfv] at time 00:50:04.179640 duration_in_ms=44.778
2019-11-20 00:50:04,183 [salt.minion      :1711][INFO    ][13404] Returning information for job: 20191120004936104272
2019-11-20 00:50:04,771 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command state.apply with jid 20191120005004763984
2019-11-20 00:50:04,786 [salt.minion      :1432][INFO    ][13777] Starting a new job with PID 13777
2019-11-20 00:50:08,305 [salt.state       :915 ][INFO    ][13777] Loading fresh modules for state activity
2019-11-20 00:50:08,398 [salt.state       :1780][INFO    ][13777] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:50:08.398025
2019-11-20 00:50:08,398 [salt.state       :1813][INFO    ][13777] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-20 00:50:08,400 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13777] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-20 00:50:09,832 [salt.state       :300 ][INFO    ][13777] {'pid': 13801, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-20 00:50:09,833 [salt.state       :1951][INFO    ][13777] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:50:09.833088 duration_in_ms=1435.062
2019-11-20 00:50:09,835 [salt.state       :1780][INFO    ][13777] Running state [maas.process_machines] at time 00:50:09.835714
2019-11-20 00:50:09,836 [salt.state       :1813][INFO    ][13777] Executing state module.run for [maas.process_machines]
2019-11-20 00:50:09,837 [salt.utils.decorators:613 ][WARNING ][13777] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:50:10,587 [salt.loaded.ext.module.maas:412 ][WARNING ][13777] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-20 00:50:10,588 [salt.loaded.ext.module.maas:92  ][INFO    ][13777] machine hostname=cmp002 power_type=ipmi mac_addresses=['00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=6dqkn3 architecture=amd64/generic power_parameters_power_user=admin
2019-11-20 00:50:11,809 [salt.loaded.ext.module.maas:412 ][WARNING ][13777] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-20 00:50:11,810 [salt.loaded.ext.module.maas:92  ][INFO    ][13777] machine hostname=cmp001 power_type=ipmi mac_addresses=['00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=spshdm architecture=amd64/generic power_parameters_power_user=admin
2019-11-20 00:50:12,911 [salt.loaded.ext.module.maas:412 ][WARNING ][13777] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-20 00:50:12,912 [salt.loaded.ext.module.maas:92  ][INFO    ][13777] machine hostname=kvm01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=pwm7q3 architecture=amd64/generic power_parameters_power_user=admin
2019-11-20 00:50:14,109 [salt.loaded.ext.module.maas:412 ][WARNING ][13777] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-20 00:50:14,109 [salt.loaded.ext.module.maas:92  ][INFO    ][13777] machine hostname=kvm03 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=67r7rs architecture=amd64/generic power_parameters_power_user=admin
2019-11-20 00:50:14,953 [salt.loaded.ext.module.maas:412 ][WARNING ][13777] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-20 00:50:14,954 [salt.loaded.ext.module.maas:92  ][INFO    ][13777] machine hostname=kvm02 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=nb8cqf architecture=amd64/generic power_parameters_power_user=admin
2019-11-20 00:50:16,134 [salt.state       :300 ][INFO    ][13777] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-11-20 00:50:16,134 [salt.state       :1951][INFO    ][13777] Completed state [maas.process_machines] at time 00:50:16.134583 duration_in_ms=6298.869
2019-11-20 00:50:16,138 [salt.minion      :1711][INFO    ][13777] Returning information for job: 20191120005004763984
2019-11-20 00:50:49,419 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command state.apply with jid 20191120005049406116
2019-11-20 00:50:49,441 [salt.minion      :1432][INFO    ][14055] Starting a new job with PID 14055
2019-11-20 00:50:53,337 [salt.state       :915 ][INFO    ][14055] Loading fresh modules for state activity
2019-11-20 00:50:53,429 [salt.state       :1780][INFO    ][14055] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:50:53.429849
2019-11-20 00:50:53,430 [salt.state       :1813][INFO    ][14055] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-20 00:50:53,432 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14055] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-20 00:50:54,886 [salt.state       :300 ][INFO    ][14055] {'pid': 14082, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-20 00:50:54,886 [salt.state       :1951][INFO    ][14055] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:50:54.886778 duration_in_ms=1456.929
2019-11-20 00:50:54,889 [salt.state       :1780][INFO    ][14055] Running state [maas.wait_for_machine_status] at time 00:50:54.889808
2019-11-20 00:50:54,890 [salt.state       :1813][INFO    ][14055] Executing state module.run for [maas.wait_for_machine_status]
2019-11-20 00:50:54,891 [salt.utils.decorators:613 ][WARNING ][14055] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:50:58,291 [salt.state       :300 ][INFO    ][14055] {'ret': True}
2019-11-20 00:50:58,292 [salt.state       :1951][INFO    ][14055] Completed state [maas.wait_for_machine_status] at time 00:50:58.292158 duration_in_ms=3402.349
2019-11-20 00:50:58,295 [salt.minion      :1711][INFO    ][14055] Returning information for job: 20191120005049406116
2019-11-20 00:50:58,915 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command state.apply with jid 20191120005058902048
2019-11-20 00:50:58,937 [salt.minion      :1432][INFO    ][14096] Starting a new job with PID 14096
2019-11-20 00:50:59,710 [salt.state       :915 ][INFO    ][14096] Loading fresh modules for state activity
2019-11-20 00:50:59,841 [salt.state       :1780][INFO    ][14096] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:50:59.840955
2019-11-20 00:50:59,841 [salt.state       :1813][INFO    ][14096] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-20 00:50:59,842 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14096] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-20 00:51:01,200 [salt.state       :300 ][INFO    ][14096] {'pid': 14103, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-20 00:51:01,200 [salt.state       :1951][INFO    ][14096] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:51:01.200414 duration_in_ms=1359.46
2019-11-20 00:51:01,201 [salt.state       :1780][INFO    ][14096] Running state [maas_machines_storage_cmp002_lvm] at time 00:51:01.201760
2019-11-20 00:51:01,201 [salt.state       :1813][INFO    ][14096] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-11-20 00:51:01,933 [salt.state       :300 ][INFO    ][14096] Machine cmp002 is not in Ready state.
2019-11-20 00:51:01,933 [salt.state       :1951][INFO    ][14096] Completed state [maas_machines_storage_cmp002_lvm] at time 00:51:01.933700 duration_in_ms=731.938
2019-11-20 00:51:01,934 [salt.state       :1780][INFO    ][14096] Running state [maas_machines_storage_cmp001_lvm] at time 00:51:01.934285
2019-11-20 00:51:01,934 [salt.state       :1813][INFO    ][14096] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-11-20 00:51:02,626 [salt.state       :300 ][INFO    ][14096] Machine cmp001 is not in Ready state.
2019-11-20 00:51:02,626 [salt.state       :1951][INFO    ][14096] Completed state [maas_machines_storage_cmp001_lvm] at time 00:51:02.626438 duration_in_ms=692.153
2019-11-20 00:51:02,630 [salt.minion      :1711][INFO    ][14096] Returning information for job: 20191120005058902048
2019-11-20 00:51:03,244 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command state.apply with jid 20191120005103231655
2019-11-20 00:51:03,268 [salt.minion      :1432][INFO    ][14116] Starting a new job with PID 14116
2019-11-20 00:51:04,035 [salt.state       :915 ][INFO    ][14116] Loading fresh modules for state activity
2019-11-20 00:51:04,123 [salt.state       :1780][INFO    ][14116] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:51:04.123755
2019-11-20 00:51:04,124 [salt.state       :1813][INFO    ][14116] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-20 00:51:04,126 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14116] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-20 00:51:05,544 [salt.state       :300 ][INFO    ][14116] {'pid': 14123, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-20 00:51:05,545 [salt.state       :1951][INFO    ][14116] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:51:05.545362 duration_in_ms=1421.608
2019-11-20 00:51:05,546 [salt.state       :1780][INFO    ][14116] Running state [maas.deploy_machines] at time 00:51:05.546526
2019-11-20 00:51:05,546 [salt.state       :1813][INFO    ][14116] Executing state module.run for [maas.deploy_machines]
2019-11-20 00:51:05,547 [salt.utils.decorators:613 ][WARNING ][14116] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:51:06,094 [salt.loaded.ext.module.maas:684 ][INFO    ][14116] deploymachines hwe_kernel=ga-18.04 system_id=67r7rs distro_series=bionic
2019-11-20 00:51:07,969 [salt.state       :300 ][INFO    ][14116] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm02'], 'errors': {}, 'success': ['kvm03']}}
2019-11-20 00:51:07,970 [salt.state       :1951][INFO    ][14116] Completed state [maas.deploy_machines] at time 00:51:07.970346 duration_in_ms=2423.818
2019-11-20 00:51:07,973 [salt.minion      :1711][INFO    ][14116] Returning information for job: 20191120005103231655
2019-11-20 00:51:08,461 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command state.apply with jid 20191120005108449616
2019-11-20 00:51:08,480 [salt.minion      :1432][INFO    ][14183] Starting a new job with PID 14183
2019-11-20 00:51:09,187 [salt.state       :915 ][INFO    ][14183] Loading fresh modules for state activity
2019-11-20 00:51:09,275 [salt.state       :1780][INFO    ][14183] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:51:09.275441
2019-11-20 00:51:09,275 [salt.state       :1813][INFO    ][14183] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-20 00:51:09,278 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14183] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-20 00:51:10,604 [salt.state       :300 ][INFO    ][14183] {'pid': 14190, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-20 00:51:10,605 [salt.state       :1951][INFO    ][14183] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:51:10.605742 duration_in_ms=1330.301
2019-11-20 00:51:10,608 [salt.state       :1780][INFO    ][14183] Running state [maas.wait_for_machine_status] at time 00:51:10.608203
2019-11-20 00:51:10,608 [salt.state       :1813][INFO    ][14183] Executing state module.run for [maas.wait_for_machine_status]
2019-11-20 00:51:10,609 [salt.utils.decorators:613 ][WARNING ][14183] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-20 00:51:13,645 [salt.loaded.ext.module.maas:1023][INFO    ][14183] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (2246.97471595s left)
2019-11-20 00:51:23,555 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120005123504842
2019-11-20 00:51:23,576 [salt.minion      :1432][INFO    ][14221] Starting a new job with PID 14221
2019-11-20 00:51:23,598 [salt.minion      :1711][INFO    ][14221] Returning information for job: 20191120005123504842
2019-11-20 00:51:47,028 [salt.loaded.ext.module.maas:1023][INFO    ][14183] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (2213.5912478s left)
2019-11-20 00:51:53,600 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120005153587462
2019-11-20 00:51:53,623 [salt.minion      :1432][INFO    ][14269] Starting a new job with PID 14269
2019-11-20 00:51:53,650 [salt.minion      :1711][INFO    ][14269] Returning information for job: 20191120005153587462
2019-11-20 00:52:20,561 [salt.loaded.ext.module.maas:1023][INFO    ][14183] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (2180.05806303s left)
2019-11-20 00:52:23,729 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120005223716435
2019-11-20 00:52:23,752 [salt.minion      :1432][INFO    ][14298] Starting a new job with PID 14298
2019-11-20 00:52:23,778 [salt.minion      :1711][INFO    ][14298] Returning information for job: 20191120005223716435
2019-11-20 00:52:53,778 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120005253765287
2019-11-20 00:52:53,797 [salt.minion      :1432][INFO    ][14368] Starting a new job with PID 14368
2019-11-20 00:52:53,822 [salt.minion      :1711][INFO    ][14368] Returning information for job: 20191120005253765287
2019-11-20 00:52:54,183 [salt.loaded.ext.module.maas:1023][INFO    ][14183] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (2146.43605781s left)
2019-11-20 00:53:23,828 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120005323815685
2019-11-20 00:53:23,851 [salt.minion      :1432][INFO    ][14429] Starting a new job with PID 14429
2019-11-20 00:53:23,878 [salt.minion      :1711][INFO    ][14429] Returning information for job: 20191120005323815685
2019-11-20 00:53:27,722 [salt.loaded.ext.module.maas:1023][INFO    ][14183] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (2112.89765096s left)
2019-11-20 00:53:53,893 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120005353880441
2019-11-20 00:53:53,916 [salt.minion      :1432][INFO    ][14611] Starting a new job with PID 14611
2019-11-20 00:53:53,944 [salt.minion      :1711][INFO    ][14611] Returning information for job: 20191120005353880441
2019-11-20 00:54:01,381 [salt.loaded.ext.module.maas:1023][INFO    ][14183] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (2079.23828292s left)
2019-11-20 00:54:23,962 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120005423947269
2019-11-20 00:54:23,985 [salt.minion      :1432][INFO    ][14648] Starting a new job with PID 14648
2019-11-20 00:54:24,011 [salt.minion      :1711][INFO    ][14648] Returning information for job: 20191120005423947269
2019-11-20 00:54:34,711 [salt.loaded.ext.module.maas:1023][INFO    ][14183] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (2045.90853882s left)
2019-11-20 00:54:54,027 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120005454014641
2019-11-20 00:54:54,049 [salt.minion      :1432][INFO    ][14728] Starting a new job with PID 14728
2019-11-20 00:54:54,075 [salt.minion      :1711][INFO    ][14728] Returning information for job: 20191120005454014641
2019-11-20 00:55:08,318 [salt.loaded.ext.module.maas:1023][INFO    ][14183] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (2012.30178499s left)
2019-11-20 00:55:24,104 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120005524091336
2019-11-20 00:55:24,119 [salt.minion      :1432][INFO    ][14762] Starting a new job with PID 14762
2019-11-20 00:55:24,131 [salt.minion      :1711][INFO    ][14762] Returning information for job: 20191120005524091336
2019-11-20 00:55:41,561 [salt.loaded.ext.module.maas:1023][INFO    ][14183] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1979.05825996s left)
2019-11-20 00:55:54,150 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120005554138552
2019-11-20 00:55:54,173 [salt.minion      :1432][INFO    ][14850] Starting a new job with PID 14850
2019-11-20 00:55:54,200 [salt.minion      :1711][INFO    ][14850] Returning information for job: 20191120005554138552
2019-11-20 00:56:14,473 [salt.loaded.ext.module.maas:1023][INFO    ][14183] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1946.1462338s left)
2019-11-20 00:56:24,229 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120005624216287
2019-11-20 00:56:24,252 [salt.minion      :1432][INFO    ][14885] Starting a new job with PID 14885
2019-11-20 00:56:24,278 [salt.minion      :1711][INFO    ][14885] Returning information for job: 20191120005624216287
2019-11-20 00:56:48,148 [salt.loaded.ext.module.maas:1023][INFO    ][14183] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1912.4717679s left)
2019-11-20 00:56:54,311 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120005654298447
2019-11-20 00:56:54,333 [salt.minion      :1432][INFO    ][15059] Starting a new job with PID 15059
2019-11-20 00:56:54,360 [salt.minion      :1711][INFO    ][15059] Returning information for job: 20191120005654298447
2019-11-20 00:57:21,745 [salt.loaded.ext.module.maas:1023][INFO    ][14183] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1878.87412786s left)
2019-11-20 00:57:24,395 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120005724382306
2019-11-20 00:57:24,418 [salt.minion      :1432][INFO    ][15092] Starting a new job with PID 15092
2019-11-20 00:57:24,444 [salt.minion      :1711][INFO    ][15092] Returning information for job: 20191120005724382306
2019-11-20 00:57:54,484 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120005754473023
2019-11-20 00:57:54,507 [salt.minion      :1432][INFO    ][15139] Starting a new job with PID 15139
2019-11-20 00:57:54,533 [salt.minion      :1711][INFO    ][15139] Returning information for job: 20191120005754473023
2019-11-20 00:57:55,457 [salt.loaded.ext.module.maas:1023][INFO    ][14183] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1845.16232181s left)
2019-11-20 00:58:24,582 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120005824569458
2019-11-20 00:58:24,605 [salt.minion      :1432][INFO    ][15187] Starting a new job with PID 15187
2019-11-20 00:58:24,631 [salt.minion      :1711][INFO    ][15187] Returning information for job: 20191120005824569458
2019-11-20 00:58:29,102 [salt.loaded.ext.module.maas:1023][INFO    ][14183] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1811.51743984s left)
2019-11-20 00:58:54,676 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command saltutil.find_job with jid 20191120005854663620
2019-11-20 00:58:54,699 [salt.minion      :1432][INFO    ][15287] Starting a new job with PID 15287
2019-11-20 00:58:54,725 [salt.minion      :1711][INFO    ][15287] Returning information for job: 20191120005854663620
2019-11-20 00:59:02,551 [salt.state       :300 ][INFO    ][14183] {'ret': True}
2019-11-20 00:59:02,552 [salt.state       :1951][INFO    ][14183] Completed state [maas.wait_for_machine_status] at time 00:59:02.552126 duration_in_ms=471943.921
2019-11-20 00:59:02,556 [salt.minion      :1711][INFO    ][14183] Returning information for job: 20191120005108449616
2019-11-20 01:28:31,081 [salt.utils.schedule:1377][INFO    ][7332] Running scheduled job: __mine_interval
2019-11-20 02:27:34,437 [salt.minion      :1308][INFO    ][7332] User sudo_ubuntu Executing command cp.push_dir with jid 20191120022734424512
2019-11-20 02:27:34,457 [salt.minion      :1432][INFO    ][21598] Starting a new job with PID 21598
