2019-06-08 05:05:02,256 [salt.utils.decorators:613 ][WARNING ][2336] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:05:03,129 [salt.utils.decorators:613 ][WARNING ][2336] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:05:06,885 [salt.loaded.int.states.file:2298][WARNING ][2640] 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-08 05:05:32,754 [salt.state       :2022][WARNING ][2907] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-08 05:05:36,033 [salt.utils.decorators:613 ][WARNING ][2907] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:05:51,389 [salt.utils.decorators:613 ][WARNING ][2907] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:06:17,095 [salt.utils.decorators:613 ][WARNING ][2907] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:06:18,252 [salt.utils.decorators:613 ][WARNING ][2907] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:06:20,617 [salt.loaded.ext.module.maasng:1008][WARNING ][2907] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-06-08 05:06:20,617 [salt.loaded.ext.module.maasng:1011][WARNING ][2907] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-06-08 05:06:21,295 [salt.loaded.ext.module.maasng:1235][WARNING ][2907] Ignoring parameter vlan:0
2019-06-08 05:06:22,063 [salt.utils.decorators:613 ][WARNING ][2907] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:06:25,648 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5471] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-08 05:06:25,683 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5471] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-08 05:06:25,729 [salt.utils.parsers:1051][WARNING ][379] Minion received a SIGTERM. Exiting.
2019-06-08 05:06:26,848 [salt.cli.daemons :293 ][INFO    ][5546] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-06-08 05:06:27,035 [salt.cli.daemons :82  ][INFO    ][5546] Starting up the Salt Minion
2019-06-08 05:06:27,036 [salt.utils.event :1017][INFO    ][5546] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-06-08 05:06:28,350 [salt.minion      :976 ][INFO    ][5546] Creating minion process manager
2019-06-08 05:06:30,618 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5546] Executing command ['date', '+%z'] in directory '/root'
2019-06-08 05:06:30,645 [salt.utils.schedule:568 ][INFO    ][5546] Updating job settings for scheduled job: __mine_interval
2019-06-08 05:06:30,647 [salt.minion      :1108][INFO    ][5546] Added mine.update to scheduler
2019-06-08 05:06:30,654 [salt.minion      :1975][INFO    ][5546] Minion is starting as user 'root'
2019-06-08 05:06:30,802 [salt.minion      :2336][INFO    ][5546] Minion is ready to receive requests!
2019-06-08 05:06:34,089 [salt.utils.decorators:613 ][WARNING ][5480] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:06:34,157 [salt.loaded.ext.module.maas:412 ][WARNING ][5480] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-08 05:06:35,605 [salt.loaded.ext.module.maas:412 ][WARNING ][5480] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-08 05:06:37,184 [salt.loaded.ext.module.maas:412 ][WARNING ][5480] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-08 05:06:38,100 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608050638092085
2019-06-08 05:06:38,128 [salt.minion      :1432][INFO    ][5865] Starting a new job with PID 5865
2019-06-08 05:06:38,158 [salt.minion      :1711][INFO    ][5865] Returning information for job: 20190608050638092085
2019-06-08 05:06:38,562 [salt.loaded.ext.module.maas:412 ][WARNING ][5480] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-08 05:06:39,916 [salt.loaded.ext.module.maas:412 ][WARNING ][5480] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-08 05:07:26,327 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command state.apply with jid 20190608050726313179
2019-06-08 05:07:26,355 [salt.minion      :1432][INFO    ][6027] Starting a new job with PID 6027
2019-06-08 05:07:34,340 [salt.state       :915 ][INFO    ][6027] Loading fresh modules for state activity
2019-06-08 05:07:34,412 [salt.fileclient  :1219][INFO    ][6027] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-08 05:07:34,472 [salt.state       :1780][INFO    ][6027] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:07:34.472028
2019-06-08 05:07:34,472 [salt.state       :1813][INFO    ][6027] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-08 05:07:34,474 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6027] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-08 05:07:36,319 [salt.state       :300 ][INFO    ][6027] {'pid': 6035, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-08 05:07:36,320 [salt.state       :1951][INFO    ][6027] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:07:36.320672 duration_in_ms=1848.642
2019-06-08 05:07:36,324 [salt.state       :1780][INFO    ][6027] Running state [maas.wait_for_machine_status] at time 05:07:36.324212
2019-06-08 05:07:36,324 [salt.state       :1813][INFO    ][6027] Executing state module.run for [maas.wait_for_machine_status]
2019-06-08 05:07:36,325 [salt.utils.decorators:613 ][WARNING ][6027] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:07:37,284 [salt.loaded.ext.module.maas:1023][INFO    ][6027] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.06128407s left)
2019-06-08 05:07:41,476 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608050741416151
2019-06-08 05:07:41,501 [salt.minion      :1432][INFO    ][6071] Starting a new job with PID 6071
2019-06-08 05:07:41,531 [salt.minion      :1711][INFO    ][6071] Returning information for job: 20190608050741416151
2019-06-08 05:08:08,195 [salt.loaded.ext.module.maas:1023][INFO    ][6027] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.14994216s left)
2019-06-08 05:08:11,574 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608050811557008
2019-06-08 05:08:11,601 [salt.minion      :1432][INFO    ][6089] Starting a new job with PID 6089
2019-06-08 05:08:11,632 [salt.minion      :1711][INFO    ][6089] Returning information for job: 20190608050811557008
2019-06-08 05:08:39,041 [salt.loaded.ext.module.maas:1023][INFO    ][6027] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.30422306s left)
2019-06-08 05:08:41,708 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608050841691391
2019-06-08 05:08:41,741 [salt.minion      :1432][INFO    ][6142] Starting a new job with PID 6142
2019-06-08 05:08:41,771 [salt.minion      :1711][INFO    ][6142] Returning information for job: 20190608050841691391
2019-06-08 05:09:10,000 [salt.loaded.ext.module.maas:1023][INFO    ][6027] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.34545708s left)
2019-06-08 05:09:11,796 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608050911783509
2019-06-08 05:09:11,816 [salt.minion      :1432][INFO    ][6183] Starting a new job with PID 6183
2019-06-08 05:09:11,844 [salt.minion      :1711][INFO    ][6183] Returning information for job: 20190608050911783509
2019-06-08 05:09:41,099 [salt.loaded.ext.module.maas:1023][INFO    ][6027] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.2461791s left)
2019-06-08 05:09:41,876 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608050941860541
2019-06-08 05:09:41,897 [salt.minion      :1432][INFO    ][6339] Starting a new job with PID 6339
2019-06-08 05:09:41,925 [salt.minion      :1711][INFO    ][6339] Returning information for job: 20190608050941860541
2019-06-08 05:10:11,975 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051011960603
2019-06-08 05:10:11,995 [salt.minion      :1432][INFO    ][6375] Starting a new job with PID 6375
2019-06-08 05:10:12,023 [salt.minion      :1711][INFO    ][6375] Returning information for job: 20190608051011960603
2019-06-08 05:10:12,275 [salt.loaded.ext.module.maas:1023][INFO    ][6027] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.07042813s left)
2019-06-08 05:10:42,125 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051042107353
2019-06-08 05:10:42,151 [salt.minion      :1432][INFO    ][6651] Starting a new job with PID 6651
2019-06-08 05:10:42,179 [salt.minion      :1711][INFO    ][6651] Returning information for job: 20190608051042107353
2019-06-08 05:10:43,561 [salt.loaded.ext.module.maas:1023][INFO    ][6027] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.78460312s left)
2019-06-08 05:11:12,211 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051112197944
2019-06-08 05:11:12,239 [salt.minion      :1432][INFO    ][6765] Starting a new job with PID 6765
2019-06-08 05:11:12,265 [salt.minion      :1711][INFO    ][6765] Returning information for job: 20190608051112197944
2019-06-08 05:11:14,949 [salt.loaded.ext.module.maas:1023][INFO    ][6027] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.39636803s left)
2019-06-08 05:11:42,355 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051142335881
2019-06-08 05:11:42,386 [salt.minion      :1432][INFO    ][7059] Starting a new job with PID 7059
2019-06-08 05:11:42,419 [salt.minion      :1711][INFO    ][7059] Returning information for job: 20190608051142335881
2019-06-08 05:11:46,749 [salt.loaded.ext.module.maas:1023][INFO    ][6027] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.59604502s left)
2019-06-08 05:12:12,478 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051212464511
2019-06-08 05:12:12,501 [salt.minion      :1432][INFO    ][7206] Starting a new job with PID 7206
2019-06-08 05:12:12,534 [salt.minion      :1711][INFO    ][7206] Returning information for job: 20190608051212464511
2019-06-08 05:12:19,024 [salt.loaded.ext.module.maas:1023][INFO    ][6027] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.32132912s left)
2019-06-08 05:12:42,652 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051242637607
2019-06-08 05:12:42,678 [salt.minion      :1432][INFO    ][7497] Starting a new job with PID 7497
2019-06-08 05:12:42,710 [salt.minion      :1711][INFO    ][7497] Returning information for job: 20190608051242637607
2019-06-08 05:12:51,445 [salt.loaded.ext.module.maas:1023][INFO    ][6027] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.90058613s left)
2019-06-08 05:13:12,780 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051312771528
2019-06-08 05:13:12,799 [salt.minion      :1432][INFO    ][7612] Starting a new job with PID 7612
2019-06-08 05:13:12,832 [salt.minion      :1711][INFO    ][7612] Returning information for job: 20190608051312771528
2019-06-08 05:13:24,288 [salt.loaded.ext.module.maas:1023][INFO    ][6027] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1152.05742121s left)
2019-06-08 05:13:42,904 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051342888663
2019-06-08 05:13:42,928 [salt.minion      :1432][INFO    ][7694] Starting a new job with PID 7694
2019-06-08 05:13:42,953 [salt.minion      :1711][INFO    ][7694] Returning information for job: 20190608051342888663
2019-06-08 05:13:57,141 [salt.state       :300 ][INFO    ][6027] {'ret': True}
2019-06-08 05:13:57,142 [salt.state       :1951][INFO    ][6027] Completed state [maas.wait_for_machine_status] at time 05:13:57.142434 duration_in_ms=380818.22
2019-06-08 05:13:57,150 [salt.minion      :1711][INFO    ][6027] Returning information for job: 20190608050726313179
2019-06-08 05:13:57,883 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command state.apply with jid 20190608051357868952
2019-06-08 05:13:57,910 [salt.minion      :1432][INFO    ][7753] Starting a new job with PID 7753
2019-06-08 05:14:06,127 [salt.state       :915 ][INFO    ][7753] Loading fresh modules for state activity
2019-06-08 05:14:06,185 [salt.fileclient  :1219][INFO    ][7753] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-08 05:14:06,281 [salt.state       :1780][INFO    ][7753] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:14:06.281101
2019-06-08 05:14:06,281 [salt.state       :1813][INFO    ][7753] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-08 05:14:06,283 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7753] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-08 05:14:08,143 [salt.state       :300 ][INFO    ][7753] {'pid': 7762, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-08 05:14:08,144 [salt.state       :1951][INFO    ][7753] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:14:08.144565 duration_in_ms=1863.463
2019-06-08 05:14:08,148 [salt.state       :1780][INFO    ][7753] Running state [maas_machines_storage_cmp002_lvm] at time 05:14:08.148269
2019-06-08 05:14:08,148 [salt.state       :1813][INFO    ][7753] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-08 05:14:09,293 [salt.loaded.ext.module.maasng:610 ][INFO    ][7753] ma6xym
2019-06-08 05:14:09,294 [salt.loaded.ext.module.maasng:626 ][INFO    ][7753] sda
2019-06-08 05:14:09,868 [salt.loaded.ext.module.maasng:361 ][INFO    ][7753] ma6xym
2019-06-08 05:14:09,961 [salt.loaded.ext.module.maasng:367 ][INFO    ][7753] [{u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/blockdevices/1/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'69d17bbf-0440-4640-b31f-890558eff0d7', u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ma6xym', u'filesystem': {u'mount_options': None, u'uuid': u'ab685628-12eb-4bed-af81-cb2d8c27383a', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}], u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ma6xym', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/blockdevices/3/', u'used_size': 800097042432, u'partitions': [], u'tags': [], u'uuid': u'eb8ab8fd-cdee-4a8f-b28b-2185ca5a2e39', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'ma6xym', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'b83ded4d-3447-4d0d-927e-f18e5912dbb5', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 3, u'size': 800097042432}]
2019-06-08 05:14:09,963 [salt.loaded.ext.module.maasng:632 ][INFO    ][7753] vgroot
2019-06-08 05:14:09,964 [salt.loaded.ext.module.maasng:635 ][INFO    ][7753] lvroot
2019-06-08 05:14:09,964 [salt.loaded.ext.module.maasng:639 ][INFO    ][7753] 107374182400
2019-06-08 05:14:10,558 [salt.loaded.ext.module.maasng:645 ][INFO    ][7753] {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'swap_size': None, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'ma6xym', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/interfaces/5/'}, u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/blockdevices/1/', u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'tags': [u'ssd'], u'uuid': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ma6xym', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'91d856ba-d8f6-4530-b40c-bdc1e712e939', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ma6xym', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'dca9a10c-2534-4a8f-8584-620f27a75778'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}]}, {u'size': 107374182400, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/blockdevices/11/', u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'tags': [], u'uuid': u'537e6bfb-d798-4d86-9eef-6e9c2c411191', u'id': 11, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'ma6xym', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'8490e925-d048-4834-a88a-b731b16cf6ce'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'partitions': []}], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/ma6xym/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'ma6xym', 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'physicalblockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/blockdevices/1/', u'type': u'physical', u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ma6xym', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'91d856ba-d8f6-4530-b40c-bdc1e712e939', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ma6xym', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'dca9a10c-2534-4a8f-8584-620f27a75778'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}]}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ma6xym', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/blockdevices/1/', u'type': u'physical', u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ma6xym', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'91d856ba-d8f6-4530-b40c-bdc1e712e939', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ma6xym', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'dca9a10c-2534-4a8f-8584-620f27a75778'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}]}, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'ma6xym', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'system_id': u'ma6xym', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/interfaces/12/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 15, u'discovered': None, u'system_id': u'ma6xym', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/interfaces/15/'}, {u'name': u'ens2f1', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'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': 13, u'discovered': None, u'system_id': u'ma6xym', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/interfaces/13/'}, {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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 14, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'system_id': u'ma6xym', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/interfaces/14/'}, {u'name': u'ens2f0', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'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'system_id': u'ma6xym', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/interfaces/16/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ma6xym/blockdevices/11/', u'type': u'virtual', u'tags': [], u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'8490e925-d048-4834-a88a-b731b16cf6ce'}, u'uuid': u'537e6bfb-d798-4d86-9eef-6e9c2c411191', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ma6xym', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'current_commissioning_result_id': 2, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-06-08 05:14:10,561 [salt.state       :300 ][INFO    ][7753] {'new': {'storage_layout': 'lvm'}}
2019-06-08 05:14:10,561 [salt.state       :1951][INFO    ][7753] Completed state [maas_machines_storage_cmp002_lvm] at time 05:14:10.561455 duration_in_ms=2413.184
2019-06-08 05:14:10,564 [salt.state       :1780][INFO    ][7753] Running state [maas_machines_storage_cmp001_lvm] at time 05:14:10.564253
2019-06-08 05:14:10,564 [salt.state       :1813][INFO    ][7753] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-08 05:14:11,703 [salt.loaded.ext.module.maasng:610 ][INFO    ][7753] fpt7by
2019-06-08 05:14:11,703 [salt.loaded.ext.module.maasng:626 ][INFO    ][7753] sda
2019-06-08 05:14:12,269 [salt.loaded.ext.module.maasng:361 ][INFO    ][7753] fpt7by
2019-06-08 05:14:12,361 [salt.loaded.ext.module.maasng:367 ][INFO    ][7753] [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/blockdevices/2/', u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'tags': [u'ssd'], u'uuid': None, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fpt7by', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'0a0ee321-9e62-4c1d-a5a6-8d68cd312e92', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fpt7by', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'4b58db66-d9c8-465c-ac11-265f7f882061'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'device_id': 2}]}, {u'size': 800097042432, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/blockdevices/4/', u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'tags': [], u'uuid': u'ec565850-85b8-4263-bfde-bfe5c451b690', u'id': 4, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'fpt7by', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'08b4d1f9-b831-4cac-91aa-21b50d517540'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'serial': None, u'partitions': []}]
2019-06-08 05:14:12,362 [salt.loaded.ext.module.maasng:632 ][INFO    ][7753] vgroot
2019-06-08 05:14:12,363 [salt.loaded.ext.module.maasng:635 ][INFO    ][7753] lvroot
2019-06-08 05:14:12,363 [salt.loaded.ext.module.maasng:639 ][INFO    ][7753] 107374182400
2019-06-08 05:14:12,973 [salt.loaded.ext.module.maasng:645 ][INFO    ][7753] {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'swap_size': None, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'fpt7by', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/interfaces/6/'}, u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/blockdevices/2/', u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'tags': [u'ssd'], u'uuid': None, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fpt7by', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'3d961c3b-0c14-4283-bf6d-2d332dfb39d7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fpt7by', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'552b7074-4950-42ec-b783-ea1e35b0ba89'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}, {u'size': 107374182400, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/blockdevices/12/', u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'tags': [], u'uuid': u'6e722b55-c0f9-499d-843d-4abf3f548a61', u'id': 12, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'fpt7by', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'211ec8a7-e6e5-4513-83ce-f3983744147c'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'partitions': []}], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/fpt7by/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'fpt7by', 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'physicalblockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/blockdevices/2/', u'type': u'physical', u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'fpt7by', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'3d961c3b-0c14-4283-bf6d-2d332dfb39d7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fpt7by', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'552b7074-4950-42ec-b783-ea1e35b0ba89'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'fpt7by', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/blockdevices/2/', u'type': u'physical', u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'fpt7by', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'3d961c3b-0c14-4283-bf6d-2d332dfb39d7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fpt7by', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'552b7074-4950-42ec-b783-ea1e35b0ba89'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'fpt7by', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/interfaces/6/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 18, u'discovered': None, u'system_id': u'fpt7by', u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/interfaces/18/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'system_id': u'fpt7by', u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/interfaces/20/'}, {u'name': u'ens2f1', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'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': 17, u'discovered': None, u'system_id': u'fpt7by', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/interfaces/17/'}, {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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 19, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.43'}], u'system_id': u'fpt7by', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/interfaces/19/'}, {u'name': u'ens2f0', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'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': 21, u'discovered': None, u'system_id': u'fpt7by', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/interfaces/21/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/fpt7by/blockdevices/12/', u'type': u'virtual', u'tags': [], u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'211ec8a7-e6e5-4513-83ce-f3983744147c'}, u'uuid': u'6e722b55-c0f9-499d-843d-4abf3f548a61', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fpt7by', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'current_commissioning_result_id': 4, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-06-08 05:14:12,976 [salt.state       :300 ][INFO    ][7753] {'new': {'storage_layout': 'lvm'}}
2019-06-08 05:14:12,976 [salt.state       :1951][INFO    ][7753] Completed state [maas_machines_storage_cmp001_lvm] at time 05:14:12.976810 duration_in_ms=2412.556
2019-06-08 05:14:12,980 [salt.minion      :1711][INFO    ][7753] Returning information for job: 20190608051357868952
2019-06-08 05:14:12,981 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051412970744
2019-06-08 05:14:13,012 [salt.minion      :1432][INFO    ][7788] Starting a new job with PID 7788
2019-06-08 05:14:13,045 [salt.minion      :1711][INFO    ][7788] Returning information for job: 20190608051412970744
2019-06-08 05:14:13,643 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command state.apply with jid 20190608051413625577
2019-06-08 05:14:13,672 [salt.minion      :1432][INFO    ][7793] Starting a new job with PID 7793
2019-06-08 05:14:14,844 [salt.state       :915 ][INFO    ][7793] Loading fresh modules for state activity
2019-06-08 05:14:14,914 [salt.fileclient  :1219][INFO    ][7793] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-08 05:14:14,975 [salt.state       :1780][INFO    ][7793] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:14:14.975274
2019-06-08 05:14:14,975 [salt.state       :1813][INFO    ][7793] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-08 05:14:14,979 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7793] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-08 05:14:16,967 [salt.state       :300 ][INFO    ][7793] {'pid': 7800, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-08 05:14:16,968 [salt.state       :1951][INFO    ][7793] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:14:16.968064 duration_in_ms=1992.79
2019-06-08 05:14:16,971 [salt.state       :1780][INFO    ][7793] Running state [maas.deploy_machines] at time 05:14:16.971819
2019-06-08 05:14:16,972 [salt.state       :1813][INFO    ][7793] Executing state module.run for [maas.deploy_machines]
2019-06-08 05:14:16,974 [salt.utils.decorators:613 ][WARNING ][7793] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:14:17,550 [salt.loaded.ext.module.maas:684 ][INFO    ][7793] deploymachines hwe_kernel=hwe-16.04 system_id=ma6xym distro_series=xenial
2019-06-08 05:14:20,287 [salt.loaded.ext.module.maas:684 ][INFO    ][7793] deploymachines hwe_kernel=hwe-16.04 system_id=fpt7by distro_series=xenial
2019-06-08 05:14:22,772 [salt.loaded.ext.module.maas:684 ][INFO    ][7793] deploymachines hwe_kernel=hwe-16.04 system_id=fsytrm distro_series=xenial
2019-06-08 05:14:25,342 [salt.loaded.ext.module.maas:684 ][INFO    ][7793] deploymachines hwe_kernel=hwe-16.04 system_id=tky38m distro_series=xenial
2019-06-08 05:14:27,884 [salt.loaded.ext.module.maas:684 ][INFO    ][7793] deploymachines hwe_kernel=hwe-16.04 system_id=kyxxd7 distro_series=xenial
2019-06-08 05:14:28,689 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051428674896
2019-06-08 05:14:28,712 [salt.minion      :1432][INFO    ][8043] Starting a new job with PID 8043
2019-06-08 05:14:28,750 [salt.minion      :1711][INFO    ][8043] Returning information for job: 20190608051428674896
2019-06-08 05:14:30,448 [salt.state       :300 ][INFO    ][7793] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-08 05:14:30,449 [salt.state       :1951][INFO    ][7793] Completed state [maas.deploy_machines] at time 05:14:30.449295 duration_in_ms=13477.476
2019-06-08 05:14:30,454 [salt.minion      :1711][INFO    ][7793] Returning information for job: 20190608051413625577
2019-06-08 05:14:31,197 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command state.apply with jid 20190608051431185240
2019-06-08 05:14:31,225 [salt.minion      :1432][INFO    ][8089] Starting a new job with PID 8089
2019-06-08 05:14:39,459 [salt.state       :915 ][INFO    ][8089] Loading fresh modules for state activity
2019-06-08 05:14:39,522 [salt.fileclient  :1219][INFO    ][8089] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-08 05:14:39,579 [salt.state       :1780][INFO    ][8089] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:14:39.579445
2019-06-08 05:14:39,579 [salt.state       :1813][INFO    ][8089] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-08 05:14:39,581 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8089] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-08 05:14:41,570 [salt.state       :300 ][INFO    ][8089] {'pid': 8137, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-08 05:14:41,571 [salt.state       :1951][INFO    ][8089] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:14:41.571686 duration_in_ms=1992.241
2019-06-08 05:14:41,576 [salt.state       :1780][INFO    ][8089] Running state [maas.wait_for_machine_status] at time 05:14:41.576195
2019-06-08 05:14:41,577 [salt.state       :1813][INFO    ][8089] Executing state module.run for [maas.wait_for_machine_status]
2019-06-08 05:14:41,577 [salt.utils.decorators:613 ][WARNING ][8089] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:14:44,574 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.0190711s left)
2019-06-08 05:14:46,325 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051446308827
2019-06-08 05:14:46,352 [salt.minion      :1432][INFO    ][8151] Starting a new job with PID 8151
2019-06-08 05:14:46,379 [salt.minion      :1711][INFO    ][8151] Returning information for job: 20190608051446308827
2019-06-08 05:15:16,400 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051516391492
2019-06-08 05:15:16,422 [salt.minion      :1432][INFO    ][8184] Starting a new job with PID 8184
2019-06-08 05:15:16,451 [salt.minion      :1711][INFO    ][8184] Returning information for job: 20190608051516391492
2019-06-08 05:15:17,388 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.20532203s left)
2019-06-08 05:15:46,512 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051546498957
2019-06-08 05:15:46,541 [salt.minion      :1432][INFO    ][8261] Starting a new job with PID 8261
2019-06-08 05:15:46,571 [salt.minion      :1711][INFO    ][8261] Returning information for job: 20190608051546498957
2019-06-08 05:15:50,346 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.24712014s left)
2019-06-08 05:16:16,593 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051616580593
2019-06-08 05:16:16,616 [salt.minion      :1432][INFO    ][8284] Starting a new job with PID 8284
2019-06-08 05:16:16,644 [salt.minion      :1711][INFO    ][8284] Returning information for job: 20190608051616580593
2019-06-08 05:16:23,206 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.38713217s left)
2019-06-08 05:16:46,667 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051646654401
2019-06-08 05:16:46,696 [salt.minion      :1432][INFO    ][8337] Starting a new job with PID 8337
2019-06-08 05:16:46,734 [salt.minion      :1711][INFO    ][8337] Returning information for job: 20190608051646654401
2019-06-08 05:16:56,034 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.55863619s left)
2019-06-08 05:17:16,748 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051716735804
2019-06-08 05:17:16,773 [salt.minion      :1432][INFO    ][8399] Starting a new job with PID 8399
2019-06-08 05:17:16,807 [salt.minion      :1711][INFO    ][8399] Returning information for job: 20190608051716735804
2019-06-08 05:17:29,135 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.45805907s left)
2019-06-08 05:17:46,895 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051746872262
2019-06-08 05:17:46,926 [salt.minion      :1432][INFO    ][8559] Starting a new job with PID 8559
2019-06-08 05:17:46,963 [salt.minion      :1711][INFO    ][8559] Returning information for job: 20190608051746872262
2019-06-08 05:18:02,104 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.48871207s left)
2019-06-08 05:18:17,015 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051816999127
2019-06-08 05:18:17,048 [salt.minion      :1432][INFO    ][8612] Starting a new job with PID 8612
2019-06-08 05:18:17,076 [salt.minion      :1711][INFO    ][8612] Returning information for job: 20190608051816999127
2019-06-08 05:18:34,971 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.62225819s left)
2019-06-08 05:18:47,147 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051847131081
2019-06-08 05:18:47,179 [salt.minion      :1432][INFO    ][8907] Starting a new job with PID 8907
2019-06-08 05:18:47,207 [salt.minion      :1711][INFO    ][8907] Returning information for job: 20190608051847131081
2019-06-08 05:19:07,596 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.9965961s left)
2019-06-08 05:19:17,274 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051917255152
2019-06-08 05:19:17,303 [salt.minion      :1432][INFO    ][8965] Starting a new job with PID 8965
2019-06-08 05:19:17,332 [salt.minion      :1711][INFO    ][8965] Returning information for job: 20190608051917255152
2019-06-08 05:19:41,025 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.568223s left)
2019-06-08 05:19:47,456 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608051947441178
2019-06-08 05:19:47,481 [salt.minion      :1432][INFO    ][9276] Starting a new job with PID 9276
2019-06-08 05:19:47,518 [salt.minion      :1711][INFO    ][9276] Returning information for job: 20190608051947441178
2019-06-08 05:20:14,043 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.54979205s left)
2019-06-08 05:20:17,605 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052017590983
2019-06-08 05:20:17,636 [salt.minion      :1432][INFO    ][9331] Starting a new job with PID 9331
2019-06-08 05:20:17,670 [salt.minion      :1711][INFO    ][9331] Returning information for job: 20190608052017590983
2019-06-08 05:20:46,945 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.6478312s left)
2019-06-08 05:20:47,831 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052047817122
2019-06-08 05:20:47,861 [salt.minion      :1432][INFO    ][9905] Starting a new job with PID 9905
2019-06-08 05:20:47,891 [salt.minion      :1711][INFO    ][9905] Returning information for job: 20190608052047817122
2019-06-08 05:21:17,986 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052117964144
2019-06-08 05:21:18,012 [salt.minion      :1432][INFO    ][9945] Starting a new job with PID 9945
2019-06-08 05:21:18,053 [salt.minion      :1711][INFO    ][9945] Returning information for job: 20190608052117964144
2019-06-08 05:21:19,988 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1851.60500622s left)
2019-06-08 05:21:48,161 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052148152272
2019-06-08 05:21:48,181 [salt.minion      :1432][INFO    ][10113] Starting a new job with PID 10113
2019-06-08 05:21:48,213 [salt.minion      :1711][INFO    ][10113] Returning information for job: 20190608052148152272
2019-06-08 05:21:52,879 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.71413016s left)
2019-06-08 05:22:18,318 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052218300932
2019-06-08 05:22:18,344 [salt.minion      :1432][INFO    ][10157] Starting a new job with PID 10157
2019-06-08 05:22:18,375 [salt.minion      :1711][INFO    ][10157] Returning information for job: 20190608052218300932
2019-06-08 05:22:25,831 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1785.76230216s left)
2019-06-08 05:22:48,533 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052248516682
2019-06-08 05:22:48,560 [salt.minion      :1432][INFO    ][10388] Starting a new job with PID 10388
2019-06-08 05:22:48,597 [salt.minion      :1711][INFO    ][10388] Returning information for job: 20190608052248516682
2019-06-08 05:22:58,581 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1753.01228213s left)
2019-06-08 05:23:18,703 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052318688200
2019-06-08 05:23:18,731 [salt.minion      :1432][INFO    ][10463] Starting a new job with PID 10463
2019-06-08 05:23:18,765 [salt.minion      :1711][INFO    ][10463] Returning information for job: 20190608052318688200
2019-06-08 05:23:31,529 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1720.06416202s left)
2019-06-08 05:23:48,851 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052348836224
2019-06-08 05:23:48,881 [salt.minion      :1432][INFO    ][10751] Starting a new job with PID 10751
2019-06-08 05:23:48,913 [salt.minion      :1711][INFO    ][10751] Returning information for job: 20190608052348836224
2019-06-08 05:24:04,278 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1687.31578517s left)
2019-06-08 05:24:19,029 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052419012010
2019-06-08 05:24:19,052 [salt.minion      :1432][INFO    ][10783] Starting a new job with PID 10783
2019-06-08 05:24:19,080 [salt.minion      :1711][INFO    ][10783] Returning information for job: 20190608052419012010
2019-06-08 05:24:37,869 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1653.7238102s left)
2019-06-08 05:24:49,131 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052449115126
2019-06-08 05:24:49,157 [salt.minion      :1432][INFO    ][10925] Starting a new job with PID 10925
2019-06-08 05:24:49,186 [salt.minion      :1711][INFO    ][10925] Returning information for job: 20190608052449115126
2019-06-08 05:25:10,843 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1620.74956203s left)
2019-06-08 05:25:19,300 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052519286749
2019-06-08 05:25:19,328 [salt.minion      :1432][INFO    ][11082] Starting a new job with PID 11082
2019-06-08 05:25:19,364 [salt.minion      :1711][INFO    ][11082] Returning information for job: 20190608052519286749
2019-06-08 05:25:43,768 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1587.82476902s left)
2019-06-08 05:25:49,508 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052549492639
2019-06-08 05:25:49,536 [salt.minion      :1432][INFO    ][11181] Starting a new job with PID 11181
2019-06-08 05:25:49,566 [salt.minion      :1711][INFO    ][11181] Returning information for job: 20190608052549492639
2019-06-08 05:26:16,580 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1555.01259017s left)
2019-06-08 05:26:19,699 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052619684029
2019-06-08 05:26:19,722 [salt.minion      :1432][INFO    ][11214] Starting a new job with PID 11214
2019-06-08 05:26:19,760 [salt.minion      :1711][INFO    ][11214] Returning information for job: 20190608052619684029
2019-06-08 05:26:49,722 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1521.87114716s left)
2019-06-08 05:26:49,906 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052649895768
2019-06-08 05:26:49,932 [salt.minion      :1432][INFO    ][11258] Starting a new job with PID 11258
2019-06-08 05:26:49,962 [salt.minion      :1711][INFO    ][11258] Returning information for job: 20190608052649895768
2019-06-08 05:27:20,129 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052720119765
2019-06-08 05:27:20,145 [salt.minion      :1432][INFO    ][11326] Starting a new job with PID 11326
2019-06-08 05:27:20,176 [salt.minion      :1711][INFO    ][11326] Returning information for job: 20190608052720119765
2019-06-08 05:27:22,657 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1488.93593717s left)
2019-06-08 05:27:50,298 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052750284922
2019-06-08 05:27:50,325 [salt.minion      :1432][INFO    ][11486] Starting a new job with PID 11486
2019-06-08 05:27:50,360 [salt.minion      :1711][INFO    ][11486] Returning information for job: 20190608052750284922
2019-06-08 05:27:55,933 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1455.6601541s left)
2019-06-08 05:28:20,343 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052820328395
2019-06-08 05:28:20,370 [salt.minion      :1432][INFO    ][11532] Starting a new job with PID 11532
2019-06-08 05:28:20,406 [salt.minion      :1711][INFO    ][11532] Returning information for job: 20190608052820328395
2019-06-08 05:28:28,775 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1422.81828117s left)
2019-06-08 05:28:50,441 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052850427638
2019-06-08 05:28:50,467 [salt.minion      :1432][INFO    ][11762] Starting a new job with PID 11762
2019-06-08 05:28:50,495 [salt.minion      :1711][INFO    ][11762] Returning information for job: 20190608052850427638
2019-06-08 05:29:01,638 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1389.95490623s left)
2019-06-08 05:29:20,473 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052920462868
2019-06-08 05:29:20,502 [salt.minion      :1432][INFO    ][11792] Starting a new job with PID 11792
2019-06-08 05:29:20,532 [salt.minion      :1711][INFO    ][11792] Returning information for job: 20190608052920462868
2019-06-08 05:29:34,528 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1357.06518507s left)
2019-06-08 05:29:50,524 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608052950508738
2019-06-08 05:29:50,555 [salt.minion      :1432][INFO    ][11848] Starting a new job with PID 11848
2019-06-08 05:29:50,585 [salt.minion      :1711][INFO    ][11848] Returning information for job: 20190608052950508738
2019-06-08 05:30:07,356 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1324.23675919s left)
2019-06-08 05:30:20,596 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053020586377
2019-06-08 05:30:20,622 [salt.minion      :1432][INFO    ][11878] Starting a new job with PID 11878
2019-06-08 05:30:20,650 [salt.minion      :1711][INFO    ][11878] Returning information for job: 20190608053020586377
2019-06-08 05:30:40,271 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1291.32201219s left)
2019-06-08 05:30:50,669 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053050652461
2019-06-08 05:30:50,700 [salt.minion      :1432][INFO    ][11928] Starting a new job with PID 11928
2019-06-08 05:30:50,731 [salt.minion      :1711][INFO    ][11928] Returning information for job: 20190608053050652461
2019-06-08 05:31:13,156 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1258.43675423s left)
2019-06-08 05:31:20,763 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053120748538
2019-06-08 05:31:20,791 [salt.minion      :1432][INFO    ][11958] Starting a new job with PID 11958
2019-06-08 05:31:20,821 [salt.minion      :1711][INFO    ][11958] Returning information for job: 20190608053120748538
2019-06-08 05:31:45,873 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1225.72013807s left)
2019-06-08 05:31:50,851 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053150840765
2019-06-08 05:31:50,868 [salt.minion      :1432][INFO    ][12013] Starting a new job with PID 12013
2019-06-08 05:31:50,897 [salt.minion      :1711][INFO    ][12013] Returning information for job: 20190608053150840765
2019-06-08 05:32:18,967 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1192.62621617s left)
2019-06-08 05:32:20,961 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053220948796
2019-06-08 05:32:20,986 [salt.minion      :1432][INFO    ][12046] Starting a new job with PID 12046
2019-06-08 05:32:21,019 [salt.minion      :1711][INFO    ][12046] Returning information for job: 20190608053220948796
2019-06-08 05:32:51,089 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053251077210
2019-06-08 05:32:51,114 [salt.minion      :1432][INFO    ][12092] Starting a new job with PID 12092
2019-06-08 05:32:51,143 [salt.minion      :1711][INFO    ][12092] Returning information for job: 20190608053251077210
2019-06-08 05:32:51,666 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1159.92682505s left)
2019-06-08 05:33:21,245 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053321225296
2019-06-08 05:33:21,273 [salt.minion      :1432][INFO    ][12120] Starting a new job with PID 12120
2019-06-08 05:33:21,304 [salt.minion      :1711][INFO    ][12120] Returning information for job: 20190608053321225296
2019-06-08 05:33:24,425 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1127.16810417s left)
2019-06-08 05:33:51,415 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053351402272
2019-06-08 05:33:51,439 [salt.minion      :1432][INFO    ][12163] Starting a new job with PID 12163
2019-06-08 05:33:51,471 [salt.minion      :1711][INFO    ][12163] Returning information for job: 20190608053351402272
2019-06-08 05:33:57,259 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1094.33368206s left)
2019-06-08 05:34:21,564 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053421551037
2019-06-08 05:34:21,592 [salt.minion      :1432][INFO    ][12193] Starting a new job with PID 12193
2019-06-08 05:34:21,623 [salt.minion      :1711][INFO    ][12193] Returning information for job: 20190608053421551037
2019-06-08 05:34:29,973 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1061.62010002s left)
2019-06-08 05:34:51,756 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053451741029
2019-06-08 05:34:51,785 [salt.minion      :1432][INFO    ][12239] Starting a new job with PID 12239
2019-06-08 05:34:51,815 [salt.minion      :1711][INFO    ][12239] Returning information for job: 20190608053451741029
2019-06-08 05:35:02,932 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1028.66091013s left)
2019-06-08 05:35:21,936 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053521924811
2019-06-08 05:35:21,961 [salt.minion      :1432][INFO    ][12277] Starting a new job with PID 12277
2019-06-08 05:35:21,992 [salt.minion      :1711][INFO    ][12277] Returning information for job: 20190608053521924811
2019-06-08 05:35:35,763 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (995.829507113s left)
2019-06-08 05:35:51,958 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053551949049
2019-06-08 05:35:51,984 [salt.minion      :1432][INFO    ][12342] Starting a new job with PID 12342
2019-06-08 05:35:52,018 [salt.minion      :1711][INFO    ][12342] Returning information for job: 20190608053551949049
2019-06-08 05:36:08,456 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (963.136502028s left)
2019-06-08 05:36:22,160 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053622145637
2019-06-08 05:36:22,186 [salt.minion      :1432][INFO    ][12376] Starting a new job with PID 12376
2019-06-08 05:36:22,217 [salt.minion      :1711][INFO    ][12376] Returning information for job: 20190608053622145637
2019-06-08 05:36:41,168 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (930.425379038s left)
2019-06-08 05:36:52,220 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053652204907
2019-06-08 05:36:52,247 [salt.minion      :1432][INFO    ][12425] Starting a new job with PID 12425
2019-06-08 05:36:52,280 [salt.minion      :1711][INFO    ][12425] Returning information for job: 20190608053652204907
2019-06-08 05:37:13,728 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (897.864814043s left)
2019-06-08 05:37:22,275 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053722260381
2019-06-08 05:37:22,304 [salt.minion      :1432][INFO    ][12454] Starting a new job with PID 12454
2019-06-08 05:37:22,336 [salt.minion      :1711][INFO    ][12454] Returning information for job: 20190608053722260381
2019-06-08 05:37:46,520 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (865.072651148s left)
2019-06-08 05:37:52,357 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053752342465
2019-06-08 05:37:52,385 [salt.minion      :1432][INFO    ][12501] Starting a new job with PID 12501
2019-06-08 05:37:52,415 [salt.minion      :1711][INFO    ][12501] Returning information for job: 20190608053752342465
2019-06-08 05:38:19,245 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (832.348100185s left)
2019-06-08 05:38:22,435 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053822417032
2019-06-08 05:38:22,462 [salt.minion      :1432][INFO    ][12531] Starting a new job with PID 12531
2019-06-08 05:38:22,494 [salt.minion      :1711][INFO    ][12531] Returning information for job: 20190608053822417032
2019-06-08 05:38:52,157 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (799.436295033s left)
2019-06-08 05:38:52,536 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053852521423
2019-06-08 05:38:52,564 [salt.minion      :1432][INFO    ][12577] Starting a new job with PID 12577
2019-06-08 05:38:52,595 [salt.minion      :1711][INFO    ][12577] Returning information for job: 20190608053852521423
2019-06-08 05:39:22,680 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053922671206
2019-06-08 05:39:22,700 [salt.minion      :1432][INFO    ][12603] Starting a new job with PID 12603
2019-06-08 05:39:22,732 [salt.minion      :1711][INFO    ][12603] Returning information for job: 20190608053922671206
2019-06-08 05:39:24,897 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (766.695878029s left)
2019-06-08 05:39:52,822 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608053952804547
2019-06-08 05:39:52,847 [salt.minion      :1432][INFO    ][12646] Starting a new job with PID 12646
2019-06-08 05:39:52,879 [salt.minion      :1711][INFO    ][12646] Returning information for job: 20190608053952804547
2019-06-08 05:39:57,774 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (733.818860054s left)
2019-06-08 05:40:22,969 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054022956171
2019-06-08 05:40:22,997 [salt.minion      :1432][INFO    ][12677] Starting a new job with PID 12677
2019-06-08 05:40:23,027 [salt.minion      :1711][INFO    ][12677] Returning information for job: 20190608054022956171
2019-06-08 05:40:30,543 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (701.049969196s left)
2019-06-08 05:40:53,159 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054053141682
2019-06-08 05:40:53,189 [salt.minion      :1432][INFO    ][12730] Starting a new job with PID 12730
2019-06-08 05:40:53,219 [salt.minion      :1711][INFO    ][12730] Returning information for job: 20190608054053141682
2019-06-08 05:41:03,301 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (668.291836023s left)
2019-06-08 05:41:23,350 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054123333242
2019-06-08 05:41:23,379 [salt.minion      :1432][INFO    ][12761] Starting a new job with PID 12761
2019-06-08 05:41:23,412 [salt.minion      :1711][INFO    ][12761] Returning information for job: 20190608054123333242
2019-06-08 05:41:35,975 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (635.618137121s left)
2019-06-08 05:41:53,380 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054153363121
2019-06-08 05:41:53,406 [salt.minion      :1432][INFO    ][12811] Starting a new job with PID 12811
2019-06-08 05:41:53,440 [salt.minion      :1711][INFO    ][12811] Returning information for job: 20190608054153363121
2019-06-08 05:42:08,695 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (602.898130178s left)
2019-06-08 05:42:23,609 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054223595548
2019-06-08 05:42:23,636 [salt.minion      :1432][INFO    ][12845] Starting a new job with PID 12845
2019-06-08 05:42:23,667 [salt.minion      :1711][INFO    ][12845] Returning information for job: 20190608054223595548
2019-06-08 05:42:41,514 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (570.078464031s left)
2019-06-08 05:42:53,632 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054253617517
2019-06-08 05:42:53,663 [salt.minion      :1432][INFO    ][12893] Starting a new job with PID 12893
2019-06-08 05:42:53,692 [salt.minion      :1711][INFO    ][12893] Returning information for job: 20190608054253617517
2019-06-08 05:43:14,455 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (537.138010025s left)
2019-06-08 05:43:23,685 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054323674548
2019-06-08 05:43:23,717 [salt.minion      :1432][INFO    ][12924] Starting a new job with PID 12924
2019-06-08 05:43:23,749 [salt.minion      :1711][INFO    ][12924] Returning information for job: 20190608054323674548
2019-06-08 05:43:47,313 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (504.279921055s left)
2019-06-08 05:43:53,764 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054353748817
2019-06-08 05:43:53,791 [salt.minion      :1432][INFO    ][12969] Starting a new job with PID 12969
2019-06-08 05:43:53,822 [salt.minion      :1711][INFO    ][12969] Returning information for job: 20190608054353748817
2019-06-08 05:44:20,164 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (471.428583145s left)
2019-06-08 05:44:23,901 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054423884453
2019-06-08 05:44:23,930 [salt.minion      :1432][INFO    ][13000] Starting a new job with PID 13000
2019-06-08 05:44:23,960 [salt.minion      :1711][INFO    ][13000] Returning information for job: 20190608054423884453
2019-06-08 05:44:52,740 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (438.853354216s left)
2019-06-08 05:44:54,021 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054454012751
2019-06-08 05:44:54,037 [salt.minion      :1432][INFO    ][13046] Starting a new job with PID 13046
2019-06-08 05:44:54,071 [salt.minion      :1711][INFO    ][13046] Returning information for job: 20190608054454012751
2019-06-08 05:45:24,180 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054524171343
2019-06-08 05:45:24,205 [salt.minion      :1432][INFO    ][13208] Starting a new job with PID 13208
2019-06-08 05:45:24,236 [salt.minion      :1711][INFO    ][13208] Returning information for job: 20190608054524171343
2019-06-08 05:45:25,715 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (405.87767601s left)
2019-06-08 05:45:54,334 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054554316182
2019-06-08 05:45:54,365 [salt.minion      :1432][INFO    ][13267] Starting a new job with PID 13267
2019-06-08 05:45:54,395 [salt.minion      :1711][INFO    ][13267] Returning information for job: 20190608054554316182
2019-06-08 05:45:58,402 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (373.19165206s left)
2019-06-08 05:46:24,467 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054624452229
2019-06-08 05:46:24,493 [salt.minion      :1432][INFO    ][13300] Starting a new job with PID 13300
2019-06-08 05:46:24,527 [salt.minion      :1711][INFO    ][13300] Returning information for job: 20190608054624452229
2019-06-08 05:46:31,147 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (340.445580006s left)
2019-06-08 05:46:54,692 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054654678521
2019-06-08 05:46:54,720 [salt.minion      :1432][INFO    ][13345] Starting a new job with PID 13345
2019-06-08 05:46:54,751 [salt.minion      :1711][INFO    ][13345] Returning information for job: 20190608054654678521
2019-06-08 05:47:03,896 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (307.696999073s left)
2019-06-08 05:47:24,887 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054724877377
2019-06-08 05:47:24,912 [salt.minion      :1432][INFO    ][13373] Starting a new job with PID 13373
2019-06-08 05:47:24,946 [salt.minion      :1711][INFO    ][13373] Returning information for job: 20190608054724877377
2019-06-08 05:47:36,963 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (274.629520178s left)
2019-06-08 05:47:54,968 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054754948928
2019-06-08 05:47:54,996 [salt.minion      :1432][INFO    ][13423] Starting a new job with PID 13423
2019-06-08 05:47:55,032 [salt.minion      :1711][INFO    ][13423] Returning information for job: 20190608054754948928
2019-06-08 05:48:09,753 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (241.840077162s left)
2019-06-08 05:48:25,173 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054825157075
2019-06-08 05:48:25,206 [salt.minion      :1432][INFO    ][13452] Starting a new job with PID 13452
2019-06-08 05:48:25,235 [salt.minion      :1711][INFO    ][13452] Returning information for job: 20190608054825157075
2019-06-08 05:48:42,535 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (209.057956219s left)
2019-06-08 05:48:55,276 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054855257705
2019-06-08 05:48:55,305 [salt.minion      :1432][INFO    ][13499] Starting a new job with PID 13499
2019-06-08 05:48:55,335 [salt.minion      :1711][INFO    ][13499] Returning information for job: 20190608054855257705
2019-06-08 05:49:15,316 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (176.276856184s left)
2019-06-08 05:49:25,374 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054925358178
2019-06-08 05:49:25,405 [salt.minion      :1432][INFO    ][13529] Starting a new job with PID 13529
2019-06-08 05:49:25,436 [salt.minion      :1711][INFO    ][13529] Returning information for job: 20190608054925358178
2019-06-08 05:49:48,208 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (143.384609222s left)
2019-06-08 05:49:55,491 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608054955477114
2019-06-08 05:49:55,512 [salt.minion      :1432][INFO    ][13575] Starting a new job with PID 13575
2019-06-08 05:49:55,545 [salt.minion      :1711][INFO    ][13575] Returning information for job: 20190608054955477114
2019-06-08 05:50:20,938 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (110.655542135s left)
2019-06-08 05:50:25,607 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055025589950
2019-06-08 05:50:25,633 [salt.minion      :1432][INFO    ][13605] Starting a new job with PID 13605
2019-06-08 05:50:25,668 [salt.minion      :1711][INFO    ][13605] Returning information for job: 20190608055025589950
2019-06-08 05:50:53,644 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (77.948734045s left)
2019-06-08 05:50:55,739 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055055725005
2019-06-08 05:50:55,770 [salt.minion      :1432][INFO    ][13654] Starting a new job with PID 13654
2019-06-08 05:50:55,800 [salt.minion      :1711][INFO    ][13654] Returning information for job: 20190608055055725005
2019-06-08 05:51:25,911 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055125902428
2019-06-08 05:51:25,928 [salt.minion      :1432][INFO    ][13684] Starting a new job with PID 13684
2019-06-08 05:51:25,959 [salt.minion      :1711][INFO    ][13684] Returning information for job: 20190608055125902428
2019-06-08 05:51:26,632 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (44.9611361027s left)
2019-06-08 05:51:56,092 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055156075071
2019-06-08 05:51:56,117 [salt.minion      :1432][INFO    ][13726] Starting a new job with PID 13726
2019-06-08 05:51:56,146 [salt.minion      :1711][INFO    ][13726] Returning information for job: 20190608055156075071
2019-06-08 05:51:59,382 [salt.loaded.ext.module.maas:1023][INFO    ][8089] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (12.2110900879s left)
2019-06-08 05:52:26,283 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055226270609
2019-06-08 05:52:26,311 [salt.minion      :1432][INFO    ][13759] Starting a new job with PID 13759
2019-06-08 05:52:26,340 [salt.minion      :1711][INFO    ][13759] Returning information for job: 20190608055226270609
2019-06-08 05:52:32,189 [salt.state       :302 ][ERROR   ][8089] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-06-08 05:52:32,190 [salt.state       :1951][INFO    ][8089] Completed state [maas.wait_for_machine_status] at time 05:52:32.190429 duration_in_ms=2270614.232
2019-06-08 05:52:32,195 [salt.minion      :1711][INFO    ][8089] Returning information for job: 20190608051431185240
2019-06-08 05:52:43,145 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command pillar.get with jid 20190608055243128416
2019-06-08 05:52:43,181 [salt.minion      :1432][INFO    ][13798] Starting a new job with PID 13798
2019-06-08 05:52:43,194 [salt.minion      :1711][INFO    ][13798] Returning information for job: 20190608055243128416
2019-06-08 05:52:43,893 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command service.status with jid 20190608055243878582
2019-06-08 05:52:43,923 [salt.minion      :1432][INFO    ][13803] Starting a new job with PID 13803
2019-06-08 05:52:44,707 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13803] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-08 05:52:44,751 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13803] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-08 05:52:44,771 [salt.minion      :1711][INFO    ][13803] Returning information for job: 20190608055243878582
2019-06-08 05:52:45,480 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command state.apply with jid 20190608055245467183
2019-06-08 05:52:45,509 [salt.minion      :1432][INFO    ][13814] Starting a new job with PID 13814
2019-06-08 05:52:53,535 [salt.state       :915 ][INFO    ][13814] Loading fresh modules for state activity
2019-06-08 05:52:54,189 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13814] Executing command 'salt-minion --version' in directory '/root'
2019-06-08 05:52:54,527 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13814] Executing command 'salt-minion --version' in directory '/root'
2019-06-08 05:52:55,647 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13814] Executing command 'salt-minion --version' in directory '/root'
2019-06-08 05:52:55,988 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13814] Executing command 'salt-minion --version' in directory '/root'
2019-06-08 05:52:58,239 [salt.state       :1780][INFO    ][13814] Running state [salt-minion] at time 05:52:58.239255
2019-06-08 05:52:58,239 [salt.state       :1813][INFO    ][13814] Executing state pkg.installed for [salt-minion]
2019-06-08 05:52:58,240 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13814] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-08 05:52:58,363 [salt.state       :300 ][INFO    ][13814] All specified packages are already installed
2019-06-08 05:52:58,363 [salt.state       :1951][INFO    ][13814] Completed state [salt-minion] at time 05:52:58.363532 duration_in_ms=124.278
2019-06-08 05:52:58,363 [salt.state       :1780][INFO    ][13814] Running state [salt_minion_dependency_packages] at time 05:52:58.363913
2019-06-08 05:52:58,364 [salt.state       :1813][INFO    ][13814] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-08 05:52:58,374 [salt.state       :300 ][INFO    ][13814] All specified packages are already installed
2019-06-08 05:52:58,375 [salt.state       :1951][INFO    ][13814] Completed state [salt_minion_dependency_packages] at time 05:52:58.375082 duration_in_ms=11.169
2019-06-08 05:52:58,378 [salt.state       :1780][INFO    ][13814] Running state [/etc/salt/minion.d/minion.conf] at time 05:52:58.378169
2019-06-08 05:52:58,378 [salt.state       :1813][INFO    ][13814] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-08 05:52:58,654 [salt.state       :300 ][INFO    ][13814] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-08 05:52:58,655 [salt.state       :1951][INFO    ][13814] Completed state [/etc/salt/minion.d/minion.conf] at time 05:52:58.655472 duration_in_ms=277.301
2019-06-08 05:52:58,656 [salt.state       :1780][INFO    ][13814] Running state [python-netaddr] at time 05:52:58.656024
2019-06-08 05:52:58,656 [salt.state       :1813][INFO    ][13814] Executing state pkg.installed for [python-netaddr]
2019-06-08 05:52:58,670 [salt.state       :300 ][INFO    ][13814] All specified packages are already installed
2019-06-08 05:52:58,670 [salt.state       :1951][INFO    ][13814] Completed state [python-netaddr] at time 05:52:58.670793 duration_in_ms=14.769
2019-06-08 05:52:58,674 [salt.state       :1780][INFO    ][13814] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:52:58.674549
2019-06-08 05:52:58,675 [salt.state       :1813][INFO    ][13814] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-08 05:52:58,696 [salt.state       :300 ][INFO    ][13814] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-08 05:52:58,696 [salt.state       :1951][INFO    ][13814] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:52:58.696650 duration_in_ms=22.103
2019-06-08 05:52:58,698 [salt.state       :1780][INFO    ][13814] Running state [salt-minion] at time 05:52:58.698638
2019-06-08 05:52:58,699 [salt.state       :1813][INFO    ][13814] Executing state service.running for [salt-minion]
2019-06-08 05:52:58,700 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13814] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-08 05:52:58,740 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13814] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-08 05:52:58,764 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13814] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-08 05:52:58,787 [salt.state       :300 ][INFO    ][13814] The service salt-minion is already running
2019-06-08 05:52:58,788 [salt.state       :1951][INFO    ][13814] Completed state [salt-minion] at time 05:52:58.788171 duration_in_ms=89.534
2019-06-08 05:52:58,792 [salt.state       :1780][INFO    ][13814] Running state [/etc/salt/grains.d] at time 05:52:58.792101
2019-06-08 05:52:58,792 [salt.state       :1813][INFO    ][13814] Executing state file.directory for [/etc/salt/grains.d]
2019-06-08 05:52:58,794 [salt.state       :300 ][INFO    ][13814] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-08 05:52:58,794 [salt.state       :1951][INFO    ][13814] Completed state [/etc/salt/grains.d] at time 05:52:58.794690 duration_in_ms=2.589
2019-06-08 05:52:58,795 [salt.state       :1780][INFO    ][13814] Running state [/etc/salt/grains] at time 05:52:58.795783
2019-06-08 05:52:58,796 [salt.state       :1813][INFO    ][13814] Executing state file.managed for [/etc/salt/grains]
2019-06-08 05:52:58,797 [salt.state       :300 ][INFO    ][13814] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-08 05:52:58,797 [salt.state       :1951][INFO    ][13814] Completed state [/etc/salt/grains] at time 05:52:58.797479 duration_in_ms=1.697
2019-06-08 05:52:58,798 [salt.state       :1780][INFO    ][13814] Running state [/etc/salt/grains.d/placeholder] at time 05:52:58.798284
2019-06-08 05:52:58,798 [salt.state       :1813][INFO    ][13814] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-08 05:52:58,799 [salt.state       :300 ][INFO    ][13814] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-08 05:52:58,800 [salt.state       :1951][INFO    ][13814] Completed state [/etc/salt/grains.d/placeholder] at time 05:52:58.799950 duration_in_ms=1.666
2019-06-08 05:52:58,800 [salt.state       :1780][INFO    ][13814] Running state [/etc/salt/grains.d/sphinx] at time 05:52:58.800729
2019-06-08 05:52:58,801 [salt.state       :1813][INFO    ][13814] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-08 05:52:58,804 [salt.state       :300 ][INFO    ][13814] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-08 05:52:58,805 [salt.state       :1951][INFO    ][13814] Completed state [/etc/salt/grains.d/sphinx] at time 05:52:58.804945 duration_in_ms=4.216
2019-06-08 05:52:58,807 [salt.state       :1780][INFO    ][13814] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:52:58.807022
2019-06-08 05:52:58,807 [salt.state       :1813][INFO    ][13814] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-08 05:52:58,807 [salt.state       :300 ][INFO    ][13814] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-08 05:52:58,807 [salt.state       :1951][INFO    ][13814] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:52:58.807868 duration_in_ms=0.846
2019-06-08 05:52:58,808 [salt.state       :1780][INFO    ][13814] Running state [/etc/salt/grains.d/dns_records] at time 05:52:58.808389
2019-06-08 05:52:58,808 [salt.state       :1813][INFO    ][13814] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-08 05:52:58,810 [salt.state       :300 ][INFO    ][13814] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-08 05:52:58,810 [salt.state       :1951][INFO    ][13814] Completed state [/etc/salt/grains.d/dns_records] at time 05:52:58.810397 duration_in_ms=2.008
2019-06-08 05:52:58,811 [salt.state       :1780][INFO    ][13814] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:52:58.811353
2019-06-08 05:52:58,811 [salt.state       :1813][INFO    ][13814] 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-08 05:52:58,812 [salt.state       :300 ][INFO    ][13814] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-08 05:52:58,812 [salt.state       :1951][INFO    ][13814] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:52:58.812202 duration_in_ms=0.849
2019-06-08 05:52:58,812 [salt.state       :1780][INFO    ][13814] Running state [/etc/salt/grains.d/salt] at time 05:52:58.812721
2019-06-08 05:52:58,813 [salt.state       :1813][INFO    ][13814] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-08 05:52:58,815 [salt.state       :300 ][INFO    ][13814] File /etc/salt/grains.d/salt is in the correct state
2019-06-08 05:52:58,815 [salt.state       :1951][INFO    ][13814] Completed state [/etc/salt/grains.d/salt] at time 05:52:58.815498 duration_in_ms=2.776
2019-06-08 05:52:58,816 [salt.state       :1780][INFO    ][13814] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:52:58.816407
2019-06-08 05:52:58,816 [salt.state       :1813][INFO    ][13814] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-08 05:52:58,817 [salt.state       :300 ][INFO    ][13814] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-08 05:52:58,817 [salt.state       :1951][INFO    ][13814] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:52:58.817252 duration_in_ms=0.845
2019-06-08 05:52:58,819 [salt.state       :1780][INFO    ][13814] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:52:58.819496
2019-06-08 05:52:58,819 [salt.state       :1813][INFO    ][13814] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-08 05:52:58,820 [salt.state       :300 ][INFO    ][13814] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-08 05:52:58,820 [salt.state       :1951][INFO    ][13814] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:52:58.820347 duration_in_ms=0.851
2019-06-08 05:52:58,821 [salt.state       :1780][INFO    ][13814] Running state [mine.update] at time 05:52:58.821046
2019-06-08 05:52:58,821 [salt.state       :1813][INFO    ][13814] Executing state module.wait for [mine.update]
2019-06-08 05:52:58,821 [salt.state       :300 ][INFO    ][13814] No changes made for mine.update
2019-06-08 05:52:58,821 [salt.state       :1951][INFO    ][13814] Completed state [mine.update] at time 05:52:58.821852 duration_in_ms=0.806
2019-06-08 05:52:58,822 [salt.state       :1780][INFO    ][13814] Running state [ca-certificates] at time 05:52:58.822131
2019-06-08 05:52:58,822 [salt.state       :1813][INFO    ][13814] Executing state pkg.installed for [ca-certificates]
2019-06-08 05:52:58,832 [salt.state       :300 ][INFO    ][13814] All specified packages are already installed
2019-06-08 05:52:58,833 [salt.state       :1951][INFO    ][13814] Completed state [ca-certificates] at time 05:52:58.833003 duration_in_ms=10.872
2019-06-08 05:52:58,833 [salt.state       :1780][INFO    ][13814] Running state [update-ca-certificates] at time 05:52:58.833737
2019-06-08 05:52:58,834 [salt.state       :1813][INFO    ][13814] Executing state cmd.wait for [update-ca-certificates]
2019-06-08 05:52:58,834 [salt.state       :300 ][INFO    ][13814] No changes made for update-ca-certificates
2019-06-08 05:52:58,834 [salt.state       :1951][INFO    ][13814] Completed state [update-ca-certificates] at time 05:52:58.834573 duration_in_ms=0.836
2019-06-08 05:52:58,834 [salt.state       :1780][INFO    ][13814] Running state [iptables] at time 05:52:58.834860
2019-06-08 05:52:58,835 [salt.state       :1813][INFO    ][13814] Executing state pkg.installed for [iptables]
2019-06-08 05:52:58,845 [salt.state       :300 ][INFO    ][13814] All specified packages are already installed
2019-06-08 05:52:58,846 [salt.state       :1951][INFO    ][13814] Completed state [iptables] at time 05:52:58.846166 duration_in_ms=11.306
2019-06-08 05:52:58,846 [salt.state       :1780][INFO    ][13814] Running state [iptables-persistent] at time 05:52:58.846457
2019-06-08 05:52:58,846 [salt.state       :1813][INFO    ][13814] Executing state pkg.installed for [iptables-persistent]
2019-06-08 05:52:58,856 [salt.state       :300 ][INFO    ][13814] All specified packages are already installed
2019-06-08 05:52:58,857 [salt.state       :1951][INFO    ][13814] Completed state [iptables-persistent] at time 05:52:58.857096 duration_in_ms=10.638
2019-06-08 05:52:58,858 [salt.state       :1780][INFO    ][13814] Running state [iptables_modules_v4_load] at time 05:52:58.858160
2019-06-08 05:52:58,858 [salt.state       :1813][INFO    ][13814] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-08 05:52:58,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13814] Executing command 'lsmod' in directory '/root'
2019-06-08 05:52:58,881 [salt.state       :300 ][INFO    ][13814] Kernel modules iptable_filter, ip_tables are already present
2019-06-08 05:52:58,882 [salt.state       :1951][INFO    ][13814] Completed state [iptables_modules_v4_load] at time 05:52:58.882281 duration_in_ms=24.119
2019-06-08 05:52:58,883 [salt.state       :1780][INFO    ][13814] Running state [/etc/iptables/rules.v4] at time 05:52:58.883599
2019-06-08 05:52:58,884 [salt.state       :1813][INFO    ][13814] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-08 05:52:58,985 [salt.state       :300 ][INFO    ][13814] File /etc/iptables/rules.v4 is in the correct state
2019-06-08 05:52:58,985 [salt.state       :1951][INFO    ][13814] Completed state [/etc/iptables/rules.v4] at time 05:52:58.985620 duration_in_ms=102.022
2019-06-08 05:52:58,987 [salt.state       :1780][INFO    ][13814] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:52:58.987091
2019-06-08 05:52:58,987 [salt.state       :1813][INFO    ][13814] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-08 05:52:58,988 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13814] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-08 05:52:59,011 [salt.state       :300 ][INFO    ][13814] onlyif execution failed
2019-06-08 05:52:59,011 [salt.state       :1951][INFO    ][13814] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:52:59.011514 duration_in_ms=24.422
2019-06-08 05:52:59,013 [salt.state       :1780][INFO    ][13814] Running state [netfilter-persistent] at time 05:52:59.013381
2019-06-08 05:52:59,014 [salt.state       :1813][INFO    ][13814] Executing state service.running for [netfilter-persistent]
2019-06-08 05:52:59,015 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13814] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-08 05:52:59,040 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13814] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-08 05:52:59,064 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13814] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-08 05:52:59,088 [salt.state       :300 ][INFO    ][13814] The service netfilter-persistent is already running
2019-06-08 05:52:59,089 [salt.state       :1951][INFO    ][13814] Completed state [netfilter-persistent] at time 05:52:59.089244 duration_in_ms=75.863
2019-06-08 05:52:59,092 [salt.state       :1780][INFO    ][13814] Running state [iptables_extra.remove_stale_tables] at time 05:52:59.092458
2019-06-08 05:52:59,093 [salt.state       :1813][INFO    ][13814] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-08 05:52:59,093 [salt.state       :300 ][INFO    ][13814] No changes made for iptables_extra.remove_stale_tables
2019-06-08 05:52:59,094 [salt.state       :1951][INFO    ][13814] Completed state [iptables_extra.remove_stale_tables] at time 05:52:59.093999 duration_in_ms=1.54
2019-06-08 05:52:59,094 [salt.state       :1780][INFO    ][13814] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:52:59.094476
2019-06-08 05:52:59,094 [salt.state       :1813][INFO    ][13814] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-08 05:52:59,096 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13814] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-08 05:52:59,111 [salt.state       :300 ][INFO    ][13814] onlyif execution failed
2019-06-08 05:52:59,112 [salt.state       :1951][INFO    ][13814] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:52:59.112275 duration_in_ms=17.798
2019-06-08 05:52:59,116 [salt.state       :1780][INFO    ][13814] Running state [/etc/iptables/rules.v6] at time 05:52:59.116225
2019-06-08 05:52:59,116 [salt.state       :1813][INFO    ][13814] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-08 05:52:59,117 [salt.state       :300 ][INFO    ][13814] File /etc/iptables/rules.v6 is not present
2019-06-08 05:52:59,117 [salt.state       :1951][INFO    ][13814] Completed state [/etc/iptables/rules.v6] at time 05:52:59.117829 duration_in_ms=1.604
2019-06-08 05:52:59,119 [salt.state       :1780][INFO    ][13814] Running state [iptables_extra.flush_all] at time 05:52:59.118963
2019-06-08 05:52:59,119 [salt.state       :1813][INFO    ][13814] Executing state module.wait for [iptables_extra.flush_all]
2019-06-08 05:52:59,119 [salt.state       :300 ][INFO    ][13814] No changes made for iptables_extra.flush_all
2019-06-08 05:52:59,120 [salt.state       :1951][INFO    ][13814] Completed state [iptables_extra.flush_all] at time 05:52:59.120237 duration_in_ms=1.275
2019-06-08 05:52:59,124 [salt.minion      :1711][INFO    ][13814] Returning information for job: 20190608055245467183
2019-06-08 05:52:59,831 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command state.apply with jid 20190608055259819212
2019-06-08 05:52:59,855 [salt.minion      :1432][INFO    ][13897] Starting a new job with PID 13897
2019-06-08 05:53:00,982 [salt.state       :915 ][INFO    ][13897] Loading fresh modules for state activity
2019-06-08 05:53:02,373 [salt.state       :1780][INFO    ][13897] Running state [maas-rack-controller] at time 05:53:02.373299
2019-06-08 05:53:02,373 [salt.state       :1813][INFO    ][13897] Executing state pkg.installed for [maas-rack-controller]
2019-06-08 05:53:02,374 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13897] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-08 05:53:02,496 [salt.state       :300 ][INFO    ][13897] All specified packages are already installed
2019-06-08 05:53:02,496 [salt.state       :1951][INFO    ][13897] Completed state [maas-rack-controller] at time 05:53:02.496851 duration_in_ms=123.552
2019-06-08 05:53:02,497 [salt.state       :1780][INFO    ][13897] Running state [ipmitool] at time 05:53:02.497252
2019-06-08 05:53:02,497 [salt.state       :1813][INFO    ][13897] Executing state pkg.installed for [ipmitool]
2019-06-08 05:53:02,508 [salt.state       :300 ][INFO    ][13897] All specified packages are already installed
2019-06-08 05:53:02,508 [salt.state       :1951][INFO    ][13897] Completed state [ipmitool] at time 05:53:02.508351 duration_in_ms=11.099
2019-06-08 05:53:02,511 [salt.state       :1780][INFO    ][13897] Running state [/etc/maas/rackd.conf] at time 05:53:02.511865
2019-06-08 05:53:02,512 [salt.state       :1813][INFO    ][13897] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-08 05:53:02,513 [salt.state       :300 ][INFO    ][13897] No changes needed to be made
2019-06-08 05:53:02,513 [salt.state       :1951][INFO    ][13897] Completed state [/etc/maas/rackd.conf] at time 05:53:02.513543 duration_in_ms=1.678
2019-06-08 05:53:02,514 [salt.state       :1780][INFO    ][13897] Running state [/etc/maas/rackd.conf] at time 05:53:02.514706
2019-06-08 05:53:02,515 [salt.state       :1813][INFO    ][13897] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-08 05:53:02,515 [salt.loaded.int.states.file:2298][WARNING ][13897] 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-08 05:53:02,516 [salt.state       :300 ][INFO    ][13897] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-08 05:53:02,516 [salt.state       :1951][INFO    ][13897] Completed state [/etc/maas/rackd.conf] at time 05:53:02.516224 duration_in_ms=1.518
2019-06-08 05:53:02,517 [salt.state       :1780][INFO    ][13897] Running state [maas-rackd] at time 05:53:02.517263
2019-06-08 05:53:02,517 [salt.state       :1813][INFO    ][13897] Executing state service.running for [maas-rackd]
2019-06-08 05:53:02,518 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13897] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-08 05:53:02,564 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13897] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-08 05:53:02,587 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13897] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-08 05:53:02,613 [salt.state       :300 ][INFO    ][13897] The service maas-rackd is already running
2019-06-08 05:53:02,613 [salt.state       :1951][INFO    ][13897] Completed state [maas-rackd] at time 05:53:02.613542 duration_in_ms=96.279
2019-06-08 05:53:02,615 [salt.minion      :1711][INFO    ][13897] Returning information for job: 20190608055259819212
2019-06-08 05:53:03,330 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command state.apply with jid 20190608055303311115
2019-06-08 05:53:03,359 [salt.minion      :1432][INFO    ][13923] Starting a new job with PID 13923
2019-06-08 05:53:04,556 [salt.state       :915 ][INFO    ][13923] Loading fresh modules for state activity
2019-06-08 05:53:05,975 [salt.state       :1780][INFO    ][13923] Running state [maas-region-controller] at time 05:53:05.975358
2019-06-08 05:53:05,975 [salt.state       :1813][INFO    ][13923] Executing state pkg.installed for [maas-region-controller]
2019-06-08 05:53:05,976 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13923] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-08 05:53:06,099 [salt.state       :300 ][INFO    ][13923] All specified packages are already installed
2019-06-08 05:53:06,099 [salt.state       :1951][INFO    ][13923] Completed state [maas-region-controller] at time 05:53:06.099824 duration_in_ms=124.466
2019-06-08 05:53:06,100 [salt.state       :1780][INFO    ][13923] Running state [python-oauth] at time 05:53:06.100204
2019-06-08 05:53:06,100 [salt.state       :1813][INFO    ][13923] Executing state pkg.installed for [python-oauth]
2019-06-08 05:53:06,110 [salt.state       :300 ][INFO    ][13923] All specified packages are already installed
2019-06-08 05:53:06,111 [salt.state       :1951][INFO    ][13923] Completed state [python-oauth] at time 05:53:06.110974 duration_in_ms=10.77
2019-06-08 05:53:06,114 [salt.state       :1780][INFO    ][13923] Running state [/etc/maas/regiond.conf] at time 05:53:06.114349
2019-06-08 05:53:06,114 [salt.state       :1813][INFO    ][13923] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-08 05:53:06,119 [salt.state       :300 ][INFO    ][13923] No changes needed to be made
2019-06-08 05:53:06,120 [salt.state       :1951][INFO    ][13923] Completed state [/etc/maas/regiond.conf] at time 05:53:06.119920 duration_in_ms=5.571
2019-06-08 05:53:06,120 [salt.state       :1780][INFO    ][13923] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:53:06.120456
2019-06-08 05:53:06,120 [salt.state       :1813][INFO    ][13923] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-08 05:53:06,188 [salt.state       :300 ][INFO    ][13923] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-08 05:53:06,188 [salt.state       :1951][INFO    ][13923] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:53:06.188474 duration_in_ms=68.018
2019-06-08 05:53:06,189 [salt.state       :1780][INFO    ][13923] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:53:06.189362
2019-06-08 05:53:06,190 [salt.state       :1813][INFO    ][13923] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-08 05:53:06,199 [salt.state       :300 ][INFO    ][13923] No changes needed to be made
2019-06-08 05:53:06,199 [salt.state       :1951][INFO    ][13923] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:53:06.199681 duration_in_ms=10.32
2019-06-08 05:53:06,200 [salt.state       :1780][INFO    ][13923] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:53:06.200305
2019-06-08 05:53:06,200 [salt.state       :1813][INFO    ][13923] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-08 05:53:06,208 [salt.state       :300 ][INFO    ][13923] No changes needed to be made
2019-06-08 05:53:06,208 [salt.state       :1951][INFO    ][13923] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:53:06.208343 duration_in_ms=8.039
2019-06-08 05:53:06,209 [salt.state       :1780][INFO    ][13923] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:53:06.208946
2019-06-08 05:53:06,209 [salt.state       :1813][INFO    ][13923] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-08 05:53:06,253 [salt.state       :300 ][INFO    ][13923] No changes needed to be made
2019-06-08 05:53:06,255 [salt.state       :1951][INFO    ][13923] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:53:06.255844 duration_in_ms=46.899
2019-06-08 05:53:06,256 [salt.state       :1780][INFO    ][13923] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:53:06.256422
2019-06-08 05:53:06,256 [salt.state       :1813][INFO    ][13923] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-08 05:53:06,274 [salt.state       :300 ][INFO    ][13923] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-08 05:53:06,274 [salt.state       :1951][INFO    ][13923] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:53:06.274368 duration_in_ms=17.946
2019-06-08 05:53:06,275 [salt.state       :1780][INFO    ][13923] Running state [a2enmod headers] at time 05:53:06.275715
2019-06-08 05:53:06,276 [salt.state       :1813][INFO    ][13923] Executing state cmd.run for [a2enmod headers]
2019-06-08 05:53:06,276 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13923] Executing command 'a2enmod headers' in directory '/root'
2019-06-08 05:53:06,347 [salt.state       :300 ][INFO    ][13923] {'pid': 13942, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-08 05:53:06,348 [salt.state       :1951][INFO    ][13923] Completed state [a2enmod headers] at time 05:53:06.348268 duration_in_ms=72.552
2019-06-08 05:53:06,349 [salt.state       :1780][INFO    ][13923] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:53:06.348989
2019-06-08 05:53:06,351 [salt.state       :1813][INFO    ][13923] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-08 05:53:06,379 [salt.state       :300 ][INFO    ][13923] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-08 05:53:06,379 [salt.state       :1951][INFO    ][13923] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:53:06.379679 duration_in_ms=30.689
2019-06-08 05:53:06,380 [salt.state       :1780][INFO    ][13923] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:53:06.380539
2019-06-08 05:53:06,380 [salt.state       :1813][INFO    ][13923] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-08 05:53:06,446 [salt.state       :300 ][INFO    ][13923] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-08 05:53:06,447 [salt.state       :1951][INFO    ][13923] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:53:06.447158 duration_in_ms=66.619
2019-06-08 05:53:06,447 [salt.state       :1780][INFO    ][13923] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:53:06.447816
2019-06-08 05:53:06,448 [salt.state       :1813][INFO    ][13923] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-08 05:53:06,505 [salt.state       :300 ][INFO    ][13923] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-08 05:53:06,505 [salt.state       :1951][INFO    ][13923] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:53:06.505476 duration_in_ms=57.66
2019-06-08 05:53:06,506 [salt.state       :1780][INFO    ][13923] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:53:06.506865
2019-06-08 05:53:06,507 [salt.state       :1813][INFO    ][13923] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-08 05:53:06,584 [salt.state       :300 ][INFO    ][13923] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-08 05:53:06,584 [salt.state       :1951][INFO    ][13923] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:53:06.584276 duration_in_ms=77.41
2019-06-08 05:53:06,584 [salt.state       :1780][INFO    ][13923] Running state [/root/.pgpass] at time 05:53:06.584595
2019-06-08 05:53:06,584 [salt.state       :1813][INFO    ][13923] Executing state file.managed for [/root/.pgpass]
2019-06-08 05:53:06,635 [salt.state       :300 ][INFO    ][13923] File /root/.pgpass is in the correct state
2019-06-08 05:53:06,636 [salt.state       :1951][INFO    ][13923] Completed state [/root/.pgpass] at time 05:53:06.636165 duration_in_ms=51.57
2019-06-08 05:53:06,642 [salt.state       :1780][INFO    ][13923] Running state [maas-region syncdb --noinput] at time 05:53:06.642229
2019-06-08 05:53:06,642 [salt.state       :1813][INFO    ][13923] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-08 05:53:06,643 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13923] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-08 05:53:09,402 [salt.state       :300 ][INFO    ][13923] {'pid': 13956, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: maasserver, auth, sessions, piston3, sites, metadataserver, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-08 05:53:09,403 [salt.state       :1951][INFO    ][13923] Completed state [maas-region syncdb --noinput] at time 05:53:09.403050 duration_in_ms=2760.82
2019-06-08 05:53:09,403 [salt.state       :2022][WARNING ][13923] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-08 05:53:09,405 [salt.state       :1780][INFO    ][13923] Running state [maas-regiond] at time 05:53:09.405136
2019-06-08 05:53:09,405 [salt.state       :1813][INFO    ][13923] Executing state service.running for [maas-regiond]
2019-06-08 05:53:09,407 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13923] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-08 05:53:09,456 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13923] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-08 05:53:09,479 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13923] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-08 05:53:09,503 [salt.state       :300 ][INFO    ][13923] The service maas-regiond is already running
2019-06-08 05:53:09,503 [salt.state       :1951][INFO    ][13923] Completed state [maas-regiond] at time 05:53:09.503744 duration_in_ms=98.607
2019-06-08 05:53:09,508 [salt.state       :1780][INFO    ][13923] Running state [bind9] at time 05:53:09.508042
2019-06-08 05:53:09,508 [salt.state       :1813][INFO    ][13923] Executing state service.running for [bind9]
2019-06-08 05:53:09,509 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13923] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-08 05:53:09,533 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13923] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-08 05:53:09,556 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13923] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-08 05:53:09,577 [salt.state       :300 ][INFO    ][13923] The service bind9 is already running
2019-06-08 05:53:09,579 [salt.state       :1951][INFO    ][13923] Completed state [bind9] at time 05:53:09.579784 duration_in_ms=71.741
2019-06-08 05:53:09,583 [salt.state       :1780][INFO    ][13923] Running state [apache2] at time 05:53:09.583184
2019-06-08 05:53:09,583 [salt.state       :1813][INFO    ][13923] Executing state service.running for [apache2]
2019-06-08 05:53:09,585 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13923] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-08 05:53:09,611 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13923] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-08 05:53:09,635 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13923] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-08 05:53:09,670 [salt.state       :300 ][INFO    ][13923] The service apache2 is already running
2019-06-08 05:53:09,671 [salt.state       :1951][INFO    ][13923] Completed state [apache2] at time 05:53:09.671191 duration_in_ms=88.004
2019-06-08 05:53:09,678 [salt.state       :1780][INFO    ][13923] Running state [maasng.wait_for_http_code] at time 05:53:09.678490
2019-06-08 05:53:09,679 [salt.state       :1813][INFO    ][13923] Executing state module.run for [maasng.wait_for_http_code]
2019-06-08 05:53:09,680 [salt.utils.decorators:613 ][WARNING ][13923] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:53:09,835 [salt.state       :300 ][INFO    ][13923] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-08 05:53:09,835 [salt.state       :1951][INFO    ][13923] Completed state [maasng.wait_for_http_code] at time 05:53:09.835438 duration_in_ms=156.949
2019-06-08 05:53:09,836 [salt.state       :1780][INFO    ][13923] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:53:09.836522
2019-06-08 05:53:09,836 [salt.state       :1813][INFO    ][13923] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-08 05:53:09,837 [salt.state       :300 ][INFO    ][13923] /var/lib/maas/.setup_admin exists
2019-06-08 05:53:09,838 [salt.state       :1951][INFO    ][13923] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:53:09.838338 duration_in_ms=1.816
2019-06-08 05:53:09,839 [salt.state       :1780][INFO    ][13923] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:09.839152
2019-06-08 05:53:09,839 [salt.state       :1813][INFO    ][13923] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-08 05:53:09,840 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13923] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-08 05:53:11,784 [salt.state       :300 ][INFO    ][13923] {'pid': 13977, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-08 05:53:11,785 [salt.state       :1951][INFO    ][13923] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:11.785409 duration_in_ms=1946.257
2019-06-08 05:53:11,796 [salt.state       :1780][INFO    ][13923] Running state [maas_region_boot_source_resources_mirror] at time 05:53:11.796278
2019-06-08 05:53:11,796 [salt.state       :1813][INFO    ][13923] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-08 05:53:11,900 [salt.state       :300 ][INFO    ][13923] {'changes': {}}
2019-06-08 05:53:11,901 [salt.state       :1951][INFO    ][13923] Completed state [maas_region_boot_source_resources_mirror] at time 05:53:11.900980 duration_in_ms=104.703
2019-06-08 05:53:11,902 [salt.state       :1780][INFO    ][13923] Running state [maasng.boot_resources_import] at time 05:53:11.902385
2019-06-08 05:53:11,902 [salt.state       :1813][INFO    ][13923] Executing state module.run for [maasng.boot_resources_import]
2019-06-08 05:53:11,903 [salt.utils.decorators:613 ][WARNING ][13923] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:53:12,001 [salt.loaded.ext.module.maasng:1600][INFO    ][13923] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-08 05:53:17,059 [salt.loaded.ext.module.maasng:1600][INFO    ][13923] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-08 05:53:18,405 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055318387686
2019-06-08 05:53:18,432 [salt.minion      :1432][INFO    ][14019] Starting a new job with PID 14019
2019-06-08 05:53:18,476 [salt.minion      :1711][INFO    ][14019] Returning information for job: 20190608055318387686
2019-06-08 05:53:22,163 [salt.state       :300 ][INFO    ][13923] {'ret': True}
2019-06-08 05:53:22,164 [salt.state       :1951][INFO    ][13923] Completed state [maasng.boot_resources_import] at time 05:53:22.164110 duration_in_ms=10261.723
2019-06-08 05:53:22,167 [salt.state       :1780][INFO    ][13923] Running state [maas_region_boot_sources_selection_xenial] at time 05:53:22.165640
2019-06-08 05:53:22,168 [salt.state       :1813][INFO    ][13923] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-08 05:53:22,350 [salt.state       :300 ][INFO    ][13923] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-08 05:53:22,351 [salt.state       :1951][INFO    ][13923] Completed state [maas_region_boot_sources_selection_xenial] at time 05:53:22.351222 duration_in_ms=185.582
2019-06-08 05:53:22,353 [salt.state       :1780][INFO    ][13923] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:53:22.352728
2019-06-08 05:53:22,353 [salt.state       :1813][INFO    ][13923] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-08 05:53:22,354 [salt.utils.decorators:613 ][WARNING ][13923] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:53:22,355 [salt.loaded.ext.module.maasng:1771][INFO    ][13923] boot-sources sync initiated for ALL Rack's
2019-06-08 05:53:23,443 [salt.state       :300 ][INFO    ][13923] {'ret': True}
2019-06-08 05:53:23,443 [salt.state       :1951][INFO    ][13923] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:53:23.443796 duration_in_ms=1091.066
2019-06-08 05:53:23,447 [salt.state       :1780][INFO    ][13923] Running state [maas.process_maas_config] at time 05:53:23.447251
2019-06-08 05:53:23,447 [salt.state       :1813][INFO    ][13923] Executing state module.run for [maas.process_maas_config]
2019-06-08 05:53:23,448 [salt.utils.decorators:613 ][WARNING ][13923] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:53:23,449 [salt.loaded.ext.module.maas:92  ][INFO    ][13923] maasconfig name=enable_http_proxy value=True
2019-06-08 05:53:23,503 [salt.loaded.ext.module.maas:92  ][INFO    ][13923] maasconfig name=upstream_dns value=8.8.8.8
2019-06-08 05:53:23,553 [salt.loaded.ext.module.maas:92  ][INFO    ][13923] maasconfig name=commissioning_distro_series value=xenial
2019-06-08 05:53:23,611 [salt.loaded.ext.module.maas:92  ][INFO    ][13923] maasconfig name=default_osystem value=ubuntu
2019-06-08 05:53:25,032 [salt.loaded.ext.module.maas:92  ][INFO    ][13923] maasconfig name=active_discovery_interval value=600
2019-06-08 05:53:25,083 [salt.loaded.ext.module.maas:92  ][INFO    ][13923] maasconfig name=dnssec_validation value=no
2019-06-08 05:53:25,139 [salt.loaded.ext.module.maas:92  ][INFO    ][13923] maasconfig name=maas_name value=mas01
2019-06-08 05:53:25,211 [salt.loaded.ext.module.maas:92  ][INFO    ][13923] maasconfig name=network_discovery value=enabled
2019-06-08 05:53:25,309 [salt.loaded.ext.module.maas:92  ][INFO    ][13923] maasconfig name=enable_third_party_drivers value=True
2019-06-08 05:53:25,388 [salt.loaded.ext.module.maas:92  ][INFO    ][13923] maasconfig name=default_storage_layout value=lvm
2019-06-08 05:53:25,444 [salt.loaded.ext.module.maas:92  ][INFO    ][13923] maasconfig name=ntp_external_only value=True
2019-06-08 05:53:25,498 [salt.loaded.ext.module.maas:92  ][INFO    ][13923] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-08 05:53:25,555 [salt.loaded.ext.module.maas:92  ][INFO    ][13923] maasconfig name=default_distro_series value=xenial
2019-06-08 05:53:25,616 [salt.loaded.ext.module.maas:92  ][INFO    ][13923] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-08 05:53:25,735 [salt.state       :300 ][INFO    ][13923] {'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-08 05:53:25,736 [salt.state       :1951][INFO    ][13923] Completed state [maas.process_maas_config] at time 05:53:25.736099 duration_in_ms=2288.848
2019-06-08 05:53:25,737 [salt.state       :1780][INFO    ][13923] Running state [pxe_admin] at time 05:53:25.737123
2019-06-08 05:53:25,738 [salt.state       :1813][INFO    ][13923] Executing state maasng.fabric_present for [pxe_admin]
2019-06-08 05:53:25,798 [salt.loaded.ext.module.maasng:945 ][INFO    ][13923] [{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'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-06-08 05:53:25,855 [salt.loaded.ext.module.maasng:1008][WARNING ][13923] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-08 05:53:25,855 [salt.loaded.ext.module.maasng:1011][WARNING ][13923] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-08 05:53:25,927 [salt.state       :300 ][INFO    ][13923] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-08 05:53:25,928 [salt.state       :1951][INFO    ][13923] Completed state [pxe_admin] at time 05:53:25.928133 duration_in_ms=191.009
2019-06-08 05:53:25,928 [salt.state       :1780][INFO    ][13923] Running state [vlan 0] at time 05:53:25.928589
2019-06-08 05:53:25,929 [salt.state       :1813][INFO    ][13923] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-08 05:53:25,999 [salt.loaded.ext.module.maasng:945 ][INFO    ][13923] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': u'qphg6g', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-06-08 05:53:26,142 [salt.loaded.ext.module.maasng:945 ][INFO    ][13923] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'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'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-06-08 05:53:26,442 [salt.loaded.ext.module.maasng:945 ][INFO    ][13923] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-08 05:53:26,542 [salt.state       :300 ][INFO    ][13923] {'new': 'Vlan untagged was updated'}
2019-06-08 05:53:26,543 [salt.state       :1951][INFO    ][13923] Completed state [vlan 0] at time 05:53:26.542877 duration_in_ms=614.287
2019-06-08 05:53:26,544 [salt.state       :1780][INFO    ][13923] Running state [192.168.11.0/24] at time 05:53:26.544509
2019-06-08 05:53:26,545 [salt.state       :1813][INFO    ][13923] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-08 05:53:26,716 [salt.loaded.ext.module.maasng:945 ][INFO    ][13923] [{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'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-06-08 05:53:26,717 [salt.loaded.ext.module.maasng:1235][WARNING ][13923] Ignoring parameter vlan:0
2019-06-08 05:53:26,788 [salt.state       :300 ][INFO    ][13923] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-08 05:53:26,788 [salt.state       :1951][INFO    ][13923] Completed state [192.168.11.0/24] at time 05:53:26.788605 duration_in_ms=244.096
2019-06-08 05:53:26,790 [salt.state       :1780][INFO    ][13923] Running state [maas_create_iprange_1] at time 05:53:26.789648
2019-06-08 05:53:26,790 [salt.state       :1813][INFO    ][13923] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-08 05:53:26,836 [salt.state       :300 ][INFO    ][13923] Iprange maas_create_iprange_1 already exist.
2019-06-08 05:53:26,837 [salt.state       :1951][INFO    ][13923] Completed state [maas_create_iprange_1] at time 05:53:26.837151 duration_in_ms=47.503
2019-06-08 05:53:26,837 [salt.state       :1780][INFO    ][13923] Running state [vlan 0] at time 05:53:26.837446
2019-06-08 05:53:26,838 [salt.state       :1813][INFO    ][13923] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-08 05:53:26,888 [salt.loaded.ext.module.maasng:945 ][INFO    ][13923] [{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'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-06-08 05:53:26,982 [salt.loaded.ext.module.maasng:945 ][INFO    ][13923] [{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'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-06-08 05:53:27,208 [salt.loaded.ext.module.maasng:945 ][INFO    ][13923] [{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'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qphg6g', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-06-08 05:53:27,292 [salt.state       :300 ][INFO    ][13923] {'new': 'Vlan untagged was updated'}
2019-06-08 05:53:27,293 [salt.state       :1951][INFO    ][13923] Completed state [vlan 0] at time 05:53:27.293291 duration_in_ms=455.844
2019-06-08 05:53:27,294 [salt.state       :1780][INFO    ][13923] Running state [opnfv] at time 05:53:27.294361
2019-06-08 05:53:27,295 [salt.state       :1813][INFO    ][13923] Executing state maasng.sshkey_present for [opnfv]
2019-06-08 05:53:27,344 [salt.loaded.ext.module.maasng:1903][INFO    ][13923] [{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-08 05:53:27,344 [salt.state       :300 ][INFO    ][13923] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-08 05:53:27,345 [salt.state       :1951][INFO    ][13923] Completed state [opnfv] at time 05:53:27.345163 duration_in_ms=50.802
2019-06-08 05:53:27,346 [salt.state       :1780][INFO    ][13923] Running state [maas.process_tags] at time 05:53:27.346468
2019-06-08 05:53:27,346 [salt.state       :1813][INFO    ][13923] Executing state module.run for [maas.process_tags]
2019-06-08 05:53:27,347 [salt.utils.decorators:613 ][WARNING ][13923] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:53:27,393 [salt.loaded.ext.module.maas:92  ][INFO    ][13923] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-06-08 05:53:27,473 [salt.state       :300 ][INFO    ][13923] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-06-08 05:53:27,474 [salt.state       :1951][INFO    ][13923] Completed state [maas.process_tags] at time 05:53:27.474231 duration_in_ms=127.762
2019-06-08 05:53:27,477 [salt.minion      :1711][INFO    ][13923] Returning information for job: 20190608055303311115
2019-06-08 05:53:28,231 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command state.apply with jid 20190608055328222641
2019-06-08 05:53:28,254 [salt.minion      :1432][INFO    ][14377] Starting a new job with PID 14377
2019-06-08 05:53:36,270 [salt.state       :915 ][INFO    ][14377] Loading fresh modules for state activity
2019-06-08 05:53:36,400 [salt.state       :1780][INFO    ][14377] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:36.400705
2019-06-08 05:53:36,401 [salt.state       :1813][INFO    ][14377] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-08 05:53:36,404 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14377] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-08 05:53:38,364 [salt.state       :300 ][INFO    ][14377] {'pid': 14412, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-08 05:53:38,365 [salt.state       :1951][INFO    ][14377] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:38.365517 duration_in_ms=1964.812
2019-06-08 05:53:38,367 [salt.state       :1780][INFO    ][14377] Running state [maas.process_machines] at time 05:53:38.367884
2019-06-08 05:53:38,368 [salt.state       :1813][INFO    ][14377] Executing state module.run for [maas.process_machines]
2019-06-08 05:53:38,368 [salt.utils.decorators:613 ][WARNING ][14377] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:53:38,889 [salt.loaded.ext.module.maas:412 ][WARNING ][14377] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-08 05:53:38,890 [salt.loaded.ext.module.maas:92  ][INFO    ][14377] 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=ma6xym architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-08 05:53:39,994 [salt.loaded.ext.module.maas:412 ][WARNING ][14377] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-08 05:53:39,995 [salt.loaded.ext.module.maas:92  ][INFO    ][14377] 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=fpt7by architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-08 05:53:41,295 [salt.loaded.ext.module.maas:412 ][WARNING ][14377] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-08 05:53:41,295 [salt.loaded.ext.module.maas:92  ][INFO    ][14377] 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=fsytrm architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-08 05:53:42,408 [salt.loaded.ext.module.maas:412 ][WARNING ][14377] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-08 05:53:42,409 [salt.loaded.ext.module.maas:92  ][INFO    ][14377] 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=tky38m architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-08 05:53:43,256 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055343244586
2019-06-08 05:53:43,280 [salt.minion      :1432][INFO    ][14608] Starting a new job with PID 14608
2019-06-08 05:53:43,315 [salt.minion      :1711][INFO    ][14608] Returning information for job: 20190608055343244586
2019-06-08 05:53:43,536 [salt.loaded.ext.module.maas:412 ][WARNING ][14377] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-08 05:53:43,538 [salt.loaded.ext.module.maas:92  ][INFO    ][14377] 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=kyxxd7 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-08 05:53:44,700 [salt.state       :300 ][INFO    ][14377] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-08 05:53:44,701 [salt.state       :1951][INFO    ][14377] Completed state [maas.process_machines] at time 05:53:44.701361 duration_in_ms=6333.475
2019-06-08 05:53:44,707 [salt.minion      :1711][INFO    ][14377] Returning information for job: 20190608055328222641
2019-06-08 05:54:18,752 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command state.apply with jid 20190608055418737468
2019-06-08 05:54:18,780 [salt.minion      :1432][INFO    ][14682] Starting a new job with PID 14682
2019-06-08 05:54:26,755 [salt.state       :915 ][INFO    ][14682] Loading fresh modules for state activity
2019-06-08 05:54:26,876 [salt.state       :1780][INFO    ][14682] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:54:26.876645
2019-06-08 05:54:26,877 [salt.state       :1813][INFO    ][14682] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-08 05:54:26,879 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14682] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-08 05:54:28,729 [salt.state       :300 ][INFO    ][14682] {'pid': 14690, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-08 05:54:28,730 [salt.state       :1951][INFO    ][14682] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:54:28.730734 duration_in_ms=1854.09
2019-06-08 05:54:28,734 [salt.state       :1780][INFO    ][14682] Running state [maas.wait_for_machine_status] at time 05:54:28.734115
2019-06-08 05:54:28,734 [salt.state       :1813][INFO    ][14682] Executing state module.run for [maas.wait_for_machine_status]
2019-06-08 05:54:28,735 [salt.utils.decorators:613 ][WARNING ][14682] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:54:31,624 [salt.state       :300 ][INFO    ][14682] {'ret': True}
2019-06-08 05:54:31,625 [salt.state       :1951][INFO    ][14682] Completed state [maas.wait_for_machine_status] at time 05:54:31.625000 duration_in_ms=2890.885
2019-06-08 05:54:31,627 [salt.minion      :1711][INFO    ][14682] Returning information for job: 20190608055418737468
2019-06-08 05:54:32,339 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command state.apply with jid 20190608055432326538
2019-06-08 05:54:32,369 [salt.minion      :1432][INFO    ][14703] Starting a new job with PID 14703
2019-06-08 05:54:33,474 [salt.state       :915 ][INFO    ][14703] Loading fresh modules for state activity
2019-06-08 05:54:33,626 [salt.state       :1780][INFO    ][14703] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:54:33.626081
2019-06-08 05:54:33,626 [salt.state       :1813][INFO    ][14703] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-08 05:54:33,628 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14703] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-08 05:54:35,504 [salt.state       :300 ][INFO    ][14703] {'pid': 14711, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-08 05:54:35,505 [salt.state       :1951][INFO    ][14703] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:54:35.505238 duration_in_ms=1879.156
2019-06-08 05:54:35,509 [salt.state       :1780][INFO    ][14703] Running state [maas_machines_storage_cmp002_lvm] at time 05:54:35.509045
2019-06-08 05:54:35,509 [salt.state       :1813][INFO    ][14703] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-08 05:54:36,066 [salt.state       :300 ][INFO    ][14703] Machine cmp002 is not in Ready state.
2019-06-08 05:54:36,067 [salt.state       :1951][INFO    ][14703] Completed state [maas_machines_storage_cmp002_lvm] at time 05:54:36.067557 duration_in_ms=558.51
2019-06-08 05:54:36,068 [salt.state       :1780][INFO    ][14703] Running state [maas_machines_storage_cmp001_lvm] at time 05:54:36.068373
2019-06-08 05:54:36,069 [salt.state       :1813][INFO    ][14703] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-08 05:54:36,812 [salt.state       :300 ][INFO    ][14703] Machine cmp001 is not in Ready state.
2019-06-08 05:54:36,813 [salt.state       :1951][INFO    ][14703] Completed state [maas_machines_storage_cmp001_lvm] at time 05:54:36.813310 duration_in_ms=744.937
2019-06-08 05:54:36,817 [salt.minion      :1711][INFO    ][14703] Returning information for job: 20190608055432326538
2019-06-08 05:54:37,508 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command state.apply with jid 20190608055437496200
2019-06-08 05:54:37,534 [salt.minion      :1432][INFO    ][14745] Starting a new job with PID 14745
2019-06-08 05:54:38,693 [salt.state       :915 ][INFO    ][14745] Loading fresh modules for state activity
2019-06-08 05:54:38,795 [salt.state       :1780][INFO    ][14745] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:54:38.795864
2019-06-08 05:54:38,796 [salt.state       :1813][INFO    ][14745] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-08 05:54:38,799 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14745] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-08 05:54:40,672 [salt.state       :300 ][INFO    ][14745] {'pid': 14756, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-08 05:54:40,673 [salt.state       :1951][INFO    ][14745] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:54:40.673334 duration_in_ms=1877.47
2019-06-08 05:54:40,677 [salt.state       :1780][INFO    ][14745] Running state [maas.deploy_machines] at time 05:54:40.677240
2019-06-08 05:54:40,678 [salt.state       :1813][INFO    ][14745] Executing state module.run for [maas.deploy_machines]
2019-06-08 05:54:40,679 [salt.utils.decorators:613 ][WARNING ][14745] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:54:41,277 [salt.loaded.ext.module.maas:684 ][INFO    ][14745] deploymachines hwe_kernel=hwe-16.04 system_id=kyxxd7 distro_series=xenial
2019-06-08 05:54:43,709 [salt.state       :300 ][INFO    ][14745] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-06-08 05:54:43,709 [salt.state       :1951][INFO    ][14745] Completed state [maas.deploy_machines] at time 05:54:43.709470 duration_in_ms=3032.23
2019-06-08 05:54:43,712 [salt.minion      :1711][INFO    ][14745] Returning information for job: 20190608055437496200
2019-06-08 05:54:44,408 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command state.apply with jid 20190608055444397542
2019-06-08 05:54:44,433 [salt.minion      :1432][INFO    ][14817] Starting a new job with PID 14817
2019-06-08 05:54:52,504 [salt.state       :915 ][INFO    ][14817] Loading fresh modules for state activity
2019-06-08 05:54:52,627 [salt.state       :1780][INFO    ][14817] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:54:52.627658
2019-06-08 05:54:52,628 [salt.state       :1813][INFO    ][14817] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-08 05:54:52,632 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14817] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-08 05:54:54,574 [salt.state       :300 ][INFO    ][14817] {'pid': 14835, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-08 05:54:54,575 [salt.state       :1951][INFO    ][14817] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:54:54.575723 duration_in_ms=1948.066
2019-06-08 05:54:54,580 [salt.state       :1780][INFO    ][14817] Running state [maas.wait_for_machine_status] at time 05:54:54.579906
2019-06-08 05:54:54,580 [salt.state       :1813][INFO    ][14817] Executing state module.run for [maas.wait_for_machine_status]
2019-06-08 05:54:54,581 [salt.utils.decorators:613 ][WARNING ][14817] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-08 05:54:57,340 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.25524998s left)
2019-06-08 05:54:59,539 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055459468902
2019-06-08 05:54:59,565 [salt.minion      :1432][INFO    ][14849] Starting a new job with PID 14849
2019-06-08 05:54:59,595 [salt.minion      :1711][INFO    ][14849] Returning information for job: 20190608055459468902
2019-06-08 05:55:29,623 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055529610204
2019-06-08 05:55:29,641 [salt.minion      :1432][INFO    ][14889] Starting a new job with PID 14889
2019-06-08 05:55:29,675 [salt.minion      :1711][INFO    ][14889] Returning information for job: 20190608055529610204
2019-06-08 05:55:30,044 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.55096388s left)
2019-06-08 05:55:59,715 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055559700549
2019-06-08 05:55:59,748 [salt.minion      :1432][INFO    ][14949] Starting a new job with PID 14949
2019-06-08 05:55:59,776 [salt.minion      :1711][INFO    ][14949] Returning information for job: 20190608055559700549
2019-06-08 05:56:02,896 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.69956398s left)
2019-06-08 05:56:29,815 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055629799482
2019-06-08 05:56:29,849 [salt.minion      :1432][INFO    ][14978] Starting a new job with PID 14978
2019-06-08 05:56:29,880 [salt.minion      :1711][INFO    ][14978] Returning information for job: 20190608055629799482
2019-06-08 05:56:35,971 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.6243999s left)
2019-06-08 05:56:59,908 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055659892494
2019-06-08 05:56:59,934 [salt.minion      :1432][INFO    ][15032] Starting a new job with PID 15032
2019-06-08 05:56:59,967 [salt.minion      :1711][INFO    ][15032] Returning information for job: 20190608055659892494
2019-06-08 05:57:08,708 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.88682699s left)
2019-06-08 05:57:30,000 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055729983297
2019-06-08 05:57:30,025 [salt.minion      :1432][INFO    ][15061] Starting a new job with PID 15061
2019-06-08 05:57:30,062 [salt.minion      :1711][INFO    ][15061] Returning information for job: 20190608055729983297
2019-06-08 05:57:41,656 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.93951297s left)
2019-06-08 05:58:00,112 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055800095230
2019-06-08 05:58:00,139 [salt.minion      :1432][INFO    ][15112] Starting a new job with PID 15112
2019-06-08 05:58:00,171 [salt.minion      :1711][INFO    ][15112] Returning information for job: 20190608055800095230
2019-06-08 05:58:14,381 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.2139709s left)
2019-06-08 05:58:30,186 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055830170830
2019-06-08 05:58:30,212 [salt.minion      :1432][INFO    ][15145] Starting a new job with PID 15145
2019-06-08 05:58:30,244 [salt.minion      :1711][INFO    ][15145] Returning information for job: 20190608055830170830
2019-06-08 05:58:47,314 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.28126597s left)
2019-06-08 05:59:00,274 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055900265948
2019-06-08 05:59:00,296 [salt.minion      :1432][INFO    ][15218] Starting a new job with PID 15218
2019-06-08 05:59:00,328 [salt.minion      :1711][INFO    ][15218] Returning information for job: 20190608055900265948
2019-06-08 05:59:20,326 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.26921487s left)
2019-06-08 05:59:30,357 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608055930346889
2019-06-08 05:59:30,387 [salt.minion      :1432][INFO    ][15276] Starting a new job with PID 15276
2019-06-08 05:59:30,417 [salt.minion      :1711][INFO    ][15276] Returning information for job: 20190608055930346889
2019-06-08 05:59:53,231 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.36379004s left)
2019-06-08 06:00:00,486 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608060000470186
2019-06-08 06:00:00,516 [salt.minion      :1432][INFO    ][15402] Starting a new job with PID 15402
2019-06-08 06:00:00,548 [salt.minion      :1711][INFO    ][15402] Returning information for job: 20190608060000470186
2019-06-08 06:00:26,024 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.57137108s left)
2019-06-08 06:00:30,604 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608060030588677
2019-06-08 06:00:30,631 [salt.minion      :1432][INFO    ][15431] Starting a new job with PID 15431
2019-06-08 06:00:30,663 [salt.minion      :1711][INFO    ][15431] Returning information for job: 20190608060030588677
2019-06-08 06:00:58,967 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.6281991s left)
2019-06-08 06:01:00,750 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608060100739517
2019-06-08 06:01:00,775 [salt.minion      :1432][INFO    ][15573] Starting a new job with PID 15573
2019-06-08 06:01:00,802 [salt.minion      :1711][INFO    ][15573] Returning information for job: 20190608060100739517
2019-06-08 06:01:30,858 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608060130843074
2019-06-08 06:01:30,882 [salt.minion      :1432][INFO    ][15602] Starting a new job with PID 15602
2019-06-08 06:01:30,915 [salt.minion      :1711][INFO    ][15602] Returning information for job: 20190608060130843074
2019-06-08 06:01:31,965 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.63010907s left)
2019-06-08 06:02:01,009 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608060200994506
2019-06-08 06:02:01,035 [salt.minion      :1432][INFO    ][15669] Starting a new job with PID 15669
2019-06-08 06:02:01,067 [salt.minion      :1711][INFO    ][15669] Returning information for job: 20190608060200994506
2019-06-08 06:02:04,968 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.62748098s left)
2019-06-08 06:02:31,171 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608060231156444
2019-06-08 06:02:31,201 [salt.minion      :1432][INFO    ][15700] Starting a new job with PID 15700
2019-06-08 06:02:31,240 [salt.minion      :1711][INFO    ][15700] Returning information for job: 20190608060231156444
2019-06-08 06:02:38,832 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1785.76330209s left)
2019-06-08 06:03:01,366 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608060301348817
2019-06-08 06:03:01,396 [salt.minion      :1432][INFO    ][15876] Starting a new job with PID 15876
2019-06-08 06:03:01,426 [salt.minion      :1711][INFO    ][15876] Returning information for job: 20190608060301348817
2019-06-08 06:03:11,675 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1752.92015195s left)
2019-06-08 06:03:31,540 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608060331532381
2019-06-08 06:03:31,566 [salt.minion      :1432][INFO    ][15905] Starting a new job with PID 15905
2019-06-08 06:03:31,596 [salt.minion      :1711][INFO    ][15905] Returning information for job: 20190608060331532381
2019-06-08 06:03:44,538 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.05800796s left)
2019-06-08 06:04:01,699 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608060401685351
2019-06-08 06:04:01,728 [salt.minion      :1432][INFO    ][15951] Starting a new job with PID 15951
2019-06-08 06:04:01,763 [salt.minion      :1711][INFO    ][15951] Returning information for job: 20190608060401685351
2019-06-08 06:04:17,543 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.05196404s left)
2019-06-08 06:04:31,880 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608060431866493
2019-06-08 06:04:31,908 [salt.minion      :1432][INFO    ][15980] Starting a new job with PID 15980
2019-06-08 06:04:31,944 [salt.minion      :1711][INFO    ][15980] Returning information for job: 20190608060431866493
2019-06-08 06:04:50,754 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1653.84106898s left)
2019-06-08 06:05:02,072 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608060502055934
2019-06-08 06:05:02,104 [salt.minion      :1432][INFO    ][16028] Starting a new job with PID 16028
2019-06-08 06:05:02,133 [salt.minion      :1711][INFO    ][16028] Returning information for job: 20190608060502055934
2019-06-08 06:05:23,788 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1620.80769801s left)
2019-06-08 06:05:32,267 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608060532251061
2019-06-08 06:05:32,297 [salt.minion      :1432][INFO    ][16068] Starting a new job with PID 16068
2019-06-08 06:05:32,327 [salt.minion      :1711][INFO    ][16068] Returning information for job: 20190608060532251061
2019-06-08 06:05:56,678 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1587.91701007s left)
2019-06-08 06:06:02,455 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608060602442980
2019-06-08 06:06:02,484 [salt.minion      :1432][INFO    ][16272] Starting a new job with PID 16272
2019-06-08 06:06:02,516 [salt.minion      :1711][INFO    ][16272] Returning information for job: 20190608060602442980
2019-06-08 06:06:29,477 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.1186049s left)
2019-06-08 06:06:31,806 [salt.utils.schedule:1377][INFO    ][5546] Running scheduled job: __mine_interval
2019-06-08 06:06:32,637 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608060632626526
2019-06-08 06:06:32,663 [salt.minion      :1432][INFO    ][16309] Starting a new job with PID 16309
2019-06-08 06:06:32,691 [salt.minion      :1711][INFO    ][16309] Returning information for job: 20190608060632626526
2019-06-08 06:07:02,461 [salt.loaded.ext.module.maas:1023][INFO    ][14817] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1522.13455009s left)
2019-06-08 06:07:02,823 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608060702810855
2019-06-08 06:07:02,848 [salt.minion      :1432][INFO    ][16382] Starting a new job with PID 16382
2019-06-08 06:07:02,876 [salt.minion      :1711][INFO    ][16382] Returning information for job: 20190608060702810855
2019-06-08 06:07:32,962 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command saltutil.find_job with jid 20190608060732949182
2019-06-08 06:07:32,988 [salt.minion      :1432][INFO    ][16424] Starting a new job with PID 16424
2019-06-08 06:07:33,024 [salt.minion      :1711][INFO    ][16424] Returning information for job: 20190608060732949182
2019-06-08 06:07:35,639 [salt.state       :300 ][INFO    ][14817] {'ret': True}
2019-06-08 06:07:35,640 [salt.state       :1951][INFO    ][14817] Completed state [maas.wait_for_machine_status] at time 06:07:35.639938 duration_in_ms=761060.027
2019-06-08 06:07:35,647 [salt.minion      :1711][INFO    ][14817] Returning information for job: 20190608055444397542
2019-06-08 07:06:31,807 [salt.utils.schedule:1377][INFO    ][5546] Running scheduled job: __mine_interval
2019-06-08 07:42:35,798 [salt.minion      :1308][INFO    ][5546] User sudo_ubuntu Executing command cp.push_dir with jid 20190608074235789826
2019-06-08 07:42:35,827 [salt.minion      :1432][INFO    ][23714] Starting a new job with PID 23714
