2019-05-28 02:03:05,879 [salt.minion      :870 ][ERROR   ][388] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-28 02:04:30,723 [salt.utils.decorators:613 ][WARNING ][2143] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:04:31,821 [salt.utils.decorators:613 ][WARNING ][2143] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:04:35,651 [salt.loaded.int.states.file:2298][WARNING ][2483] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-28 02:05:02,858 [salt.state       :2022][WARNING ][2832] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-28 02:05:06,152 [salt.utils.decorators:613 ][WARNING ][2832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:05:17,125 [salt.utils.decorators:613 ][WARNING ][2832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:05:44,085 [salt.utils.decorators:613 ][WARNING ][2832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:05:45,008 [salt.utils.decorators:613 ][WARNING ][2832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:05:47,252 [salt.loaded.ext.module.maasng:1008][WARNING ][2832] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-05-28 02:05:47,252 [salt.loaded.ext.module.maasng:1011][WARNING ][2832] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-05-28 02:05:47,947 [salt.loaded.ext.module.maasng:1235][WARNING ][2832] Ignoring parameter vlan:0
2019-05-28 02:05:52,739 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5108] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-28 02:05:52,779 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5108] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-28 02:05:52,831 [salt.utils.parsers:1051][WARNING ][388] Minion received a SIGTERM. Exiting.
2019-05-28 02:05:53,976 [salt.cli.daemons :293 ][INFO    ][5181] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-05-28 02:05:54,159 [salt.cli.daemons :82  ][INFO    ][5181] Starting up the Salt Minion
2019-05-28 02:05:54,160 [salt.utils.event :1017][INFO    ][5181] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-05-28 02:05:55,394 [salt.minion      :976 ][INFO    ][5181] Creating minion process manager
2019-05-28 02:05:57,601 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5181] Executing command ['date', '+%z'] in directory '/root'
2019-05-28 02:05:57,630 [salt.utils.schedule:568 ][INFO    ][5181] Updating job settings for scheduled job: __mine_interval
2019-05-28 02:05:57,632 [salt.minion      :1108][INFO    ][5181] Added mine.update to scheduler
2019-05-28 02:05:57,637 [salt.minion      :1975][INFO    ][5181] Minion is starting as user 'root'
2019-05-28 02:05:57,658 [salt.minion      :2336][INFO    ][5181] Minion is ready to receive requests!
2019-05-28 02:06:00,764 [salt.utils.decorators:613 ][WARNING ][5111] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:06:00,815 [salt.loaded.ext.module.maas:412 ][WARNING ][5111] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-28 02:06:02,096 [salt.loaded.ext.module.maas:412 ][WARNING ][5111] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-28 02:06:03,308 [salt.loaded.ext.module.maas:412 ][WARNING ][5111] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-28 02:06:04,458 [salt.loaded.ext.module.maas:412 ][WARNING ][5111] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-28 02:06:04,927 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528020604918734
2019-05-28 02:06:04,942 [salt.minion      :1432][INFO    ][5461] Starting a new job with PID 5461
2019-05-28 02:06:04,975 [salt.minion      :1711][INFO    ][5461] Returning information for job: 20190528020604918734
2019-05-28 02:06:05,580 [salt.loaded.ext.module.maas:412 ][WARNING ][5111] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-28 02:06:38,395 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command state.apply with jid 20190528020638380864
2019-05-28 02:06:38,418 [salt.minion      :1432][INFO    ][5590] Starting a new job with PID 5590
2019-05-28 02:06:46,618 [salt.state       :915 ][INFO    ][5590] Loading fresh modules for state activity
2019-05-28 02:06:46,676 [salt.fileclient  :1219][INFO    ][5590] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-28 02:06:46,725 [salt.state       :1780][INFO    ][5590] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:46.725144
2019-05-28 02:06:46,725 [salt.state       :1813][INFO    ][5590] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-28 02:06:46,727 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5590] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-28 02:06:48,607 [salt.state       :300 ][INFO    ][5590] {'pid': 5597, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-28 02:06:48,608 [salt.state       :1951][INFO    ][5590] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:48.608743 duration_in_ms=1883.6
2019-05-28 02:06:48,612 [salt.state       :1780][INFO    ][5590] Running state [maas.wait_for_machine_status] at time 02:06:48.612703
2019-05-28 02:06:48,613 [salt.state       :1813][INFO    ][5590] Executing state module.run for [maas.wait_for_machine_status]
2019-05-28 02:06:48,615 [salt.utils.decorators:613 ][WARNING ][5590] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:06:49,459 [salt.loaded.ext.module.maas:1023][INFO    ][5590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.17093801s left)
2019-05-28 02:06:53,485 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528020653474412
2019-05-28 02:06:53,508 [salt.minion      :1432][INFO    ][5621] Starting a new job with PID 5621
2019-05-28 02:06:53,542 [salt.minion      :1711][INFO    ][5621] Returning information for job: 20190528020653474412
2019-05-28 02:07:20,302 [salt.loaded.ext.module.maas:1023][INFO    ][5590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.32758999s left)
2019-05-28 02:07:23,564 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528020723547636
2019-05-28 02:07:23,585 [salt.minion      :1432][INFO    ][5666] Starting a new job with PID 5666
2019-05-28 02:07:23,619 [salt.minion      :1711][INFO    ][5666] Returning information for job: 20190528020723547636
2019-05-28 02:07:51,090 [salt.loaded.ext.module.maas:1023][INFO    ][5590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.53959489s left)
2019-05-28 02:07:53,660 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528020753650893
2019-05-28 02:07:53,682 [salt.minion      :1432][INFO    ][5694] Starting a new job with PID 5694
2019-05-28 02:07:53,709 [salt.minion      :1711][INFO    ][5694] Returning information for job: 20190528020753650893
2019-05-28 02:08:22,007 [salt.loaded.ext.module.maas:1023][INFO    ][5590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.62284803s left)
2019-05-28 02:08:23,728 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528020823711748
2019-05-28 02:08:23,756 [salt.minion      :1432][INFO    ][5742] Starting a new job with PID 5742
2019-05-28 02:08:23,791 [salt.minion      :1711][INFO    ][5742] Returning information for job: 20190528020823711748
2019-05-28 02:08:53,026 [salt.loaded.ext.module.maas:1023][INFO    ][5590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.60342693s left)
2019-05-28 02:08:53,862 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528020853846411
2019-05-28 02:08:53,884 [salt.minion      :1432][INFO    ][5800] Starting a new job with PID 5800
2019-05-28 02:08:53,911 [salt.minion      :1711][INFO    ][5800] Returning information for job: 20190528020853846411
2019-05-28 02:09:23,969 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528020923955695
2019-05-28 02:09:23,988 [salt.minion      :1432][INFO    ][5982] Starting a new job with PID 5982
2019-05-28 02:09:24,022 [salt.minion      :1711][INFO    ][5982] Returning information for job: 20190528020923955695
2019-05-28 02:09:24,271 [salt.loaded.ext.module.maas:1023][INFO    ][5590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.35862184s left)
2019-05-28 02:09:54,088 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528020954066175
2019-05-28 02:09:54,109 [salt.minion      :1432][INFO    ][6053] Starting a new job with PID 6053
2019-05-28 02:09:54,137 [salt.minion      :1711][INFO    ][6053] Returning information for job: 20190528020954066175
2019-05-28 02:09:55,615 [salt.loaded.ext.module.maas:1023][INFO    ][5590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.01632786s left)
2019-05-28 02:10:24,212 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021024202721
2019-05-28 02:10:24,227 [salt.minion      :1432][INFO    ][6352] Starting a new job with PID 6352
2019-05-28 02:10:24,252 [salt.minion      :1711][INFO    ][6352] Returning information for job: 20190528021024202721
2019-05-28 02:10:26,860 [salt.loaded.ext.module.maas:1023][INFO    ][5590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.76932096s left)
2019-05-28 02:10:54,311 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021054297612
2019-05-28 02:10:54,342 [salt.minion      :1432][INFO    ][6475] Starting a new job with PID 6475
2019-05-28 02:10:54,372 [salt.minion      :1711][INFO    ][6475] Returning information for job: 20190528021054297612
2019-05-28 02:10:58,696 [salt.loaded.ext.module.maas:1023][INFO    ][5590] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.93378592s left)
2019-05-28 02:11:24,538 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021124522891
2019-05-28 02:11:24,562 [salt.minion      :1432][INFO    ][7016] Starting a new job with PID 7016
2019-05-28 02:11:24,595 [salt.minion      :1711][INFO    ][7016] Returning information for job: 20190528021124522891
2019-05-28 02:11:30,825 [salt.loaded.ext.module.maas:1023][INFO    ][5590] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.80499601s left)
2019-05-28 02:11:54,648 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021154635181
2019-05-28 02:11:54,672 [salt.minion      :1432][INFO    ][7064] Starting a new job with PID 7064
2019-05-28 02:11:54,705 [salt.minion      :1711][INFO    ][7064] Returning information for job: 20190528021154635181
2019-05-28 02:12:03,196 [salt.loaded.ext.module.maas:1023][INFO    ][5590] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.43318582s left)
2019-05-28 02:12:24,791 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021224780052
2019-05-28 02:12:24,817 [salt.minion      :1432][INFO    ][7206] Starting a new job with PID 7206
2019-05-28 02:12:24,845 [salt.minion      :1711][INFO    ][7206] Returning information for job: 20190528021224780052
2019-05-28 02:12:36,175 [salt.state       :300 ][INFO    ][5590] {'ret': True}
2019-05-28 02:12:36,175 [salt.state       :1951][INFO    ][5590] Completed state [maas.wait_for_machine_status] at time 02:12:36.175816 duration_in_ms=347563.112
2019-05-28 02:12:36,179 [salt.minion      :1711][INFO    ][5590] Returning information for job: 20190528020638380864
2019-05-28 02:12:36,885 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command state.apply with jid 20190528021236877244
2019-05-28 02:12:36,900 [salt.minion      :1432][INFO    ][7224] Starting a new job with PID 7224
2019-05-28 02:12:45,218 [salt.state       :915 ][INFO    ][7224] Loading fresh modules for state activity
2019-05-28 02:12:45,296 [salt.fileclient  :1219][INFO    ][7224] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-28 02:12:45,416 [salt.state       :1780][INFO    ][7224] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:45.416579
2019-05-28 02:12:45,417 [salt.state       :1813][INFO    ][7224] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-28 02:12:45,420 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7224] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-28 02:12:47,473 [salt.state       :300 ][INFO    ][7224] {'pid': 7255, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-28 02:12:47,475 [salt.state       :1951][INFO    ][7224] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:47.475301 duration_in_ms=2058.723
2019-05-28 02:12:47,479 [salt.state       :1780][INFO    ][7224] Running state [maas_machines_storage_cmp002_lvm] at time 02:12:47.479560
2019-05-28 02:12:47,480 [salt.state       :1813][INFO    ][7224] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-28 02:12:48,815 [salt.loaded.ext.module.maasng:610 ][INFO    ][7224] tybwmw
2019-05-28 02:12:48,815 [salt.loaded.ext.module.maasng:626 ][INFO    ][7224] sda
2019-05-28 02:12:49,385 [salt.loaded.ext.module.maasng:361 ][INFO    ][7224] tybwmw
2019-05-28 02:12:49,483 [salt.loaded.ext.module.maasng:367 ][INFO    ][7224] [{u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'772e3a40-34d3-4878-913c-97fe69115bd3', u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tybwmw', u'filesystem': {u'uuid': u'b4ea8940-0b04-4697-b261-eab5cd47e8d2', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}], u'id': 1, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'tybwmw', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/blockdevices/3/', u'uuid': u'2dae79b6-173f-44ec-a547-3d69830230bf', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'id': 3, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'tybwmw', u'partition_table_type': None, u'filesystem': {u'uuid': u'751f22cd-dfe7-46c4-8cab-b1e3a15bc0a6', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'model': None, u'size': 800097042432}]
2019-05-28 02:12:49,484 [salt.loaded.ext.module.maasng:632 ][INFO    ][7224] vgroot
2019-05-28 02:12:49,485 [salt.loaded.ext.module.maasng:635 ][INFO    ][7224] lvroot
2019-05-28 02:12:49,485 [salt.loaded.ext.module.maasng:639 ][INFO    ][7224] 107374182400
2019-05-28 02:12:50,100 [salt.loaded.ext.module.maasng:645 ][INFO    ][7224] {u'hwe_kernel': u'', u'status_action': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', 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': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'tybwmw', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/interfaces/4/'}, 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'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'tybwmw', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'b83eb456-9c56-4d4a-ab28-df06c906d540', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tybwmw', u'filesystem': {u'mount_options': None, u'uuid': u'376e7514-0dd1-4f8f-95f1-008a0e2c9e05', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/blockdevices/1/partition/6'}], u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/tybwmw/', u'disable_ipv4': False, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'tybwmw', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/blockdevices/11/', u'uuid': u'7b37199a-5175-41e1-aafb-72fce66eaaee', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 11, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'tybwmw', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'a61c8ef2-bfff-46fa-add2-48eca0a4f0f6', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'used_size': 107374182400, u'model': None, u'size': 107374182400}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'tybwmw', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', 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': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'tybwmw', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/interfaces/4/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:51', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'tybwmw', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/interfaces/12/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:50', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'tybwmw', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/interfaces/15/'}, {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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:49', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'tybwmw', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/interfaces/11/'}, {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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:48', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'tybwmw', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/interfaces/13/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:1c', 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': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'tybwmw', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/interfaces/14/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'tybwmw', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'b83eb456-9c56-4d4a-ab28-df06c906d540', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tybwmw', u'filesystem': {u'mount_options': None, u'uuid': u'376e7514-0dd1-4f8f-95f1-008a0e2c9e05', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/blockdevices/1/partition/6'}], u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'blockdevice_set': [{u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/blockdevices/1/', u'id': 1, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'used_size': 800106479616, u'system_id': u'tybwmw', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'b83eb456-9c56-4d4a-ab28-df06c906d540', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tybwmw', u'filesystem': {u'mount_options': None, u'uuid': u'376e7514-0dd1-4f8f-95f1-008a0e2c9e05', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/blockdevices/1/partition/6'}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'block_size': 4096, u'uuid': u'7b37199a-5175-41e1-aafb-72fce66eaaee', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/tybwmw/blockdevices/11/', u'id': 11, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'used_size': 107374182400, u'system_id': u'tybwmw', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'a61c8ef2-bfff-46fa-add2-48eca0a4f0f6', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'size': 107374182400}], u'distro_series': u'', u'memory_test_status': -1}
2019-05-28 02:12:50,104 [salt.state       :300 ][INFO    ][7224] {'new': {'storage_layout': 'lvm'}}
2019-05-28 02:12:50,105 [salt.state       :1951][INFO    ][7224] Completed state [maas_machines_storage_cmp002_lvm] at time 02:12:50.105116 duration_in_ms=2625.555
2019-05-28 02:12:50,106 [salt.state       :1780][INFO    ][7224] Running state [maas_machines_storage_cmp001_lvm] at time 02:12:50.106209
2019-05-28 02:12:50,106 [salt.state       :1813][INFO    ][7224] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-28 02:12:51,296 [salt.loaded.ext.module.maasng:610 ][INFO    ][7224] 6e877m
2019-05-28 02:12:51,297 [salt.loaded.ext.module.maasng:626 ][INFO    ][7224] sda
2019-05-28 02:12:51,900 [salt.loaded.ext.module.maasng:361 ][INFO    ][7224] 6e877m
2019-05-28 02:12:51,973 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021251959847
2019-05-28 02:12:51,997 [salt.minion      :1432][INFO    ][7283] Starting a new job with PID 7283
2019-05-28 02:12:52,004 [salt.loaded.ext.module.maasng:367 ][INFO    ][7224] [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'6e877m', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'60c99a6b-9e4a-4b1a-a682-0e43b8bf7c71', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6e877m', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'336ee97c-350e-4d9b-a88b-aa766e7aac5a', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'device_id': 2}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/blockdevices/2/'}, {u'size': 800097042432, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'bc463538-41ea-4038-9e71-e3dba16f5269', u'fstype': u'ext4'}, u'uuid': u'ddbf7c1d-fbbd-4356-930e-217c5eac2739', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6e877m', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'id': 4, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/blockdevices/4/'}]
2019-05-28 02:12:52,004 [salt.loaded.ext.module.maasng:632 ][INFO    ][7224] vgroot
2019-05-28 02:12:52,005 [salt.loaded.ext.module.maasng:635 ][INFO    ][7224] lvroot
2019-05-28 02:12:52,005 [salt.loaded.ext.module.maasng:639 ][INFO    ][7224] 107374182400
2019-05-28 02:12:52,036 [salt.minion      :1711][INFO    ][7283] Returning information for job: 20190528021251959847
2019-05-28 02:12:52,604 [salt.loaded.ext.module.maasng:645 ][INFO    ][7224] {u'hwe_kernel': u'', u'status_action': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', 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': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6e877m', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/interfaces/5/'}, 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'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'6e877m', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'da6c60af-cd8d-4a2c-9da2-2504f7e0c3d2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6e877m', u'filesystem': {u'mount_options': None, u'uuid': u'7c4252c7-7e9b-43bd-ad2d-bcd5c0d6ebae', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/blockdevices/2/partition/7'}], u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/6e877m/', u'disable_ipv4': False, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'6e877m', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/blockdevices/12/', u'uuid': u'6d527c6e-dcf7-4c3f-ad7e-4cb39a501660', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'6e877m', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'e168d87c-25d9-462d-a6b6-530a14a9534d', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'used_size': 107374182400, u'model': None, u'size': 107374182400}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'6e877m', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', 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': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6e877m', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d5', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6e877m', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/interfaces/19/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d4', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6e877m', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/interfaces/20/'}, {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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6e877m', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/interfaces/16/'}, {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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6e877m', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/interfaces/17/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', 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': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.41'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6e877m', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/interfaces/18/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'6e877m', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'da6c60af-cd8d-4a2c-9da2-2504f7e0c3d2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6e877m', u'filesystem': {u'mount_options': None, u'uuid': u'7c4252c7-7e9b-43bd-ad2d-bcd5c0d6ebae', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/blockdevices/2/partition/7'}], u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'blockdevice_set': [{u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/blockdevices/2/', u'id': 2, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'used_size': 800106479616, u'system_id': u'6e877m', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'da6c60af-cd8d-4a2c-9da2-2504f7e0c3d2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6e877m', u'filesystem': {u'mount_options': None, u'uuid': u'7c4252c7-7e9b-43bd-ad2d-bcd5c0d6ebae', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/blockdevices/2/partition/7'}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'block_size': 4096, u'uuid': u'6d527c6e-dcf7-4c3f-ad7e-4cb39a501660', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/6e877m/blockdevices/12/', u'id': 12, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'used_size': 107374182400, u'system_id': u'6e877m', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'e168d87c-25d9-462d-a6b6-530a14a9534d', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'size': 107374182400}], u'distro_series': u'', u'memory_test_status': -1}
2019-05-28 02:12:52,608 [salt.state       :300 ][INFO    ][7224] {'new': {'storage_layout': 'lvm'}}
2019-05-28 02:12:52,608 [salt.state       :1951][INFO    ][7224] Completed state [maas_machines_storage_cmp001_lvm] at time 02:12:52.608867 duration_in_ms=2502.657
2019-05-28 02:12:52,613 [salt.minion      :1711][INFO    ][7224] Returning information for job: 20190528021236877244
2019-05-28 02:12:53,363 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command state.apply with jid 20190528021253348706
2019-05-28 02:12:53,388 [salt.minion      :1432][INFO    ][7291] Starting a new job with PID 7291
2019-05-28 02:12:54,519 [salt.state       :915 ][INFO    ][7291] Loading fresh modules for state activity
2019-05-28 02:12:54,596 [salt.fileclient  :1219][INFO    ][7291] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-28 02:12:54,646 [salt.state       :1780][INFO    ][7291] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:54.646885
2019-05-28 02:12:54,647 [salt.state       :1813][INFO    ][7291] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-28 02:12:54,649 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7291] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-28 02:12:56,485 [salt.state       :300 ][INFO    ][7291] {'pid': 7298, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-28 02:12:56,487 [salt.state       :1951][INFO    ][7291] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:56.487061 duration_in_ms=1840.176
2019-05-28 02:12:56,490 [salt.state       :1780][INFO    ][7291] Running state [maas.deploy_machines] at time 02:12:56.490416
2019-05-28 02:12:56,491 [salt.state       :1813][INFO    ][7291] Executing state module.run for [maas.deploy_machines]
2019-05-28 02:12:56,492 [salt.utils.decorators:613 ][WARNING ][7291] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:12:57,054 [salt.loaded.ext.module.maas:684 ][INFO    ][7291] deploymachines hwe_kernel=ga-16.04 system_id=tybwmw distro_series=xenial
2019-05-28 02:12:59,552 [salt.loaded.ext.module.maas:684 ][INFO    ][7291] deploymachines hwe_kernel=ga-16.04 system_id=6e877m distro_series=xenial
2019-05-28 02:13:02,120 [salt.loaded.ext.module.maas:684 ][INFO    ][7291] deploymachines hwe_kernel=ga-16.04 system_id=7a7ppf distro_series=xenial
2019-05-28 02:13:04,651 [salt.loaded.ext.module.maas:684 ][INFO    ][7291] deploymachines hwe_kernel=ga-16.04 system_id=mrwqnc distro_series=xenial
2019-05-28 02:13:07,448 [salt.loaded.ext.module.maas:684 ][INFO    ][7291] deploymachines hwe_kernel=ga-16.04 system_id=4pkekm distro_series=xenial
2019-05-28 02:13:08,479 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021308468688
2019-05-28 02:13:08,501 [salt.minion      :1432][INFO    ][7576] Starting a new job with PID 7576
2019-05-28 02:13:08,529 [salt.minion      :1711][INFO    ][7576] Returning information for job: 20190528021308468688
2019-05-28 02:13:09,908 [salt.state       :300 ][INFO    ][7291] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-28 02:13:09,909 [salt.state       :1951][INFO    ][7291] Completed state [maas.deploy_machines] at time 02:13:09.909402 duration_in_ms=13418.985
2019-05-28 02:13:09,913 [salt.minion      :1711][INFO    ][7291] Returning information for job: 20190528021253348706
2019-05-28 02:13:10,651 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command state.apply with jid 20190528021310635492
2019-05-28 02:13:10,680 [salt.minion      :1432][INFO    ][7605] Starting a new job with PID 7605
2019-05-28 02:13:18,941 [salt.state       :915 ][INFO    ][7605] Loading fresh modules for state activity
2019-05-28 02:13:19,001 [salt.fileclient  :1219][INFO    ][7605] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-28 02:13:19,065 [salt.state       :1780][INFO    ][7605] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:19.065825
2019-05-28 02:13:19,066 [salt.state       :1813][INFO    ][7605] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-28 02:13:19,068 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7605] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-28 02:13:20,928 [salt.state       :300 ][INFO    ][7605] {'pid': 7622, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-28 02:13:20,930 [salt.state       :1951][INFO    ][7605] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:20.929578 duration_in_ms=1863.752
2019-05-28 02:13:20,933 [salt.state       :1780][INFO    ][7605] Running state [maas.wait_for_machine_status] at time 02:13:20.933875
2019-05-28 02:13:20,934 [salt.state       :1813][INFO    ][7605] Executing state module.run for [maas.wait_for_machine_status]
2019-05-28 02:13:20,935 [salt.utils.decorators:613 ][WARNING ][7605] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:13:23,775 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.17620993s left)
2019-05-28 02:13:25,699 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021325680531
2019-05-28 02:13:25,727 [salt.minion      :1432][INFO    ][7633] Starting a new job with PID 7633
2019-05-28 02:13:25,757 [salt.minion      :1711][INFO    ][7633] Returning information for job: 20190528021325680531
2019-05-28 02:13:55,795 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021355783405
2019-05-28 02:13:55,821 [salt.minion      :1432][INFO    ][7663] Starting a new job with PID 7663
2019-05-28 02:13:55,863 [salt.minion      :1711][INFO    ][7663] Returning information for job: 20190528021355783405
2019-05-28 02:13:57,015 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.93617296s left)
2019-05-28 02:14:25,933 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021425918096
2019-05-28 02:14:25,964 [salt.minion      :1432][INFO    ][7721] Starting a new job with PID 7721
2019-05-28 02:14:25,992 [salt.minion      :1711][INFO    ][7721] Returning information for job: 20190528021425918096
2019-05-28 02:14:29,877 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.07474089s left)
2019-05-28 02:14:56,011 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021455998871
2019-05-28 02:14:56,046 [salt.minion      :1432][INFO    ][7762] Starting a new job with PID 7762
2019-05-28 02:14:56,074 [salt.minion      :1711][INFO    ][7762] Returning information for job: 20190528021455998871
2019-05-28 02:15:02,747 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.20466685s left)
2019-05-28 02:15:26,108 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021526093958
2019-05-28 02:15:26,136 [salt.minion      :1432][INFO    ][7825] Starting a new job with PID 7825
2019-05-28 02:15:26,167 [salt.minion      :1711][INFO    ][7825] Returning information for job: 20190528021526093958
2019-05-28 02:15:35,646 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.30523396s left)
2019-05-28 02:15:56,228 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021556214102
2019-05-28 02:15:56,252 [salt.minion      :1432][INFO    ][7912] Starting a new job with PID 7912
2019-05-28 02:15:56,288 [salt.minion      :1711][INFO    ][7912] Returning information for job: 20190528021556214102
2019-05-28 02:16:09,105 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.84623981s left)
2019-05-28 02:16:26,337 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021626322907
2019-05-28 02:16:26,369 [salt.minion      :1432][INFO    ][8079] Starting a new job with PID 8079
2019-05-28 02:16:26,407 [salt.minion      :1711][INFO    ][8079] Returning information for job: 20190528021626322907
2019-05-28 02:16:42,064 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.88742995s left)
2019-05-28 02:16:56,459 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021656443436
2019-05-28 02:16:56,483 [salt.minion      :1432][INFO    ][8114] Starting a new job with PID 8114
2019-05-28 02:16:56,512 [salt.minion      :1711][INFO    ][8114] Returning information for job: 20190528021656443436
2019-05-28 02:17:15,357 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.59511089s left)
2019-05-28 02:17:26,595 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021726579573
2019-05-28 02:17:26,622 [salt.minion      :1432][INFO    ][8443] Starting a new job with PID 8443
2019-05-28 02:17:26,652 [salt.minion      :1711][INFO    ][8443] Returning information for job: 20190528021726579573
2019-05-28 02:17:48,391 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.56020379s left)
2019-05-28 02:17:56,722 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021756710238
2019-05-28 02:17:56,749 [salt.minion      :1432][INFO    ][8536] Starting a new job with PID 8536
2019-05-28 02:17:56,779 [salt.minion      :1711][INFO    ][8536] Returning information for job: 20190528021756710238
2019-05-28 02:18:21,535 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.41687584s left)
2019-05-28 02:18:26,908 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021826893305
2019-05-28 02:18:26,938 [salt.minion      :1432][INFO    ][8968] Starting a new job with PID 8968
2019-05-28 02:18:26,972 [salt.minion      :1711][INFO    ][8968] Returning information for job: 20190528021826893305
2019-05-28 02:18:54,451 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.50071096s left)
2019-05-28 02:18:57,027 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021857011626
2019-05-28 02:18:57,057 [salt.minion      :1432][INFO    ][9027] Starting a new job with PID 9027
2019-05-28 02:18:57,086 [salt.minion      :1711][INFO    ][9027] Returning information for job: 20190528021857011626
2019-05-28 02:19:27,193 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021927180693
2019-05-28 02:19:27,215 [salt.minion      :1432][INFO    ][9312] Starting a new job with PID 9312
2019-05-28 02:19:27,248 [salt.minion      :1711][INFO    ][9312] Returning information for job: 20190528021927180693
2019-05-28 02:19:27,667 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.28421283s left)
2019-05-28 02:19:57,365 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528021957347696
2019-05-28 02:19:57,393 [salt.minion      :1432][INFO    ][9358] Starting a new job with PID 9358
2019-05-28 02:19:57,425 [salt.minion      :1711][INFO    ][9358] Returning information for job: 20190528021957347696
2019-05-28 02:20:00,596 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.35598588s left)
2019-05-28 02:20:27,579 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022027564342
2019-05-28 02:20:27,608 [salt.minion      :1432][INFO    ][9733] Starting a new job with PID 9733
2019-05-28 02:20:27,640 [salt.minion      :1711][INFO    ][9733] Returning information for job: 20190528022027564342
2019-05-28 02:20:33,599 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.35314894s left)
2019-05-28 02:20:57,745 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022057728416
2019-05-28 02:20:57,771 [salt.minion      :1432][INFO    ][9763] Starting a new job with PID 9763
2019-05-28 02:20:57,809 [salt.minion      :1711][INFO    ][9763] Returning information for job: 20190528022057728416
2019-05-28 02:21:07,173 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1783.77831793s left)
2019-05-28 02:21:27,906 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022127895774
2019-05-28 02:21:27,931 [salt.minion      :1432][INFO    ][9934] Starting a new job with PID 9934
2019-05-28 02:21:27,959 [salt.minion      :1711][INFO    ][9934] Returning information for job: 20190528022127895774
2019-05-28 02:21:40,128 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1750.82392001s left)
2019-05-28 02:21:58,047 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022158033363
2019-05-28 02:21:58,076 [salt.minion      :1432][INFO    ][9966] Starting a new job with PID 9966
2019-05-28 02:21:58,107 [salt.minion      :1711][INFO    ][9966] Returning information for job: 20190528022158033363
2019-05-28 02:22:13,061 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1717.89031696s left)
2019-05-28 02:22:28,200 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022228186547
2019-05-28 02:22:28,229 [salt.minion      :1432][INFO    ][10153] Starting a new job with PID 10153
2019-05-28 02:22:28,264 [salt.minion      :1711][INFO    ][10153] Returning information for job: 20190528022228186547
2019-05-28 02:22:46,061 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1684.891083s left)
2019-05-28 02:22:58,385 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022258371075
2019-05-28 02:22:58,411 [salt.minion      :1432][INFO    ][10221] Starting a new job with PID 10221
2019-05-28 02:22:58,439 [salt.minion      :1711][INFO    ][10221] Returning information for job: 20190528022258371075
2019-05-28 02:23:19,057 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1651.89495683s left)
2019-05-28 02:23:28,604 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022328587548
2019-05-28 02:23:28,633 [salt.minion      :1432][INFO    ][10450] Starting a new job with PID 10450
2019-05-28 02:23:28,664 [salt.minion      :1711][INFO    ][10450] Returning information for job: 20190528022328587548
2019-05-28 02:23:52,097 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1618.854491s left)
2019-05-28 02:23:58,777 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022358760480
2019-05-28 02:23:58,807 [salt.minion      :1432][INFO    ][10480] Starting a new job with PID 10480
2019-05-28 02:23:58,839 [salt.minion      :1711][INFO    ][10480] Returning information for job: 20190528022358760480
2019-05-28 02:24:24,980 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1585.97190189s left)
2019-05-28 02:24:28,973 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022428956386
2019-05-28 02:24:29,002 [salt.minion      :1432][INFO    ][10525] Starting a new job with PID 10525
2019-05-28 02:24:29,033 [salt.minion      :1711][INFO    ][10525] Returning information for job: 20190528022428956386
2019-05-28 02:24:57,958 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1552.99320102s left)
2019-05-28 02:24:59,180 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022459164484
2019-05-28 02:24:59,206 [salt.minion      :1432][INFO    ][10690] Starting a new job with PID 10690
2019-05-28 02:24:59,239 [salt.minion      :1711][INFO    ][10690] Returning information for job: 20190528022459164484
2019-05-28 02:25:29,396 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022529383248
2019-05-28 02:25:29,418 [salt.minion      :1432][INFO    ][10768] Starting a new job with PID 10768
2019-05-28 02:25:29,445 [salt.minion      :1711][INFO    ][10768] Returning information for job: 20190528022529383248
2019-05-28 02:25:30,991 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1519.96058893s left)
2019-05-28 02:25:59,488 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022559480755
2019-05-28 02:25:59,508 [salt.minion      :1432][INFO    ][10849] Starting a new job with PID 10849
2019-05-28 02:25:59,540 [salt.minion      :1711][INFO    ][10849] Returning information for job: 20190528022559480755
2019-05-28 02:26:03,877 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1487.07497001s left)
2019-05-28 02:26:29,677 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022629660756
2019-05-28 02:26:29,704 [salt.minion      :1432][INFO    ][11082] Starting a new job with PID 11082
2019-05-28 02:26:29,735 [salt.minion      :1711][INFO    ][11082] Returning information for job: 20190528022629660756
2019-05-28 02:26:37,008 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1453.94351101s left)
2019-05-28 02:26:59,891 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022659879545
2019-05-28 02:26:59,920 [salt.minion      :1432][INFO    ][11109] Starting a new job with PID 11109
2019-05-28 02:26:59,947 [salt.minion      :1711][INFO    ][11109] Returning information for job: 20190528022659879545
2019-05-28 02:27:09,934 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1421.01732492s left)
2019-05-28 02:27:29,933 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022729918735
2019-05-28 02:27:29,961 [salt.minion      :1432][INFO    ][11210] Starting a new job with PID 11210
2019-05-28 02:27:29,991 [salt.minion      :1711][INFO    ][11210] Returning information for job: 20190528022729918735
2019-05-28 02:27:42,779 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1388.17266083s left)
2019-05-28 02:27:59,988 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022759981373
2019-05-28 02:28:00,008 [salt.minion      :1432][INFO    ][11237] Starting a new job with PID 11237
2019-05-28 02:28:00,042 [salt.minion      :1711][INFO    ][11237] Returning information for job: 20190528022759981373
2019-05-28 02:28:15,759 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1355.19437099s left)
2019-05-28 02:28:30,041 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022830025332
2019-05-28 02:28:30,072 [salt.minion      :1432][INFO    ][11283] Starting a new job with PID 11283
2019-05-28 02:28:30,101 [salt.minion      :1711][INFO    ][11283] Returning information for job: 20190528022830025332
2019-05-28 02:28:48,978 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1321.97339487s left)
2019-05-28 02:29:00,125 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022900111438
2019-05-28 02:29:00,153 [salt.minion      :1432][INFO    ][11319] Starting a new job with PID 11319
2019-05-28 02:29:00,179 [salt.minion      :1711][INFO    ][11319] Returning information for job: 20190528022900111438
2019-05-28 02:29:21,730 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1289.22189093s left)
2019-05-28 02:29:30,197 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528022930179443
2019-05-28 02:29:30,225 [salt.minion      :1432][INFO    ][11366] Starting a new job with PID 11366
2019-05-28 02:29:30,251 [salt.minion      :1711][INFO    ][11366] Returning information for job: 20190528022930179443
2019-05-28 02:29:54,586 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1256.36515999s left)
2019-05-28 02:30:00,291 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023000278580
2019-05-28 02:30:00,322 [salt.minion      :1432][INFO    ][11395] Starting a new job with PID 11395
2019-05-28 02:30:00,353 [salt.minion      :1711][INFO    ][11395] Returning information for job: 20190528023000278580
2019-05-28 02:30:27,330 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1223.62125897s left)
2019-05-28 02:30:30,369 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023030352998
2019-05-28 02:30:30,400 [salt.minion      :1432][INFO    ][11448] Starting a new job with PID 11448
2019-05-28 02:30:30,429 [salt.minion      :1711][INFO    ][11448] Returning information for job: 20190528023030352998
2019-05-28 02:31:00,191 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1190.76089096s left)
2019-05-28 02:31:00,511 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023100498641
2019-05-28 02:31:00,540 [salt.minion      :1432][INFO    ][11478] Starting a new job with PID 11478
2019-05-28 02:31:00,572 [salt.minion      :1711][INFO    ][11478] Returning information for job: 20190528023100498641
2019-05-28 02:31:30,635 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023130619047
2019-05-28 02:31:30,659 [salt.minion      :1432][INFO    ][11521] Starting a new job with PID 11521
2019-05-28 02:31:30,693 [salt.minion      :1711][INFO    ][11521] Returning information for job: 20190528023130619047
2019-05-28 02:31:33,127 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1157.8249929s left)
2019-05-28 02:32:00,804 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023200788106
2019-05-28 02:32:00,834 [salt.minion      :1432][INFO    ][11555] Starting a new job with PID 11555
2019-05-28 02:32:00,863 [salt.minion      :1711][INFO    ][11555] Returning information for job: 20190528023200788106
2019-05-28 02:32:05,932 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1125.02013087s left)
2019-05-28 02:32:30,967 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023230951812
2019-05-28 02:32:30,992 [salt.minion      :1432][INFO    ][11603] Starting a new job with PID 11603
2019-05-28 02:32:31,027 [salt.minion      :1711][INFO    ][11603] Returning information for job: 20190528023230951812
2019-05-28 02:32:38,686 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1092.266047s left)
2019-05-28 02:33:01,161 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023301148443
2019-05-28 02:33:01,193 [salt.minion      :1432][INFO    ][11634] Starting a new job with PID 11634
2019-05-28 02:33:01,224 [salt.minion      :1711][INFO    ][11634] Returning information for job: 20190528023301148443
2019-05-28 02:33:11,576 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1059.3754909s left)
2019-05-28 02:33:31,352 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023331341008
2019-05-28 02:33:31,375 [salt.minion      :1432][INFO    ][11681] Starting a new job with PID 11681
2019-05-28 02:33:31,408 [salt.minion      :1711][INFO    ][11681] Returning information for job: 20190528023331341008
2019-05-28 02:33:44,397 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1026.55474186s left)
2019-05-28 02:34:01,542 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023401529818
2019-05-28 02:34:01,572 [salt.minion      :1432][INFO    ][11711] Starting a new job with PID 11711
2019-05-28 02:34:01,604 [salt.minion      :1711][INFO    ][11711] Returning information for job: 20190528023401529818
2019-05-28 02:34:17,196 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (993.755666971s left)
2019-05-28 02:34:31,561 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023431545894
2019-05-28 02:34:31,594 [salt.minion      :1432][INFO    ][11760] Starting a new job with PID 11760
2019-05-28 02:34:31,624 [salt.minion      :1711][INFO    ][11760] Returning information for job: 20190528023431545894
2019-05-28 02:34:50,137 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (960.815004826s left)
2019-05-28 02:35:01,765 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023501751570
2019-05-28 02:35:01,795 [salt.minion      :1432][INFO    ][11803] Starting a new job with PID 11803
2019-05-28 02:35:01,827 [salt.minion      :1711][INFO    ][11803] Returning information for job: 20190528023501751570
2019-05-28 02:35:23,116 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (927.835212946s left)
2019-05-28 02:35:31,822 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023531814129
2019-05-28 02:35:31,848 [salt.minion      :1432][INFO    ][11864] Starting a new job with PID 11864
2019-05-28 02:35:31,878 [salt.minion      :1711][INFO    ][11864] Returning information for job: 20190528023531814129
2019-05-28 02:35:55,864 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (895.087559938s left)
2019-05-28 02:36:02,040 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023602028240
2019-05-28 02:36:02,063 [salt.minion      :1432][INFO    ][11899] Starting a new job with PID 11899
2019-05-28 02:36:02,091 [salt.minion      :1711][INFO    ][11899] Returning information for job: 20190528023602028240
2019-05-28 02:36:28,679 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (862.272843838s left)
2019-05-28 02:36:32,096 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023632085658
2019-05-28 02:36:32,123 [salt.minion      :1432][INFO    ][11948] Starting a new job with PID 11948
2019-05-28 02:36:32,159 [salt.minion      :1711][INFO    ][11948] Returning information for job: 20190528023632085658
2019-05-28 02:37:01,530 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (829.421900988s left)
2019-05-28 02:37:02,165 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023702148409
2019-05-28 02:37:02,194 [salt.minion      :1432][INFO    ][11980] Starting a new job with PID 11980
2019-05-28 02:37:02,227 [salt.minion      :1711][INFO    ][11980] Returning information for job: 20190528023702148409
2019-05-28 02:37:32,263 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023732251038
2019-05-28 02:37:32,286 [salt.minion      :1432][INFO    ][12022] Starting a new job with PID 12022
2019-05-28 02:37:32,317 [salt.minion      :1711][INFO    ][12022] Returning information for job: 20190528023732251038
2019-05-28 02:37:34,416 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (796.535903931s left)
2019-05-28 02:38:02,366 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023802350909
2019-05-28 02:38:02,394 [salt.minion      :1432][INFO    ][12048] Starting a new job with PID 12048
2019-05-28 02:38:02,427 [salt.minion      :1711][INFO    ][12048] Returning information for job: 20190528023802350909
2019-05-28 02:38:07,313 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (763.638671875s left)
2019-05-28 02:38:32,552 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023832537611
2019-05-28 02:38:32,580 [salt.minion      :1432][INFO    ][12095] Starting a new job with PID 12095
2019-05-28 02:38:32,615 [salt.minion      :1711][INFO    ][12095] Returning information for job: 20190528023832537611
2019-05-28 02:38:40,102 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (730.849419832s left)
2019-05-28 02:39:02,713 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023902704020
2019-05-28 02:39:02,733 [salt.minion      :1432][INFO    ][12128] Starting a new job with PID 12128
2019-05-28 02:39:02,763 [salt.minion      :1711][INFO    ][12128] Returning information for job: 20190528023902704020
2019-05-28 02:39:12,939 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (698.013062s left)
2019-05-28 02:39:32,877 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528023932868053
2019-05-28 02:39:32,903 [salt.minion      :1432][INFO    ][12171] Starting a new job with PID 12171
2019-05-28 02:39:32,935 [salt.minion      :1711][INFO    ][12171] Returning information for job: 20190528023932868053
2019-05-28 02:39:45,762 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (665.190459013s left)
2019-05-28 02:40:03,060 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024003048630
2019-05-28 02:40:03,090 [salt.minion      :1432][INFO    ][12202] Starting a new job with PID 12202
2019-05-28 02:40:03,119 [salt.minion      :1711][INFO    ][12202] Returning information for job: 20190528024003048630
2019-05-28 02:40:18,632 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (632.319984913s left)
2019-05-28 02:40:33,268 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024033251979
2019-05-28 02:40:33,297 [salt.minion      :1432][INFO    ][12251] Starting a new job with PID 12251
2019-05-28 02:40:33,327 [salt.minion      :1711][INFO    ][12251] Returning information for job: 20190528024033251979
2019-05-28 02:40:51,382 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (599.569541931s left)
2019-05-28 02:41:03,309 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024103294532
2019-05-28 02:41:03,340 [salt.minion      :1432][INFO    ][12282] Starting a new job with PID 12282
2019-05-28 02:41:03,368 [salt.minion      :1711][INFO    ][12282] Returning information for job: 20190528024103294532
2019-05-28 02:41:24,371 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (566.580709934s left)
2019-05-28 02:41:33,511 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024133496867
2019-05-28 02:41:33,539 [salt.minion      :1432][INFO    ][12332] Starting a new job with PID 12332
2019-05-28 02:41:33,569 [salt.minion      :1711][INFO    ][12332] Returning information for job: 20190528024133496867
2019-05-28 02:41:57,104 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (533.847756863s left)
2019-05-28 02:42:03,601 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024203584788
2019-05-28 02:42:03,629 [salt.minion      :1432][INFO    ][12362] Starting a new job with PID 12362
2019-05-28 02:42:03,661 [salt.minion      :1711][INFO    ][12362] Returning information for job: 20190528024203584788
2019-05-28 02:42:29,831 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (501.120666981s left)
2019-05-28 02:42:33,673 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024233660822
2019-05-28 02:42:33,705 [salt.minion      :1432][INFO    ][12410] Starting a new job with PID 12410
2019-05-28 02:42:33,738 [salt.minion      :1711][INFO    ][12410] Returning information for job: 20190528024233660822
2019-05-28 02:43:02,716 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (468.235225916s left)
2019-05-28 02:43:03,820 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024303804912
2019-05-28 02:43:03,847 [salt.minion      :1432][INFO    ][12445] Starting a new job with PID 12445
2019-05-28 02:43:03,875 [salt.minion      :1711][INFO    ][12445] Returning information for job: 20190528024303804912
2019-05-28 02:43:33,946 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024333937320
2019-05-28 02:43:33,969 [salt.minion      :1432][INFO    ][12491] Starting a new job with PID 12491
2019-05-28 02:43:34,000 [salt.minion      :1711][INFO    ][12491] Returning information for job: 20190528024333937320
2019-05-28 02:43:35,411 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (435.540656805s left)
2019-05-28 02:44:04,107 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024404090112
2019-05-28 02:44:04,135 [salt.minion      :1432][INFO    ][12517] Starting a new job with PID 12517
2019-05-28 02:44:04,165 [salt.minion      :1711][INFO    ][12517] Returning information for job: 20190528024404090112
2019-05-28 02:44:08,728 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (402.223145962s left)
2019-05-28 02:44:34,269 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024434252957
2019-05-28 02:44:34,297 [salt.minion      :1432][INFO    ][12570] Starting a new job with PID 12570
2019-05-28 02:44:34,329 [salt.minion      :1711][INFO    ][12570] Returning information for job: 20190528024434252957
2019-05-28 02:44:41,468 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (369.484012842s left)
2019-05-28 02:45:04,423 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024504407658
2019-05-28 02:45:04,450 [salt.minion      :1432][INFO    ][12737] Starting a new job with PID 12737
2019-05-28 02:45:04,487 [salt.minion      :1711][INFO    ][12737] Returning information for job: 20190528024504407658
2019-05-28 02:45:14,353 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (336.598681927s left)
2019-05-28 02:45:34,596 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024534586855
2019-05-28 02:45:34,618 [salt.minion      :1432][INFO    ][12797] Starting a new job with PID 12797
2019-05-28 02:45:34,650 [salt.minion      :1711][INFO    ][12797] Returning information for job: 20190528024534586855
2019-05-28 02:45:47,068 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (303.883985996s left)
2019-05-28 02:46:04,627 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024604616198
2019-05-28 02:46:04,656 [salt.minion      :1432][INFO    ][12830] Starting a new job with PID 12830
2019-05-28 02:46:04,686 [salt.minion      :1711][INFO    ][12830] Returning information for job: 20190528024604616198
2019-05-28 02:46:19,819 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (271.132787943s left)
2019-05-28 02:46:34,657 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024634646817
2019-05-28 02:46:34,687 [salt.minion      :1432][INFO    ][12877] Starting a new job with PID 12877
2019-05-28 02:46:34,719 [salt.minion      :1711][INFO    ][12877] Returning information for job: 20190528024634646817
2019-05-28 02:46:52,570 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (238.382116795s left)
2019-05-28 02:47:04,684 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024704669069
2019-05-28 02:47:04,710 [salt.minion      :1432][INFO    ][12913] Starting a new job with PID 12913
2019-05-28 02:47:04,741 [salt.minion      :1711][INFO    ][12913] Returning information for job: 20190528024704669069
2019-05-28 02:47:25,596 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (205.355741024s left)
2019-05-28 02:47:34,731 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024734716804
2019-05-28 02:47:34,758 [salt.minion      :1432][INFO    ][12960] Starting a new job with PID 12960
2019-05-28 02:47:34,788 [salt.minion      :1711][INFO    ][12960] Returning information for job: 20190528024734716804
2019-05-28 02:47:58,340 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (172.611771822s left)
2019-05-28 02:48:04,794 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024804778218
2019-05-28 02:48:04,823 [salt.minion      :1432][INFO    ][12988] Starting a new job with PID 12988
2019-05-28 02:48:04,855 [salt.minion      :1711][INFO    ][12988] Returning information for job: 20190528024804778218
2019-05-28 02:48:31,075 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (139.876876831s left)
2019-05-28 02:48:34,927 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024834911559
2019-05-28 02:48:34,955 [salt.minion      :1432][INFO    ][13033] Starting a new job with PID 13033
2019-05-28 02:48:34,984 [salt.minion      :1711][INFO    ][13033] Returning information for job: 20190528024834911559
2019-05-28 02:49:04,002 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (106.950325966s left)
2019-05-28 02:49:05,050 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024905032745
2019-05-28 02:49:05,079 [salt.minion      :1432][INFO    ][13065] Starting a new job with PID 13065
2019-05-28 02:49:05,111 [salt.minion      :1711][INFO    ][13065] Returning information for job: 20190528024905032745
2019-05-28 02:49:35,214 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528024935199549
2019-05-28 02:49:35,238 [salt.minion      :1432][INFO    ][13107] Starting a new job with PID 13107
2019-05-28 02:49:35,265 [salt.minion      :1711][INFO    ][13107] Returning information for job: 20190528024935199549
2019-05-28 02:49:36,812 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (74.1394050121s left)
2019-05-28 02:50:05,347 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025005331898
2019-05-28 02:50:05,370 [salt.minion      :1432][INFO    ][13133] Starting a new job with PID 13133
2019-05-28 02:50:05,399 [salt.minion      :1711][INFO    ][13133] Returning information for job: 20190528025005331898
2019-05-28 02:50:09,773 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (41.1787397861s left)
2019-05-28 02:50:35,535 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025035526646
2019-05-28 02:50:35,560 [salt.minion      :1432][INFO    ][13183] Starting a new job with PID 13183
2019-05-28 02:50:35,592 [salt.minion      :1711][INFO    ][13183] Returning information for job: 20190528025035526646
2019-05-28 02:50:42,672 [salt.loaded.ext.module.maas:1023][INFO    ][7605] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (8.27978801727s left)
2019-05-28 02:51:05,711 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025105699214
2019-05-28 02:51:05,732 [salt.minion      :1432][INFO    ][13215] Starting a new job with PID 13215
2019-05-28 02:51:05,760 [salt.minion      :1711][INFO    ][13215] Returning information for job: 20190528025105699214
2019-05-28 02:51:15,730 [salt.state       :302 ][ERROR   ][7605] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-28 02:51:15,731 [salt.state       :1951][INFO    ][7605] Completed state [maas.wait_for_machine_status] at time 02:51:15.731332 duration_in_ms=2274797.451
2019-05-28 02:51:15,738 [salt.minion      :1711][INFO    ][7605] Returning information for job: 20190528021310635492
2019-05-28 02:51:26,719 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command pillar.get with jid 20190528025126708629
2019-05-28 02:51:26,747 [salt.minion      :1432][INFO    ][13261] Starting a new job with PID 13261
2019-05-28 02:51:26,756 [salt.minion      :1711][INFO    ][13261] Returning information for job: 20190528025126708629
2019-05-28 02:51:27,465 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command service.status with jid 20190528025127451637
2019-05-28 02:51:27,489 [salt.minion      :1432][INFO    ][13266] Starting a new job with PID 13266
2019-05-28 02:51:28,315 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13266] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-28 02:51:28,365 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13266] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-28 02:51:28,389 [salt.minion      :1711][INFO    ][13266] Returning information for job: 20190528025127451637
2019-05-28 02:51:29,131 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command state.apply with jid 20190528025129117914
2019-05-28 02:51:29,159 [salt.minion      :1432][INFO    ][13277] Starting a new job with PID 13277
2019-05-28 02:51:37,464 [salt.state       :915 ][INFO    ][13277] Loading fresh modules for state activity
2019-05-28 02:51:38,098 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13277] Executing command 'salt-minion --version' in directory '/root'
2019-05-28 02:51:38,467 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13277] Executing command 'salt-minion --version' in directory '/root'
2019-05-28 02:51:39,598 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13277] Executing command 'salt-minion --version' in directory '/root'
2019-05-28 02:51:39,958 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13277] Executing command 'salt-minion --version' in directory '/root'
2019-05-28 02:51:42,268 [salt.state       :1780][INFO    ][13277] Running state [salt-minion] at time 02:51:42.268303
2019-05-28 02:51:42,268 [salt.state       :1813][INFO    ][13277] Executing state pkg.installed for [salt-minion]
2019-05-28 02:51:42,269 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13277] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-28 02:51:42,379 [salt.state       :300 ][INFO    ][13277] All specified packages are already installed
2019-05-28 02:51:42,380 [salt.state       :1951][INFO    ][13277] Completed state [salt-minion] at time 02:51:42.380250 duration_in_ms=111.948
2019-05-28 02:51:42,380 [salt.state       :1780][INFO    ][13277] Running state [salt_minion_dependency_packages] at time 02:51:42.380618
2019-05-28 02:51:42,380 [salt.state       :1813][INFO    ][13277] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-28 02:51:42,391 [salt.state       :300 ][INFO    ][13277] All specified packages are already installed
2019-05-28 02:51:42,391 [salt.state       :1951][INFO    ][13277] Completed state [salt_minion_dependency_packages] at time 02:51:42.391497 duration_in_ms=10.878
2019-05-28 02:51:42,394 [salt.state       :1780][INFO    ][13277] Running state [/etc/salt/minion.d/minion.conf] at time 02:51:42.394700
2019-05-28 02:51:42,395 [salt.state       :1813][INFO    ][13277] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-28 02:51:42,682 [salt.state       :300 ][INFO    ][13277] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-28 02:51:42,682 [salt.state       :1951][INFO    ][13277] Completed state [/etc/salt/minion.d/minion.conf] at time 02:51:42.682440 duration_in_ms=287.74
2019-05-28 02:51:42,682 [salt.state       :1780][INFO    ][13277] Running state [python-netaddr] at time 02:51:42.682743
2019-05-28 02:51:42,683 [salt.state       :1813][INFO    ][13277] Executing state pkg.installed for [python-netaddr]
2019-05-28 02:51:42,692 [salt.state       :300 ][INFO    ][13277] All specified packages are already installed
2019-05-28 02:51:42,692 [salt.state       :1951][INFO    ][13277] Completed state [python-netaddr] at time 02:51:42.692331 duration_in_ms=9.588
2019-05-28 02:51:42,695 [salt.state       :1780][INFO    ][13277] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:51:42.695558
2019-05-28 02:51:42,695 [salt.state       :1813][INFO    ][13277] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-28 02:51:42,709 [salt.state       :300 ][INFO    ][13277] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-28 02:51:42,711 [salt.state       :1951][INFO    ][13277] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:51:42.711118 duration_in_ms=15.56
2019-05-28 02:51:42,712 [salt.state       :1780][INFO    ][13277] Running state [salt-minion] at time 02:51:42.712311
2019-05-28 02:51:42,712 [salt.state       :1813][INFO    ][13277] Executing state service.running for [salt-minion]
2019-05-28 02:51:42,713 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13277] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-28 02:51:42,759 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13277] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-28 02:51:42,779 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13277] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-28 02:51:42,801 [salt.state       :300 ][INFO    ][13277] The service salt-minion is already running
2019-05-28 02:51:42,802 [salt.state       :1951][INFO    ][13277] Completed state [salt-minion] at time 02:51:42.802325 duration_in_ms=90.014
2019-05-28 02:51:42,804 [salt.state       :1780][INFO    ][13277] Running state [/etc/salt/grains.d] at time 02:51:42.804815
2019-05-28 02:51:42,805 [salt.state       :1813][INFO    ][13277] Executing state file.directory for [/etc/salt/grains.d]
2019-05-28 02:51:42,809 [salt.state       :300 ][INFO    ][13277] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-28 02:51:42,809 [salt.state       :1951][INFO    ][13277] Completed state [/etc/salt/grains.d] at time 02:51:42.809381 duration_in_ms=4.566
2019-05-28 02:51:42,811 [salt.state       :1780][INFO    ][13277] Running state [/etc/salt/grains] at time 02:51:42.810940
2019-05-28 02:51:42,811 [salt.state       :1813][INFO    ][13277] Executing state file.managed for [/etc/salt/grains]
2019-05-28 02:51:42,812 [salt.state       :300 ][INFO    ][13277] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-28 02:51:42,812 [salt.state       :1951][INFO    ][13277] Completed state [/etc/salt/grains] at time 02:51:42.812349 duration_in_ms=1.409
2019-05-28 02:51:42,813 [salt.state       :1780][INFO    ][13277] Running state [/etc/salt/grains.d/placeholder] at time 02:51:42.813031
2019-05-28 02:51:42,813 [salt.state       :1813][INFO    ][13277] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-28 02:51:42,814 [salt.state       :300 ][INFO    ][13277] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-28 02:51:42,814 [salt.state       :1951][INFO    ][13277] Completed state [/etc/salt/grains.d/placeholder] at time 02:51:42.814861 duration_in_ms=1.83
2019-05-28 02:51:42,815 [salt.state       :1780][INFO    ][13277] Running state [/etc/salt/grains.d/sphinx] at time 02:51:42.815535
2019-05-28 02:51:42,815 [salt.state       :1813][INFO    ][13277] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-28 02:51:42,817 [salt.state       :300 ][INFO    ][13277] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-28 02:51:42,820 [salt.state       :1951][INFO    ][13277] Completed state [/etc/salt/grains.d/sphinx] at time 02:51:42.820249 duration_in_ms=4.714
2019-05-28 02:51:42,824 [salt.state       :1780][INFO    ][13277] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:51:42.824322
2019-05-28 02:51:42,824 [salt.state       :1813][INFO    ][13277] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-28 02:51:42,825 [salt.state       :300 ][INFO    ][13277] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-28 02:51:42,826 [salt.state       :1951][INFO    ][13277] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:51:42.826201 duration_in_ms=1.88
2019-05-28 02:51:42,827 [salt.state       :1780][INFO    ][13277] Running state [/etc/salt/grains.d/dns_records] at time 02:51:42.827083
2019-05-28 02:51:42,827 [salt.state       :1813][INFO    ][13277] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-28 02:51:42,829 [salt.state       :300 ][INFO    ][13277] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-28 02:51:42,831 [salt.state       :1951][INFO    ][13277] Completed state [/etc/salt/grains.d/dns_records] at time 02:51:42.831711 duration_in_ms=4.627
2019-05-28 02:51:42,834 [salt.state       :1780][INFO    ][13277] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:51:42.833641
2019-05-28 02:51:42,834 [salt.state       :1813][INFO    ][13277] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-28 02:51:42,834 [salt.state       :300 ][INFO    ][13277] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-28 02:51:42,835 [salt.state       :1951][INFO    ][13277] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:51:42.835279 duration_in_ms=1.638
2019-05-28 02:51:42,836 [salt.state       :1780][INFO    ][13277] Running state [/etc/salt/grains.d/salt] at time 02:51:42.836093
2019-05-28 02:51:42,836 [salt.state       :1813][INFO    ][13277] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-28 02:51:42,838 [salt.state       :300 ][INFO    ][13277] File /etc/salt/grains.d/salt is in the correct state
2019-05-28 02:51:42,838 [salt.state       :1951][INFO    ][13277] Completed state [/etc/salt/grains.d/salt] at time 02:51:42.838678 duration_in_ms=2.585
2019-05-28 02:51:42,840 [salt.state       :1780][INFO    ][13277] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:51:42.840114
2019-05-28 02:51:42,840 [salt.state       :1813][INFO    ][13277] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-28 02:51:42,841 [salt.state       :300 ][INFO    ][13277] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-28 02:51:42,841 [salt.state       :1951][INFO    ][13277] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:51:42.841443 duration_in_ms=1.329
2019-05-28 02:51:42,845 [salt.state       :1780][INFO    ][13277] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:51:42.845223
2019-05-28 02:51:42,845 [salt.state       :1813][INFO    ][13277] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-28 02:51:42,845 [salt.state       :300 ][INFO    ][13277] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-28 02:51:42,846 [salt.state       :1951][INFO    ][13277] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:51:42.846130 duration_in_ms=0.907
2019-05-28 02:51:42,846 [salt.state       :1780][INFO    ][13277] Running state [mine.update] at time 02:51:42.846845
2019-05-28 02:51:42,847 [salt.state       :1813][INFO    ][13277] Executing state module.wait for [mine.update]
2019-05-28 02:51:42,847 [salt.state       :300 ][INFO    ][13277] No changes made for mine.update
2019-05-28 02:51:42,847 [salt.state       :1951][INFO    ][13277] Completed state [mine.update] at time 02:51:42.847648 duration_in_ms=0.803
2019-05-28 02:51:42,847 [salt.state       :1780][INFO    ][13277] Running state [ca-certificates] at time 02:51:42.847928
2019-05-28 02:51:42,848 [salt.state       :1813][INFO    ][13277] Executing state pkg.installed for [ca-certificates]
2019-05-28 02:51:42,858 [salt.state       :300 ][INFO    ][13277] All specified packages are already installed
2019-05-28 02:51:42,859 [salt.state       :1951][INFO    ][13277] Completed state [ca-certificates] at time 02:51:42.859154 duration_in_ms=11.225
2019-05-28 02:51:42,859 [salt.state       :1780][INFO    ][13277] Running state [update-ca-certificates] at time 02:51:42.859880
2019-05-28 02:51:42,860 [salt.state       :1813][INFO    ][13277] Executing state cmd.wait for [update-ca-certificates]
2019-05-28 02:51:42,860 [salt.state       :300 ][INFO    ][13277] No changes made for update-ca-certificates
2019-05-28 02:51:42,860 [salt.state       :1951][INFO    ][13277] Completed state [update-ca-certificates] at time 02:51:42.860711 duration_in_ms=0.831
2019-05-28 02:51:42,861 [salt.state       :1780][INFO    ][13277] Running state [iptables] at time 02:51:42.861003
2019-05-28 02:51:42,861 [salt.state       :1813][INFO    ][13277] Executing state pkg.installed for [iptables]
2019-05-28 02:51:42,871 [salt.state       :300 ][INFO    ][13277] All specified packages are already installed
2019-05-28 02:51:42,872 [salt.state       :1951][INFO    ][13277] Completed state [iptables] at time 02:51:42.872065 duration_in_ms=11.061
2019-05-28 02:51:42,872 [salt.state       :1780][INFO    ][13277] Running state [iptables-persistent] at time 02:51:42.872350
2019-05-28 02:51:42,872 [salt.state       :1813][INFO    ][13277] Executing state pkg.installed for [iptables-persistent]
2019-05-28 02:51:42,882 [salt.state       :300 ][INFO    ][13277] All specified packages are already installed
2019-05-28 02:51:42,882 [salt.state       :1951][INFO    ][13277] Completed state [iptables-persistent] at time 02:51:42.882340 duration_in_ms=9.989
2019-05-28 02:51:42,883 [salt.state       :1780][INFO    ][13277] Running state [iptables_modules_v4_load] at time 02:51:42.883430
2019-05-28 02:51:42,883 [salt.state       :1813][INFO    ][13277] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-28 02:51:42,884 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13277] Executing command 'lsmod' in directory '/root'
2019-05-28 02:51:42,907 [salt.state       :300 ][INFO    ][13277] Kernel modules iptable_filter, ip_tables are already present
2019-05-28 02:51:42,907 [salt.state       :1951][INFO    ][13277] Completed state [iptables_modules_v4_load] at time 02:51:42.907629 duration_in_ms=24.198
2019-05-28 02:51:42,909 [salt.state       :1780][INFO    ][13277] Running state [/etc/iptables/rules.v4] at time 02:51:42.908931
2019-05-28 02:51:42,909 [salt.state       :1813][INFO    ][13277] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-28 02:51:43,027 [salt.state       :300 ][INFO    ][13277] File /etc/iptables/rules.v4 is in the correct state
2019-05-28 02:51:43,027 [salt.state       :1951][INFO    ][13277] Completed state [/etc/iptables/rules.v4] at time 02:51:43.027635 duration_in_ms=118.704
2019-05-28 02:51:43,028 [salt.state       :1780][INFO    ][13277] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:51:43.028794
2019-05-28 02:51:43,029 [salt.state       :1813][INFO    ][13277] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-28 02:51:43,029 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13277] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-28 02:51:43,053 [salt.state       :300 ][INFO    ][13277] onlyif execution failed
2019-05-28 02:51:43,054 [salt.state       :1951][INFO    ][13277] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:51:43.054065 duration_in_ms=25.27
2019-05-28 02:51:43,056 [salt.state       :1780][INFO    ][13277] Running state [netfilter-persistent] at time 02:51:43.056250
2019-05-28 02:51:43,057 [salt.state       :1813][INFO    ][13277] Executing state service.running for [netfilter-persistent]
2019-05-28 02:51:43,061 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13277] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-28 02:51:43,085 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13277] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-28 02:51:43,109 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13277] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-28 02:51:43,126 [salt.state       :300 ][INFO    ][13277] The service netfilter-persistent is already running
2019-05-28 02:51:43,127 [salt.state       :1951][INFO    ][13277] Completed state [netfilter-persistent] at time 02:51:43.127129 duration_in_ms=70.88
2019-05-28 02:51:43,128 [salt.state       :1780][INFO    ][13277] Running state [iptables_extra.remove_stale_tables] at time 02:51:43.128059
2019-05-28 02:51:43,128 [salt.state       :1813][INFO    ][13277] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-28 02:51:43,128 [salt.state       :300 ][INFO    ][13277] No changes made for iptables_extra.remove_stale_tables
2019-05-28 02:51:43,129 [salt.state       :1951][INFO    ][13277] Completed state [iptables_extra.remove_stale_tables] at time 02:51:43.128984 duration_in_ms=0.925
2019-05-28 02:51:43,129 [salt.state       :1780][INFO    ][13277] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:51:43.129279
2019-05-28 02:51:43,129 [salt.state       :1813][INFO    ][13277] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-28 02:51:43,132 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13277] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-28 02:51:43,150 [salt.state       :300 ][INFO    ][13277] onlyif execution failed
2019-05-28 02:51:43,150 [salt.state       :1951][INFO    ][13277] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:51:43.150658 duration_in_ms=21.38
2019-05-28 02:51:43,151 [salt.state       :1780][INFO    ][13277] Running state [/etc/iptables/rules.v6] at time 02:51:43.151648
2019-05-28 02:51:43,152 [salt.state       :1813][INFO    ][13277] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-28 02:51:43,152 [salt.state       :300 ][INFO    ][13277] File /etc/iptables/rules.v6 is not present
2019-05-28 02:51:43,152 [salt.state       :1951][INFO    ][13277] Completed state [/etc/iptables/rules.v6] at time 02:51:43.152669 duration_in_ms=1.021
2019-05-28 02:51:43,153 [salt.state       :1780][INFO    ][13277] Running state [iptables_extra.flush_all] at time 02:51:43.153405
2019-05-28 02:51:43,156 [salt.state       :1813][INFO    ][13277] Executing state module.wait for [iptables_extra.flush_all]
2019-05-28 02:51:43,157 [salt.state       :300 ][INFO    ][13277] No changes made for iptables_extra.flush_all
2019-05-28 02:51:43,157 [salt.state       :1951][INFO    ][13277] Completed state [iptables_extra.flush_all] at time 02:51:43.157275 duration_in_ms=3.87
2019-05-28 02:51:43,160 [salt.minion      :1711][INFO    ][13277] Returning information for job: 20190528025129117914
2019-05-28 02:51:43,893 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command state.apply with jid 20190528025143882124
2019-05-28 02:51:43,923 [salt.minion      :1432][INFO    ][13358] Starting a new job with PID 13358
2019-05-28 02:51:45,091 [salt.state       :915 ][INFO    ][13358] Loading fresh modules for state activity
2019-05-28 02:51:46,463 [salt.state       :1780][INFO    ][13358] Running state [maas-rack-controller] at time 02:51:46.463123
2019-05-28 02:51:46,463 [salt.state       :1813][INFO    ][13358] Executing state pkg.installed for [maas-rack-controller]
2019-05-28 02:51:46,464 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13358] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-28 02:51:46,593 [salt.state       :300 ][INFO    ][13358] All specified packages are already installed
2019-05-28 02:51:46,593 [salt.state       :1951][INFO    ][13358] Completed state [maas-rack-controller] at time 02:51:46.593815 duration_in_ms=130.692
2019-05-28 02:51:46,594 [salt.state       :1780][INFO    ][13358] Running state [ipmitool] at time 02:51:46.594185
2019-05-28 02:51:46,594 [salt.state       :1813][INFO    ][13358] Executing state pkg.installed for [ipmitool]
2019-05-28 02:51:46,604 [salt.state       :300 ][INFO    ][13358] All specified packages are already installed
2019-05-28 02:51:46,604 [salt.state       :1951][INFO    ][13358] Completed state [ipmitool] at time 02:51:46.604238 duration_in_ms=10.053
2019-05-28 02:51:46,607 [salt.state       :1780][INFO    ][13358] Running state [/etc/maas/rackd.conf] at time 02:51:46.607444
2019-05-28 02:51:46,607 [salt.state       :1813][INFO    ][13358] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-28 02:51:46,608 [salt.state       :300 ][INFO    ][13358] No changes needed to be made
2019-05-28 02:51:46,609 [salt.state       :1951][INFO    ][13358] Completed state [/etc/maas/rackd.conf] at time 02:51:46.608970 duration_in_ms=1.527
2019-05-28 02:51:46,609 [salt.state       :1780][INFO    ][13358] Running state [/etc/maas/rackd.conf] at time 02:51:46.609266
2019-05-28 02:51:46,609 [salt.state       :1813][INFO    ][13358] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-28 02:51:46,610 [salt.loaded.int.states.file:2298][WARNING ][13358] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-28 02:51:46,611 [salt.state       :300 ][INFO    ][13358] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-28 02:51:46,611 [salt.state       :1951][INFO    ][13358] Completed state [/etc/maas/rackd.conf] at time 02:51:46.611813 duration_in_ms=2.547
2019-05-28 02:51:46,612 [salt.state       :1780][INFO    ][13358] Running state [maas-rackd] at time 02:51:46.612885
2019-05-28 02:51:46,613 [salt.state       :1813][INFO    ][13358] Executing state service.running for [maas-rackd]
2019-05-28 02:51:46,614 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13358] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-28 02:51:46,656 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13358] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-28 02:51:46,680 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13358] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-28 02:51:46,708 [salt.state       :300 ][INFO    ][13358] The service maas-rackd is already running
2019-05-28 02:51:46,709 [salt.state       :1951][INFO    ][13358] Completed state [maas-rackd] at time 02:51:46.709286 duration_in_ms=96.4
2019-05-28 02:51:46,712 [salt.minion      :1711][INFO    ][13358] Returning information for job: 20190528025143882124
2019-05-28 02:51:47,412 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command state.apply with jid 20190528025147396913
2019-05-28 02:51:47,443 [salt.minion      :1432][INFO    ][13381] Starting a new job with PID 13381
2019-05-28 02:51:48,603 [salt.state       :915 ][INFO    ][13381] Loading fresh modules for state activity
2019-05-28 02:51:50,110 [salt.state       :1780][INFO    ][13381] Running state [maas-region-controller] at time 02:51:50.110504
2019-05-28 02:51:50,111 [salt.state       :1813][INFO    ][13381] Executing state pkg.installed for [maas-region-controller]
2019-05-28 02:51:50,111 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-28 02:51:50,234 [salt.state       :300 ][INFO    ][13381] All specified packages are already installed
2019-05-28 02:51:50,234 [salt.state       :1951][INFO    ][13381] Completed state [maas-region-controller] at time 02:51:50.234867 duration_in_ms=124.365
2019-05-28 02:51:50,235 [salt.state       :1780][INFO    ][13381] Running state [python-oauth] at time 02:51:50.235252
2019-05-28 02:51:50,235 [salt.state       :1813][INFO    ][13381] Executing state pkg.installed for [python-oauth]
2019-05-28 02:51:50,244 [salt.state       :300 ][INFO    ][13381] All specified packages are already installed
2019-05-28 02:51:50,244 [salt.state       :1951][INFO    ][13381] Completed state [python-oauth] at time 02:51:50.244625 duration_in_ms=9.373
2019-05-28 02:51:50,248 [salt.state       :1780][INFO    ][13381] Running state [/etc/maas/regiond.conf] at time 02:51:50.248933
2019-05-28 02:51:50,249 [salt.state       :1813][INFO    ][13381] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-28 02:51:50,254 [salt.state       :300 ][INFO    ][13381] No changes needed to be made
2019-05-28 02:51:50,254 [salt.state       :1951][INFO    ][13381] Completed state [/etc/maas/regiond.conf] at time 02:51:50.254340 duration_in_ms=5.407
2019-05-28 02:51:50,254 [salt.state       :1780][INFO    ][13381] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:51:50.254858
2019-05-28 02:51:50,255 [salt.state       :1813][INFO    ][13381] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-28 02:51:50,319 [salt.state       :300 ][INFO    ][13381] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-28 02:51:50,319 [salt.state       :1951][INFO    ][13381] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:51:50.319396 duration_in_ms=64.538
2019-05-28 02:51:50,319 [salt.state       :1780][INFO    ][13381] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:50.319919
2019-05-28 02:51:50,320 [salt.state       :1813][INFO    ][13381] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-28 02:51:50,324 [salt.state       :300 ][INFO    ][13381] No changes needed to be made
2019-05-28 02:51:50,325 [salt.state       :1951][INFO    ][13381] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:50.325071 duration_in_ms=5.152
2019-05-28 02:51:50,325 [salt.state       :1780][INFO    ][13381] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:50.325606
2019-05-28 02:51:50,325 [salt.state       :1813][INFO    ][13381] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-28 02:51:50,331 [salt.state       :300 ][INFO    ][13381] No changes needed to be made
2019-05-28 02:51:50,331 [salt.state       :1951][INFO    ][13381] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:50.331385 duration_in_ms=5.779
2019-05-28 02:51:50,331 [salt.state       :1780][INFO    ][13381] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:51:50.331907
2019-05-28 02:51:50,332 [salt.state       :1813][INFO    ][13381] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-28 02:51:50,362 [salt.state       :300 ][INFO    ][13381] No changes needed to be made
2019-05-28 02:51:50,362 [salt.state       :1951][INFO    ][13381] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:51:50.362640 duration_in_ms=30.733
2019-05-28 02:51:50,363 [salt.state       :1780][INFO    ][13381] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:51:50.363228
2019-05-28 02:51:50,363 [salt.state       :1813][INFO    ][13381] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-28 02:51:50,383 [salt.state       :300 ][INFO    ][13381] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-28 02:51:50,384 [salt.state       :1951][INFO    ][13381] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:51:50.384044 duration_in_ms=20.814
2019-05-28 02:51:50,385 [salt.state       :1780][INFO    ][13381] Running state [a2enmod headers] at time 02:51:50.385840
2019-05-28 02:51:50,386 [salt.state       :1813][INFO    ][13381] Executing state cmd.run for [a2enmod headers]
2019-05-28 02:51:50,387 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command 'a2enmod headers' in directory '/root'
2019-05-28 02:51:50,456 [salt.state       :300 ][INFO    ][13381] {'pid': 13413, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-28 02:51:50,457 [salt.state       :1951][INFO    ][13381] Completed state [a2enmod headers] at time 02:51:50.457332 duration_in_ms=71.49
2019-05-28 02:51:50,458 [salt.state       :1780][INFO    ][13381] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:51:50.458220
2019-05-28 02:51:50,458 [salt.state       :1813][INFO    ][13381] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-28 02:51:50,483 [salt.state       :300 ][INFO    ][13381] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-28 02:51:50,483 [salt.state       :1951][INFO    ][13381] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:51:50.483825 duration_in_ms=25.605
2019-05-28 02:51:50,484 [salt.state       :1780][INFO    ][13381] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:51:50.484579
2019-05-28 02:51:50,484 [salt.state       :1813][INFO    ][13381] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-28 02:51:50,548 [salt.state       :300 ][INFO    ][13381] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-28 02:51:50,549 [salt.state       :1951][INFO    ][13381] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:51:50.549103 duration_in_ms=64.524
2019-05-28 02:51:50,551 [salt.state       :1780][INFO    ][13381] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:51:50.549687
2019-05-28 02:51:50,551 [salt.state       :1813][INFO    ][13381] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-28 02:51:50,619 [salt.state       :300 ][INFO    ][13381] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-28 02:51:50,619 [salt.state       :1951][INFO    ][13381] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:51:50.619537 duration_in_ms=69.85
2019-05-28 02:51:50,620 [salt.state       :1780][INFO    ][13381] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:51:50.620104
2019-05-28 02:51:50,620 [salt.state       :1813][INFO    ][13381] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-28 02:51:50,688 [salt.state       :300 ][INFO    ][13381] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-28 02:51:50,688 [salt.state       :1951][INFO    ][13381] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:51:50.688817 duration_in_ms=68.713
2019-05-28 02:51:50,689 [salt.state       :1780][INFO    ][13381] Running state [/root/.pgpass] at time 02:51:50.689272
2019-05-28 02:51:50,690 [salt.state       :1813][INFO    ][13381] Executing state file.managed for [/root/.pgpass]
2019-05-28 02:51:50,744 [salt.state       :300 ][INFO    ][13381] File /root/.pgpass is in the correct state
2019-05-28 02:51:50,744 [salt.state       :1951][INFO    ][13381] Completed state [/root/.pgpass] at time 02:51:50.744719 duration_in_ms=55.447
2019-05-28 02:51:50,750 [salt.state       :1780][INFO    ][13381] Running state [maas-region syncdb --noinput] at time 02:51:50.750625
2019-05-28 02:51:50,750 [salt.state       :1813][INFO    ][13381] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-28 02:51:50,751 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-28 02:51:53,511 [salt.state       :300 ][INFO    ][13381] {'pid': 13426, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: contenttypes, sessions, piston3, sites, auth, maasserver, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-28 02:51:53,512 [salt.state       :1951][INFO    ][13381] Completed state [maas-region syncdb --noinput] at time 02:51:53.511987 duration_in_ms=2761.361
2019-05-28 02:51:53,512 [salt.state       :2022][WARNING ][13381] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-28 02:51:53,521 [salt.state       :1780][INFO    ][13381] Running state [maas-regiond] at time 02:51:53.520836
2019-05-28 02:51:53,524 [salt.state       :1813][INFO    ][13381] Executing state service.running for [maas-regiond]
2019-05-28 02:51:53,527 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-28 02:51:53,577 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-28 02:51:53,600 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-28 02:51:53,626 [salt.state       :300 ][INFO    ][13381] The service maas-regiond is already running
2019-05-28 02:51:53,627 [salt.state       :1951][INFO    ][13381] Completed state [maas-regiond] at time 02:51:53.627386 duration_in_ms=106.557
2019-05-28 02:51:53,632 [salt.state       :1780][INFO    ][13381] Running state [bind9] at time 02:51:53.632561
2019-05-28 02:51:53,632 [salt.state       :1813][INFO    ][13381] Executing state service.running for [bind9]
2019-05-28 02:51:53,634 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-28 02:51:53,659 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-28 02:51:53,687 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-28 02:51:53,713 [salt.state       :300 ][INFO    ][13381] The service bind9 is already running
2019-05-28 02:51:53,715 [salt.state       :1951][INFO    ][13381] Completed state [bind9] at time 02:51:53.715756 duration_in_ms=83.195
2019-05-28 02:51:53,718 [salt.state       :1780][INFO    ][13381] Running state [apache2] at time 02:51:53.718409
2019-05-28 02:51:53,718 [salt.state       :1813][INFO    ][13381] Executing state service.running for [apache2]
2019-05-28 02:51:53,720 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-28 02:51:53,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-28 02:51:53,765 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-28 02:51:53,792 [salt.state       :300 ][INFO    ][13381] The service apache2 is already running
2019-05-28 02:51:53,792 [salt.state       :1951][INFO    ][13381] Completed state [apache2] at time 02:51:53.792723 duration_in_ms=74.307
2019-05-28 02:51:53,795 [salt.state       :1780][INFO    ][13381] Running state [maasng.wait_for_http_code] at time 02:51:53.795734
2019-05-28 02:51:53,796 [salt.state       :1813][INFO    ][13381] Executing state module.run for [maasng.wait_for_http_code]
2019-05-28 02:51:53,797 [salt.utils.decorators:613 ][WARNING ][13381] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:51:53,921 [salt.state       :300 ][INFO    ][13381] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-28 02:51:53,922 [salt.state       :1951][INFO    ][13381] Completed state [maasng.wait_for_http_code] at time 02:51:53.922805 duration_in_ms=127.071
2019-05-28 02:51:53,923 [salt.state       :1780][INFO    ][13381] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:51:53.923728
2019-05-28 02:51:53,924 [salt.state       :1813][INFO    ][13381] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-28 02:51:53,924 [salt.state       :300 ][INFO    ][13381] /var/lib/maas/.setup_admin exists
2019-05-28 02:51:53,924 [salt.state       :1951][INFO    ][13381] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:51:53.924783 duration_in_ms=1.055
2019-05-28 02:51:53,925 [salt.state       :1780][INFO    ][13381] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:53.925502
2019-05-28 02:51:53,925 [salt.state       :1813][INFO    ][13381] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-28 02:51:53,926 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13381] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-28 02:51:55,776 [salt.state       :300 ][INFO    ][13381] {'pid': 13448, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-28 02:51:55,778 [salt.state       :1951][INFO    ][13381] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:55.777713 duration_in_ms=1852.207
2019-05-28 02:51:55,789 [salt.state       :1780][INFO    ][13381] Running state [maas_region_boot_source_resources_mirror] at time 02:51:55.789061
2019-05-28 02:51:55,791 [salt.state       :1813][INFO    ][13381] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-28 02:51:55,877 [salt.state       :300 ][INFO    ][13381] {'changes': {}}
2019-05-28 02:51:55,878 [salt.state       :1951][INFO    ][13381] Completed state [maas_region_boot_source_resources_mirror] at time 02:51:55.877666 duration_in_ms=88.605
2019-05-28 02:51:55,879 [salt.state       :1780][INFO    ][13381] Running state [maasng.boot_resources_import] at time 02:51:55.879530
2019-05-28 02:51:55,880 [salt.state       :1813][INFO    ][13381] Executing state module.run for [maasng.boot_resources_import]
2019-05-28 02:51:55,880 [salt.utils.decorators:613 ][WARNING ][13381] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:51:55,979 [salt.loaded.ext.module.maasng:1600][INFO    ][13381] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-28 02:52:01,031 [salt.loaded.ext.module.maasng:1600][INFO    ][13381] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-28 02:52:02,443 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025202428255
2019-05-28 02:52:02,473 [salt.minion      :1432][INFO    ][13475] Starting a new job with PID 13475
2019-05-28 02:52:02,513 [salt.minion      :1711][INFO    ][13475] Returning information for job: 20190528025202428255
2019-05-28 02:52:06,138 [salt.state       :300 ][INFO    ][13381] {'ret': True}
2019-05-28 02:52:06,139 [salt.state       :1951][INFO    ][13381] Completed state [maasng.boot_resources_import] at time 02:52:06.139026 duration_in_ms=10259.495
2019-05-28 02:52:06,140 [salt.state       :1780][INFO    ][13381] Running state [maas_region_boot_sources_selection_xenial] at time 02:52:06.140345
2019-05-28 02:52:06,140 [salt.state       :1813][INFO    ][13381] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-28 02:52:06,336 [salt.state       :300 ][INFO    ][13381] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-28 02:52:06,336 [salt.state       :1951][INFO    ][13381] Completed state [maas_region_boot_sources_selection_xenial] at time 02:52:06.336426 duration_in_ms=196.08
2019-05-28 02:52:06,341 [salt.state       :1780][INFO    ][13381] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:52:06.341483
2019-05-28 02:52:06,342 [salt.state       :1813][INFO    ][13381] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-28 02:52:06,342 [salt.utils.decorators:613 ][WARNING ][13381] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:52:06,342 [salt.loaded.ext.module.maasng:1771][INFO    ][13381] boot-sources sync initiated for ALL Rack's
2019-05-28 02:52:07,532 [salt.state       :300 ][INFO    ][13381] {'ret': True}
2019-05-28 02:52:07,533 [salt.state       :1951][INFO    ][13381] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:52:07.533298 duration_in_ms=1191.815
2019-05-28 02:52:07,535 [salt.state       :1780][INFO    ][13381] Running state [maas.process_maas_config] at time 02:52:07.535831
2019-05-28 02:52:07,536 [salt.state       :1813][INFO    ][13381] Executing state module.run for [maas.process_maas_config]
2019-05-28 02:52:07,536 [salt.utils.decorators:613 ][WARNING ][13381] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:52:07,537 [salt.loaded.ext.module.maas:92  ][INFO    ][13381] maasconfig name=enable_http_proxy value=True
2019-05-28 02:52:07,591 [salt.loaded.ext.module.maas:92  ][INFO    ][13381] maasconfig name=upstream_dns value=8.8.8.8
2019-05-28 02:52:09,082 [salt.loaded.ext.module.maas:92  ][INFO    ][13381] maasconfig name=commissioning_distro_series value=xenial
2019-05-28 02:52:09,152 [salt.loaded.ext.module.maas:92  ][INFO    ][13381] maasconfig name=default_osystem value=ubuntu
2019-05-28 02:52:09,228 [salt.loaded.ext.module.maas:92  ][INFO    ][13381] maasconfig name=active_discovery_interval value=600
2019-05-28 02:52:09,284 [salt.loaded.ext.module.maas:92  ][INFO    ][13381] maasconfig name=dnssec_validation value=no
2019-05-28 02:52:09,331 [salt.loaded.ext.module.maas:92  ][INFO    ][13381] maasconfig name=maas_name value=mas01
2019-05-28 02:52:09,379 [salt.loaded.ext.module.maas:92  ][INFO    ][13381] maasconfig name=network_discovery value=enabled
2019-05-28 02:52:09,521 [salt.loaded.ext.module.maas:92  ][INFO    ][13381] maasconfig name=enable_third_party_drivers value=True
2019-05-28 02:52:09,576 [salt.loaded.ext.module.maas:92  ][INFO    ][13381] maasconfig name=default_storage_layout value=lvm
2019-05-28 02:52:09,624 [salt.loaded.ext.module.maas:92  ][INFO    ][13381] maasconfig name=ntp_external_only value=True
2019-05-28 02:52:09,696 [salt.loaded.ext.module.maas:92  ][INFO    ][13381] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-28 02:52:09,754 [salt.loaded.ext.module.maas:92  ][INFO    ][13381] maasconfig name=default_distro_series value=xenial
2019-05-28 02:52:09,815 [salt.loaded.ext.module.maas:92  ][INFO    ][13381] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-05-28 02:52:09,929 [salt.state       :300 ][INFO    ][13381] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-28 02:52:09,930 [salt.state       :1951][INFO    ][13381] Completed state [maas.process_maas_config] at time 02:52:09.930209 duration_in_ms=2394.378
2019-05-28 02:52:09,930 [salt.state       :1780][INFO    ][13381] Running state [pxe_admin] at time 02:52:09.930887
2019-05-28 02:52:09,931 [salt.state       :1813][INFO    ][13381] Executing state maasng.fabric_present for [pxe_admin]
2019-05-28 02:52:09,996 [salt.loaded.ext.module.maasng:945 ][INFO    ][13381] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fwehwa', 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-05-28 02:52:10,044 [salt.loaded.ext.module.maasng:1008][WARNING ][13381] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-28 02:52:10,045 [salt.loaded.ext.module.maasng:1011][WARNING ][13381] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-28 02:52:10,088 [salt.state       :300 ][INFO    ][13381] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-28 02:52:10,088 [salt.state       :1951][INFO    ][13381] Completed state [pxe_admin] at time 02:52:10.088422 duration_in_ms=157.534
2019-05-28 02:52:10,088 [salt.state       :1780][INFO    ][13381] Running state [vlan 0] at time 02:52:10.088774
2019-05-28 02:52:10,089 [salt.state       :1813][INFO    ][13381] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-28 02:52:10,144 [salt.loaded.ext.module.maasng:945 ][INFO    ][13381] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'id': 1}]
2019-05-28 02:52:10,243 [salt.loaded.ext.module.maasng:945 ][INFO    ][13381] [{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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-28 02:52:10,490 [salt.loaded.ext.module.maasng:945 ][INFO    ][13381] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fwehwa', 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-05-28 02:52:10,553 [salt.state       :300 ][INFO    ][13381] {'new': 'Vlan untagged was updated'}
2019-05-28 02:52:10,554 [salt.state       :1951][INFO    ][13381] Completed state [vlan 0] at time 02:52:10.554023 duration_in_ms=465.249
2019-05-28 02:52:10,555 [salt.state       :1780][INFO    ][13381] Running state [192.168.11.0/24] at time 02:52:10.554989
2019-05-28 02:52:10,555 [salt.state       :1813][INFO    ][13381] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-28 02:52:10,757 [salt.loaded.ext.module.maasng:945 ][INFO    ][13381] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'fwehwa', 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-05-28 02:52:10,758 [salt.loaded.ext.module.maasng:1235][WARNING ][13381] Ignoring parameter vlan:0
2019-05-28 02:52:10,821 [salt.state       :300 ][INFO    ][13381] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-28 02:52:10,821 [salt.state       :1951][INFO    ][13381] Completed state [192.168.11.0/24] at time 02:52:10.821322 duration_in_ms=266.331
2019-05-28 02:52:10,822 [salt.state       :1780][INFO    ][13381] Running state [maas_create_iprange_1] at time 02:52:10.822384
2019-05-28 02:52:10,822 [salt.state       :1813][INFO    ][13381] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-28 02:52:10,891 [salt.state       :300 ][INFO    ][13381] Iprange maas_create_iprange_1 already exist.
2019-05-28 02:52:10,891 [salt.state       :1951][INFO    ][13381] Completed state [maas_create_iprange_1] at time 02:52:10.891428 duration_in_ms=69.043
2019-05-28 02:52:10,891 [salt.state       :1780][INFO    ][13381] Running state [vlan 0] at time 02:52:10.891868
2019-05-28 02:52:10,892 [salt.state       :1813][INFO    ][13381] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-28 02:52:10,949 [salt.loaded.ext.module.maasng:945 ][INFO    ][13381] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fwehwa', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-28 02:52:11,058 [salt.loaded.ext.module.maasng:945 ][INFO    ][13381] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'fwehwa', 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-05-28 02:52:11,284 [salt.loaded.ext.module.maasng:945 ][INFO    ][13381] [{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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'fwehwa', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-28 02:52:11,388 [salt.state       :300 ][INFO    ][13381] {'new': 'Vlan untagged was updated'}
2019-05-28 02:52:11,388 [salt.state       :1951][INFO    ][13381] Completed state [vlan 0] at time 02:52:11.388535 duration_in_ms=496.666
2019-05-28 02:52:11,389 [salt.state       :1780][INFO    ][13381] Running state [opnfv] at time 02:52:11.389189
2019-05-28 02:52:11,389 [salt.state       :1813][INFO    ][13381] Executing state maasng.sshkey_present for [opnfv]
2019-05-28 02:52:11,456 [salt.loaded.ext.module.maasng:1903][INFO    ][13381] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-05-28 02:52:11,457 [salt.state       :300 ][INFO    ][13381] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-28 02:52:11,457 [salt.state       :1951][INFO    ][13381] Completed state [opnfv] at time 02:52:11.457480 duration_in_ms=68.291
2019-05-28 02:52:11,460 [salt.minion      :1711][INFO    ][13381] Returning information for job: 20190528025147396913
2019-05-28 02:52:12,295 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command state.apply with jid 20190528025212282624
2019-05-28 02:52:12,318 [salt.minion      :1432][INFO    ][13856] Starting a new job with PID 13856
2019-05-28 02:52:20,766 [salt.state       :915 ][INFO    ][13856] Loading fresh modules for state activity
2019-05-28 02:52:20,902 [salt.state       :1780][INFO    ][13856] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:20.901983
2019-05-28 02:52:20,902 [salt.state       :1813][INFO    ][13856] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-28 02:52:20,904 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13856] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-28 02:52:22,732 [salt.state       :300 ][INFO    ][13856] {'pid': 13883, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-28 02:52:22,733 [salt.state       :1951][INFO    ][13856] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:22.733257 duration_in_ms=1831.274
2019-05-28 02:52:22,737 [salt.state       :1780][INFO    ][13856] Running state [maas.process_machines] at time 02:52:22.737253
2019-05-28 02:52:22,738 [salt.state       :1813][INFO    ][13856] Executing state module.run for [maas.process_machines]
2019-05-28 02:52:22,740 [salt.utils.decorators:613 ][WARNING ][13856] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:52:23,340 [salt.loaded.ext.module.maas:412 ][WARNING ][13856] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-28 02:52:23,341 [salt.loaded.ext.module.maas:92  ][INFO    ][13856] machine hostname=cmp002 power_type=ipmi mac_addresses=['9c:b6:54:8a:10:18'] power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 system_id=tybwmw architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-28 02:52:24,521 [salt.loaded.ext.module.maas:412 ][WARNING ][13856] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-28 02:52:24,523 [salt.loaded.ext.module.maas:92  ][INFO    ][13856] machine hostname=cmp001 power_type=ipmi mac_addresses=['9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=6e877m architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-28 02:52:25,769 [salt.loaded.ext.module.maas:412 ][WARNING ][13856] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-28 02:52:25,771 [salt.loaded.ext.module.maas:92  ][INFO    ][13856] machine hostname=kvm01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=7a7ppf architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-28 02:52:27,022 [salt.loaded.ext.module.maas:412 ][WARNING ][13856] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-28 02:52:27,023 [salt.loaded.ext.module.maas:92  ][INFO    ][13856] machine hostname=kvm03 power_type=ipmi mac_addresses=['14:58:d0:54:7a:28'] power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 system_id=mrwqnc architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-28 02:52:27,320 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025227305571
2019-05-28 02:52:27,347 [salt.minion      :1432][INFO    ][14033] Starting a new job with PID 14033
2019-05-28 02:52:27,383 [salt.minion      :1711][INFO    ][14033] Returning information for job: 20190528025227305571
2019-05-28 02:52:28,269 [salt.loaded.ext.module.maas:412 ][WARNING ][13856] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-28 02:52:28,270 [salt.loaded.ext.module.maas:92  ][INFO    ][13856] machine hostname=kvm02 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=4pkekm architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-28 02:52:29,421 [salt.state       :300 ][INFO    ][13856] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-28 02:52:29,421 [salt.state       :1951][INFO    ][13856] Completed state [maas.process_machines] at time 02:52:29.421439 duration_in_ms=6684.186
2019-05-28 02:52:29,425 [salt.minion      :1711][INFO    ][13856] Returning information for job: 20190528025212282624
2019-05-28 02:53:03,486 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command state.apply with jid 20190528025303473326
2019-05-28 02:53:03,513 [salt.minion      :1432][INFO    ][14124] Starting a new job with PID 14124
2019-05-28 02:53:11,828 [salt.state       :915 ][INFO    ][14124] Loading fresh modules for state activity
2019-05-28 02:53:11,954 [salt.state       :1780][INFO    ][14124] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:11.954837
2019-05-28 02:53:11,955 [salt.state       :1813][INFO    ][14124] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-28 02:53:11,957 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14124] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-28 02:53:13,862 [salt.state       :300 ][INFO    ][14124] {'pid': 14168, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-28 02:53:13,863 [salt.state       :1951][INFO    ][14124] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:13.863262 duration_in_ms=1908.424
2019-05-28 02:53:13,866 [salt.state       :1780][INFO    ][14124] Running state [maas.wait_for_machine_status] at time 02:53:13.866327
2019-05-28 02:53:13,867 [salt.state       :1813][INFO    ][14124] Executing state module.run for [maas.wait_for_machine_status]
2019-05-28 02:53:13,867 [salt.utils.decorators:613 ][WARNING ][14124] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:53:16,497 [salt.state       :300 ][INFO    ][14124] {'ret': True}
2019-05-28 02:53:16,497 [salt.state       :1951][INFO    ][14124] Completed state [maas.wait_for_machine_status] at time 02:53:16.497323 duration_in_ms=2630.996
2019-05-28 02:53:16,501 [salt.minion      :1711][INFO    ][14124] Returning information for job: 20190528025303473326
2019-05-28 02:53:17,216 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command state.apply with jid 20190528025317202189
2019-05-28 02:53:17,239 [salt.minion      :1432][INFO    ][14181] Starting a new job with PID 14181
2019-05-28 02:53:18,352 [salt.state       :915 ][INFO    ][14181] Loading fresh modules for state activity
2019-05-28 02:53:18,514 [salt.state       :1780][INFO    ][14181] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:18.514297
2019-05-28 02:53:18,514 [salt.state       :1813][INFO    ][14181] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-28 02:53:18,519 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14181] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-28 02:53:20,363 [salt.state       :300 ][INFO    ][14181] {'pid': 14188, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-28 02:53:20,364 [salt.state       :1951][INFO    ][14181] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:20.363935 duration_in_ms=1849.639
2019-05-28 02:53:20,369 [salt.state       :1780][INFO    ][14181] Running state [maas_machines_storage_cmp002_lvm] at time 02:53:20.369226
2019-05-28 02:53:20,370 [salt.state       :1813][INFO    ][14181] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-28 02:53:20,937 [salt.state       :300 ][INFO    ][14181] Machine cmp002 is not in Ready state.
2019-05-28 02:53:20,937 [salt.state       :1951][INFO    ][14181] Completed state [maas_machines_storage_cmp002_lvm] at time 02:53:20.937512 duration_in_ms=568.286
2019-05-28 02:53:20,938 [salt.state       :1780][INFO    ][14181] Running state [maas_machines_storage_cmp001_lvm] at time 02:53:20.938296
2019-05-28 02:53:20,938 [salt.state       :1813][INFO    ][14181] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-28 02:53:21,482 [salt.state       :300 ][INFO    ][14181] Machine cmp001 is not in Ready state.
2019-05-28 02:53:21,483 [salt.state       :1951][INFO    ][14181] Completed state [maas_machines_storage_cmp001_lvm] at time 02:53:21.483539 duration_in_ms=545.242
2019-05-28 02:53:21,488 [salt.minion      :1711][INFO    ][14181] Returning information for job: 20190528025317202189
2019-05-28 02:53:22,267 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command state.apply with jid 20190528025322211189
2019-05-28 02:53:22,294 [salt.minion      :1432][INFO    ][14204] Starting a new job with PID 14204
2019-05-28 02:53:23,421 [salt.state       :915 ][INFO    ][14204] Loading fresh modules for state activity
2019-05-28 02:53:23,525 [salt.state       :1780][INFO    ][14204] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:23.525382
2019-05-28 02:53:23,525 [salt.state       :1813][INFO    ][14204] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-28 02:53:23,527 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14204] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-28 02:53:25,373 [salt.state       :300 ][INFO    ][14204] {'pid': 14211, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-28 02:53:25,374 [salt.state       :1951][INFO    ][14204] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:25.374062 duration_in_ms=1848.679
2019-05-28 02:53:25,377 [salt.state       :1780][INFO    ][14204] Running state [maas.deploy_machines] at time 02:53:25.377188
2019-05-28 02:53:25,378 [salt.state       :1813][INFO    ][14204] Executing state module.run for [maas.deploy_machines]
2019-05-28 02:53:25,379 [salt.utils.decorators:613 ][WARNING ][14204] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:53:25,939 [salt.loaded.ext.module.maas:684 ][INFO    ][14204] deploymachines hwe_kernel=ga-16.04 system_id=4pkekm distro_series=xenial
2019-05-28 02:53:28,360 [salt.state       :300 ][INFO    ][14204] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-28 02:53:28,361 [salt.state       :1951][INFO    ][14204] Completed state [maas.deploy_machines] at time 02:53:28.361393 duration_in_ms=2984.206
2019-05-28 02:53:28,365 [salt.minion      :1711][INFO    ][14204] Returning information for job: 20190528025322211189
2019-05-28 02:53:29,107 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command state.apply with jid 20190528025329093324
2019-05-28 02:53:29,133 [salt.minion      :1432][INFO    ][14271] Starting a new job with PID 14271
2019-05-28 02:53:37,492 [salt.state       :915 ][INFO    ][14271] Loading fresh modules for state activity
2019-05-28 02:53:37,618 [salt.state       :1780][INFO    ][14271] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:37.617983
2019-05-28 02:53:37,618 [salt.state       :1813][INFO    ][14271] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-28 02:53:37,620 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14271] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-28 02:53:39,347 [salt.state       :300 ][INFO    ][14271] {'pid': 14287, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-28 02:53:39,349 [salt.state       :1951][INFO    ][14271] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:39.349035 duration_in_ms=1731.051
2019-05-28 02:53:39,353 [salt.state       :1780][INFO    ][14271] Running state [maas.wait_for_machine_status] at time 02:53:39.353206
2019-05-28 02:53:39,354 [salt.state       :1813][INFO    ][14271] Executing state module.run for [maas.wait_for_machine_status]
2019-05-28 02:53:39,355 [salt.utils.decorators:613 ][WARNING ][14271] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 02:53:42,320 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.06093502s left)
2019-05-28 02:53:44,132 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025344117702
2019-05-28 02:53:44,159 [salt.minion      :1432][INFO    ][14298] Starting a new job with PID 14298
2019-05-28 02:53:44,188 [salt.minion      :1711][INFO    ][14298] Returning information for job: 20190528025344117702
2019-05-28 02:54:14,214 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025414201227
2019-05-28 02:54:14,244 [salt.minion      :1432][INFO    ][14356] Starting a new job with PID 14356
2019-05-28 02:54:14,268 [salt.minion      :1711][INFO    ][14356] Returning information for job: 20190528025414201227
2019-05-28 02:54:15,067 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.31448793s left)
2019-05-28 02:54:44,301 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025444289577
2019-05-28 02:54:44,333 [salt.minion      :1432][INFO    ][14373] Starting a new job with PID 14373
2019-05-28 02:54:44,356 [salt.minion      :1711][INFO    ][14373] Returning information for job: 20190528025444289577
2019-05-28 02:54:48,001 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.38041687s left)
2019-05-28 02:55:14,389 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025514374236
2019-05-28 02:55:14,418 [salt.minion      :1432][INFO    ][14452] Starting a new job with PID 14452
2019-05-28 02:55:14,442 [salt.minion      :1711][INFO    ][14452] Returning information for job: 20190528025514374236
2019-05-28 02:55:20,873 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.50841403s left)
2019-05-28 02:55:44,453 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025544438240
2019-05-28 02:55:44,481 [salt.minion      :1432][INFO    ][14474] Starting a new job with PID 14474
2019-05-28 02:55:44,503 [salt.minion      :1711][INFO    ][14474] Returning information for job: 20190528025544438240
2019-05-28 02:55:53,743 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.63783288s left)
2019-05-28 02:56:14,540 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025614530615
2019-05-28 02:56:14,569 [salt.minion      :1432][INFO    ][14533] Starting a new job with PID 14533
2019-05-28 02:56:14,594 [salt.minion      :1711][INFO    ][14533] Returning information for job: 20190528025614530615
2019-05-28 02:56:26,494 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.88720489s left)
2019-05-28 02:56:44,637 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025644620338
2019-05-28 02:56:44,664 [salt.minion      :1432][INFO    ][14555] Starting a new job with PID 14555
2019-05-28 02:56:44,685 [salt.minion      :1711][INFO    ][14555] Returning information for job: 20190528025644620338
2019-05-28 02:56:59,220 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.16135001s left)
2019-05-28 02:57:14,732 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025714716974
2019-05-28 02:57:14,760 [salt.minion      :1432][INFO    ][14617] Starting a new job with PID 14617
2019-05-28 02:57:14,787 [salt.minion      :1711][INFO    ][14617] Returning information for job: 20190528025714716974
2019-05-28 02:57:31,996 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.38538003s left)
2019-05-28 02:57:44,839 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025744828143
2019-05-28 02:57:44,863 [salt.minion      :1432][INFO    ][14659] Starting a new job with PID 14659
2019-05-28 02:57:44,886 [salt.minion      :1711][INFO    ][14659] Returning information for job: 20190528025744828143
2019-05-28 02:58:05,047 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.33415103s left)
2019-05-28 02:58:14,934 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025814919680
2019-05-28 02:58:14,961 [salt.minion      :1432][INFO    ][14744] Starting a new job with PID 14744
2019-05-28 02:58:14,984 [salt.minion      :1711][INFO    ][14744] Returning information for job: 20190528025814919680
2019-05-28 02:58:38,087 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.29415298s left)
2019-05-28 02:58:45,037 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025845030673
2019-05-28 02:58:45,054 [salt.minion      :1432][INFO    ][14773] Starting a new job with PID 14773
2019-05-28 02:58:45,079 [salt.minion      :1711][INFO    ][14773] Returning information for job: 20190528025845030673
2019-05-28 02:59:11,107 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.27402306s left)
2019-05-28 02:59:15,154 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025915147014
2019-05-28 02:59:15,183 [salt.minion      :1432][INFO    ][14983] Starting a new job with PID 14983
2019-05-28 02:59:15,206 [salt.minion      :1711][INFO    ][14983] Returning information for job: 20190528025915147014
2019-05-28 02:59:44,009 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.37245893s left)
2019-05-28 02:59:45,285 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528025945271097
2019-05-28 02:59:45,311 [salt.minion      :1432][INFO    ][15007] Starting a new job with PID 15007
2019-05-28 02:59:45,343 [salt.minion      :1711][INFO    ][15007] Returning information for job: 20190528025945271097
2019-05-28 03:00:15,437 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528030015422777
2019-05-28 03:00:15,464 [salt.minion      :1432][INFO    ][15142] Starting a new job with PID 15142
2019-05-28 03:00:15,488 [salt.minion      :1711][INFO    ][15142] Returning information for job: 20190528030015422777
2019-05-28 03:00:16,932 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.44913006s left)
2019-05-28 03:00:45,584 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528030045573915
2019-05-28 03:00:45,612 [salt.minion      :1432][INFO    ][15165] Starting a new job with PID 15165
2019-05-28 03:00:45,635 [salt.minion      :1711][INFO    ][15165] Returning information for job: 20190528030045573915
2019-05-28 03:00:49,943 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.43787098s left)
2019-05-28 03:01:15,748 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528030115734493
2019-05-28 03:01:15,775 [salt.minion      :1432][INFO    ][15306] Starting a new job with PID 15306
2019-05-28 03:01:15,798 [salt.minion      :1711][INFO    ][15306] Returning information for job: 20190528030115734493
2019-05-28 03:01:22,956 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.42539096s left)
2019-05-28 03:01:45,904 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528030145890667
2019-05-28 03:01:45,933 [salt.minion      :1432][INFO    ][15326] Starting a new job with PID 15326
2019-05-28 03:01:45,957 [salt.minion      :1711][INFO    ][15326] Returning information for job: 20190528030145890667
2019-05-28 03:01:55,793 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.58864903s left)
2019-05-28 03:02:16,063 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528030216050161
2019-05-28 03:02:16,093 [salt.minion      :1432][INFO    ][15381] Starting a new job with PID 15381
2019-05-28 03:02:16,121 [salt.minion      :1711][INFO    ][15381] Returning information for job: 20190528030216050161
2019-05-28 03:02:28,975 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.40612197s left)
2019-05-28 03:02:46,243 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528030246222928
2019-05-28 03:02:46,271 [salt.minion      :1432][INFO    ][15404] Starting a new job with PID 15404
2019-05-28 03:02:46,295 [salt.minion      :1711][INFO    ][15404] Returning information for job: 20190528030246222928
2019-05-28 03:03:01,944 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.43729591s left)
2019-05-28 03:03:16,428 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528030316410895
2019-05-28 03:03:16,457 [salt.minion      :1432][INFO    ][15463] Starting a new job with PID 15463
2019-05-28 03:03:16,481 [salt.minion      :1711][INFO    ][15463] Returning information for job: 20190528030316410895
2019-05-28 03:03:34,840 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.5410769s left)
2019-05-28 03:03:46,607 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528030346591013
2019-05-28 03:03:46,637 [salt.minion      :1432][INFO    ][15482] Starting a new job with PID 15482
2019-05-28 03:03:46,663 [salt.minion      :1711][INFO    ][15482] Returning information for job: 20190528030346591013
2019-05-28 03:04:07,878 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.50324297s left)
2019-05-28 03:04:16,771 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528030416762449
2019-05-28 03:04:16,798 [salt.minion      :1432][INFO    ][15540] Starting a new job with PID 15540
2019-05-28 03:04:16,821 [salt.minion      :1711][INFO    ][15540] Returning information for job: 20190528030416762449
2019-05-28 03:04:40,796 [salt.loaded.ext.module.maas:1023][INFO    ][14271] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.58469987s left)
2019-05-28 03:04:46,952 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528030446931585
2019-05-28 03:04:46,984 [salt.minion      :1432][INFO    ][15561] Starting a new job with PID 15561
2019-05-28 03:04:47,012 [salt.minion      :1711][INFO    ][15561] Returning information for job: 20190528030446931585
2019-05-28 03:05:14,043 [salt.state       :300 ][INFO    ][14271] {'ret': True}
2019-05-28 03:05:14,044 [salt.state       :1951][INFO    ][14271] Completed state [maas.wait_for_machine_status] at time 03:05:14.043897 duration_in_ms=694690.681
2019-05-28 03:05:14,048 [salt.minion      :1711][INFO    ][14271] Returning information for job: 20190528025329093324
2019-05-28 03:05:58,662 [salt.utils.schedule:1377][INFO    ][5181] Running scheduled job: __mine_interval
2019-05-28 04:05:58,661 [salt.utils.schedule:1377][INFO    ][5181] Running scheduled job: __mine_interval
2019-05-28 04:38:27,991 [salt.minion      :1308][INFO    ][5181] User sudo_ubuntu Executing command cp.push_dir with jid 20190528043827980207
2019-05-28 04:38:28,024 [salt.minion      :1432][INFO    ][22288] Starting a new job with PID 22288
