2019-06-29 02:06:38,007 [salt.utils.decorators:613 ][WARNING ][2321] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:06:39,043 [salt.utils.decorators:613 ][WARNING ][2321] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:06:42,334 [salt.loaded.int.states.file:2298][WARNING ][2540] 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-06-29 02:07:06,709 [salt.state       :2022][WARNING ][2846] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-29 02:07:09,801 [salt.utils.decorators:613 ][WARNING ][2846] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:07:23,704 [salt.utils.decorators:613 ][WARNING ][2846] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:07:50,540 [salt.utils.decorators:613 ][WARNING ][2846] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:07:51,584 [salt.utils.decorators:613 ][WARNING ][2846] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:07:53,878 [salt.loaded.ext.module.maasng:1008][WARNING ][2846] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-06-29 02:07:53,878 [salt.loaded.ext.module.maasng:1011][WARNING ][2846] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-06-29 02:07:54,788 [salt.loaded.ext.module.maasng:1235][WARNING ][2846] Ignoring parameter vlan:0
2019-06-29 02:07:58,739 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5369] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-29 02:07:58,774 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5369] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-29 02:07:58,831 [salt.utils.parsers:1051][WARNING ][388] Minion received a SIGTERM. Exiting.
2019-06-29 02:07:59,920 [salt.cli.daemons :293 ][INFO    ][5441] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-06-29 02:08:00,034 [salt.cli.daemons :82  ][INFO    ][5441] Starting up the Salt Minion
2019-06-29 02:08:00,035 [salt.utils.event :1017][INFO    ][5441] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-06-29 02:08:01,146 [salt.minion      :976 ][INFO    ][5441] Creating minion process manager
2019-06-29 02:08:03,206 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5441] Executing command ['date', '+%z'] in directory '/root'
2019-06-29 02:08:03,233 [salt.utils.schedule:568 ][INFO    ][5441] Updating job settings for scheduled job: __mine_interval
2019-06-29 02:08:03,235 [salt.minion      :1108][INFO    ][5441] Added mine.update to scheduler
2019-06-29 02:08:03,240 [salt.minion      :1975][INFO    ][5441] Minion is starting as user 'root'
2019-06-29 02:08:03,261 [salt.minion      :2336][INFO    ][5441] Minion is ready to receive requests!
2019-06-29 02:08:07,096 [salt.utils.decorators:613 ][WARNING ][5372] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:08:07,167 [salt.loaded.ext.module.maas:412 ][WARNING ][5372] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-29 02:08:08,706 [salt.loaded.ext.module.maas:412 ][WARNING ][5372] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-29 02:08:10,138 [salt.loaded.ext.module.maas:412 ][WARNING ][5372] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-29 02:08:11,581 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629020811569374
2019-06-29 02:08:11,601 [salt.minion      :1432][INFO    ][5709] Starting a new job with PID 5709
2019-06-29 02:08:11,621 [salt.loaded.ext.module.maas:412 ][WARNING ][5372] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-29 02:08:11,629 [salt.minion      :1711][INFO    ][5709] Returning information for job: 20190629020811569374
2019-06-29 02:08:13,254 [salt.loaded.ext.module.maas:412 ][WARNING ][5372] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-29 02:08:45,788 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command state.apply with jid 20190629020845777081
2019-06-29 02:08:45,815 [salt.minion      :1432][INFO    ][5848] Starting a new job with PID 5848
2019-06-29 02:08:53,855 [salt.state       :915 ][INFO    ][5848] Loading fresh modules for state activity
2019-06-29 02:08:53,912 [salt.fileclient  :1219][INFO    ][5848] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-29 02:08:53,963 [salt.state       :1780][INFO    ][5848] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:08:53.963750
2019-06-29 02:08:53,964 [salt.state       :1813][INFO    ][5848] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-29 02:08:53,966 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5848] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-29 02:08:55,706 [salt.state       :300 ][INFO    ][5848] {'pid': 5866, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-29 02:08:55,708 [salt.state       :1951][INFO    ][5848] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:08:55.707859 duration_in_ms=1744.107
2019-06-29 02:08:55,711 [salt.state       :1780][INFO    ][5848] Running state [maas.wait_for_machine_status] at time 02:08:55.711885
2019-06-29 02:08:55,712 [salt.state       :1813][INFO    ][5848] Executing state module.run for [maas.wait_for_machine_status]
2019-06-29 02:08:55,713 [salt.utils.decorators:613 ][WARNING ][5848] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:08:56,697 [salt.loaded.ext.module.maas:1023][INFO    ][5848] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.0272181s left)
2019-06-29 02:09:00,839 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629020900821222
2019-06-29 02:09:00,867 [salt.minion      :1432][INFO    ][5878] Starting a new job with PID 5878
2019-06-29 02:09:00,887 [salt.minion      :1711][INFO    ][5878] Returning information for job: 20190629020900821222
2019-06-29 02:09:27,637 [salt.loaded.ext.module.maas:1023][INFO    ][5848] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.08783507s left)
2019-06-29 02:09:30,910 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629020930896986
2019-06-29 02:09:30,925 [salt.minion      :1432][INFO    ][5921] Starting a new job with PID 5921
2019-06-29 02:09:30,952 [salt.minion      :1711][INFO    ][5921] Returning information for job: 20190629020930896986
2019-06-29 02:09:58,493 [salt.loaded.ext.module.maas:1023][INFO    ][5848] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.23126316s left)
2019-06-29 02:10:01,037 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021001022195
2019-06-29 02:10:01,066 [salt.minion      :1432][INFO    ][5950] Starting a new job with PID 5950
2019-06-29 02:10:01,088 [salt.minion      :1711][INFO    ][5950] Returning information for job: 20190629021001022195
2019-06-29 02:10:29,351 [salt.loaded.ext.module.maas:1023][INFO    ][5848] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.37323618s left)
2019-06-29 02:10:31,112 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021031095031
2019-06-29 02:10:31,136 [salt.minion      :1432][INFO    ][5998] Starting a new job with PID 5998
2019-06-29 02:10:31,156 [salt.minion      :1711][INFO    ][5998] Returning information for job: 20190629021031095031
2019-06-29 02:11:00,431 [salt.loaded.ext.module.maas:1023][INFO    ][5848] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.29333115s left)
2019-06-29 02:11:01,172 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021101157696
2019-06-29 02:11:01,197 [salt.minion      :1432][INFO    ][6058] Starting a new job with PID 6058
2019-06-29 02:11:01,217 [salt.minion      :1711][INFO    ][6058] Returning information for job: 20190629021101157696
2019-06-29 02:11:31,257 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021131245109
2019-06-29 02:11:31,281 [salt.minion      :1432][INFO    ][6225] Starting a new job with PID 6225
2019-06-29 02:11:31,304 [salt.minion      :1711][INFO    ][6225] Returning information for job: 20190629021131245109
2019-06-29 02:11:31,615 [salt.loaded.ext.module.maas:1023][INFO    ][5848] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.10951304s left)
2019-06-29 02:12:01,363 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021201351217
2019-06-29 02:12:01,388 [salt.minion      :1432][INFO    ][6325] Starting a new job with PID 6325
2019-06-29 02:12:01,419 [salt.minion      :1711][INFO    ][6325] Returning information for job: 20190629021201351217
2019-06-29 02:12:02,952 [salt.loaded.ext.module.maas:1023][INFO    ][5848] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.77203417s left)
2019-06-29 02:12:31,508 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021231493270
2019-06-29 02:12:31,532 [salt.minion      :1432][INFO    ][6623] Starting a new job with PID 6623
2019-06-29 02:12:31,553 [salt.minion      :1711][INFO    ][6623] Returning information for job: 20190629021231493270
2019-06-29 02:12:34,386 [salt.loaded.ext.module.maas:1023][INFO    ][5848] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.33818603s left)
2019-06-29 02:13:01,614 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021301600942
2019-06-29 02:13:01,640 [salt.minion      :1432][INFO    ][6754] Starting a new job with PID 6754
2019-06-29 02:13:01,662 [salt.minion      :1711][INFO    ][6754] Returning information for job: 20190629021301600942
2019-06-29 02:13:06,459 [salt.loaded.ext.module.maas:1023][INFO    ][5848] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.26555109s left)
2019-06-29 02:13:31,774 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021331762122
2019-06-29 02:13:31,799 [salt.minion      :1432][INFO    ][7174] Starting a new job with PID 7174
2019-06-29 02:13:31,819 [salt.minion      :1711][INFO    ][7174] Returning information for job: 20190629021331762122
2019-06-29 02:13:38,733 [salt.loaded.ext.module.maas:1023][INFO    ][5848] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.99141407s left)
2019-06-29 02:14:01,893 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021401875634
2019-06-29 02:14:01,921 [salt.minion      :1432][INFO    ][7250] Starting a new job with PID 7250
2019-06-29 02:14:01,944 [salt.minion      :1711][INFO    ][7250] Returning information for job: 20190629021401875634
2019-06-29 02:14:12,300 [salt.loaded.ext.module.maas:1023][INFO    ][5848] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1183.42435813s left)
2019-06-29 02:14:32,053 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021432039596
2019-06-29 02:14:32,084 [salt.minion      :1432][INFO    ][7490] Starting a new job with PID 7490
2019-06-29 02:14:32,107 [salt.minion      :1711][INFO    ][7490] Returning information for job: 20190629021432039596
2019-06-29 02:14:45,063 [salt.loaded.ext.module.maas:1023][INFO    ][5848] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1150.66121316s left)
2019-06-29 02:15:02,192 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021502172843
2019-06-29 02:15:02,220 [salt.minion      :1432][INFO    ][7578] Starting a new job with PID 7578
2019-06-29 02:15:02,246 [salt.minion      :1711][INFO    ][7578] Returning information for job: 20190629021502172843
2019-06-29 02:15:18,036 [salt.state       :300 ][INFO    ][5848] {'ret': True}
2019-06-29 02:15:18,037 [salt.state       :1951][INFO    ][5848] Completed state [maas.wait_for_machine_status] at time 02:15:18.037413 duration_in_ms=382325.527
2019-06-29 02:15:18,044 [salt.minion      :1711][INFO    ][5848] Returning information for job: 20190629020845777081
2019-06-29 02:15:18,632 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command state.apply with jid 20190629021518619419
2019-06-29 02:15:18,660 [salt.minion      :1432][INFO    ][7633] Starting a new job with PID 7633
2019-06-29 02:15:26,636 [salt.state       :915 ][INFO    ][7633] Loading fresh modules for state activity
2019-06-29 02:15:26,699 [salt.fileclient  :1219][INFO    ][7633] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-29 02:15:26,800 [salt.state       :1780][INFO    ][7633] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:26.800832
2019-06-29 02:15:26,801 [salt.state       :1813][INFO    ][7633] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-29 02:15:26,803 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7633] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-29 02:15:28,502 [salt.state       :300 ][INFO    ][7633] {'pid': 7644, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-29 02:15:28,503 [salt.state       :1951][INFO    ][7633] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:28.503617 duration_in_ms=1702.784
2019-06-29 02:15:28,507 [salt.state       :1780][INFO    ][7633] Running state [maas_machines_storage_cmp002_lvm] at time 02:15:28.507568
2019-06-29 02:15:28,508 [salt.state       :1813][INFO    ][7633] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-29 02:15:29,681 [salt.loaded.ext.module.maasng:610 ][INFO    ][7633] yegdmc
2019-06-29 02:15:29,682 [salt.loaded.ext.module.maasng:626 ][INFO    ][7633] sda
2019-06-29 02:15:30,248 [salt.loaded.ext.module.maasng:361 ][INFO    ][7633] yegdmc
2019-06-29 02:15:30,349 [salt.loaded.ext.module.maasng:367 ][INFO    ][7633] [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'd6a36c49-f4c6-41ce-bb90-2e9f057fec8c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yegdmc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'24f02836-be53-41f0-8823-48d8cc957909', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}], u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'yegdmc', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/blockdevices/1/'}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'0c2b53cb-b6ab-43d9-9d84-1cd1e83f176c', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'yegdmc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'fad673ed-3968-43ed-bec6-5925e605c85a', u'fstype': u'ext4'}, u'id': 3, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 800097042432, u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/blockdevices/3/'}]
2019-06-29 02:15:30,350 [salt.loaded.ext.module.maasng:632 ][INFO    ][7633] vgroot
2019-06-29 02:15:30,350 [salt.loaded.ext.module.maasng:635 ][INFO    ][7633] lvroot
2019-06-29 02:15:30,351 [salt.loaded.ext.module.maasng:639 ][INFO    ][7633] 107374182400
2019-06-29 02:15:30,934 [salt.loaded.ext.module.maasng:645 ][INFO    ][7633] {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'hostname': u'cmp002', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'owner_data': {}, u'hwe_kernel': u'', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wsebwp', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 17, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wsebwp', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wsebwp', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'yegdmc', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/interfaces/4/'}, u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/yegdmc/', u'disable_ipv4': False, u'current_commissioning_result_id': 2, u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'yegdmc', 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'block_size': 4096, u'available_size': 0, u'uuid': u'd65e6817-0d1b-4f2b-bfbb-8f2037e1bfff', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 11, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'yegdmc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'f593930c-4a38-4074-a369-1ad2c9d71385', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/blockdevices/11/', u'type': u'virtual', u'model': None, u'size': 107374182400}], u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'112e64cf-361c-44c9-bc01-992be9552403', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yegdmc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c05c7809-f543-496f-8636-de7c3077614e', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'yegdmc', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/blockdevices/1/'}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'd65e6817-0d1b-4f2b-bfbb-8f2037e1bfff', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'yegdmc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'f593930c-4a38-4074-a369-1ad2c9d71385', u'fstype': u'ext4'}, u'id': 11, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 107374182400, u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/blockdevices/11/'}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'partitions': [{u'size': 800101236736, u'uuid': u'112e64cf-361c-44c9-bc01-992be9552403', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yegdmc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c05c7809-f543-496f-8636-de7c3077614e', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'yegdmc', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/blockdevices/1/', u'type': u'physical', u'model': u'LOGICAL VOLUME', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'yegdmc', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'partitions': [{u'size': 800101236736, u'uuid': u'112e64cf-361c-44c9-bc01-992be9552403', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yegdmc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c05c7809-f543-496f-8636-de7c3077614e', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'yegdmc', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/blockdevices/1/', u'type': u'physical', u'model': u'LOGICAL VOLUME', u'size': 800109715456}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', 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'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wsebwp', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 17, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wsebwp', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wsebwp', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'yegdmc', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'yegdmc', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/interfaces/14/'}, {u'name': u'ens2f0', u'links': [{u'id': 18, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'yegdmc', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/interfaces/15/'}, {u'name': u'ens2f1', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'yegdmc', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/interfaces/11/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wsebwp', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wsebwp', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wsebwp', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'system_id': u'yegdmc', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/interfaces/13/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'yegdmc', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/yegdmc/interfaces/12/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-06-29 02:15:30,937 [salt.state       :300 ][INFO    ][7633] {'new': {'storage_layout': 'lvm'}}
2019-06-29 02:15:30,939 [salt.state       :1951][INFO    ][7633] Completed state [maas_machines_storage_cmp002_lvm] at time 02:15:30.939251 duration_in_ms=2431.683
2019-06-29 02:15:30,939 [salt.state       :1780][INFO    ][7633] Running state [maas_machines_storage_cmp001_lvm] at time 02:15:30.939604
2019-06-29 02:15:30,939 [salt.state       :1813][INFO    ][7633] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-29 02:15:32,069 [salt.loaded.ext.module.maasng:610 ][INFO    ][7633] 6bwf8b
2019-06-29 02:15:32,070 [salt.loaded.ext.module.maasng:626 ][INFO    ][7633] sda
2019-06-29 02:15:32,664 [salt.loaded.ext.module.maasng:361 ][INFO    ][7633] 6bwf8b
2019-06-29 02:15:32,758 [salt.loaded.ext.module.maasng:367 ][INFO    ][7633] [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/blockdevices/2/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'name': u'sda', u'system_id': u'6bwf8b', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'bf58db50-cee5-452b-99ca-916083a1eac3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6bwf8b', u'filesystem': {u'label': None, u'uuid': u'9ca4ed0f-31d9-42dd-a4c2-57e04627ea7d', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'device_id': 2}]}, {u'size': 800097042432, u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/blockdevices/4/', u'available_size': 0, u'uuid': u'e173b088-fa59-4487-b0ec-0fd27e9db841', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'name': u'vgroot-lvroot', u'system_id': u'6bwf8b', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'6becda3e-12aa-4703-b0ba-e0a2eeda8680', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'serial': None, u'partitions': []}]
2019-06-29 02:15:32,759 [salt.loaded.ext.module.maasng:632 ][INFO    ][7633] vgroot
2019-06-29 02:15:32,759 [salt.loaded.ext.module.maasng:635 ][INFO    ][7633] lvroot
2019-06-29 02:15:32,759 [salt.loaded.ext.module.maasng:639 ][INFO    ][7633] 107374182400
2019-06-29 02:15:33,367 [salt.loaded.ext.module.maasng:645 ][INFO    ][7633] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, 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'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wsebwp', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wsebwp', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wsebwp', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'6bwf8b', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/blockdevices/2/', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'32b2755c-aef4-42aa-af74-06a033aa9847', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6bwf8b', u'filesystem': {u'uuid': u'd87dc296-b36f-4df5-a2d1-c08cb05712fd', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'6bwf8b', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'tags': [u'ssd'], u'size': 800109715456}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/6bwf8b/', u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'6bwf8b', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'32b2755c-aef4-42aa-af74-06a033aa9847', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6bwf8b', u'filesystem': {u'uuid': u'd87dc296-b36f-4df5-a2d1-c08cb05712fd', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'6bwf8b', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/blockdevices/2/'}, {u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'9f24138b-81f3-4556-a2b3-880150e75a41', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'1627d5c5-c19a-40ed-84b7-8c64bb7a2901', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6bwf8b', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/blockdevices/12/'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/blockdevices/2/', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'32b2755c-aef4-42aa-af74-06a033aa9847', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6bwf8b', u'filesystem': {u'uuid': u'd87dc296-b36f-4df5-a2d1-c08cb05712fd', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'6bwf8b', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'tags': [u'ssd'], u'size': 800109715456}], u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'6bwf8b', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'model': None, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/blockdevices/12/', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'9f24138b-81f3-4556-a2b3-880150e75a41', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'1627d5c5-c19a-40ed-84b7-8c64bb7a2901', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6bwf8b', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'tags': [], u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wsebwp', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wsebwp', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wsebwp', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'6bwf8b', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'parents': [], u'system_id': u'6bwf8b', u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/interfaces/17/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 19, u'discovered': None, u'parents': [], u'system_id': u'6bwf8b', u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/interfaces/19/'}, {u'name': u'ens2f1', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'parents': [], u'system_id': u'6bwf8b', u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/interfaces/16/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wsebwp', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wsebwp', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 18, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wsebwp', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'parents': [], u'system_id': u'6bwf8b', u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/interfaces/18/'}, {u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'parents': [], u'system_id': u'6bwf8b', u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6bwf8b/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-06-29 02:15:33,370 [salt.state       :300 ][INFO    ][7633] {'new': {'storage_layout': 'lvm'}}
2019-06-29 02:15:33,370 [salt.state       :1951][INFO    ][7633] Completed state [maas_machines_storage_cmp001_lvm] at time 02:15:33.370822 duration_in_ms=2431.216
2019-06-29 02:15:33,376 [salt.minion      :1711][INFO    ][7633] Returning information for job: 20190629021518619419
2019-06-29 02:15:33,955 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command state.apply with jid 20190629021533942692
2019-06-29 02:15:33,976 [salt.minion      :1432][INFO    ][7662] Starting a new job with PID 7662
2019-06-29 02:15:35,107 [salt.state       :915 ][INFO    ][7662] Loading fresh modules for state activity
2019-06-29 02:15:35,167 [salt.fileclient  :1219][INFO    ][7662] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-29 02:15:35,217 [salt.state       :1780][INFO    ][7662] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:35.216975
2019-06-29 02:15:35,217 [salt.state       :1813][INFO    ][7662] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-29 02:15:35,219 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7662] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-29 02:15:36,929 [salt.state       :300 ][INFO    ][7662] {'pid': 7671, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-29 02:15:36,930 [salt.state       :1951][INFO    ][7662] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:36.930293 duration_in_ms=1713.318
2019-06-29 02:15:36,932 [salt.state       :1780][INFO    ][7662] Running state [maas.deploy_machines] at time 02:15:36.932611
2019-06-29 02:15:36,933 [salt.state       :1813][INFO    ][7662] Executing state module.run for [maas.deploy_machines]
2019-06-29 02:15:36,934 [salt.utils.decorators:613 ][WARNING ][7662] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:15:37,506 [salt.loaded.ext.module.maas:684 ][INFO    ][7662] deploymachines hwe_kernel=ga-16.04 system_id=yegdmc distro_series=xenial
2019-06-29 02:15:39,897 [salt.loaded.ext.module.maas:684 ][INFO    ][7662] deploymachines hwe_kernel=ga-16.04 system_id=6bwf8b distro_series=xenial
2019-06-29 02:15:42,224 [salt.loaded.ext.module.maas:684 ][INFO    ][7662] deploymachines hwe_kernel=ga-16.04 system_id=q4mwbk distro_series=xenial
2019-06-29 02:15:44,610 [salt.loaded.ext.module.maas:684 ][INFO    ][7662] deploymachines hwe_kernel=ga-16.04 system_id=fkkxt3 distro_series=xenial
2019-06-29 02:15:47,111 [salt.loaded.ext.module.maas:684 ][INFO    ][7662] deploymachines hwe_kernel=ga-16.04 system_id=rmrm88 distro_series=xenial
2019-06-29 02:15:49,056 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021549042931
2019-06-29 02:15:49,075 [salt.minion      :1432][INFO    ][7932] Starting a new job with PID 7932
2019-06-29 02:15:49,097 [salt.minion      :1711][INFO    ][7932] Returning information for job: 20190629021549042931
2019-06-29 02:15:49,711 [salt.state       :300 ][INFO    ][7662] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-29 02:15:49,711 [salt.state       :1951][INFO    ][7662] Completed state [maas.deploy_machines] at time 02:15:49.711860 duration_in_ms=12779.249
2019-06-29 02:15:49,715 [salt.minion      :1711][INFO    ][7662] Returning information for job: 20190629021533942692
2019-06-29 02:15:50,279 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command state.apply with jid 20190629021550262228
2019-06-29 02:15:50,303 [salt.minion      :1432][INFO    ][7947] Starting a new job with PID 7947
2019-06-29 02:15:58,612 [salt.state       :915 ][INFO    ][7947] Loading fresh modules for state activity
2019-06-29 02:15:58,672 [salt.fileclient  :1219][INFO    ][7947] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-29 02:15:58,722 [salt.state       :1780][INFO    ][7947] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:58.722173
2019-06-29 02:15:58,722 [salt.state       :1813][INFO    ][7947] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-29 02:15:58,724 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7947] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-29 02:16:00,507 [salt.state       :300 ][INFO    ][7947] {'pid': 7979, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-29 02:16:00,508 [salt.state       :1951][INFO    ][7947] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:16:00.508672 duration_in_ms=1786.499
2019-06-29 02:16:00,512 [salt.state       :1780][INFO    ][7947] Running state [maas.wait_for_machine_status] at time 02:16:00.512818
2019-06-29 02:16:00,513 [salt.state       :1813][INFO    ][7947] Executing state module.run for [maas.wait_for_machine_status]
2019-06-29 02:16:00,514 [salt.utils.decorators:613 ][WARNING ][7947] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:16:03,584 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.94210792s left)
2019-06-29 02:16:05,361 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021605350575
2019-06-29 02:16:05,391 [salt.minion      :1432][INFO    ][7995] Starting a new job with PID 7995
2019-06-29 02:16:05,416 [salt.minion      :1711][INFO    ][7995] Returning information for job: 20190629021605350575
2019-06-29 02:16:35,443 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021635427679
2019-06-29 02:16:35,470 [salt.minion      :1432][INFO    ][8050] Starting a new job with PID 8050
2019-06-29 02:16:35,494 [salt.minion      :1711][INFO    ][8050] Returning information for job: 20190629021635427679
2019-06-29 02:16:36,589 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.93750501s left)
2019-06-29 02:17:05,545 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021705531913
2019-06-29 02:17:05,568 [salt.minion      :1432][INFO    ][8096] Starting a new job with PID 8096
2019-06-29 02:17:05,591 [salt.minion      :1711][INFO    ][8096] Returning information for job: 20190629021705531913
2019-06-29 02:17:09,539 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.98698902s left)
2019-06-29 02:17:35,626 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021735609047
2019-06-29 02:17:35,653 [salt.minion      :1432][INFO    ][8155] Starting a new job with PID 8155
2019-06-29 02:17:35,676 [salt.minion      :1711][INFO    ][8155] Returning information for job: 20190629021735609047
2019-06-29 02:17:42,532 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.99383998s left)
2019-06-29 02:18:05,712 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021805696637
2019-06-29 02:18:05,742 [salt.minion      :1432][INFO    ][8187] Starting a new job with PID 8187
2019-06-29 02:18:05,764 [salt.minion      :1711][INFO    ][8187] Returning information for job: 20190629021805696637
2019-06-29 02:18:15,649 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.87715006s left)
2019-06-29 02:18:35,817 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021835798188
2019-06-29 02:18:35,847 [salt.minion      :1432][INFO    ][8252] Starting a new job with PID 8252
2019-06-29 02:18:35,867 [salt.minion      :1711][INFO    ][8252] Returning information for job: 20190629021835798188
2019-06-29 02:18:48,784 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.74252796s left)
2019-06-29 02:19:05,919 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021905902614
2019-06-29 02:19:05,947 [salt.minion      :1432][INFO    ][8336] Starting a new job with PID 8336
2019-06-29 02:19:05,971 [salt.minion      :1711][INFO    ][8336] Returning information for job: 20190629021905902614
2019-06-29 02:19:21,909 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.6174109s left)
2019-06-29 02:19:36,045 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629021936037319
2019-06-29 02:19:36,070 [salt.minion      :1432][INFO    ][8562] Starting a new job with PID 8562
2019-06-29 02:19:36,099 [salt.minion      :1711][INFO    ][8562] Returning information for job: 20190629021936037319
2019-06-29 02:19:54,934 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.59179091s left)
2019-06-29 02:20:06,138 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022006129634
2019-06-29 02:20:06,154 [salt.minion      :1432][INFO    ][8628] Starting a new job with PID 8628
2019-06-29 02:20:06,175 [salt.minion      :1711][INFO    ][8628] Returning information for job: 20190629022006129634
2019-06-29 02:20:27,952 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.57405186s left)
2019-06-29 02:20:36,259 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022036243921
2019-06-29 02:20:36,289 [salt.minion      :1432][INFO    ][8882] Starting a new job with PID 8882
2019-06-29 02:20:36,335 [salt.minion      :1711][INFO    ][8882] Returning information for job: 20190629022036243921
2019-06-29 02:21:01,009 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.5174799s left)
2019-06-29 02:21:06,428 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022106411396
2019-06-29 02:21:06,455 [salt.minion      :1432][INFO    ][8954] Starting a new job with PID 8954
2019-06-29 02:21:06,476 [salt.minion      :1711][INFO    ][8954] Returning information for job: 20190629022106411396
2019-06-29 02:21:33,801 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.72505093s left)
2019-06-29 02:21:36,619 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022136606253
2019-06-29 02:21:36,645 [salt.minion      :1432][INFO    ][9429] Starting a new job with PID 9429
2019-06-29 02:21:36,672 [salt.minion      :1711][INFO    ][9429] Returning information for job: 20190629022136606253
2019-06-29 02:22:06,752 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022206740356
2019-06-29 02:22:06,768 [salt.minion      :1432][INFO    ][9485] Starting a new job with PID 9485
2019-06-29 02:22:06,792 [salt.minion      :1711][INFO    ][9485] Returning information for job: 20190629022206740356
2019-06-29 02:22:06,931 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.5950439s left)
2019-06-29 02:22:36,909 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022236894334
2019-06-29 02:22:36,940 [salt.minion      :1432][INFO    ][9690] Starting a new job with PID 9690
2019-06-29 02:22:36,965 [salt.minion      :1711][INFO    ][9690] Returning information for job: 20190629022236894334
2019-06-29 02:22:40,050 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.47591901s left)
2019-06-29 02:23:07,078 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022307056975
2019-06-29 02:23:07,109 [salt.minion      :1432][INFO    ][9730] Starting a new job with PID 9730
2019-06-29 02:23:07,132 [salt.minion      :1711][INFO    ][9730] Returning information for job: 20190629022307056975
2019-06-29 02:23:15,199 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1815.32681704s left)
2019-06-29 02:23:37,299 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022337281186
2019-06-29 02:23:37,325 [salt.minion      :1432][INFO    ][10178] Starting a new job with PID 10178
2019-06-29 02:23:37,348 [salt.minion      :1711][INFO    ][10178] Returning information for job: 20190629022337281186
2019-06-29 02:23:48,421 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1782.10551906s left)
2019-06-29 02:24:07,460 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022407448316
2019-06-29 02:24:07,489 [salt.minion      :1432][INFO    ][10216] Starting a new job with PID 10216
2019-06-29 02:24:07,512 [salt.minion      :1711][INFO    ][10216] Returning information for job: 20190629022407448316
2019-06-29 02:24:21,336 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1749.19057894s left)
2019-06-29 02:24:37,640 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022437625108
2019-06-29 02:24:37,666 [salt.minion      :1432][INFO    ][10445] Starting a new job with PID 10445
2019-06-29 02:24:37,692 [salt.minion      :1711][INFO    ][10445] Returning information for job: 20190629022437625108
2019-06-29 02:24:54,276 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1716.25043702s left)
2019-06-29 02:25:07,829 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022507812257
2019-06-29 02:25:07,864 [salt.minion      :1432][INFO    ][10495] Starting a new job with PID 10495
2019-06-29 02:25:07,886 [salt.minion      :1711][INFO    ][10495] Returning information for job: 20190629022507812257
2019-06-29 02:25:27,142 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1683.38500786s left)
2019-06-29 02:25:38,010 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022537997288
2019-06-29 02:25:38,027 [salt.minion      :1432][INFO    ][10606] Starting a new job with PID 10606
2019-06-29 02:25:38,050 [salt.minion      :1711][INFO    ][10606] Returning information for job: 20190629022537997288
2019-06-29 02:26:00,213 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1650.31290388s left)
2019-06-29 02:26:08,075 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022608067618
2019-06-29 02:26:08,101 [salt.minion      :1432][INFO    ][10659] Starting a new job with PID 10659
2019-06-29 02:26:08,125 [salt.minion      :1711][INFO    ][10659] Returning information for job: 20190629022608067618
2019-06-29 02:26:33,190 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1617.33660793s left)
2019-06-29 02:26:38,094 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022638079122
2019-06-29 02:26:38,125 [salt.minion      :1432][INFO    ][10903] Starting a new job with PID 10903
2019-06-29 02:26:38,147 [salt.minion      :1711][INFO    ][10903] Returning information for job: 20190629022638079122
2019-06-29 02:27:06,063 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1584.46280599s left)
2019-06-29 02:27:08,291 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022708274286
2019-06-29 02:27:08,319 [salt.minion      :1432][INFO    ][11067] Starting a new job with PID 11067
2019-06-29 02:27:08,339 [salt.minion      :1711][INFO    ][11067] Returning information for job: 20190629022708274286
2019-06-29 02:27:38,485 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022738471956
2019-06-29 02:27:38,511 [salt.minion      :1432][INFO    ][11123] Starting a new job with PID 11123
2019-06-29 02:27:38,532 [salt.minion      :1711][INFO    ][11123] Returning information for job: 20190629022738471956
2019-06-29 02:27:38,962 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1551.56384897s left)
2019-06-29 02:28:08,684 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022808666317
2019-06-29 02:28:08,711 [salt.minion      :1432][INFO    ][11147] Starting a new job with PID 11147
2019-06-29 02:28:08,735 [salt.minion      :1711][INFO    ][11147] Returning information for job: 20190629022808666317
2019-06-29 02:28:12,240 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1518.28628588s left)
2019-06-29 02:28:38,920 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022838900613
2019-06-29 02:28:38,939 [salt.minion      :1432][INFO    ][11258] Starting a new job with PID 11258
2019-06-29 02:28:38,968 [salt.minion      :1711][INFO    ][11258] Returning information for job: 20190629022838900613
2019-06-29 02:28:45,467 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1485.05879688s left)
2019-06-29 02:29:09,072 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022909056951
2019-06-29 02:29:09,100 [salt.minion      :1432][INFO    ][11329] Starting a new job with PID 11329
2019-06-29 02:29:09,123 [salt.minion      :1711][INFO    ][11329] Returning information for job: 20190629022909056951
2019-06-29 02:29:18,463 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1452.06315589s left)
2019-06-29 02:29:39,127 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629022939111991
2019-06-29 02:29:39,154 [salt.minion      :1432][INFO    ][11529] Starting a new job with PID 11529
2019-06-29 02:29:39,177 [salt.minion      :1711][INFO    ][11529] Returning information for job: 20190629022939111991
2019-06-29 02:29:51,456 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1419.06968594s left)
2019-06-29 02:30:09,186 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023009168235
2019-06-29 02:30:09,215 [salt.minion      :1432][INFO    ][11558] Starting a new job with PID 11558
2019-06-29 02:30:09,249 [salt.minion      :1711][INFO    ][11558] Returning information for job: 20190629023009168235
2019-06-29 02:30:24,388 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1386.13782692s left)
2019-06-29 02:30:39,247 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023039230236
2019-06-29 02:30:39,273 [salt.minion      :1432][INFO    ][11608] Starting a new job with PID 11608
2019-06-29 02:30:39,302 [salt.minion      :1711][INFO    ][11608] Returning information for job: 20190629023039230236
2019-06-29 02:30:57,228 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1353.29796886s left)
2019-06-29 02:31:09,319 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023109302546
2019-06-29 02:31:09,348 [salt.minion      :1432][INFO    ][11638] Starting a new job with PID 11638
2019-06-29 02:31:09,368 [salt.minion      :1711][INFO    ][11638] Returning information for job: 20190629023109302546
2019-06-29 02:31:30,227 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1320.29940796s left)
2019-06-29 02:31:39,401 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023139384931
2019-06-29 02:31:39,432 [salt.minion      :1432][INFO    ][11687] Starting a new job with PID 11687
2019-06-29 02:31:39,457 [salt.minion      :1711][INFO    ][11687] Returning information for job: 20190629023139384931
2019-06-29 02:32:03,071 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1287.45488906s left)
2019-06-29 02:32:09,485 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023209472303
2019-06-29 02:32:09,509 [salt.minion      :1432][INFO    ][11716] Starting a new job with PID 11716
2019-06-29 02:32:09,532 [salt.minion      :1711][INFO    ][11716] Returning information for job: 20190629023209472303
2019-06-29 02:32:35,951 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1254.57484889s left)
2019-06-29 02:32:39,559 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023239547979
2019-06-29 02:32:39,588 [salt.minion      :1432][INFO    ][11775] Starting a new job with PID 11775
2019-06-29 02:32:39,611 [salt.minion      :1711][INFO    ][11775] Returning information for job: 20190629023239547979
2019-06-29 02:33:08,980 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1221.54573393s left)
2019-06-29 02:33:09,664 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023309648927
2019-06-29 02:33:09,691 [salt.minion      :1432][INFO    ][11804] Starting a new job with PID 11804
2019-06-29 02:33:09,714 [salt.minion      :1711][INFO    ][11804] Returning information for job: 20190629023309648927
2019-06-29 02:33:39,736 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023339724782
2019-06-29 02:33:39,761 [salt.minion      :1432][INFO    ][11861] Starting a new job with PID 11861
2019-06-29 02:33:39,781 [salt.minion      :1711][INFO    ][11861] Returning information for job: 20190629023339724782
2019-06-29 02:33:41,809 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1188.71702385s left)
2019-06-29 02:34:09,868 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023409848417
2019-06-29 02:34:09,897 [salt.minion      :1432][INFO    ][11888] Starting a new job with PID 11888
2019-06-29 02:34:09,920 [salt.minion      :1711][INFO    ][11888] Returning information for job: 20190629023409848417
2019-06-29 02:34:14,673 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1155.85311198s left)
2019-06-29 02:34:39,995 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023439984553
2019-06-29 02:34:40,017 [salt.minion      :1432][INFO    ][11939] Starting a new job with PID 11939
2019-06-29 02:34:40,040 [salt.minion      :1711][INFO    ][11939] Returning information for job: 20190629023439984553
2019-06-29 02:34:47,398 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1123.12856889s left)
2019-06-29 02:35:10,186 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023510162735
2019-06-29 02:35:10,218 [salt.minion      :1432][INFO    ][11987] Starting a new job with PID 11987
2019-06-29 02:35:10,255 [salt.minion      :1711][INFO    ][11987] Returning information for job: 20190629023510162735
2019-06-29 02:35:20,179 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1090.34751201s left)
2019-06-29 02:35:40,368 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023540354516
2019-06-29 02:35:40,396 [salt.minion      :1432][INFO    ][12020] Starting a new job with PID 12020
2019-06-29 02:35:40,418 [salt.minion      :1711][INFO    ][12020] Returning information for job: 20190629023540354516
2019-06-29 02:35:53,115 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1057.41146994s left)
2019-06-29 02:36:10,555 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023610541706
2019-06-29 02:36:10,581 [salt.minion      :1432][INFO    ][12070] Starting a new job with PID 12070
2019-06-29 02:36:10,606 [salt.minion      :1711][INFO    ][12070] Returning information for job: 20190629023610541706
2019-06-29 02:36:26,010 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1024.51603985s left)
2019-06-29 02:36:40,740 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023640723623
2019-06-29 02:36:40,765 [salt.minion      :1432][INFO    ][12097] Starting a new job with PID 12097
2019-06-29 02:36:40,791 [salt.minion      :1711][INFO    ][12097] Returning information for job: 20190629023640723623
2019-06-29 02:36:58,720 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (991.805783033s left)
2019-06-29 02:37:10,962 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023710947671
2019-06-29 02:37:10,987 [salt.minion      :1432][INFO    ][12165] Starting a new job with PID 12165
2019-06-29 02:37:11,014 [salt.minion      :1711][INFO    ][12165] Returning information for job: 20190629023710947671
2019-06-29 02:37:31,459 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (959.066774845s left)
2019-06-29 02:37:40,973 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023740965689
2019-06-29 02:37:40,990 [salt.minion      :1432][INFO    ][12194] Starting a new job with PID 12194
2019-06-29 02:37:41,011 [salt.minion      :1711][INFO    ][12194] Returning information for job: 20190629023740965689
2019-06-29 02:38:04,175 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (926.350836039s left)
2019-06-29 02:38:10,996 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023810984319
2019-06-29 02:38:11,023 [salt.minion      :1432][INFO    ][12251] Starting a new job with PID 12251
2019-06-29 02:38:11,047 [salt.minion      :1711][INFO    ][12251] Returning information for job: 20190629023810984319
2019-06-29 02:38:37,024 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (893.502413988s left)
2019-06-29 02:38:41,094 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023841084193
2019-06-29 02:38:41,122 [salt.minion      :1432][INFO    ][12274] Starting a new job with PID 12274
2019-06-29 02:38:41,144 [salt.minion      :1711][INFO    ][12274] Returning information for job: 20190629023841084193
2019-06-29 02:39:09,865 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (860.661172867s left)
2019-06-29 02:39:11,152 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023911137154
2019-06-29 02:39:11,179 [salt.minion      :1432][INFO    ][12329] Starting a new job with PID 12329
2019-06-29 02:39:11,205 [salt.minion      :1711][INFO    ][12329] Returning information for job: 20190629023911137154
2019-06-29 02:39:41,262 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629023941252133
2019-06-29 02:39:41,286 [salt.minion      :1432][INFO    ][12348] Starting a new job with PID 12348
2019-06-29 02:39:41,311 [salt.minion      :1711][INFO    ][12348] Returning information for job: 20190629023941252133
2019-06-29 02:39:42,989 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (827.537215948s left)
2019-06-29 02:40:11,367 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024011351574
2019-06-29 02:40:11,394 [salt.minion      :1432][INFO    ][12401] Starting a new job with PID 12401
2019-06-29 02:40:11,416 [salt.minion      :1711][INFO    ][12401] Returning information for job: 20190629024011351574
2019-06-29 02:40:15,670 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (794.856976986s left)
2019-06-29 02:40:41,535 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024041523125
2019-06-29 02:40:41,561 [salt.minion      :1432][INFO    ][12420] Starting a new job with PID 12420
2019-06-29 02:40:41,590 [salt.minion      :1711][INFO    ][12420] Returning information for job: 20190629024041523125
2019-06-29 02:40:48,415 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (762.111588001s left)
2019-06-29 02:41:11,680 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024111664043
2019-06-29 02:41:11,705 [salt.minion      :1432][INFO    ][12473] Starting a new job with PID 12473
2019-06-29 02:41:11,731 [salt.minion      :1711][INFO    ][12473] Returning information for job: 20190629024111664043
2019-06-29 02:41:21,319 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (729.207507849s left)
2019-06-29 02:41:41,850 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024141833019
2019-06-29 02:41:41,882 [salt.minion      :1432][INFO    ][12493] Starting a new job with PID 12493
2019-06-29 02:41:41,907 [salt.minion      :1711][INFO    ][12493] Returning information for job: 20190629024141833019
2019-06-29 02:41:54,226 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (696.300648928s left)
2019-06-29 02:42:12,035 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024212027343
2019-06-29 02:42:12,050 [salt.minion      :1432][INFO    ][12549] Starting a new job with PID 12549
2019-06-29 02:42:12,078 [salt.minion      :1711][INFO    ][12549] Returning information for job: 20190629024212027343
2019-06-29 02:42:27,041 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (663.485275984s left)
2019-06-29 02:42:42,253 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024242240610
2019-06-29 02:42:42,287 [salt.minion      :1432][INFO    ][12569] Starting a new job with PID 12569
2019-06-29 02:42:42,311 [salt.minion      :1711][INFO    ][12569] Returning information for job: 20190629024242240610
2019-06-29 02:42:59,791 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (630.735285044s left)
2019-06-29 02:43:12,445 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024312426630
2019-06-29 02:43:12,474 [salt.minion      :1432][INFO    ][12626] Starting a new job with PID 12626
2019-06-29 02:43:12,500 [salt.minion      :1711][INFO    ][12626] Returning information for job: 20190629024312426630
2019-06-29 02:43:32,566 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (597.960543871s left)
2019-06-29 02:43:42,487 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024342470930
2019-06-29 02:43:42,516 [salt.minion      :1432][INFO    ][12650] Starting a new job with PID 12650
2019-06-29 02:43:42,537 [salt.minion      :1711][INFO    ][12650] Returning information for job: 20190629024342470930
2019-06-29 02:44:05,574 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (564.95175004s left)
2019-06-29 02:44:12,553 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024412544151
2019-06-29 02:44:12,574 [salt.minion      :1432][INFO    ][12706] Starting a new job with PID 12706
2019-06-29 02:44:12,603 [salt.minion      :1711][INFO    ][12706] Returning information for job: 20190629024412544151
2019-06-29 02:44:38,373 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (532.153423071s left)
2019-06-29 02:44:42,631 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024442623107
2019-06-29 02:44:42,655 [salt.minion      :1432][INFO    ][12724] Starting a new job with PID 12724
2019-06-29 02:44:42,678 [salt.minion      :1711][INFO    ][12724] Returning information for job: 20190629024442623107
2019-06-29 02:45:11,169 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (499.35760498s left)
2019-06-29 02:45:12,732 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024512717159
2019-06-29 02:45:12,763 [salt.minion      :1432][INFO    ][12777] Starting a new job with PID 12777
2019-06-29 02:45:12,796 [salt.minion      :1711][INFO    ][12777] Returning information for job: 20190629024512717159
2019-06-29 02:45:42,884 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024542872969
2019-06-29 02:45:42,906 [salt.minion      :1432][INFO    ][12796] Starting a new job with PID 12796
2019-06-29 02:45:42,936 [salt.minion      :1711][INFO    ][12796] Returning information for job: 20190629024542872969
2019-06-29 02:45:44,027 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (466.499076843s left)
2019-06-29 02:46:13,054 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024613036178
2019-06-29 02:46:13,076 [salt.minion      :1432][INFO    ][12847] Starting a new job with PID 12847
2019-06-29 02:46:13,101 [salt.minion      :1711][INFO    ][12847] Returning information for job: 20190629024613036178
2019-06-29 02:46:16,923 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (433.602982044s left)
2019-06-29 02:46:43,163 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024643145858
2019-06-29 02:46:43,190 [salt.minion      :1432][INFO    ][12869] Starting a new job with PID 12869
2019-06-29 02:46:43,216 [salt.minion      :1711][INFO    ][12869] Returning information for job: 20190629024643145858
2019-06-29 02:46:49,648 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (400.877882004s left)
2019-06-29 02:47:13,356 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024713347349
2019-06-29 02:47:13,374 [salt.minion      :1432][INFO    ][13065] Starting a new job with PID 13065
2019-06-29 02:47:13,403 [salt.minion      :1711][INFO    ][13065] Returning information for job: 20190629024713347349
2019-06-29 02:47:22,410 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (368.116637945s left)
2019-06-29 02:47:43,546 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024743534094
2019-06-29 02:47:43,572 [salt.minion      :1432][INFO    ][13090] Starting a new job with PID 13090
2019-06-29 02:47:43,596 [salt.minion      :1711][INFO    ][13090] Returning information for job: 20190629024743534094
2019-06-29 02:47:55,292 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (335.234657049s left)
2019-06-29 02:48:13,768 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024813752565
2019-06-29 02:48:13,794 [salt.minion      :1432][INFO    ][13144] Starting a new job with PID 13144
2019-06-29 02:48:13,828 [salt.minion      :1711][INFO    ][13144] Returning information for job: 20190629024813752565
2019-06-29 02:48:28,164 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (302.362133026s left)
2019-06-29 02:48:43,964 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024843954386
2019-06-29 02:48:43,995 [salt.minion      :1432][INFO    ][13167] Starting a new job with PID 13167
2019-06-29 02:48:44,023 [salt.minion      :1711][INFO    ][13167] Returning information for job: 20190629024843954386
2019-06-29 02:49:01,089 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (269.437483072s left)
2019-06-29 02:49:14,055 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024914035700
2019-06-29 02:49:14,084 [salt.minion      :1432][INFO    ][13223] Starting a new job with PID 13223
2019-06-29 02:49:14,108 [salt.minion      :1711][INFO    ][13223] Returning information for job: 20190629024914035700
2019-06-29 02:49:33,947 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (236.578755856s left)
2019-06-29 02:49:44,141 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629024944128319
2019-06-29 02:49:44,161 [salt.minion      :1432][INFO    ][13245] Starting a new job with PID 13245
2019-06-29 02:49:44,185 [salt.minion      :1711][INFO    ][13245] Returning information for job: 20190629024944128319
2019-06-29 02:50:06,712 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (203.813716888s left)
2019-06-29 02:50:14,212 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025014196919
2019-06-29 02:50:14,242 [salt.minion      :1432][INFO    ][13300] Starting a new job with PID 13300
2019-06-29 02:50:14,267 [salt.minion      :1711][INFO    ][13300] Returning information for job: 20190629025014196919
2019-06-29 02:50:39,667 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (170.85957098s left)
2019-06-29 02:50:44,335 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025044317308
2019-06-29 02:50:44,363 [salt.minion      :1432][INFO    ][13321] Starting a new job with PID 13321
2019-06-29 02:50:44,384 [salt.minion      :1711][INFO    ][13321] Returning information for job: 20190629025044317308
2019-06-29 02:51:12,540 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (137.986266851s left)
2019-06-29 02:51:14,420 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025114403412
2019-06-29 02:51:14,446 [salt.minion      :1432][INFO    ][13381] Starting a new job with PID 13381
2019-06-29 02:51:14,468 [salt.minion      :1711][INFO    ][13381] Returning information for job: 20190629025114403412
2019-06-29 02:51:44,569 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025144555584
2019-06-29 02:51:44,591 [salt.minion      :1432][INFO    ][13401] Starting a new job with PID 13401
2019-06-29 02:51:44,616 [salt.minion      :1711][INFO    ][13401] Returning information for job: 20190629025144555584
2019-06-29 02:51:45,376 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (105.150410891s left)
2019-06-29 02:52:14,737 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025214729620
2019-06-29 02:52:14,756 [salt.minion      :1432][INFO    ][13453] Starting a new job with PID 13453
2019-06-29 02:52:14,779 [salt.minion      :1711][INFO    ][13453] Returning information for job: 20190629025214729620
2019-06-29 02:52:18,262 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (72.2638199329s left)
2019-06-29 02:52:44,936 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025244920177
2019-06-29 02:52:44,969 [salt.minion      :1432][INFO    ][13480] Starting a new job with PID 13480
2019-06-29 02:52:44,992 [salt.minion      :1711][INFO    ][13480] Returning information for job: 20190629025244920177
2019-06-29 02:52:50,964 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (39.5621399879s left)
2019-06-29 02:53:15,147 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025315133337
2019-06-29 02:53:15,177 [salt.minion      :1432][INFO    ][13533] Starting a new job with PID 13533
2019-06-29 02:53:15,201 [salt.minion      :1711][INFO    ][13533] Returning information for job: 20190629025315133337
2019-06-29 02:53:23,747 [salt.loaded.ext.module.maas:1023][INFO    ][7947] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (6.77880787849s left)
2019-06-29 02:53:45,172 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025345156719
2019-06-29 02:53:45,201 [salt.minion      :1432][INFO    ][13555] Starting a new job with PID 13555
2019-06-29 02:53:45,222 [salt.minion      :1711][INFO    ][13555] Returning information for job: 20190629025345156719
2019-06-29 02:53:56,464 [salt.state       :302 ][ERROR   ][7947] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-06-29 02:53:56,464 [salt.state       :1951][INFO    ][7947] Completed state [maas.wait_for_machine_status] at time 02:53:56.464871 duration_in_ms=2275952.052
2019-06-29 02:53:56,471 [salt.minion      :1711][INFO    ][7947] Returning information for job: 20190629021550262228
2019-06-29 02:54:07,241 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command pillar.get with jid 20190629025407228970
2019-06-29 02:54:07,266 [salt.minion      :1432][INFO    ][13580] Starting a new job with PID 13580
2019-06-29 02:54:07,273 [salt.minion      :1711][INFO    ][13580] Returning information for job: 20190629025407228970
2019-06-29 02:54:07,834 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command service.status with jid 20190629025407824342
2019-06-29 02:54:07,861 [salt.minion      :1432][INFO    ][13585] Starting a new job with PID 13585
2019-06-29 02:54:08,594 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13585] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-29 02:54:08,635 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13585] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-29 02:54:08,656 [salt.minion      :1711][INFO    ][13585] Returning information for job: 20190629025407824342
2019-06-29 02:54:09,236 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command state.apply with jid 20190629025409225250
2019-06-29 02:54:09,262 [salt.minion      :1432][INFO    ][13597] Starting a new job with PID 13597
2019-06-29 02:54:17,510 [salt.state       :915 ][INFO    ][13597] Loading fresh modules for state activity
2019-06-29 02:54:18,138 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13597] Executing command 'salt-minion --version' in directory '/root'
2019-06-29 02:54:18,428 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13597] Executing command 'salt-minion --version' in directory '/root'
2019-06-29 02:54:19,448 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13597] Executing command 'salt-minion --version' in directory '/root'
2019-06-29 02:54:19,747 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13597] Executing command 'salt-minion --version' in directory '/root'
2019-06-29 02:54:21,625 [salt.state       :1780][INFO    ][13597] Running state [salt-minion] at time 02:54:21.625234
2019-06-29 02:54:21,625 [salt.state       :1813][INFO    ][13597] Executing state pkg.installed for [salt-minion]
2019-06-29 02:54:21,627 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13597] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-29 02:54:21,742 [salt.state       :300 ][INFO    ][13597] All specified packages are already installed
2019-06-29 02:54:21,742 [salt.state       :1951][INFO    ][13597] Completed state [salt-minion] at time 02:54:21.742779 duration_in_ms=117.546
2019-06-29 02:54:21,743 [salt.state       :1780][INFO    ][13597] Running state [salt_minion_dependency_packages] at time 02:54:21.743096
2019-06-29 02:54:21,743 [salt.state       :1813][INFO    ][13597] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-29 02:54:21,753 [salt.state       :300 ][INFO    ][13597] All specified packages are already installed
2019-06-29 02:54:21,753 [salt.state       :1951][INFO    ][13597] Completed state [salt_minion_dependency_packages] at time 02:54:21.753357 duration_in_ms=10.26
2019-06-29 02:54:21,756 [salt.state       :1780][INFO    ][13597] Running state [/etc/salt/minion.d/minion.conf] at time 02:54:21.756209
2019-06-29 02:54:21,756 [salt.state       :1813][INFO    ][13597] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-29 02:54:22,031 [salt.state       :300 ][INFO    ][13597] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-29 02:54:22,031 [salt.state       :1951][INFO    ][13597] Completed state [/etc/salt/minion.d/minion.conf] at time 02:54:22.031460 duration_in_ms=275.251
2019-06-29 02:54:22,031 [salt.state       :1780][INFO    ][13597] Running state [python-netaddr] at time 02:54:22.031713
2019-06-29 02:54:22,031 [salt.state       :1813][INFO    ][13597] Executing state pkg.installed for [python-netaddr]
2019-06-29 02:54:22,040 [salt.state       :300 ][INFO    ][13597] All specified packages are already installed
2019-06-29 02:54:22,040 [salt.state       :1951][INFO    ][13597] Completed state [python-netaddr] at time 02:54:22.040719 duration_in_ms=9.006
2019-06-29 02:54:22,044 [salt.state       :1780][INFO    ][13597] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:54:22.044506
2019-06-29 02:54:22,044 [salt.state       :1813][INFO    ][13597] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-29 02:54:22,058 [salt.state       :300 ][INFO    ][13597] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-29 02:54:22,059 [salt.state       :1951][INFO    ][13597] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:54:22.058962 duration_in_ms=14.453
2019-06-29 02:54:22,061 [salt.state       :1780][INFO    ][13597] Running state [salt-minion] at time 02:54:22.061390
2019-06-29 02:54:22,063 [salt.state       :1813][INFO    ][13597] Executing state service.running for [salt-minion]
2019-06-29 02:54:22,064 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13597] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-29 02:54:22,114 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13597] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-29 02:54:22,135 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13597] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-29 02:54:22,158 [salt.state       :300 ][INFO    ][13597] The service salt-minion is already running
2019-06-29 02:54:22,159 [salt.state       :1951][INFO    ][13597] Completed state [salt-minion] at time 02:54:22.159488 duration_in_ms=98.098
2019-06-29 02:54:22,162 [salt.state       :1780][INFO    ][13597] Running state [/etc/salt/grains.d] at time 02:54:22.162101
2019-06-29 02:54:22,162 [salt.state       :1813][INFO    ][13597] Executing state file.directory for [/etc/salt/grains.d]
2019-06-29 02:54:22,164 [salt.state       :300 ][INFO    ][13597] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-29 02:54:22,164 [salt.state       :1951][INFO    ][13597] Completed state [/etc/salt/grains.d] at time 02:54:22.164538 duration_in_ms=2.437
2019-06-29 02:54:22,167 [salt.state       :1780][INFO    ][13597] Running state [/etc/salt/grains] at time 02:54:22.165699
2019-06-29 02:54:22,168 [salt.state       :1813][INFO    ][13597] Executing state file.managed for [/etc/salt/grains]
2019-06-29 02:54:22,168 [salt.state       :300 ][INFO    ][13597] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-29 02:54:22,168 [salt.state       :1951][INFO    ][13597] Completed state [/etc/salt/grains] at time 02:54:22.168851 duration_in_ms=3.152
2019-06-29 02:54:22,169 [salt.state       :1780][INFO    ][13597] Running state [/etc/salt/grains.d/placeholder] at time 02:54:22.169390
2019-06-29 02:54:22,169 [salt.state       :1813][INFO    ][13597] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-29 02:54:22,170 [salt.state       :300 ][INFO    ][13597] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-29 02:54:22,170 [salt.state       :1951][INFO    ][13597] Completed state [/etc/salt/grains.d/placeholder] at time 02:54:22.170498 duration_in_ms=1.108
2019-06-29 02:54:22,171 [salt.state       :1780][INFO    ][13597] Running state [/etc/salt/grains.d/sphinx] at time 02:54:22.171017
2019-06-29 02:54:22,171 [salt.state       :1813][INFO    ][13597] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-29 02:54:22,172 [salt.state       :300 ][INFO    ][13597] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-29 02:54:22,172 [salt.state       :1951][INFO    ][13597] Completed state [/etc/salt/grains.d/sphinx] at time 02:54:22.172733 duration_in_ms=1.716
2019-06-29 02:54:22,175 [salt.state       :1780][INFO    ][13597] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:54:22.175268
2019-06-29 02:54:22,175 [salt.state       :1813][INFO    ][13597] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-29 02:54:22,175 [salt.state       :300 ][INFO    ][13597] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-29 02:54:22,176 [salt.state       :1951][INFO    ][13597] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:54:22.176116 duration_in_ms=0.848
2019-06-29 02:54:22,176 [salt.state       :1780][INFO    ][13597] Running state [/etc/salt/grains.d/dns_records] at time 02:54:22.176651
2019-06-29 02:54:22,176 [salt.state       :1813][INFO    ][13597] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-29 02:54:22,179 [salt.state       :300 ][INFO    ][13597] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-29 02:54:22,180 [salt.state       :1951][INFO    ][13597] Completed state [/etc/salt/grains.d/dns_records] at time 02:54:22.180324 duration_in_ms=3.672
2019-06-29 02:54:22,182 [salt.state       :1780][INFO    ][13597] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:54:22.182138
2019-06-29 02:54:22,182 [salt.state       :1813][INFO    ][13597] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-29 02:54:22,183 [salt.state       :300 ][INFO    ][13597] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-29 02:54:22,183 [salt.state       :1951][INFO    ][13597] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:54:22.183787 duration_in_ms=1.649
2019-06-29 02:54:22,184 [salt.state       :1780][INFO    ][13597] Running state [/etc/salt/grains.d/salt] at time 02:54:22.184819
2019-06-29 02:54:22,185 [salt.state       :1813][INFO    ][13597] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-29 02:54:22,187 [salt.state       :300 ][INFO    ][13597] File /etc/salt/grains.d/salt is in the correct state
2019-06-29 02:54:22,187 [salt.state       :1951][INFO    ][13597] Completed state [/etc/salt/grains.d/salt] at time 02:54:22.187926 duration_in_ms=3.108
2019-06-29 02:54:22,189 [salt.state       :1780][INFO    ][13597] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:54:22.188988
2019-06-29 02:54:22,189 [salt.state       :1813][INFO    ][13597] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-29 02:54:22,189 [salt.state       :300 ][INFO    ][13597] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-29 02:54:22,191 [salt.state       :1951][INFO    ][13597] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:54:22.191642 duration_in_ms=2.655
2019-06-29 02:54:22,193 [salt.state       :1780][INFO    ][13597] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:54:22.193868
2019-06-29 02:54:22,194 [salt.state       :1813][INFO    ][13597] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-29 02:54:22,194 [salt.state       :300 ][INFO    ][13597] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-29 02:54:22,194 [salt.state       :1951][INFO    ][13597] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:54:22.194633 duration_in_ms=0.764
2019-06-29 02:54:22,195 [salt.state       :1780][INFO    ][13597] Running state [mine.update] at time 02:54:22.195603
2019-06-29 02:54:22,195 [salt.state       :1813][INFO    ][13597] Executing state module.wait for [mine.update]
2019-06-29 02:54:22,196 [salt.state       :300 ][INFO    ][13597] No changes made for mine.update
2019-06-29 02:54:22,196 [salt.state       :1951][INFO    ][13597] Completed state [mine.update] at time 02:54:22.196307 duration_in_ms=0.704
2019-06-29 02:54:22,196 [salt.state       :1780][INFO    ][13597] Running state [ca-certificates] at time 02:54:22.196545
2019-06-29 02:54:22,196 [salt.state       :1813][INFO    ][13597] Executing state pkg.installed for [ca-certificates]
2019-06-29 02:54:22,207 [salt.state       :300 ][INFO    ][13597] All specified packages are already installed
2019-06-29 02:54:22,207 [salt.state       :1951][INFO    ][13597] Completed state [ca-certificates] at time 02:54:22.207324 duration_in_ms=10.779
2019-06-29 02:54:22,208 [salt.state       :1780][INFO    ][13597] Running state [update-ca-certificates] at time 02:54:22.208014
2019-06-29 02:54:22,208 [salt.state       :1813][INFO    ][13597] Executing state cmd.wait for [update-ca-certificates]
2019-06-29 02:54:22,208 [salt.state       :300 ][INFO    ][13597] No changes made for update-ca-certificates
2019-06-29 02:54:22,208 [salt.state       :1951][INFO    ][13597] Completed state [update-ca-certificates] at time 02:54:22.208732 duration_in_ms=0.718
2019-06-29 02:54:22,209 [salt.state       :1780][INFO    ][13597] Running state [iptables] at time 02:54:22.208966
2019-06-29 02:54:22,209 [salt.state       :1813][INFO    ][13597] Executing state pkg.installed for [iptables]
2019-06-29 02:54:22,219 [salt.state       :300 ][INFO    ][13597] All specified packages are already installed
2019-06-29 02:54:22,219 [salt.state       :1951][INFO    ][13597] Completed state [iptables] at time 02:54:22.219373 duration_in_ms=10.407
2019-06-29 02:54:22,219 [salt.state       :1780][INFO    ][13597] Running state [iptables-persistent] at time 02:54:22.219628
2019-06-29 02:54:22,219 [salt.state       :1813][INFO    ][13597] Executing state pkg.installed for [iptables-persistent]
2019-06-29 02:54:22,228 [salt.state       :300 ][INFO    ][13597] All specified packages are already installed
2019-06-29 02:54:22,229 [salt.state       :1951][INFO    ][13597] Completed state [iptables-persistent] at time 02:54:22.229079 duration_in_ms=9.451
2019-06-29 02:54:22,230 [salt.state       :1780][INFO    ][13597] Running state [iptables_modules_v4_load] at time 02:54:22.230689
2019-06-29 02:54:22,230 [salt.state       :1813][INFO    ][13597] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-29 02:54:22,231 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13597] Executing command 'lsmod' in directory '/root'
2019-06-29 02:54:22,253 [salt.state       :300 ][INFO    ][13597] Kernel modules iptable_filter, ip_tables are already present
2019-06-29 02:54:22,253 [salt.state       :1951][INFO    ][13597] Completed state [iptables_modules_v4_load] at time 02:54:22.253580 duration_in_ms=22.89
2019-06-29 02:54:22,255 [salt.state       :1780][INFO    ][13597] Running state [/etc/iptables/rules.v4] at time 02:54:22.255586
2019-06-29 02:54:22,256 [salt.state       :1813][INFO    ][13597] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-29 02:54:22,363 [salt.state       :300 ][INFO    ][13597] File /etc/iptables/rules.v4 is in the correct state
2019-06-29 02:54:22,364 [salt.state       :1951][INFO    ][13597] Completed state [/etc/iptables/rules.v4] at time 02:54:22.364186 duration_in_ms=108.6
2019-06-29 02:54:22,366 [salt.state       :1780][INFO    ][13597] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:54:22.366242
2019-06-29 02:54:22,366 [salt.state       :1813][INFO    ][13597] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-29 02:54:22,367 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13597] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-29 02:54:22,391 [salt.state       :300 ][INFO    ][13597] onlyif execution failed
2019-06-29 02:54:22,392 [salt.state       :1951][INFO    ][13597] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:54:22.392301 duration_in_ms=26.058
2019-06-29 02:54:22,396 [salt.state       :1780][INFO    ][13597] Running state [netfilter-persistent] at time 02:54:22.396558
2019-06-29 02:54:22,397 [salt.state       :1813][INFO    ][13597] Executing state service.running for [netfilter-persistent]
2019-06-29 02:54:22,398 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13597] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-29 02:54:22,422 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13597] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-29 02:54:22,446 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13597] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-29 02:54:22,469 [salt.state       :300 ][INFO    ][13597] The service netfilter-persistent is already running
2019-06-29 02:54:22,470 [salt.state       :1951][INFO    ][13597] Completed state [netfilter-persistent] at time 02:54:22.470315 duration_in_ms=73.757
2019-06-29 02:54:22,472 [salt.state       :1780][INFO    ][13597] Running state [iptables_extra.remove_stale_tables] at time 02:54:22.472050
2019-06-29 02:54:22,472 [salt.state       :1813][INFO    ][13597] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-29 02:54:22,473 [salt.state       :300 ][INFO    ][13597] No changes made for iptables_extra.remove_stale_tables
2019-06-29 02:54:22,473 [salt.state       :1951][INFO    ][13597] Completed state [iptables_extra.remove_stale_tables] at time 02:54:22.473681 duration_in_ms=1.631
2019-06-29 02:54:22,474 [salt.state       :1780][INFO    ][13597] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:54:22.474171
2019-06-29 02:54:22,474 [salt.state       :1813][INFO    ][13597] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-29 02:54:22,475 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13597] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-29 02:54:22,495 [salt.state       :300 ][INFO    ][13597] onlyif execution failed
2019-06-29 02:54:22,496 [salt.state       :1951][INFO    ][13597] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:54:22.496182 duration_in_ms=22.011
2019-06-29 02:54:22,497 [salt.state       :1780][INFO    ][13597] Running state [/etc/iptables/rules.v6] at time 02:54:22.497812
2019-06-29 02:54:22,498 [salt.state       :1813][INFO    ][13597] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-29 02:54:22,499 [salt.state       :300 ][INFO    ][13597] File /etc/iptables/rules.v6 is not present
2019-06-29 02:54:22,499 [salt.state       :1951][INFO    ][13597] Completed state [/etc/iptables/rules.v6] at time 02:54:22.499373 duration_in_ms=1.561
2019-06-29 02:54:22,500 [salt.state       :1780][INFO    ][13597] Running state [iptables_extra.flush_all] at time 02:54:22.500560
2019-06-29 02:54:22,501 [salt.state       :1813][INFO    ][13597] Executing state module.wait for [iptables_extra.flush_all]
2019-06-29 02:54:22,501 [salt.state       :300 ][INFO    ][13597] No changes made for iptables_extra.flush_all
2019-06-29 02:54:22,504 [salt.state       :1951][INFO    ][13597] Completed state [iptables_extra.flush_all] at time 02:54:22.504145 duration_in_ms=3.585
2019-06-29 02:54:22,507 [salt.minion      :1711][INFO    ][13597] Returning information for job: 20190629025409225250
2019-06-29 02:54:23,085 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command state.apply with jid 20190629025423073111
2019-06-29 02:54:23,116 [salt.minion      :1432][INFO    ][13704] Starting a new job with PID 13704
2019-06-29 02:54:24,258 [salt.state       :915 ][INFO    ][13704] Loading fresh modules for state activity
2019-06-29 02:54:25,161 [salt.state       :1780][INFO    ][13704] Running state [maas-rack-controller] at time 02:54:25.161171
2019-06-29 02:54:25,161 [salt.state       :1813][INFO    ][13704] Executing state pkg.installed for [maas-rack-controller]
2019-06-29 02:54:25,165 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13704] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-29 02:54:25,289 [salt.state       :300 ][INFO    ][13704] All specified packages are already installed
2019-06-29 02:54:25,289 [salt.state       :1951][INFO    ][13704] Completed state [maas-rack-controller] at time 02:54:25.289538 duration_in_ms=128.366
2019-06-29 02:54:25,290 [salt.state       :1780][INFO    ][13704] Running state [ipmitool] at time 02:54:25.290267
2019-06-29 02:54:25,290 [salt.state       :1813][INFO    ][13704] Executing state pkg.installed for [ipmitool]
2019-06-29 02:54:25,300 [salt.state       :300 ][INFO    ][13704] All specified packages are already installed
2019-06-29 02:54:25,300 [salt.state       :1951][INFO    ][13704] Completed state [ipmitool] at time 02:54:25.300691 duration_in_ms=10.424
2019-06-29 02:54:25,305 [salt.state       :1780][INFO    ][13704] Running state [/etc/maas/rackd.conf] at time 02:54:25.304954
2019-06-29 02:54:25,305 [salt.state       :1813][INFO    ][13704] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-29 02:54:25,306 [salt.state       :300 ][INFO    ][13704] No changes needed to be made
2019-06-29 02:54:25,306 [salt.state       :1951][INFO    ][13704] Completed state [/etc/maas/rackd.conf] at time 02:54:25.306781 duration_in_ms=1.827
2019-06-29 02:54:25,307 [salt.state       :1780][INFO    ][13704] Running state [/etc/maas/rackd.conf] at time 02:54:25.307024
2019-06-29 02:54:25,307 [salt.state       :1813][INFO    ][13704] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-29 02:54:25,307 [salt.loaded.int.states.file:2298][WARNING ][13704] 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-06-29 02:54:25,308 [salt.state       :300 ][INFO    ][13704] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-29 02:54:25,308 [salt.state       :1951][INFO    ][13704] Completed state [/etc/maas/rackd.conf] at time 02:54:25.308588 duration_in_ms=1.564
2019-06-29 02:54:25,309 [salt.state       :1780][INFO    ][13704] Running state [maas-rackd] at time 02:54:25.309657
2019-06-29 02:54:25,310 [salt.state       :1813][INFO    ][13704] Executing state service.running for [maas-rackd]
2019-06-29 02:54:25,311 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13704] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-29 02:54:25,350 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13704] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-29 02:54:25,373 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13704] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-29 02:54:25,393 [salt.state       :300 ][INFO    ][13704] The service maas-rackd is already running
2019-06-29 02:54:25,393 [salt.state       :1951][INFO    ][13704] Completed state [maas-rackd] at time 02:54:25.393544 duration_in_ms=83.886
2019-06-29 02:54:25,398 [salt.minion      :1711][INFO    ][13704] Returning information for job: 20190629025423073111
2019-06-29 02:54:25,956 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command state.apply with jid 20190629025425943623
2019-06-29 02:54:25,980 [salt.minion      :1432][INFO    ][13727] Starting a new job with PID 13727
2019-06-29 02:54:27,100 [salt.state       :915 ][INFO    ][13727] Loading fresh modules for state activity
2019-06-29 02:54:28,180 [salt.state       :1780][INFO    ][13727] Running state [maas-region-controller] at time 02:54:28.180256
2019-06-29 02:54:28,180 [salt.state       :1813][INFO    ][13727] Executing state pkg.installed for [maas-region-controller]
2019-06-29 02:54:28,181 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13727] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-29 02:54:28,292 [salt.state       :300 ][INFO    ][13727] All specified packages are already installed
2019-06-29 02:54:28,292 [salt.state       :1951][INFO    ][13727] Completed state [maas-region-controller] at time 02:54:28.292385 duration_in_ms=112.13
2019-06-29 02:54:28,292 [salt.state       :1780][INFO    ][13727] Running state [python-oauth] at time 02:54:28.292705
2019-06-29 02:54:28,293 [salt.state       :1813][INFO    ][13727] Executing state pkg.installed for [python-oauth]
2019-06-29 02:54:28,303 [salt.state       :300 ][INFO    ][13727] All specified packages are already installed
2019-06-29 02:54:28,303 [salt.state       :1951][INFO    ][13727] Completed state [python-oauth] at time 02:54:28.303359 duration_in_ms=10.653
2019-06-29 02:54:28,306 [salt.state       :1780][INFO    ][13727] Running state [/etc/maas/regiond.conf] at time 02:54:28.306841
2019-06-29 02:54:28,307 [salt.state       :1813][INFO    ][13727] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-29 02:54:28,312 [salt.state       :300 ][INFO    ][13727] No changes needed to be made
2019-06-29 02:54:28,312 [salt.state       :1951][INFO    ][13727] Completed state [/etc/maas/regiond.conf] at time 02:54:28.312259 duration_in_ms=5.418
2019-06-29 02:54:28,312 [salt.state       :1780][INFO    ][13727] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:54:28.312744
2019-06-29 02:54:28,313 [salt.state       :1813][INFO    ][13727] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-29 02:54:28,384 [salt.state       :300 ][INFO    ][13727] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-29 02:54:28,385 [salt.state       :1951][INFO    ][13727] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:54:28.384952 duration_in_ms=72.208
2019-06-29 02:54:28,385 [salt.state       :1780][INFO    ][13727] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:28.385445
2019-06-29 02:54:28,385 [salt.state       :1813][INFO    ][13727] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-29 02:54:28,389 [salt.state       :300 ][INFO    ][13727] No changes needed to be made
2019-06-29 02:54:28,390 [salt.state       :1951][INFO    ][13727] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:28.389704 duration_in_ms=4.259
2019-06-29 02:54:28,390 [salt.state       :1780][INFO    ][13727] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:28.390506
2019-06-29 02:54:28,390 [salt.state       :1813][INFO    ][13727] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-29 02:54:28,395 [salt.state       :300 ][INFO    ][13727] No changes needed to be made
2019-06-29 02:54:28,395 [salt.state       :1951][INFO    ][13727] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:28.395195 duration_in_ms=4.689
2019-06-29 02:54:28,395 [salt.state       :1780][INFO    ][13727] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:54:28.395674
2019-06-29 02:54:28,395 [salt.state       :1813][INFO    ][13727] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-29 02:54:28,420 [salt.state       :300 ][INFO    ][13727] No changes needed to be made
2019-06-29 02:54:28,420 [salt.state       :1951][INFO    ][13727] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:54:28.420186 duration_in_ms=24.511
2019-06-29 02:54:28,420 [salt.state       :1780][INFO    ][13727] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:54:28.420685
2019-06-29 02:54:28,420 [salt.state       :1813][INFO    ][13727] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-29 02:54:28,437 [salt.state       :300 ][INFO    ][13727] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-29 02:54:28,437 [salt.state       :1951][INFO    ][13727] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:54:28.437245 duration_in_ms=16.558
2019-06-29 02:54:28,439 [salt.state       :1780][INFO    ][13727] Running state [a2enmod headers] at time 02:54:28.439014
2019-06-29 02:54:28,439 [salt.state       :1813][INFO    ][13727] Executing state cmd.run for [a2enmod headers]
2019-06-29 02:54:28,440 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13727] Executing command 'a2enmod headers' in directory '/root'
2019-06-29 02:54:28,512 [salt.state       :300 ][INFO    ][13727] {'pid': 13746, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-29 02:54:28,513 [salt.state       :1951][INFO    ][13727] Completed state [a2enmod headers] at time 02:54:28.513224 duration_in_ms=74.21
2019-06-29 02:54:28,514 [salt.state       :1780][INFO    ][13727] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:54:28.514763
2019-06-29 02:54:28,515 [salt.state       :1813][INFO    ][13727] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-29 02:54:28,543 [salt.state       :300 ][INFO    ][13727] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-29 02:54:28,544 [salt.state       :1951][INFO    ][13727] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:54:28.544123 duration_in_ms=29.359
2019-06-29 02:54:28,544 [salt.state       :1780][INFO    ][13727] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:54:28.544907
2019-06-29 02:54:28,545 [salt.state       :1813][INFO    ][13727] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-29 02:54:28,605 [salt.state       :300 ][INFO    ][13727] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-29 02:54:28,606 [salt.state       :1951][INFO    ][13727] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:54:28.606259 duration_in_ms=61.35
2019-06-29 02:54:28,607 [salt.state       :1780][INFO    ][13727] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:54:28.607353
2019-06-29 02:54:28,607 [salt.state       :1813][INFO    ][13727] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-29 02:54:28,672 [salt.state       :300 ][INFO    ][13727] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-29 02:54:28,673 [salt.state       :1951][INFO    ][13727] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:54:28.673090 duration_in_ms=65.738
2019-06-29 02:54:28,673 [salt.state       :1780][INFO    ][13727] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:54:28.673607
2019-06-29 02:54:28,674 [salt.state       :1813][INFO    ][13727] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-29 02:54:28,753 [salt.state       :300 ][INFO    ][13727] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-29 02:54:28,754 [salt.state       :1951][INFO    ][13727] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:54:28.754696 duration_in_ms=81.089
2019-06-29 02:54:28,755 [salt.state       :1780][INFO    ][13727] Running state [/root/.pgpass] at time 02:54:28.755024
2019-06-29 02:54:28,755 [salt.state       :1813][INFO    ][13727] Executing state file.managed for [/root/.pgpass]
2019-06-29 02:54:28,813 [salt.state       :300 ][INFO    ][13727] File /root/.pgpass is in the correct state
2019-06-29 02:54:28,813 [salt.state       :1951][INFO    ][13727] Completed state [/root/.pgpass] at time 02:54:28.813536 duration_in_ms=58.512
2019-06-29 02:54:28,823 [salt.state       :1780][INFO    ][13727] Running state [maas-region syncdb --noinput] at time 02:54:28.823225
2019-06-29 02:54:28,823 [salt.state       :1813][INFO    ][13727] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-29 02:54:28,824 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13727] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-29 02:54:31,396 [salt.state       :300 ][INFO    ][13727] {'pid': 13759, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: auth, metadataserver, sites, contenttypes, piston3, maasserver, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-29 02:54:31,398 [salt.state       :1951][INFO    ][13727] Completed state [maas-region syncdb --noinput] at time 02:54:31.397630 duration_in_ms=2574.404
2019-06-29 02:54:31,398 [salt.state       :2022][WARNING ][13727] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-29 02:54:31,402 [salt.state       :1780][INFO    ][13727] Running state [maas-regiond] at time 02:54:31.402284
2019-06-29 02:54:31,403 [salt.state       :1813][INFO    ][13727] Executing state service.running for [maas-regiond]
2019-06-29 02:54:31,404 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13727] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-29 02:54:31,453 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13727] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-29 02:54:31,476 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13727] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-29 02:54:31,504 [salt.state       :300 ][INFO    ][13727] The service maas-regiond is already running
2019-06-29 02:54:31,505 [salt.state       :1951][INFO    ][13727] Completed state [maas-regiond] at time 02:54:31.505174 duration_in_ms=102.89
2019-06-29 02:54:31,508 [salt.state       :1780][INFO    ][13727] Running state [bind9] at time 02:54:31.508560
2019-06-29 02:54:31,509 [salt.state       :1813][INFO    ][13727] Executing state service.running for [bind9]
2019-06-29 02:54:31,510 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13727] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-29 02:54:31,533 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13727] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-29 02:54:31,553 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13727] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-29 02:54:31,576 [salt.state       :300 ][INFO    ][13727] The service bind9 is already running
2019-06-29 02:54:31,576 [salt.state       :1951][INFO    ][13727] Completed state [bind9] at time 02:54:31.576729 duration_in_ms=68.169
2019-06-29 02:54:31,580 [salt.state       :1780][INFO    ][13727] Running state [apache2] at time 02:54:31.580197
2019-06-29 02:54:31,580 [salt.state       :1813][INFO    ][13727] Executing state service.running for [apache2]
2019-06-29 02:54:31,581 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13727] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-29 02:54:31,603 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13727] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-29 02:54:31,621 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13727] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-29 02:54:31,650 [salt.state       :300 ][INFO    ][13727] The service apache2 is already running
2019-06-29 02:54:31,651 [salt.state       :1951][INFO    ][13727] Completed state [apache2] at time 02:54:31.651377 duration_in_ms=71.18
2019-06-29 02:54:31,653 [salt.state       :1780][INFO    ][13727] Running state [maasng.wait_for_http_code] at time 02:54:31.653798
2019-06-29 02:54:31,654 [salt.state       :1813][INFO    ][13727] Executing state module.run for [maasng.wait_for_http_code]
2019-06-29 02:54:31,655 [salt.utils.decorators:613 ][WARNING ][13727] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:54:31,668 [salt.state       :300 ][INFO    ][13727] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-29 02:54:31,668 [salt.state       :1951][INFO    ][13727] Completed state [maasng.wait_for_http_code] at time 02:54:31.668703 duration_in_ms=14.904
2019-06-29 02:54:31,674 [salt.state       :1780][INFO    ][13727] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:54:31.674874
2019-06-29 02:54:31,675 [salt.state       :1813][INFO    ][13727] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-29 02:54:31,675 [salt.state       :300 ][INFO    ][13727] /var/lib/maas/.setup_admin exists
2019-06-29 02:54:31,676 [salt.state       :1951][INFO    ][13727] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:54:31.675938 duration_in_ms=1.064
2019-06-29 02:54:31,676 [salt.state       :1780][INFO    ][13727] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:31.676742
2019-06-29 02:54:31,677 [salt.state       :1813][INFO    ][13727] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-29 02:54:31,677 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13727] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-29 02:54:33,536 [salt.state       :300 ][INFO    ][13727] {'pid': 13778, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-29 02:54:33,538 [salt.state       :1951][INFO    ][13727] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:33.538354 duration_in_ms=1861.612
2019-06-29 02:54:33,545 [salt.state       :1780][INFO    ][13727] Running state [maas_region_boot_source_resources_mirror] at time 02:54:33.545142
2019-06-29 02:54:33,545 [salt.state       :1813][INFO    ][13727] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-29 02:54:33,639 [salt.state       :300 ][INFO    ][13727] {'changes': {}}
2019-06-29 02:54:33,640 [salt.state       :1951][INFO    ][13727] Completed state [maas_region_boot_source_resources_mirror] at time 02:54:33.640183 duration_in_ms=95.041
2019-06-29 02:54:33,641 [salt.state       :1780][INFO    ][13727] Running state [maasng.boot_resources_import] at time 02:54:33.641377
2019-06-29 02:54:33,642 [salt.state       :1813][INFO    ][13727] Executing state module.run for [maasng.boot_resources_import]
2019-06-29 02:54:33,642 [salt.utils.decorators:613 ][WARNING ][13727] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:54:33,741 [salt.loaded.ext.module.maasng:1600][INFO    ][13727] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-29 02:54:38,790 [salt.loaded.ext.module.maasng:1600][INFO    ][13727] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-29 02:54:41,034 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025441022893
2019-06-29 02:54:41,057 [salt.minion      :1432][INFO    ][13816] Starting a new job with PID 13816
2019-06-29 02:54:41,081 [salt.minion      :1711][INFO    ][13816] Returning information for job: 20190629025441022893
2019-06-29 02:54:43,893 [salt.state       :300 ][INFO    ][13727] {'ret': True}
2019-06-29 02:54:43,894 [salt.state       :1951][INFO    ][13727] Completed state [maasng.boot_resources_import] at time 02:54:43.894336 duration_in_ms=10252.959
2019-06-29 02:54:43,895 [salt.state       :1780][INFO    ][13727] Running state [maas_region_boot_sources_selection_xenial] at time 02:54:43.895488
2019-06-29 02:54:43,896 [salt.state       :1813][INFO    ][13727] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-29 02:54:44,082 [salt.state       :300 ][INFO    ][13727] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-29 02:54:44,082 [salt.state       :1951][INFO    ][13727] Completed state [maas_region_boot_sources_selection_xenial] at time 02:54:44.082753 duration_in_ms=187.265
2019-06-29 02:54:44,084 [salt.state       :1780][INFO    ][13727] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:54:44.084223
2019-06-29 02:54:44,084 [salt.state       :1813][INFO    ][13727] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-29 02:54:44,085 [salt.utils.decorators:613 ][WARNING ][13727] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:54:44,086 [salt.loaded.ext.module.maasng:1771][INFO    ][13727] boot-sources sync initiated for ALL Rack's
2019-06-29 02:54:45,195 [salt.state       :300 ][INFO    ][13727] {'ret': True}
2019-06-29 02:54:45,196 [salt.state       :1951][INFO    ][13727] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:54:45.196242 duration_in_ms=1112.017
2019-06-29 02:54:45,199 [salt.state       :1780][INFO    ][13727] Running state [maas.process_maas_config] at time 02:54:45.199415
2019-06-29 02:54:45,199 [salt.state       :1813][INFO    ][13727] Executing state module.run for [maas.process_maas_config]
2019-06-29 02:54:45,200 [salt.utils.decorators:613 ][WARNING ][13727] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:54:45,202 [salt.loaded.ext.module.maas:92  ][INFO    ][13727] maasconfig name=enable_http_proxy value=True
2019-06-29 02:54:45,265 [salt.loaded.ext.module.maas:92  ][INFO    ][13727] maasconfig name=upstream_dns value=8.8.8.8
2019-06-29 02:54:45,334 [salt.loaded.ext.module.maas:92  ][INFO    ][13727] maasconfig name=commissioning_distro_series value=xenial
2019-06-29 02:54:45,390 [salt.loaded.ext.module.maas:92  ][INFO    ][13727] maasconfig name=default_osystem value=ubuntu
2019-06-29 02:54:45,446 [salt.loaded.ext.module.maas:92  ][INFO    ][13727] maasconfig name=active_discovery_interval value=600
2019-06-29 02:54:46,748 [salt.loaded.ext.module.maas:92  ][INFO    ][13727] maasconfig name=dnssec_validation value=no
2019-06-29 02:54:46,798 [salt.loaded.ext.module.maas:92  ][INFO    ][13727] maasconfig name=maas_name value=mas01
2019-06-29 02:54:46,846 [salt.loaded.ext.module.maas:92  ][INFO    ][13727] maasconfig name=network_discovery value=enabled
2019-06-29 02:54:46,936 [salt.loaded.ext.module.maas:92  ][INFO    ][13727] maasconfig name=enable_third_party_drivers value=True
2019-06-29 02:54:46,986 [salt.loaded.ext.module.maas:92  ][INFO    ][13727] maasconfig name=default_storage_layout value=lvm
2019-06-29 02:54:47,031 [salt.loaded.ext.module.maas:92  ][INFO    ][13727] maasconfig name=ntp_external_only value=True
2019-06-29 02:54:47,075 [salt.loaded.ext.module.maas:92  ][INFO    ][13727] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-29 02:54:47,129 [salt.loaded.ext.module.maas:92  ][INFO    ][13727] maasconfig name=default_distro_series value=xenial
2019-06-29 02:54:47,204 [salt.loaded.ext.module.maas:92  ][INFO    ][13727] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-06-29 02:54:47,364 [salt.state       :300 ][INFO    ][13727] {'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-06-29 02:54:47,365 [salt.state       :1951][INFO    ][13727] Completed state [maas.process_maas_config] at time 02:54:47.365014 duration_in_ms=2165.599
2019-06-29 02:54:47,368 [salt.state       :1780][INFO    ][13727] Running state [pxe_admin] at time 02:54:47.368581
2019-06-29 02:54:47,369 [salt.state       :1813][INFO    ][13727] Executing state maasng.fabric_present for [pxe_admin]
2019-06-29 02:54:47,432 [salt.loaded.ext.module.maasng:945 ][INFO    ][13727] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'wsebwp', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-06-29 02:54:47,506 [salt.loaded.ext.module.maasng:1008][WARNING ][13727] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-29 02:54:47,506 [salt.loaded.ext.module.maasng:1011][WARNING ][13727] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-29 02:54:47,568 [salt.state       :300 ][INFO    ][13727] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-29 02:54:47,569 [salt.state       :1951][INFO    ][13727] Completed state [pxe_admin] at time 02:54:47.569170 duration_in_ms=200.588
2019-06-29 02:54:47,569 [salt.state       :1780][INFO    ][13727] Running state [vlan 0] at time 02:54:47.569532
2019-06-29 02:54:47,571 [salt.state       :1813][INFO    ][13727] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-29 02:54:47,643 [salt.loaded.ext.module.maasng:945 ][INFO    ][13727] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wsebwp', 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'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-29 02:54:47,745 [salt.loaded.ext.module.maasng:945 ][INFO    ][13727] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wsebwp', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-29 02:54:48,009 [salt.loaded.ext.module.maasng:945 ][INFO    ][13727] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wsebwp', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-29 02:54:48,094 [salt.state       :300 ][INFO    ][13727] {'new': 'Vlan untagged was updated'}
2019-06-29 02:54:48,095 [salt.state       :1951][INFO    ][13727] Completed state [vlan 0] at time 02:54:48.095240 duration_in_ms=525.707
2019-06-29 02:54:48,097 [salt.state       :1780][INFO    ][13727] Running state [192.168.11.0/24] at time 02:54:48.096920
2019-06-29 02:54:48,097 [salt.state       :1813][INFO    ][13727] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-29 02:54:48,289 [salt.loaded.ext.module.maasng:945 ][INFO    ][13727] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wsebwp', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-29 02:54:48,293 [salt.loaded.ext.module.maasng:1235][WARNING ][13727] Ignoring parameter vlan:0
2019-06-29 02:54:48,358 [salt.state       :300 ][INFO    ][13727] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-29 02:54:48,359 [salt.state       :1951][INFO    ][13727] Completed state [192.168.11.0/24] at time 02:54:48.359089 duration_in_ms=262.168
2019-06-29 02:54:48,360 [salt.state       :1780][INFO    ][13727] Running state [maas_create_iprange_1] at time 02:54:48.360044
2019-06-29 02:54:48,360 [salt.state       :1813][INFO    ][13727] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-29 02:54:48,409 [salt.state       :300 ][INFO    ][13727] Iprange maas_create_iprange_1 already exist.
2019-06-29 02:54:48,409 [salt.state       :1951][INFO    ][13727] Completed state [maas_create_iprange_1] at time 02:54:48.409580 duration_in_ms=49.536
2019-06-29 02:54:48,412 [salt.state       :1780][INFO    ][13727] Running state [vlan 0] at time 02:54:48.411968
2019-06-29 02:54:48,412 [salt.state       :1813][INFO    ][13727] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-29 02:54:48,467 [salt.loaded.ext.module.maasng:945 ][INFO    ][13727] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wsebwp', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-29 02:54:48,600 [salt.loaded.ext.module.maasng:945 ][INFO    ][13727] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wsebwp', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-29 02:54:48,836 [salt.loaded.ext.module.maasng:945 ][INFO    ][13727] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'wsebwp', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-06-29 02:54:48,948 [salt.state       :300 ][INFO    ][13727] {'new': 'Vlan untagged was updated'}
2019-06-29 02:54:48,949 [salt.state       :1951][INFO    ][13727] Completed state [vlan 0] at time 02:54:48.949096 duration_in_ms=537.128
2019-06-29 02:54:48,955 [salt.state       :1780][INFO    ][13727] Running state [opnfv] at time 02:54:48.955300
2019-06-29 02:54:48,955 [salt.state       :1813][INFO    ][13727] Executing state maasng.sshkey_present for [opnfv]
2019-06-29 02:54:49,036 [salt.loaded.ext.module.maasng:1903][INFO    ][13727] [{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-06-29 02:54:49,037 [salt.state       :300 ][INFO    ][13727] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-29 02:54:49,037 [salt.state       :1951][INFO    ][13727] Completed state [opnfv] at time 02:54:49.037467 duration_in_ms=82.167
2019-06-29 02:54:49,041 [salt.minion      :1711][INFO    ][13727] Returning information for job: 20190629025425943623
2019-06-29 02:54:49,631 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command state.apply with jid 20190629025449620999
2019-06-29 02:54:49,655 [salt.minion      :1432][INFO    ][14158] Starting a new job with PID 14158
2019-06-29 02:54:58,097 [salt.state       :915 ][INFO    ][14158] Loading fresh modules for state activity
2019-06-29 02:54:58,206 [salt.state       :1780][INFO    ][14158] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:58.206871
2019-06-29 02:54:58,207 [salt.state       :1813][INFO    ][14158] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-29 02:54:58,208 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14158] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-29 02:54:59,917 [salt.state       :300 ][INFO    ][14158] {'pid': 14198, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-29 02:54:59,919 [salt.state       :1951][INFO    ][14158] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:59.918911 duration_in_ms=1712.04
2019-06-29 02:54:59,922 [salt.state       :1780][INFO    ][14158] Running state [maas.process_machines] at time 02:54:59.922171
2019-06-29 02:54:59,922 [salt.state       :1813][INFO    ][14158] Executing state module.run for [maas.process_machines]
2019-06-29 02:54:59,923 [salt.utils.decorators:613 ][WARNING ][14158] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:55:00,612 [salt.loaded.ext.module.maas:412 ][WARNING ][14158] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-29 02:55:00,613 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] 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=yegdmc architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-29 02:55:01,807 [salt.loaded.ext.module.maas:412 ][WARNING ][14158] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-29 02:55:01,807 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] 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=6bwf8b architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-29 02:55:03,018 [salt.loaded.ext.module.maas:412 ][WARNING ][14158] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-29 02:55:03,020 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] 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=q4mwbk architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-29 02:55:04,188 [salt.loaded.ext.module.maas:412 ][WARNING ][14158] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-29 02:55:04,189 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] 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=fkkxt3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-29 02:55:04,744 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025504734577
2019-06-29 02:55:04,763 [salt.minion      :1432][INFO    ][14361] Starting a new job with PID 14361
2019-06-29 02:55:04,787 [salt.minion      :1711][INFO    ][14361] Returning information for job: 20190629025504734577
2019-06-29 02:55:05,388 [salt.loaded.ext.module.maas:412 ][WARNING ][14158] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-29 02:55:05,391 [salt.loaded.ext.module.maas:92  ][INFO    ][14158] 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=rmrm88 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-29 02:55:06,595 [salt.state       :300 ][INFO    ][14158] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-29 02:55:06,596 [salt.state       :1951][INFO    ][14158] Completed state [maas.process_machines] at time 02:55:06.595895 duration_in_ms=6673.721
2019-06-29 02:55:06,599 [salt.minion      :1711][INFO    ][14158] Returning information for job: 20190629025449620999
2019-06-29 02:55:39,588 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command state.apply with jid 20190629025539579676
2019-06-29 02:55:39,613 [salt.minion      :1432][INFO    ][14459] Starting a new job with PID 14459
2019-06-29 02:55:47,778 [salt.state       :915 ][INFO    ][14459] Loading fresh modules for state activity
2019-06-29 02:55:47,883 [salt.state       :1780][INFO    ][14459] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:47.883651
2019-06-29 02:55:47,883 [salt.state       :1813][INFO    ][14459] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-29 02:55:47,885 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14459] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-29 02:55:49,599 [salt.state       :300 ][INFO    ][14459] {'pid': 14469, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-29 02:55:49,600 [salt.state       :1951][INFO    ][14459] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:49.600385 duration_in_ms=1716.733
2019-06-29 02:55:49,603 [salt.state       :1780][INFO    ][14459] Running state [maas.wait_for_machine_status] at time 02:55:49.603329
2019-06-29 02:55:49,604 [salt.state       :1813][INFO    ][14459] Executing state module.run for [maas.wait_for_machine_status]
2019-06-29 02:55:49,604 [salt.utils.decorators:613 ][WARNING ][14459] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:55:52,456 [salt.state       :300 ][INFO    ][14459] {'ret': True}
2019-06-29 02:55:52,456 [salt.state       :1951][INFO    ][14459] Completed state [maas.wait_for_machine_status] at time 02:55:52.456394 duration_in_ms=2853.066
2019-06-29 02:55:52,460 [salt.minion      :1711][INFO    ][14459] Returning information for job: 20190629025539579676
2019-06-29 02:55:53,039 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command state.apply with jid 20190629025553017230
2019-06-29 02:55:53,065 [salt.minion      :1432][INFO    ][14495] Starting a new job with PID 14495
2019-06-29 02:55:54,260 [salt.state       :915 ][INFO    ][14495] Loading fresh modules for state activity
2019-06-29 02:55:54,409 [salt.state       :1780][INFO    ][14495] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:54.409347
2019-06-29 02:55:54,410 [salt.state       :1813][INFO    ][14495] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-29 02:55:54,411 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14495] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-29 02:55:56,125 [salt.state       :300 ][INFO    ][14495] {'pid': 14502, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-29 02:55:56,127 [salt.state       :1951][INFO    ][14495] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:56.127700 duration_in_ms=1718.352
2019-06-29 02:55:56,131 [salt.state       :1780][INFO    ][14495] Running state [maas_machines_storage_cmp002_lvm] at time 02:55:56.131092
2019-06-29 02:55:56,131 [salt.state       :1813][INFO    ][14495] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-29 02:55:56,696 [salt.state       :300 ][INFO    ][14495] Machine cmp002 is not in Ready state.
2019-06-29 02:55:56,697 [salt.state       :1951][INFO    ][14495] Completed state [maas_machines_storage_cmp002_lvm] at time 02:55:56.696867 duration_in_ms=565.774
2019-06-29 02:55:56,697 [salt.state       :1780][INFO    ][14495] Running state [maas_machines_storage_cmp001_lvm] at time 02:55:56.697577
2019-06-29 02:55:56,698 [salt.state       :1813][INFO    ][14495] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-29 02:55:57,245 [salt.state       :300 ][INFO    ][14495] Machine cmp001 is not in Ready state.
2019-06-29 02:55:57,246 [salt.state       :1951][INFO    ][14495] Completed state [maas_machines_storage_cmp001_lvm] at time 02:55:57.246180 duration_in_ms=548.603
2019-06-29 02:55:57,251 [salt.minion      :1711][INFO    ][14495] Returning information for job: 20190629025553017230
2019-06-29 02:55:57,810 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command state.apply with jid 20190629025557792377
2019-06-29 02:55:57,835 [salt.minion      :1432][INFO    ][14513] Starting a new job with PID 14513
2019-06-29 02:55:58,972 [salt.state       :915 ][INFO    ][14513] Loading fresh modules for state activity
2019-06-29 02:55:59,073 [salt.state       :1780][INFO    ][14513] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:59.073150
2019-06-29 02:55:59,073 [salt.state       :1813][INFO    ][14513] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-29 02:55:59,075 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14513] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-29 02:56:00,792 [salt.state       :300 ][INFO    ][14513] {'pid': 14520, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-29 02:56:00,794 [salt.state       :1951][INFO    ][14513] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:56:00.793689 duration_in_ms=1720.539
2019-06-29 02:56:00,797 [salt.state       :1780][INFO    ][14513] Running state [maas.deploy_machines] at time 02:56:00.797338
2019-06-29 02:56:00,797 [salt.state       :1813][INFO    ][14513] Executing state module.run for [maas.deploy_machines]
2019-06-29 02:56:00,798 [salt.utils.decorators:613 ][WARNING ][14513] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:56:01,361 [salt.loaded.ext.module.maas:684 ][INFO    ][14513] deploymachines hwe_kernel=ga-16.04 system_id=rmrm88 distro_series=xenial
2019-06-29 02:56:03,804 [salt.state       :300 ][INFO    ][14513] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-06-29 02:56:03,805 [salt.state       :1951][INFO    ][14513] Completed state [maas.deploy_machines] at time 02:56:03.804987 duration_in_ms=3007.649
2019-06-29 02:56:03,811 [salt.minion      :1711][INFO    ][14513] Returning information for job: 20190629025557792377
2019-06-29 02:56:04,401 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command state.apply with jid 20190629025604388384
2019-06-29 02:56:04,435 [salt.minion      :1432][INFO    ][14580] Starting a new job with PID 14580
2019-06-29 02:56:12,786 [salt.state       :915 ][INFO    ][14580] Loading fresh modules for state activity
2019-06-29 02:56:12,888 [salt.state       :1780][INFO    ][14580] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:56:12.888855
2019-06-29 02:56:12,889 [salt.state       :1813][INFO    ][14580] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-29 02:56:12,891 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14580] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-29 02:56:14,710 [salt.state       :300 ][INFO    ][14580] {'pid': 14624, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-29 02:56:14,711 [salt.state       :1951][INFO    ][14580] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:56:14.711223 duration_in_ms=1822.368
2019-06-29 02:56:14,712 [salt.state       :1780][INFO    ][14580] Running state [maas.wait_for_machine_status] at time 02:56:14.712787
2019-06-29 02:56:14,713 [salt.state       :1813][INFO    ][14580] Executing state module.run for [maas.wait_for_machine_status]
2019-06-29 02:56:14,713 [salt.utils.decorators:613 ][WARNING ][14580] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-29 02:56:17,472 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.26814508s left)
2019-06-29 02:56:19,569 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025619506369
2019-06-29 02:56:19,592 [salt.minion      :1432][INFO    ][14638] Starting a new job with PID 14638
2019-06-29 02:56:19,616 [salt.minion      :1711][INFO    ][14638] Returning information for job: 20190629025619506369
2019-06-29 02:56:49,652 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025649642575
2019-06-29 02:56:49,677 [salt.minion      :1432][INFO    ][14657] Starting a new job with PID 14657
2019-06-29 02:56:49,699 [salt.minion      :1711][INFO    ][14657] Returning information for job: 20190629025649642575
2019-06-29 02:56:50,311 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.43002295s left)
2019-06-29 02:57:19,779 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025719768114
2019-06-29 02:57:19,804 [salt.minion      :1432][INFO    ][14731] Starting a new job with PID 14731
2019-06-29 02:57:19,825 [salt.minion      :1711][INFO    ][14731] Returning information for job: 20190629025719768114
2019-06-29 02:57:22,942 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.79910898s left)
2019-06-29 02:57:49,843 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025749831293
2019-06-29 02:57:49,873 [salt.minion      :1432][INFO    ][14757] Starting a new job with PID 14757
2019-06-29 02:57:49,895 [salt.minion      :1711][INFO    ][14757] Returning information for job: 20190629025749831293
2019-06-29 02:57:55,745 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.99574399s left)
2019-06-29 02:58:19,945 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025819928032
2019-06-29 02:58:19,974 [salt.minion      :1432][INFO    ][14817] Starting a new job with PID 14817
2019-06-29 02:58:20,001 [salt.minion      :1711][INFO    ][14817] Returning information for job: 20190629025819928032
2019-06-29 02:58:28,400 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.3410511s left)
2019-06-29 02:58:50,048 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025850027770
2019-06-29 02:58:50,082 [salt.minion      :1432][INFO    ][14841] Starting a new job with PID 14841
2019-06-29 02:58:50,105 [salt.minion      :1711][INFO    ][14841] Returning information for job: 20190629025850027770
2019-06-29 02:59:01,332 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.4085381s left)
2019-06-29 02:59:20,155 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025920137368
2019-06-29 02:59:20,189 [salt.minion      :1432][INFO    ][14897] Starting a new job with PID 14897
2019-06-29 02:59:20,215 [salt.minion      :1711][INFO    ][14897] Returning information for job: 20190629025920137368
2019-06-29 02:59:34,035 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.70568609s left)
2019-06-29 02:59:50,249 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629025950235230
2019-06-29 02:59:50,274 [salt.minion      :1432][INFO    ][14921] Starting a new job with PID 14921
2019-06-29 02:59:50,295 [salt.minion      :1711][INFO    ][14921] Returning information for job: 20190629025950235230
2019-06-29 03:00:06,996 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.7447629s left)
2019-06-29 03:00:20,362 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030020341040
2019-06-29 03:00:20,383 [salt.minion      :1432][INFO    ][15001] Starting a new job with PID 15001
2019-06-29 03:00:20,408 [salt.minion      :1711][INFO    ][15001] Returning information for job: 20190629030020341040
2019-06-29 03:00:39,897 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.84353995s left)
2019-06-29 03:00:50,445 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030050434167
2019-06-29 03:00:50,475 [salt.minion      :1432][INFO    ][15047] Starting a new job with PID 15047
2019-06-29 03:00:50,496 [salt.minion      :1711][INFO    ][15047] Returning information for job: 20190629030050434167
2019-06-29 03:01:13,681 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.05989408s left)
2019-06-29 03:01:20,592 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030120575644
2019-06-29 03:01:20,620 [salt.minion      :1432][INFO    ][15229] Starting a new job with PID 15229
2019-06-29 03:01:20,648 [salt.minion      :1711][INFO    ][15229] Returning information for job: 20190629030120575644
2019-06-29 03:01:46,680 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.06034088s left)
2019-06-29 03:01:50,708 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030150696079
2019-06-29 03:01:50,737 [salt.minion      :1432][INFO    ][15251] Starting a new job with PID 15251
2019-06-29 03:01:50,760 [salt.minion      :1711][INFO    ][15251] Returning information for job: 20190629030150696079
2019-06-29 03:02:19,599 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.14130998s left)
2019-06-29 03:02:20,848 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030220834431
2019-06-29 03:02:20,878 [salt.minion      :1432][INFO    ][15355] Starting a new job with PID 15355
2019-06-29 03:02:20,901 [salt.minion      :1711][INFO    ][15355] Returning information for job: 20190629030220834431
2019-06-29 03:02:50,984 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030250971183
2019-06-29 03:02:51,001 [salt.minion      :1432][INFO    ][15375] Starting a new job with PID 15375
2019-06-29 03:02:51,032 [salt.minion      :1711][INFO    ][15375] Returning information for job: 20190629030250971183
2019-06-29 03:02:52,385 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.355685s left)
2019-06-29 03:03:21,143 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030321125772
2019-06-29 03:03:21,172 [salt.minion      :1432][INFO    ][15511] Starting a new job with PID 15511
2019-06-29 03:03:21,198 [salt.minion      :1711][INFO    ][15511] Returning information for job: 20190629030321125772
2019-06-29 03:03:25,258 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.48229003s left)
2019-06-29 03:03:51,300 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030351284470
2019-06-29 03:03:51,328 [salt.minion      :1432][INFO    ][15534] Starting a new job with PID 15534
2019-06-29 03:03:51,354 [salt.minion      :1711][INFO    ][15534] Returning information for job: 20190629030351284470
2019-06-29 03:03:58,317 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.42365408s left)
2019-06-29 03:04:21,475 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030421458773
2019-06-29 03:04:21,505 [salt.minion      :1432][INFO    ][15699] Starting a new job with PID 15699
2019-06-29 03:04:21,531 [salt.minion      :1711][INFO    ][15699] Returning information for job: 20190629030421458773
2019-06-29 03:04:31,369 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.37172198s left)
2019-06-29 03:04:51,653 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030451639042
2019-06-29 03:04:51,683 [salt.minion      :1432][INFO    ][15723] Starting a new job with PID 15723
2019-06-29 03:04:51,732 [salt.minion      :1711][INFO    ][15723] Returning information for job: 20190629030451639042
2019-06-29 03:05:04,229 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.51141095s left)
2019-06-29 03:05:21,847 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030521828721
2019-06-29 03:05:21,879 [salt.minion      :1432][INFO    ][15778] Starting a new job with PID 15778
2019-06-29 03:05:21,906 [salt.minion      :1711][INFO    ][15778] Returning information for job: 20190629030521828721
2019-06-29 03:05:37,104 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.63621593s left)
2019-06-29 03:05:52,033 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030552018425
2019-06-29 03:05:52,061 [salt.minion      :1432][INFO    ][15800] Starting a new job with PID 15800
2019-06-29 03:05:52,087 [salt.minion      :1711][INFO    ][15800] Returning information for job: 20190629030552018425
2019-06-29 03:06:09,886 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.8543489s left)
2019-06-29 03:06:22,212 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030622195567
2019-06-29 03:06:22,236 [salt.minion      :1432][INFO    ][15856] Starting a new job with PID 15856
2019-06-29 03:06:22,264 [salt.minion      :1711][INFO    ][15856] Returning information for job: 20190629030622195567
2019-06-29 03:06:43,108 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.63327599s left)
2019-06-29 03:06:52,396 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030652382699
2019-06-29 03:06:52,427 [salt.minion      :1432][INFO    ][15876] Starting a new job with PID 15876
2019-06-29 03:06:52,451 [salt.minion      :1711][INFO    ][15876] Returning information for job: 20190629030652382699
2019-06-29 03:07:15,984 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.75656295s left)
2019-06-29 03:07:22,578 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030722565581
2019-06-29 03:07:22,606 [salt.minion      :1432][INFO    ][16098] Starting a new job with PID 16098
2019-06-29 03:07:22,636 [salt.minion      :1711][INFO    ][16098] Returning information for job: 20190629030722565581
2019-06-29 03:07:48,804 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.93708587s left)
2019-06-29 03:07:52,784 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030752767630
2019-06-29 03:07:52,816 [salt.minion      :1432][INFO    ][16120] Starting a new job with PID 16120
2019-06-29 03:07:52,841 [salt.minion      :1711][INFO    ][16120] Returning information for job: 20190629030752767630
2019-06-29 03:08:04,264 [salt.utils.schedule:1377][INFO    ][5441] Running scheduled job: __mine_interval
2019-06-29 03:08:21,693 [salt.loaded.ext.module.maas:1023][INFO    ][14580] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1523.04764795s left)
2019-06-29 03:08:22,992 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030822980662
2019-06-29 03:08:23,016 [salt.minion      :1432][INFO    ][16213] Starting a new job with PID 16213
2019-06-29 03:08:23,050 [salt.minion      :1711][INFO    ][16213] Returning information for job: 20190629030822980662
2019-06-29 03:08:53,051 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command saltutil.find_job with jid 20190629030853038464
2019-06-29 03:08:53,082 [salt.minion      :1432][INFO    ][16258] Starting a new job with PID 16258
2019-06-29 03:08:53,108 [salt.minion      :1711][INFO    ][16258] Returning information for job: 20190629030853038464
2019-06-29 03:08:54,755 [salt.state       :300 ][INFO    ][14580] {'ret': True}
2019-06-29 03:08:54,756 [salt.state       :1951][INFO    ][14580] Completed state [maas.wait_for_machine_status] at time 03:08:54.756226 duration_in_ms=760043.435
2019-06-29 03:08:54,763 [salt.minion      :1711][INFO    ][14580] Returning information for job: 20190629025604388384
2019-06-29 04:08:04,264 [salt.utils.schedule:1377][INFO    ][5441] Running scheduled job: __mine_interval
2019-06-29 04:42:00,222 [salt.minion      :1308][INFO    ][5441] User sudo_ubuntu Executing command cp.push_dir with jid 20190629044200208515
2019-06-29 04:42:00,251 [salt.minion      :1432][INFO    ][22430] Starting a new job with PID 22430
