2019-04-17 08:17:21,021 [salt.minion      :870 ][ERROR   ][378] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-17 08:18:11,073 [salt.minion      :870 ][ERROR   ][378] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-17 08:19:01,126 [salt.minion      :870 ][ERROR   ][378] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-17 08:19:51,177 [salt.minion      :870 ][ERROR   ][378] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-17 08:20:41,228 [salt.minion      :870 ][ERROR   ][378] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-17 08:22:55,881 [salt.utils.decorators:613 ][WARNING ][2655] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 08:22:56,743 [salt.utils.decorators:613 ][WARNING ][2655] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 08:22:59,495 [salt.loaded.int.states.file:2298][WARNING ][2789] 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-04-17 08:23:11,899 [salt.state       :2022][WARNING ][2905] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-17 08:23:14,877 [salt.utils.decorators:613 ][WARNING ][2905] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 08:24:42,733 [salt.utils.decorators:613 ][WARNING ][2905] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 08:26:45,614 [salt.utils.decorators:613 ][WARNING ][2905] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 08:26:46,478 [salt.utils.decorators:613 ][WARNING ][2905] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 08:26:49,376 [salt.loaded.ext.module.maasng:1008][WARNING ][2905] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-04-17 08:26:49,376 [salt.loaded.ext.module.maasng:1011][WARNING ][2905] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-04-17 08:26:50,018 [salt.loaded.ext.module.maasng:1235][WARNING ][2905] Ignoring parameter vlan:0
2019-04-17 08:26:50,700 [salt.utils.decorators:613 ][WARNING ][2905] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 08:26:59,898 [salt.utils.decorators:613 ][WARNING ][8999] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 08:26:59,947 [salt.loaded.ext.module.maas:412 ][WARNING ][8999] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-17 08:27:01,233 [salt.loaded.ext.module.maas:412 ][WARNING ][8999] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-17 08:27:02,785 [salt.loaded.ext.module.maas:412 ][WARNING ][8999] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-17 08:27:03,885 [salt.loaded.ext.module.maas:412 ][WARNING ][8999] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-17 08:27:05,054 [salt.loaded.ext.module.maas:412 ][WARNING ][8999] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-17 08:27:09,468 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9595] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-17 08:27:09,500 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9595] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-17 08:27:09,557 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-04-17 08:27:10,646 [salt.cli.daemons :293 ][INFO    ][9657] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-04-17 08:27:10,769 [salt.cli.daemons :82  ][INFO    ][9657] Starting up the Salt Minion
2019-04-17 08:27:10,770 [salt.utils.event :1017][INFO    ][9657] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-04-17 08:27:11,996 [salt.minion      :976 ][INFO    ][9657] Creating minion process manager
2019-04-17 08:27:13,883 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][9657] Executing command ['date', '+%z'] in directory '/root'
2019-04-17 08:27:13,907 [salt.utils.schedule:568 ][INFO    ][9657] Updating job settings for scheduled job: __mine_interval
2019-04-17 08:27:13,910 [salt.minion      :1108][INFO    ][9657] Added mine.update to scheduler
2019-04-17 08:27:13,916 [salt.minion      :1975][INFO    ][9657] Minion is starting as user 'root'
2019-04-17 08:27:13,930 [salt.minion      :2336][INFO    ][9657] Minion is ready to receive requests!
2019-04-17 08:27:47,341 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command state.apply with jid 20190417082747332463
2019-04-17 08:27:47,360 [salt.minion      :1432][INFO    ][9784] Starting a new job with PID 9784
2019-04-17 08:27:53,305 [salt.state       :915 ][INFO    ][9784] Loading fresh modules for state activity
2019-04-17 08:27:53,353 [salt.fileclient  :1219][INFO    ][9784] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-17 08:27:53,395 [salt.state       :1780][INFO    ][9784] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:27:53.395681
2019-04-17 08:27:53,396 [salt.state       :1813][INFO    ][9784] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-17 08:27:53,397 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9784] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-17 08:27:55,144 [salt.state       :300 ][INFO    ][9784] {'pid': 9791, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-17 08:27:55,145 [salt.state       :1951][INFO    ][9784] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:27:55.144983 duration_in_ms=1749.302
2019-04-17 08:27:55,148 [salt.state       :1780][INFO    ][9784] Running state [maas.wait_for_machine_status] at time 08:27:55.148908
2019-04-17 08:27:55,149 [salt.state       :1813][INFO    ][9784] Executing state module.run for [maas.wait_for_machine_status]
2019-04-17 08:27:55,150 [salt.utils.decorators:613 ][WARNING ][9784] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 08:27:55,953 [salt.loaded.ext.module.maas:1023][INFO    ][9784] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.20648193s left)
2019-04-17 08:28:02,454 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417082802401252
2019-04-17 08:28:02,470 [salt.minion      :1432][INFO    ][9831] Starting a new job with PID 9831
2019-04-17 08:28:02,491 [salt.minion      :1711][INFO    ][9831] Returning information for job: 20190417082802401252
2019-04-17 08:28:26,776 [salt.loaded.ext.module.maas:1023][INFO    ][9784] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.38379502s left)
2019-04-17 08:28:32,514 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417082832499856
2019-04-17 08:28:32,530 [salt.minion      :1432][INFO    ][9875] Starting a new job with PID 9875
2019-04-17 08:28:32,552 [salt.minion      :1711][INFO    ][9875] Returning information for job: 20190417082832499856
2019-04-17 08:28:57,417 [salt.loaded.ext.module.maas:1023][INFO    ][9784] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.74256706s left)
2019-04-17 08:29:02,585 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417082902572230
2019-04-17 08:29:02,602 [salt.minion      :1432][INFO    ][9933] Starting a new job with PID 9933
2019-04-17 08:29:02,622 [salt.minion      :1711][INFO    ][9933] Returning information for job: 20190417082902572230
2019-04-17 08:29:28,283 [salt.loaded.ext.module.maas:1023][INFO    ][9784] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.87635589s left)
2019-04-17 08:29:32,636 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417082932628472
2019-04-17 08:29:32,650 [salt.minion      :1432][INFO    ][10002] Starting a new job with PID 10002
2019-04-17 08:29:32,669 [salt.minion      :1711][INFO    ][10002] Returning information for job: 20190417082932628472
2019-04-17 08:29:59,267 [salt.loaded.ext.module.maas:1023][INFO    ][9784] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.89270806s left)
2019-04-17 08:30:02,663 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083002653031
2019-04-17 08:30:02,676 [salt.minion      :1432][INFO    ][10116] Starting a new job with PID 10116
2019-04-17 08:30:02,699 [salt.minion      :1711][INFO    ][10116] Returning information for job: 20190417083002653031
2019-04-17 08:30:30,333 [salt.loaded.ext.module.maas:1023][INFO    ][9784] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.82684088s left)
2019-04-17 08:30:32,750 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083032736135
2019-04-17 08:30:32,770 [salt.minion      :1432][INFO    ][10270] Starting a new job with PID 10270
2019-04-17 08:30:32,791 [salt.minion      :1711][INFO    ][10270] Returning information for job: 20190417083032736135
2019-04-17 08:31:01,581 [salt.loaded.ext.module.maas:1023][INFO    ][9784] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.57822204s left)
2019-04-17 08:31:02,832 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083102818423
2019-04-17 08:31:02,852 [salt.minion      :1432][INFO    ][10445] Starting a new job with PID 10445
2019-04-17 08:31:02,874 [salt.minion      :1711][INFO    ][10445] Returning information for job: 20190417083102818423
2019-04-17 08:31:32,888 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083132875440
2019-04-17 08:31:32,903 [salt.minion      :1432][INFO    ][10666] Starting a new job with PID 10666
2019-04-17 08:31:32,925 [salt.minion      :1711][INFO    ][10666] Returning information for job: 20190417083132875440
2019-04-17 08:31:33,030 [salt.loaded.ext.module.maas:1023][INFO    ][9784] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.13011503s left)
2019-04-17 08:32:02,985 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083202974935
2019-04-17 08:32:03,001 [salt.minion      :1432][INFO    ][10858] Starting a new job with PID 10858
2019-04-17 08:32:03,023 [salt.minion      :1711][INFO    ][10858] Returning information for job: 20190417083202974935
2019-04-17 08:32:05,048 [salt.loaded.ext.module.maas:1023][INFO    ][9784] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.11202192s left)
2019-04-17 08:32:33,085 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083233073453
2019-04-17 08:32:33,104 [salt.minion      :1432][INFO    ][11198] Starting a new job with PID 11198
2019-04-17 08:32:33,125 [salt.minion      :1711][INFO    ][11198] Returning information for job: 20190417083233073453
2019-04-17 08:32:37,272 [salt.loaded.ext.module.maas:1023][INFO    ][9784] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.88800788s left)
2019-04-17 08:33:03,179 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083303167996
2019-04-17 08:33:03,200 [salt.minion      :1432][INFO    ][11368] Starting a new job with PID 11368
2019-04-17 08:33:03,225 [salt.minion      :1711][INFO    ][11368] Returning information for job: 20190417083303167996
2019-04-17 08:33:09,428 [salt.loaded.ext.module.maas:1023][INFO    ][9784] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.73195195s left)
2019-04-17 08:33:33,264 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083333248672
2019-04-17 08:33:33,284 [salt.minion      :1432][INFO    ][11537] Starting a new job with PID 11537
2019-04-17 08:33:33,303 [salt.minion      :1711][INFO    ][11537] Returning information for job: 20190417083333248672
2019-04-17 08:33:42,213 [salt.loaded.ext.module.maas:1023][INFO    ][9784] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1152.94672608s left)
2019-04-17 08:34:03,397 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083403384165
2019-04-17 08:34:03,417 [salt.minion      :1432][INFO    ][11698] Starting a new job with PID 11698
2019-04-17 08:34:03,438 [salt.minion      :1711][INFO    ][11698] Returning information for job: 20190417083403384165
2019-04-17 08:34:15,197 [salt.state       :300 ][INFO    ][9784] {'ret': True}
2019-04-17 08:34:15,198 [salt.state       :1951][INFO    ][9784] Completed state [maas.wait_for_machine_status] at time 08:34:15.198232 duration_in_ms=380049.324
2019-04-17 08:34:15,200 [salt.minion      :1711][INFO    ][9784] Returning information for job: 20190417082747332463
2019-04-17 08:34:15,804 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command state.apply with jid 20190417083415793764
2019-04-17 08:34:15,825 [salt.minion      :1432][INFO    ][11776] Starting a new job with PID 11776
2019-04-17 08:34:21,706 [salt.state       :915 ][INFO    ][11776] Loading fresh modules for state activity
2019-04-17 08:34:21,755 [salt.fileclient  :1219][INFO    ][11776] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-17 08:34:21,850 [salt.state       :1780][INFO    ][11776] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:34:21.850788
2019-04-17 08:34:21,851 [salt.state       :1813][INFO    ][11776] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-17 08:34:21,852 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11776] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-17 08:34:23,646 [salt.state       :300 ][INFO    ][11776] {'pid': 11784, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-17 08:34:23,647 [salt.state       :1951][INFO    ][11776] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:34:23.647772 duration_in_ms=1796.984
2019-04-17 08:34:23,649 [salt.state       :1780][INFO    ][11776] Running state [maas_machines_storage_cmp002_lvm] at time 08:34:23.649950
2019-04-17 08:34:23,650 [salt.state       :1813][INFO    ][11776] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-17 08:34:24,604 [salt.loaded.ext.module.maasng:610 ][INFO    ][11776] x3aptm
2019-04-17 08:34:24,604 [salt.loaded.ext.module.maasng:626 ][INFO    ][11776] sda
2019-04-17 08:34:25,088 [salt.loaded.ext.module.maasng:361 ][INFO    ][11776] x3aptm
2019-04-17 08:34:25,168 [salt.loaded.ext.module.maasng:367 ][INFO    ][11776] [{u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'x3aptm', u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/blockdevices/1/', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'9402f2a4-4591-4ec9-b36e-581a522213d1', u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'x3aptm', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'55b60bcf-c3ad-463e-9f43-c9632194c612', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}]}, {u'model': None, u'partition_table_type': None, u'block_size': 4096, u'uuid': u'5ab06292-18c4-46d9-8dae-44d8c5c93ea3', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'367ad10f-61cd-447c-8f84-7135707b1631', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'x3aptm', u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/blockdevices/3/', u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 800097042432, u'used_size': 800097042432, u'id': 3, u'partitions': []}]
2019-04-17 08:34:25,169 [salt.loaded.ext.module.maasng:632 ][INFO    ][11776] vgroot
2019-04-17 08:34:25,169 [salt.loaded.ext.module.maasng:635 ][INFO    ][11776] lvroot
2019-04-17 08:34:25,169 [salt.loaded.ext.module.maasng:639 ][INFO    ][11776] 107374182400
2019-04-17 08:34:25,645 [salt.loaded.ext.module.maasng:645 ][INFO    ][11776] {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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pqrf3k', u'mtu': 1500, 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': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pqrf3k', u'mtu': 1500, 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'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pqrf3k', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'x3aptm', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/interfaces/4/'}, u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/x3aptm/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'x3aptm', 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'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'x3aptm', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'6ecad89c-582c-4ac2-a1bc-c02f847a8c2e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'x3aptm', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'9f7e57f3-b061-4c88-a873-66a5aae942ce', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/blockdevices/1/partition/6'}], u'type': u'physical', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/blockdevices/1/'}], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'x3aptm', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/blockdevices/1/', u'type': u'physical', u'id': 1, u'partitions': [{u'size': 800101236736, u'uuid': u'6ecad89c-582c-4ac2-a1bc-c02f847a8c2e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'x3aptm', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'9f7e57f3-b061-4c88-a873-66a5aae942ce', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/blockdevices/1/partition/6'}]}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'b7776eee-f1a5-4506-9d14-cd3426e8976b', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'x3aptm', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'ad90016e-a8cc-449e-b549-74ef928f7e11', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/blockdevices/11/', u'type': u'virtual', u'id': 11, u'partitions': []}], u'status': 4, u'bcaches': [], 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'x3aptm', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'x3aptm', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'6ecad89c-582c-4ac2-a1bc-c02f847a8c2e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'x3aptm', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'9f7e57f3-b061-4c88-a873-66a5aae942ce', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/blockdevices/1/partition/6'}], u'type': u'physical', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/blockdevices/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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pqrf3k', u'mtu': 1500, 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': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pqrf3k', u'mtu': 1500, 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'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pqrf3k', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'x3aptm', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/interfaces/4/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pqrf3k', u'mtu': 1500, 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': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pqrf3k', u'mtu': 1500, 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'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pqrf3k', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'x3aptm', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/interfaces/13/'}, {u'name': u'ens2f1', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'x3aptm', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/interfaces/14/'}, {u'name': u'ens2f0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'x3aptm', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/interfaces/15/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'x3aptm', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/interfaces/11/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'x3aptm', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/interfaces/12/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, 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'model': None, u'block_size': 4096, u'uuid': u'b7776eee-f1a5-4506-9d14-cd3426e8976b', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'ad90016e-a8cc-449e-b549-74ef928f7e11', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'x3aptm', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/x3aptm/blockdevices/11/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-04-17 08:34:25,646 [salt.state       :300 ][INFO    ][11776] {'new': {'storage_layout': 'lvm'}}
2019-04-17 08:34:25,648 [salt.state       :1951][INFO    ][11776] Completed state [maas_machines_storage_cmp002_lvm] at time 08:34:25.648595 duration_in_ms=1998.644
2019-04-17 08:34:25,648 [salt.state       :1780][INFO    ][11776] Running state [maas_machines_storage_cmp001_lvm] at time 08:34:25.648937
2019-04-17 08:34:25,649 [salt.state       :1813][INFO    ][11776] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-17 08:34:26,594 [salt.loaded.ext.module.maasng:610 ][INFO    ][11776] 8ydwhy
2019-04-17 08:34:26,595 [salt.loaded.ext.module.maasng:626 ][INFO    ][11776] sda
2019-04-17 08:34:27,082 [salt.loaded.ext.module.maasng:361 ][INFO    ][11776] 8ydwhy
2019-04-17 08:34:27,176 [salt.loaded.ext.module.maasng:367 ][INFO    ][11776] [{u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'8ydwhy', u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/blockdevices/2/', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'partitions': [{u'uuid': u'2c48ad2b-f59a-49c5-a6e2-e160cbc35c63', u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8ydwhy', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'b4ded995-99b4-4793-9ba4-5b8fca914b97', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}]}, {u'model': None, u'partition_table_type': None, u'block_size': 4096, u'uuid': u'547dd583-2f9d-42dc-82b5-796b2c4693cb', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'91f21495-5437-4bf5-8f8b-70149576adaa', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8ydwhy', u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/blockdevices/4/', u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 800097042432, u'used_size': 800097042432, u'id': 4, u'partitions': []}]
2019-04-17 08:34:27,177 [salt.loaded.ext.module.maasng:632 ][INFO    ][11776] vgroot
2019-04-17 08:34:27,177 [salt.loaded.ext.module.maasng:635 ][INFO    ][11776] lvroot
2019-04-17 08:34:27,177 [salt.loaded.ext.module.maasng:639 ][INFO    ][11776] 107374182400
2019-04-17 08:34:27,765 [salt.loaded.ext.module.maasng:645 ][INFO    ][11776] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.42'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/8ydwhy/', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'8ydwhy', 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'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'75d30b7f-b0ea-4f84-b0a2-0d2a8a9743b6', u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8ydwhy', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'3b89739f-5687-4efb-a390-dfef77983d0e', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'8ydwhy', u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/blockdevices/2/', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'name': u'sda'}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'8ydwhy', u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/blockdevices/2/', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'partitions': [{u'uuid': u'75d30b7f-b0ea-4f84-b0a2-0d2a8a9743b6', u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8ydwhy', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'3b89739f-5687-4efb-a390-dfef77983d0e', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}, {u'model': None, u'partition_table_type': None, u'block_size': 4096, u'uuid': u'29b47d7e-92a8-4db8-91e1-e07e78e688ff', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'2f3be6bd-bb2d-4fdc-a425-920cfb9dbbc9', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8ydwhy', u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/blockdevices/12/', u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 12, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'8ydwhy', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'75d30b7f-b0ea-4f84-b0a2-0d2a8a9743b6', u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8ydwhy', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'3b89739f-5687-4efb-a390-dfef77983d0e', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'8ydwhy', u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/blockdevices/2/', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'name': u'sda'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'partition_table_type': None, u'uuid': u'29b47d7e-92a8-4db8-91e1-e07e78e688ff', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'2f3be6bd-bb2d-4fdc-a425-920cfb9dbbc9', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8ydwhy', u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/blockdevices/12/', u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'name': u'vgroot-lvroot'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'pqrf3k', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'pqrf3k', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'pqrf3k', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'8ydwhy', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/interfaces/5/'}, u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'pqrf3k', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'pqrf3k', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'pqrf3k', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'8ydwhy', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d5', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'8ydwhy', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/interfaces/16/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d4', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'8ydwhy', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/interfaces/20/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'pqrf3k', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'pqrf3k', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'pqrf3k', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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.42'}], u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'8ydwhy', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/interfaces/17/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'ens2f0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'8ydwhy', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/interfaces/18/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'ens2f1', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'8ydwhy', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/8ydwhy/interfaces/19/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-04-17 08:34:27,768 [salt.state       :300 ][INFO    ][11776] {'new': {'storage_layout': 'lvm'}}
2019-04-17 08:34:27,768 [salt.state       :1951][INFO    ][11776] Completed state [maas_machines_storage_cmp001_lvm] at time 08:34:27.768476 duration_in_ms=2119.537
2019-04-17 08:34:27,772 [salt.minion      :1711][INFO    ][11776] Returning information for job: 20190417083415793764
2019-04-17 08:34:28,319 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command state.apply with jid 20190417083428307683
2019-04-17 08:34:28,339 [salt.minion      :1432][INFO    ][11804] Starting a new job with PID 11804
2019-04-17 08:34:29,289 [salt.state       :915 ][INFO    ][11804] Loading fresh modules for state activity
2019-04-17 08:34:29,341 [salt.fileclient  :1219][INFO    ][11804] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-17 08:34:29,389 [salt.state       :1780][INFO    ][11804] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:34:29.389293
2019-04-17 08:34:29,389 [salt.state       :1813][INFO    ][11804] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-17 08:34:29,391 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11804] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-17 08:34:31,168 [salt.state       :300 ][INFO    ][11804] {'pid': 11811, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-17 08:34:31,169 [salt.state       :1951][INFO    ][11804] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:34:31.169780 duration_in_ms=1780.486
2019-04-17 08:34:31,173 [salt.state       :1780][INFO    ][11804] Running state [maas.deploy_machines] at time 08:34:31.173287
2019-04-17 08:34:31,173 [salt.state       :1813][INFO    ][11804] Executing state module.run for [maas.deploy_machines]
2019-04-17 08:34:31,175 [salt.utils.decorators:613 ][WARNING ][11804] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 08:34:31,763 [salt.loaded.ext.module.maas:684 ][INFO    ][11804] deploymachines hwe_kernel=hwe-16.04 system_id=x3aptm distro_series=xenial
2019-04-17 08:34:34,112 [salt.loaded.ext.module.maas:684 ][INFO    ][11804] deploymachines hwe_kernel=hwe-16.04 system_id=8ydwhy distro_series=xenial
2019-04-17 08:34:36,476 [salt.loaded.ext.module.maas:684 ][INFO    ][11804] deploymachines hwe_kernel=hwe-16.04 system_id=qh8cnp distro_series=xenial
2019-04-17 08:34:38,805 [salt.loaded.ext.module.maas:684 ][INFO    ][11804] deploymachines hwe_kernel=hwe-16.04 system_id=bk4xg8 distro_series=xenial
2019-04-17 08:34:41,174 [salt.loaded.ext.module.maas:684 ][INFO    ][11804] deploymachines hwe_kernel=hwe-16.04 system_id=s7mt44 distro_series=xenial
2019-04-17 08:34:43,447 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083443433185
2019-04-17 08:34:43,464 [salt.minion      :1432][INFO    ][12080] Starting a new job with PID 12080
2019-04-17 08:34:43,488 [salt.minion      :1711][INFO    ][12080] Returning information for job: 20190417083443433185
2019-04-17 08:34:43,536 [salt.state       :300 ][INFO    ][11804] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-17 08:34:43,536 [salt.state       :1951][INFO    ][11804] Completed state [maas.deploy_machines] at time 08:34:43.536740 duration_in_ms=12363.453
2019-04-17 08:34:43,542 [salt.minion      :1711][INFO    ][11804] Returning information for job: 20190417083428307683
2019-04-17 08:34:44,109 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command state.apply with jid 20190417083444090794
2019-04-17 08:34:44,133 [salt.minion      :1432][INFO    ][12102] Starting a new job with PID 12102
2019-04-17 08:34:50,001 [salt.state       :915 ][INFO    ][12102] Loading fresh modules for state activity
2019-04-17 08:34:50,057 [salt.fileclient  :1219][INFO    ][12102] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-17 08:34:50,102 [salt.state       :1780][INFO    ][12102] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:34:50.102565
2019-04-17 08:34:50,103 [salt.state       :1813][INFO    ][12102] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-17 08:34:50,105 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12102] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-17 08:34:51,887 [salt.state       :300 ][INFO    ][12102] {'pid': 12121, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-17 08:34:51,888 [salt.state       :1951][INFO    ][12102] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:34:51.888665 duration_in_ms=1786.1
2019-04-17 08:34:51,892 [salt.state       :1780][INFO    ][12102] Running state [maas.wait_for_machine_status] at time 08:34:51.892318
2019-04-17 08:34:51,892 [salt.state       :1813][INFO    ][12102] Executing state module.run for [maas.wait_for_machine_status]
2019-04-17 08:34:51,893 [salt.utils.decorators:613 ][WARNING ][12102] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 08:34:54,786 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.11378288s left)
2019-04-17 08:34:59,167 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083459154576
2019-04-17 08:34:59,193 [salt.minion      :1432][INFO    ][12133] Starting a new job with PID 12133
2019-04-17 08:34:59,214 [salt.minion      :1711][INFO    ][12133] Returning information for job: 20190417083459154576
2019-04-17 08:35:27,712 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.18763399s left)
2019-04-17 08:35:29,220 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083529213201
2019-04-17 08:35:29,232 [salt.minion      :1432][INFO    ][12198] Starting a new job with PID 12198
2019-04-17 08:35:29,251 [salt.minion      :1711][INFO    ][12198] Returning information for job: 20190417083529213201
2019-04-17 08:35:59,261 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083559252615
2019-04-17 08:35:59,277 [salt.minion      :1432][INFO    ][12231] Starting a new job with PID 12231
2019-04-17 08:35:59,301 [salt.minion      :1711][INFO    ][12231] Returning information for job: 20190417083559252615
2019-04-17 08:36:00,446 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.45338392s left)
2019-04-17 08:36:29,322 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083629308077
2019-04-17 08:36:29,340 [salt.minion      :1432][INFO    ][12303] Starting a new job with PID 12303
2019-04-17 08:36:29,363 [salt.minion      :1711][INFO    ][12303] Returning information for job: 20190417083629308077
2019-04-17 08:36:33,230 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.66952896s left)
2019-04-17 08:36:59,372 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083659358386
2019-04-17 08:36:59,394 [salt.minion      :1432][INFO    ][12340] Starting a new job with PID 12340
2019-04-17 08:36:59,416 [salt.minion      :1711][INFO    ][12340] Returning information for job: 20190417083659358386
2019-04-17 08:37:06,031 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.86856699s left)
2019-04-17 08:37:29,429 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083729414371
2019-04-17 08:37:29,448 [salt.minion      :1432][INFO    ][12423] Starting a new job with PID 12423
2019-04-17 08:37:29,468 [salt.minion      :1711][INFO    ][12423] Returning information for job: 20190417083729414371
2019-04-17 08:37:38,622 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.27731705s left)
2019-04-17 08:37:59,485 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083759469365
2019-04-17 08:37:59,505 [salt.minion      :1432][INFO    ][12525] Starting a new job with PID 12525
2019-04-17 08:37:59,526 [salt.minion      :1711][INFO    ][12525] Returning information for job: 20190417083759469365
2019-04-17 08:38:11,365 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.53413606s left)
2019-04-17 08:38:29,597 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083829585648
2019-04-17 08:38:29,616 [salt.minion      :1432][INFO    ][12778] Starting a new job with PID 12778
2019-04-17 08:38:29,636 [salt.minion      :1711][INFO    ][12778] Returning information for job: 20190417083829585648
2019-04-17 08:38:44,030 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.86986899s left)
2019-04-17 08:38:59,634 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083859625497
2019-04-17 08:38:59,648 [salt.minion      :1432][INFO    ][12910] Starting a new job with PID 12910
2019-04-17 08:38:59,666 [salt.minion      :1711][INFO    ][12910] Returning information for job: 20190417083859625497
2019-04-17 08:39:17,325 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1984.57479405s left)
2019-04-17 08:39:29,726 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083929713277
2019-04-17 08:39:29,744 [salt.minion      :1432][INFO    ][13112] Starting a new job with PID 13112
2019-04-17 08:39:29,764 [salt.minion      :1711][INFO    ][13112] Returning information for job: 20190417083929713277
2019-04-17 08:39:50,123 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1951.77655983s left)
2019-04-17 08:39:59,810 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417083959795921
2019-04-17 08:39:59,833 [salt.minion      :1432][INFO    ][13199] Starting a new job with PID 13199
2019-04-17 08:39:59,855 [salt.minion      :1711][INFO    ][13199] Returning information for job: 20190417083959795921
2019-04-17 08:40:23,090 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.80932498s left)
2019-04-17 08:40:29,934 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084029923023
2019-04-17 08:40:29,948 [salt.minion      :1432][INFO    ][13565] Starting a new job with PID 13565
2019-04-17 08:40:29,969 [salt.minion      :1711][INFO    ][13565] Returning information for job: 20190417084029923023
2019-04-17 08:40:56,011 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1885.88825297s left)
2019-04-17 08:41:00,013 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084100006445
2019-04-17 08:41:00,026 [salt.minion      :1432][INFO    ][13689] Starting a new job with PID 13689
2019-04-17 08:41:00,047 [salt.minion      :1711][INFO    ][13689] Returning information for job: 20190417084100006445
2019-04-17 08:41:28,918 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1852.98163486s left)
2019-04-17 08:41:30,153 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084130137997
2019-04-17 08:41:30,174 [salt.minion      :1432][INFO    ][14073] Starting a new job with PID 14073
2019-04-17 08:41:30,196 [salt.minion      :1711][INFO    ][14073] Returning information for job: 20190417084130137997
2019-04-17 08:42:00,278 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084200268953
2019-04-17 08:42:00,295 [salt.minion      :1432][INFO    ][14194] Starting a new job with PID 14194
2019-04-17 08:42:00,317 [salt.minion      :1711][INFO    ][14194] Returning information for job: 20190417084200268953
2019-04-17 08:42:01,760 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1820.139498s left)
2019-04-17 08:42:30,416 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084230400544
2019-04-17 08:42:30,440 [salt.minion      :1432][INFO    ][14445] Starting a new job with PID 14445
2019-04-17 08:42:30,465 [salt.minion      :1711][INFO    ][14445] Returning information for job: 20190417084230400544
2019-04-17 08:42:34,657 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1787.24239802s left)
2019-04-17 08:43:00,573 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084300563540
2019-04-17 08:43:00,596 [salt.minion      :1432][INFO    ][14564] Starting a new job with PID 14564
2019-04-17 08:43:00,617 [salt.minion      :1711][INFO    ][14564] Returning information for job: 20190417084300563540
2019-04-17 08:43:07,336 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1754.56354785s left)
2019-04-17 08:43:30,729 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084330715170
2019-04-17 08:43:30,752 [salt.minion      :1432][INFO    ][14910] Starting a new job with PID 14910
2019-04-17 08:43:30,772 [salt.minion      :1711][INFO    ][14910] Returning information for job: 20190417084330715170
2019-04-17 08:43:40,080 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1721.81986904s left)
2019-04-17 08:44:00,877 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084400863819
2019-04-17 08:44:00,894 [salt.minion      :1432][INFO    ][14991] Starting a new job with PID 14991
2019-04-17 08:44:00,916 [salt.minion      :1711][INFO    ][14991] Returning information for job: 20190417084400863819
2019-04-17 08:44:12,805 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1689.09426785s left)
2019-04-17 08:44:30,945 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084430931383
2019-04-17 08:44:30,967 [salt.minion      :1432][INFO    ][15148] Starting a new job with PID 15148
2019-04-17 08:44:30,986 [salt.minion      :1711][INFO    ][15148] Returning information for job: 20190417084430931383
2019-04-17 08:44:45,983 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1655.91687298s left)
2019-04-17 08:45:00,992 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084500983862
2019-04-17 08:45:01,009 [salt.minion      :1432][INFO    ][15263] Starting a new job with PID 15263
2019-04-17 08:45:01,032 [salt.minion      :1711][INFO    ][15263] Returning information for job: 20190417084500983862
2019-04-17 08:45:18,614 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1623.28560686s left)
2019-04-17 08:45:31,148 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084531134641
2019-04-17 08:45:31,170 [salt.minion      :1432][INFO    ][15448] Starting a new job with PID 15448
2019-04-17 08:45:31,198 [salt.minion      :1711][INFO    ][15448] Returning information for job: 20190417084531134641
2019-04-17 08:45:51,477 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1590.42256594s left)
2019-04-17 08:46:01,331 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084601310536
2019-04-17 08:46:01,358 [salt.minion      :1432][INFO    ][15513] Starting a new job with PID 15513
2019-04-17 08:46:01,411 [salt.minion      :1711][INFO    ][15513] Returning information for job: 20190417084601310536
2019-04-17 08:46:24,243 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1557.6570549s left)
2019-04-17 08:46:31,521 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084631512209
2019-04-17 08:46:31,543 [salt.minion      :1432][INFO    ][15572] Starting a new job with PID 15572
2019-04-17 08:46:31,572 [salt.minion      :1711][INFO    ][15572] Returning information for job: 20190417084631512209
2019-04-17 08:46:56,995 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1524.90464687s left)
2019-04-17 08:47:01,567 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084701558792
2019-04-17 08:47:01,581 [salt.minion      :1432][INFO    ][15640] Starting a new job with PID 15640
2019-04-17 08:47:01,607 [salt.minion      :1711][INFO    ][15640] Returning information for job: 20190417084701558792
2019-04-17 08:47:29,709 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1492.19049096s left)
2019-04-17 08:47:31,637 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084731628665
2019-04-17 08:47:31,651 [salt.minion      :1432][INFO    ][15794] Starting a new job with PID 15794
2019-04-17 08:47:31,685 [salt.minion      :1711][INFO    ][15794] Returning information for job: 20190417084731628665
2019-04-17 08:48:01,774 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084801768006
2019-04-17 08:48:01,789 [salt.minion      :1432][INFO    ][15922] Starting a new job with PID 15922
2019-04-17 08:48:01,818 [salt.minion      :1711][INFO    ][15922] Returning information for job: 20190417084801768006
2019-04-17 08:48:02,507 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1459.39284897s left)
2019-04-17 08:48:31,801 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084831790331
2019-04-17 08:48:31,822 [salt.minion      :1432][INFO    ][16072] Starting a new job with PID 16072
2019-04-17 08:48:31,854 [salt.minion      :1711][INFO    ][16072] Returning information for job: 20190417084831790331
2019-04-17 08:48:35,223 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1426.67599702s left)
2019-04-17 08:49:02,010 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084902002404
2019-04-17 08:49:02,026 [salt.minion      :1432][INFO    ][16130] Starting a new job with PID 16130
2019-04-17 08:49:02,052 [salt.minion      :1711][INFO    ][16130] Returning information for job: 20190417084902002404
2019-04-17 08:49:07,936 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1393.96299696s left)
2019-04-17 08:49:32,030 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417084932016431
2019-04-17 08:49:32,053 [salt.minion      :1432][INFO    ][16181] Starting a new job with PID 16181
2019-04-17 08:49:32,085 [salt.minion      :1711][INFO    ][16181] Returning information for job: 20190417084932016431
2019-04-17 08:49:40,532 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1361.36718798s left)
2019-04-17 08:50:02,049 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085002041523
2019-04-17 08:50:02,064 [salt.minion      :1432][INFO    ][16234] Starting a new job with PID 16234
2019-04-17 08:50:02,096 [salt.minion      :1711][INFO    ][16234] Returning information for job: 20190417085002041523
2019-04-17 08:50:13,385 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1328.51424384s left)
2019-04-17 08:50:32,100 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085032085505
2019-04-17 08:50:32,121 [salt.minion      :1432][INFO    ][16285] Starting a new job with PID 16285
2019-04-17 08:50:32,149 [salt.minion      :1711][INFO    ][16285] Returning information for job: 20190417085032085505
2019-04-17 08:50:46,030 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1295.86940289s left)
2019-04-17 08:51:02,133 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085102125723
2019-04-17 08:51:02,147 [salt.minion      :1432][INFO    ][16342] Starting a new job with PID 16342
2019-04-17 08:51:02,178 [salt.minion      :1711][INFO    ][16342] Returning information for job: 20190417085102125723
2019-04-17 08:51:18,930 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1262.96942401s left)
2019-04-17 08:51:32,195 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085132181780
2019-04-17 08:51:32,217 [salt.minion      :1432][INFO    ][16400] Starting a new job with PID 16400
2019-04-17 08:51:32,245 [salt.minion      :1711][INFO    ][16400] Returning information for job: 20190417085132181780
2019-04-17 08:51:51,477 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1230.4222579s left)
2019-04-17 08:52:02,252 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085202240791
2019-04-17 08:52:02,269 [salt.minion      :1432][INFO    ][16460] Starting a new job with PID 16460
2019-04-17 08:52:02,300 [salt.minion      :1711][INFO    ][16460] Returning information for job: 20190417085202240791
2019-04-17 08:52:24,126 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1197.77349091s left)
2019-04-17 08:52:32,330 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085232316372
2019-04-17 08:52:32,350 [salt.minion      :1432][INFO    ][16507] Starting a new job with PID 16507
2019-04-17 08:52:32,375 [salt.minion      :1711][INFO    ][16507] Returning information for job: 20190417085232316372
2019-04-17 08:52:56,637 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1165.26251101s left)
2019-04-17 08:53:02,397 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085302385923
2019-04-17 08:53:02,417 [salt.minion      :1432][INFO    ][16568] Starting a new job with PID 16568
2019-04-17 08:53:02,446 [salt.minion      :1711][INFO    ][16568] Returning information for job: 20190417085302385923
2019-04-17 08:53:29,250 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1132.64958882s left)
2019-04-17 08:53:32,517 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085332500029
2019-04-17 08:53:32,539 [salt.minion      :1432][INFO    ][16631] Starting a new job with PID 16631
2019-04-17 08:53:32,567 [salt.minion      :1711][INFO    ][16631] Returning information for job: 20190417085332500029
2019-04-17 08:54:02,170 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1099.72915006s left)
2019-04-17 08:54:02,642 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085402633445
2019-04-17 08:54:02,661 [salt.minion      :1432][INFO    ][16688] Starting a new job with PID 16688
2019-04-17 08:54:02,689 [salt.minion      :1711][INFO    ][16688] Returning information for job: 20190417085402633445
2019-04-17 08:54:32,768 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085432758322
2019-04-17 08:54:32,786 [salt.minion      :1432][INFO    ][16730] Starting a new job with PID 16730
2019-04-17 08:54:32,819 [salt.minion      :1711][INFO    ][16730] Returning information for job: 20190417085432758322
2019-04-17 08:54:34,954 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1066.9453249s left)
2019-04-17 08:55:02,936 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085502924227
2019-04-17 08:55:02,954 [salt.minion      :1432][INFO    ][16787] Starting a new job with PID 16787
2019-04-17 08:55:02,985 [salt.minion      :1711][INFO    ][16787] Returning information for job: 20190417085502924227
2019-04-17 08:55:07,541 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1034.35874891s left)
2019-04-17 08:55:33,082 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085533069803
2019-04-17 08:55:33,103 [salt.minion      :1432][INFO    ][16832] Starting a new job with PID 16832
2019-04-17 08:55:33,137 [salt.minion      :1711][INFO    ][16832] Returning information for job: 20190417085533069803
2019-04-17 08:55:40,117 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1001.78236485s left)
2019-04-17 08:56:03,284 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085603273457
2019-04-17 08:56:03,305 [salt.minion      :1432][INFO    ][16892] Starting a new job with PID 16892
2019-04-17 08:56:03,335 [salt.minion      :1711][INFO    ][16892] Returning information for job: 20190417085603273457
2019-04-17 08:56:12,774 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (969.125766039s left)
2019-04-17 08:56:33,461 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085633446404
2019-04-17 08:56:33,477 [salt.minion      :1432][INFO    ][16937] Starting a new job with PID 16937
2019-04-17 08:56:33,504 [salt.minion      :1711][INFO    ][16937] Returning information for job: 20190417085633446404
2019-04-17 08:56:45,461 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (936.438349962s left)
2019-04-17 08:57:03,683 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085703672734
2019-04-17 08:57:03,703 [salt.minion      :1432][INFO    ][16995] Starting a new job with PID 16995
2019-04-17 08:57:03,733 [salt.minion      :1711][INFO    ][16995] Returning information for job: 20190417085703672734
2019-04-17 08:57:18,226 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (903.673863888s left)
2019-04-17 08:57:33,872 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085733863506
2019-04-17 08:57:33,895 [salt.minion      :1432][INFO    ][17040] Starting a new job with PID 17040
2019-04-17 08:57:33,924 [salt.minion      :1711][INFO    ][17040] Returning information for job: 20190417085733863506
2019-04-17 08:57:50,946 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (870.953505039s left)
2019-04-17 08:58:03,886 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085803877357
2019-04-17 08:58:03,907 [salt.minion      :1432][INFO    ][17102] Starting a new job with PID 17102
2019-04-17 08:58:03,941 [salt.minion      :1711][INFO    ][17102] Returning information for job: 20190417085803877357
2019-04-17 08:58:23,577 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (838.322381973s left)
2019-04-17 08:58:33,902 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085833892593
2019-04-17 08:58:33,921 [salt.minion      :1432][INFO    ][17147] Starting a new job with PID 17147
2019-04-17 08:58:33,952 [salt.minion      :1711][INFO    ][17147] Returning information for job: 20190417085833892593
2019-04-17 08:58:56,186 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (805.713605881s left)
2019-04-17 08:59:03,980 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085903967137
2019-04-17 08:59:04,003 [salt.minion      :1432][INFO    ][17205] Starting a new job with PID 17205
2019-04-17 08:59:04,033 [salt.minion      :1711][INFO    ][17205] Returning information for job: 20190417085903967137
2019-04-17 08:59:28,893 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (773.006470919s left)
2019-04-17 08:59:33,998 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417085933991641
2019-04-17 08:59:34,013 [salt.minion      :1432][INFO    ][17250] Starting a new job with PID 17250
2019-04-17 08:59:34,041 [salt.minion      :1711][INFO    ][17250] Returning information for job: 20190417085933991641
2019-04-17 09:00:01,585 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (740.31427598s left)
2019-04-17 09:00:04,106 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090004097840
2019-04-17 09:00:04,126 [salt.minion      :1432][INFO    ][17307] Starting a new job with PID 17307
2019-04-17 09:00:04,156 [salt.minion      :1711][INFO    ][17307] Returning information for job: 20190417090004097840
2019-04-17 09:00:34,221 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090034214779
2019-04-17 09:00:34,235 [salt.minion      :1432][INFO    ][17356] Starting a new job with PID 17356
2019-04-17 09:00:34,266 [salt.minion      :1711][INFO    ][17356] Returning information for job: 20190417090034214779
2019-04-17 09:00:34,445 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (707.454646826s left)
2019-04-17 09:01:04,320 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090104310522
2019-04-17 09:01:04,340 [salt.minion      :1432][INFO    ][17410] Starting a new job with PID 17410
2019-04-17 09:01:04,370 [salt.minion      :1711][INFO    ][17410] Returning information for job: 20190417090104310522
2019-04-17 09:01:07,078 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (674.821481943s left)
2019-04-17 09:01:34,484 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090134471497
2019-04-17 09:01:34,505 [salt.minion      :1432][INFO    ][17459] Starting a new job with PID 17459
2019-04-17 09:01:34,535 [salt.minion      :1711][INFO    ][17459] Returning information for job: 20190417090134471497
2019-04-17 09:01:39,714 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (642.185760975s left)
2019-04-17 09:02:04,639 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090204624903
2019-04-17 09:02:04,662 [salt.minion      :1432][INFO    ][17516] Starting a new job with PID 17516
2019-04-17 09:02:04,692 [salt.minion      :1711][INFO    ][17516] Returning information for job: 20190417090204624903
2019-04-17 09:02:12,299 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (609.600847006s left)
2019-04-17 09:02:34,858 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090234845802
2019-04-17 09:02:34,881 [salt.minion      :1432][INFO    ][17561] Starting a new job with PID 17561
2019-04-17 09:02:34,912 [salt.minion      :1711][INFO    ][17561] Returning information for job: 20190417090234845802
2019-04-17 09:02:45,065 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (576.834542036s left)
2019-04-17 09:03:05,030 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090305019623
2019-04-17 09:03:05,051 [salt.minion      :1432][INFO    ][17748] Starting a new job with PID 17748
2019-04-17 09:03:05,085 [salt.minion      :1711][INFO    ][17748] Returning information for job: 20190417090305019623
2019-04-17 09:03:17,770 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (544.12951088s left)
2019-04-17 09:03:35,075 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090335061419
2019-04-17 09:03:35,096 [salt.minion      :1432][INFO    ][17808] Starting a new job with PID 17808
2019-04-17 09:03:35,126 [salt.minion      :1711][INFO    ][17808] Returning information for job: 20190417090335061419
2019-04-17 09:03:50,495 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (511.404098034s left)
2019-04-17 09:04:05,275 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090405267241
2019-04-17 09:04:05,298 [salt.minion      :1432][INFO    ][17866] Starting a new job with PID 17866
2019-04-17 09:04:05,329 [salt.minion      :1711][INFO    ][17866] Returning information for job: 20190417090405267241
2019-04-17 09:04:23,365 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (478.534674883s left)
2019-04-17 09:04:35,361 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090435345968
2019-04-17 09:04:35,382 [salt.minion      :1432][INFO    ][17911] Starting a new job with PID 17911
2019-04-17 09:04:35,411 [salt.minion      :1711][INFO    ][17911] Returning information for job: 20190417090435345968
2019-04-17 09:04:55,996 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (445.903157949s left)
2019-04-17 09:05:05,382 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090505373597
2019-04-17 09:05:05,402 [salt.minion      :1432][INFO    ][17971] Starting a new job with PID 17971
2019-04-17 09:05:05,435 [salt.minion      :1711][INFO    ][17971] Returning information for job: 20190417090505373597
2019-04-17 09:05:28,722 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (413.177075863s left)
2019-04-17 09:05:35,508 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090535494354
2019-04-17 09:05:35,528 [salt.minion      :1432][INFO    ][18022] Starting a new job with PID 18022
2019-04-17 09:05:35,560 [salt.minion      :1711][INFO    ][18022] Returning information for job: 20190417090535494354
2019-04-17 09:06:01,440 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (380.459713936s left)
2019-04-17 09:06:05,606 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090605599945
2019-04-17 09:06:05,618 [salt.minion      :1432][INFO    ][18081] Starting a new job with PID 18081
2019-04-17 09:06:05,648 [salt.minion      :1711][INFO    ][18081] Returning information for job: 20190417090605599945
2019-04-17 09:06:34,205 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (347.694854021s left)
2019-04-17 09:06:35,742 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090635728611
2019-04-17 09:06:35,762 [salt.minion      :1432][INFO    ][18126] Starting a new job with PID 18126
2019-04-17 09:06:35,794 [salt.minion      :1711][INFO    ][18126] Returning information for job: 20190417090635728611
2019-04-17 09:07:05,935 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090705926486
2019-04-17 09:07:05,951 [salt.minion      :1432][INFO    ][18184] Starting a new job with PID 18184
2019-04-17 09:07:05,982 [salt.minion      :1711][INFO    ][18184] Returning information for job: 20190417090705926486
2019-04-17 09:07:06,943 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (314.956030846s left)
2019-04-17 09:07:36,090 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090736082818
2019-04-17 09:07:36,105 [salt.minion      :1432][INFO    ][18225] Starting a new job with PID 18225
2019-04-17 09:07:36,133 [salt.minion      :1711][INFO    ][18225] Returning information for job: 20190417090736082818
2019-04-17 09:07:39,678 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (282.221082926s left)
2019-04-17 09:08:06,114 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090806099579
2019-04-17 09:08:06,132 [salt.minion      :1432][INFO    ][18283] Starting a new job with PID 18283
2019-04-17 09:08:06,164 [salt.minion      :1711][INFO    ][18283] Returning information for job: 20190417090806099579
2019-04-17 09:08:12,275 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (249.624058962s left)
2019-04-17 09:08:36,293 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090836281737
2019-04-17 09:08:36,312 [salt.minion      :1432][INFO    ][18330] Starting a new job with PID 18330
2019-04-17 09:08:36,342 [salt.minion      :1711][INFO    ][18330] Returning information for job: 20190417090836281737
2019-04-17 09:08:45,049 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (216.850111961s left)
2019-04-17 09:09:06,345 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090906331034
2019-04-17 09:09:06,366 [salt.minion      :1432][INFO    ][18388] Starting a new job with PID 18388
2019-04-17 09:09:06,395 [salt.minion      :1711][INFO    ][18388] Returning information for job: 20190417090906331034
2019-04-17 09:09:17,812 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (184.087188005s left)
2019-04-17 09:09:36,560 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417090936544315
2019-04-17 09:09:36,580 [salt.minion      :1432][INFO    ][18439] Starting a new job with PID 18439
2019-04-17 09:09:36,607 [salt.minion      :1711][INFO    ][18439] Returning information for job: 20190417090936544315
2019-04-17 09:09:50,417 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (151.482261896s left)
2019-04-17 09:10:06,636 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091006622485
2019-04-17 09:10:06,657 [salt.minion      :1432][INFO    ][18499] Starting a new job with PID 18499
2019-04-17 09:10:06,691 [salt.minion      :1711][INFO    ][18499] Returning information for job: 20190417091006622485
2019-04-17 09:10:23,170 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (118.729796886s left)
2019-04-17 09:10:36,660 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091036651662
2019-04-17 09:10:36,681 [salt.minion      :1432][INFO    ][18544] Starting a new job with PID 18544
2019-04-17 09:10:36,708 [salt.minion      :1711][INFO    ][18544] Returning information for job: 20190417091036651662
2019-04-17 09:10:55,883 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (86.0162580013s left)
2019-04-17 09:11:06,767 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091106754947
2019-04-17 09:11:06,787 [salt.minion      :1432][INFO    ][18602] Starting a new job with PID 18602
2019-04-17 09:11:06,815 [salt.minion      :1711][INFO    ][18602] Returning information for job: 20190417091106754947
2019-04-17 09:11:28,511 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (53.3884780407s left)
2019-04-17 09:11:36,840 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091136831354
2019-04-17 09:11:36,859 [salt.minion      :1432][INFO    ][18647] Starting a new job with PID 18647
2019-04-17 09:11:36,888 [salt.minion      :1711][INFO    ][18647] Returning information for job: 20190417091136831354
2019-04-17 09:12:01,328 [salt.loaded.ext.module.maas:1023][INFO    ][12102] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (20.5709269047s left)
2019-04-17 09:12:07,010 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091206997640
2019-04-17 09:12:07,032 [salt.minion      :1432][INFO    ][18706] Starting a new job with PID 18706
2019-04-17 09:12:07,063 [salt.minion      :1711][INFO    ][18706] Returning information for job: 20190417091206997640
2019-04-17 09:12:33,966 [salt.state       :302 ][ERROR   ][12102] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-04-17 09:12:33,967 [salt.state       :1951][INFO    ][12102] Completed state [maas.wait_for_machine_status] at time 09:12:33.967219 duration_in_ms=2262074.897
2019-04-17 09:12:33,972 [salt.minion      :1711][INFO    ][12102] Returning information for job: 20190417083444090794
2019-04-17 09:12:44,933 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command pillar.get with jid 20190417091244924502
2019-04-17 09:12:44,952 [salt.minion      :1432][INFO    ][18772] Starting a new job with PID 18772
2019-04-17 09:12:44,962 [salt.minion      :1711][INFO    ][18772] Returning information for job: 20190417091244924502
2019-04-17 09:12:45,637 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command service.status with jid 20190417091245629671
2019-04-17 09:12:45,657 [salt.minion      :1432][INFO    ][18777] Starting a new job with PID 18777
2019-04-17 09:12:46,699 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][18777] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-17 09:12:46,734 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][18777] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-17 09:12:46,751 [salt.minion      :1711][INFO    ][18777] Returning information for job: 20190417091245629671
2019-04-17 09:12:47,426 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command state.apply with jid 20190417091247419474
2019-04-17 09:12:47,444 [salt.minion      :1432][INFO    ][18790] Starting a new job with PID 18790
2019-04-17 09:12:53,293 [salt.state       :915 ][INFO    ][18790] Loading fresh modules for state activity
2019-04-17 09:12:53,883 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18790] Executing command 'salt-minion --version' in directory '/root'
2019-04-17 09:12:54,259 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18790] Executing command 'salt-minion --version' in directory '/root'
2019-04-17 09:12:55,269 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18790] Executing command 'salt-minion --version' in directory '/root'
2019-04-17 09:12:55,605 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18790] Executing command 'salt-minion --version' in directory '/root'
2019-04-17 09:12:57,797 [salt.state       :1780][INFO    ][18790] Running state [salt-minion] at time 09:12:57.797234
2019-04-17 09:12:57,797 [salt.state       :1813][INFO    ][18790] Executing state pkg.installed for [salt-minion]
2019-04-17 09:12:57,798 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18790] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-17 09:12:57,925 [salt.state       :300 ][INFO    ][18790] All specified packages are already installed
2019-04-17 09:12:57,925 [salt.state       :1951][INFO    ][18790] Completed state [salt-minion] at time 09:12:57.925859 duration_in_ms=128.626
2019-04-17 09:12:57,926 [salt.state       :1780][INFO    ][18790] Running state [salt_minion_dependency_packages] at time 09:12:57.926228
2019-04-17 09:12:57,926 [salt.state       :1813][INFO    ][18790] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-17 09:12:57,936 [salt.state       :300 ][INFO    ][18790] All specified packages are already installed
2019-04-17 09:12:57,937 [salt.state       :1951][INFO    ][18790] Completed state [salt_minion_dependency_packages] at time 09:12:57.937194 duration_in_ms=10.966
2019-04-17 09:12:57,944 [salt.state       :1780][INFO    ][18790] Running state [/etc/salt/minion.d/minion.conf] at time 09:12:57.944454
2019-04-17 09:12:57,945 [salt.state       :1813][INFO    ][18790] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-17 09:12:58,230 [salt.state       :300 ][INFO    ][18790] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-17 09:12:58,230 [salt.state       :1951][INFO    ][18790] Completed state [/etc/salt/minion.d/minion.conf] at time 09:12:58.230528 duration_in_ms=286.074
2019-04-17 09:12:58,231 [salt.state       :1780][INFO    ][18790] Running state [python-netaddr] at time 09:12:58.230823
2019-04-17 09:12:58,231 [salt.state       :1813][INFO    ][18790] Executing state pkg.installed for [python-netaddr]
2019-04-17 09:12:58,241 [salt.state       :300 ][INFO    ][18790] All specified packages are already installed
2019-04-17 09:12:58,242 [salt.state       :1951][INFO    ][18790] Completed state [python-netaddr] at time 09:12:58.242171 duration_in_ms=11.348
2019-04-17 09:12:58,246 [salt.state       :1780][INFO    ][18790] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 09:12:58.246281
2019-04-17 09:12:58,246 [salt.state       :1813][INFO    ][18790] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-17 09:12:58,257 [salt.state       :300 ][INFO    ][18790] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-17 09:12:58,257 [salt.state       :1951][INFO    ][18790] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 09:12:58.257761 duration_in_ms=11.478
2019-04-17 09:12:58,259 [salt.state       :1780][INFO    ][18790] Running state [salt-minion] at time 09:12:58.259564
2019-04-17 09:12:58,260 [salt.state       :1813][INFO    ][18790] Executing state service.running for [salt-minion]
2019-04-17 09:12:58,260 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18790] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-17 09:12:58,301 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18790] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-17 09:12:58,320 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18790] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-17 09:12:58,343 [salt.state       :300 ][INFO    ][18790] The service salt-minion is already running
2019-04-17 09:12:58,343 [salt.state       :1951][INFO    ][18790] Completed state [salt-minion] at time 09:12:58.343660 duration_in_ms=84.095
2019-04-17 09:12:58,349 [salt.state       :1780][INFO    ][18790] Running state [/etc/salt/grains.d] at time 09:12:58.349149
2019-04-17 09:12:58,349 [salt.state       :1813][INFO    ][18790] Executing state file.directory for [/etc/salt/grains.d]
2019-04-17 09:12:58,352 [salt.state       :300 ][INFO    ][18790] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-17 09:12:58,352 [salt.state       :1951][INFO    ][18790] Completed state [/etc/salt/grains.d] at time 09:12:58.352610 duration_in_ms=3.46
2019-04-17 09:12:58,353 [salt.state       :1780][INFO    ][18790] Running state [/etc/salt/grains] at time 09:12:58.353832
2019-04-17 09:12:58,354 [salt.state       :1813][INFO    ][18790] Executing state file.managed for [/etc/salt/grains]
2019-04-17 09:12:58,355 [salt.state       :300 ][INFO    ][18790] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-17 09:12:58,355 [salt.state       :1951][INFO    ][18790] Completed state [/etc/salt/grains] at time 09:12:58.355813 duration_in_ms=1.981
2019-04-17 09:12:58,356 [salt.state       :1780][INFO    ][18790] Running state [/etc/salt/grains.d/placeholder] at time 09:12:58.356675
2019-04-17 09:12:58,357 [salt.state       :1813][INFO    ][18790] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-17 09:12:58,358 [salt.state       :300 ][INFO    ][18790] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-17 09:12:58,358 [salt.state       :1951][INFO    ][18790] Completed state [/etc/salt/grains.d/placeholder] at time 09:12:58.358621 duration_in_ms=1.947
2019-04-17 09:12:58,361 [salt.state       :1780][INFO    ][18790] Running state [/etc/salt/grains.d/sphinx] at time 09:12:58.361746
2019-04-17 09:12:58,362 [salt.state       :1813][INFO    ][18790] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-17 09:12:58,363 [salt.state       :300 ][INFO    ][18790] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-17 09:12:58,364 [salt.state       :1951][INFO    ][18790] Completed state [/etc/salt/grains.d/sphinx] at time 09:12:58.364276 duration_in_ms=2.529
2019-04-17 09:12:58,367 [salt.state       :1780][INFO    ][18790] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:12:58.367603
2019-04-17 09:12:58,368 [salt.state       :1813][INFO    ][18790] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-17 09:12:58,368 [salt.state       :300 ][INFO    ][18790] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-17 09:12:58,368 [salt.state       :1951][INFO    ][18790] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:12:58.368862 duration_in_ms=1.259
2019-04-17 09:12:58,369 [salt.state       :1780][INFO    ][18790] Running state [/etc/salt/grains.d/dns_records] at time 09:12:58.369623
2019-04-17 09:12:58,370 [salt.state       :1813][INFO    ][18790] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-17 09:12:58,373 [salt.state       :300 ][INFO    ][18790] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-17 09:12:58,374 [salt.state       :1951][INFO    ][18790] Completed state [/etc/salt/grains.d/dns_records] at time 09:12:58.374197 duration_in_ms=4.573
2019-04-17 09:12:58,376 [salt.state       :1780][INFO    ][18790] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:12:58.376038
2019-04-17 09:12:58,376 [salt.state       :1813][INFO    ][18790] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-17 09:12:58,377 [salt.state       :300 ][INFO    ][18790] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-17 09:12:58,377 [salt.state       :1951][INFO    ][18790] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:12:58.377287 duration_in_ms=1.25
2019-04-17 09:12:58,378 [salt.state       :1780][INFO    ][18790] Running state [/etc/salt/grains.d/salt] at time 09:12:58.377902
2019-04-17 09:12:58,378 [salt.state       :1813][INFO    ][18790] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-17 09:12:58,380 [salt.state       :300 ][INFO    ][18790] File /etc/salt/grains.d/salt is in the correct state
2019-04-17 09:12:58,380 [salt.state       :1951][INFO    ][18790] Completed state [/etc/salt/grains.d/salt] at time 09:12:58.380601 duration_in_ms=2.699
2019-04-17 09:12:58,382 [salt.state       :1780][INFO    ][18790] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:12:58.381988
2019-04-17 09:12:58,382 [salt.state       :1813][INFO    ][18790] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-17 09:12:58,385 [salt.state       :300 ][INFO    ][18790] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-17 09:12:58,385 [salt.state       :1951][INFO    ][18790] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:12:58.385494 duration_in_ms=3.506
2019-04-17 09:12:58,388 [salt.state       :1780][INFO    ][18790] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:12:58.388534
2019-04-17 09:12:58,389 [salt.state       :1813][INFO    ][18790] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-17 09:12:58,389 [salt.state       :300 ][INFO    ][18790] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-17 09:12:58,389 [salt.state       :1951][INFO    ][18790] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:12:58.389712 duration_in_ms=1.178
2019-04-17 09:12:58,390 [salt.state       :1780][INFO    ][18790] Running state [mine.update] at time 09:12:58.390682
2019-04-17 09:12:58,391 [salt.state       :1813][INFO    ][18790] Executing state module.wait for [mine.update]
2019-04-17 09:12:58,391 [salt.state       :300 ][INFO    ][18790] No changes made for mine.update
2019-04-17 09:12:58,391 [salt.state       :1951][INFO    ][18790] Completed state [mine.update] at time 09:12:58.391802 duration_in_ms=1.12
2019-04-17 09:12:58,392 [salt.state       :1780][INFO    ][18790] Running state [ca-certificates] at time 09:12:58.392178
2019-04-17 09:12:58,392 [salt.state       :1813][INFO    ][18790] Executing state pkg.installed for [ca-certificates]
2019-04-17 09:12:58,404 [salt.state       :300 ][INFO    ][18790] All specified packages are already installed
2019-04-17 09:12:58,404 [salt.state       :1951][INFO    ][18790] Completed state [ca-certificates] at time 09:12:58.404497 duration_in_ms=12.319
2019-04-17 09:12:58,405 [salt.state       :1780][INFO    ][18790] Running state [update-ca-certificates] at time 09:12:58.405238
2019-04-17 09:12:58,405 [salt.state       :1813][INFO    ][18790] Executing state cmd.wait for [update-ca-certificates]
2019-04-17 09:12:58,405 [salt.state       :300 ][INFO    ][18790] No changes made for update-ca-certificates
2019-04-17 09:12:58,406 [salt.state       :1951][INFO    ][18790] Completed state [update-ca-certificates] at time 09:12:58.406056 duration_in_ms=0.818
2019-04-17 09:12:58,406 [salt.state       :1780][INFO    ][18790] Running state [iptables] at time 09:12:58.406330
2019-04-17 09:12:58,406 [salt.state       :1813][INFO    ][18790] Executing state pkg.installed for [iptables]
2019-04-17 09:12:58,416 [salt.state       :300 ][INFO    ][18790] All specified packages are already installed
2019-04-17 09:12:58,416 [salt.state       :1951][INFO    ][18790] Completed state [iptables] at time 09:12:58.416879 duration_in_ms=10.549
2019-04-17 09:12:58,417 [salt.state       :1780][INFO    ][18790] Running state [iptables-persistent] at time 09:12:58.417159
2019-04-17 09:12:58,417 [salt.state       :1813][INFO    ][18790] Executing state pkg.installed for [iptables-persistent]
2019-04-17 09:12:58,427 [salt.state       :300 ][INFO    ][18790] All specified packages are already installed
2019-04-17 09:12:58,427 [salt.state       :1951][INFO    ][18790] Completed state [iptables-persistent] at time 09:12:58.427773 duration_in_ms=10.615
2019-04-17 09:12:58,428 [salt.state       :1780][INFO    ][18790] Running state [iptables_modules_v4_load] at time 09:12:58.428823
2019-04-17 09:12:58,429 [salt.state       :1813][INFO    ][18790] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-17 09:12:58,429 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18790] Executing command 'lsmod' in directory '/root'
2019-04-17 09:12:58,452 [salt.state       :300 ][INFO    ][18790] Kernel modules iptable_filter, ip_tables are already present
2019-04-17 09:12:58,453 [salt.state       :1951][INFO    ][18790] Completed state [iptables_modules_v4_load] at time 09:12:58.452962 duration_in_ms=24.138
2019-04-17 09:12:58,454 [salt.state       :1780][INFO    ][18790] Running state [/etc/iptables/rules.v4] at time 09:12:58.454148
2019-04-17 09:12:58,454 [salt.state       :1813][INFO    ][18790] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-17 09:12:58,560 [salt.state       :300 ][INFO    ][18790] File /etc/iptables/rules.v4 is in the correct state
2019-04-17 09:12:58,561 [salt.state       :1951][INFO    ][18790] Completed state [/etc/iptables/rules.v4] at time 09:12:58.560878 duration_in_ms=106.731
2019-04-17 09:12:58,563 [salt.state       :1780][INFO    ][18790] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 09:12:58.563321
2019-04-17 09:12:58,563 [salt.state       :1813][INFO    ][18790] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-17 09:12:58,564 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18790] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-17 09:12:58,586 [salt.state       :300 ][INFO    ][18790] onlyif execution failed
2019-04-17 09:12:58,586 [salt.state       :1951][INFO    ][18790] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 09:12:58.586479 duration_in_ms=23.159
2019-04-17 09:12:58,593 [salt.state       :1780][INFO    ][18790] Running state [netfilter-persistent] at time 09:12:58.592929
2019-04-17 09:12:58,593 [salt.state       :1813][INFO    ][18790] Executing state service.running for [netfilter-persistent]
2019-04-17 09:12:58,595 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18790] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-17 09:12:58,617 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18790] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-17 09:12:58,640 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18790] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-17 09:12:58,658 [salt.state       :300 ][INFO    ][18790] The service netfilter-persistent is already running
2019-04-17 09:12:58,658 [salt.state       :1951][INFO    ][18790] Completed state [netfilter-persistent] at time 09:12:58.658708 duration_in_ms=65.781
2019-04-17 09:12:58,662 [salt.state       :1780][INFO    ][18790] Running state [iptables_extra.remove_stale_tables] at time 09:12:58.662159
2019-04-17 09:12:58,662 [salt.state       :1813][INFO    ][18790] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-17 09:12:58,663 [salt.state       :300 ][INFO    ][18790] No changes made for iptables_extra.remove_stale_tables
2019-04-17 09:12:58,663 [salt.state       :1951][INFO    ][18790] Completed state [iptables_extra.remove_stale_tables] at time 09:12:58.663621 duration_in_ms=1.461
2019-04-17 09:12:58,664 [salt.state       :1780][INFO    ][18790] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 09:12:58.664077
2019-04-17 09:12:58,664 [salt.state       :1813][INFO    ][18790] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-17 09:12:58,665 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18790] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-17 09:12:58,680 [salt.state       :300 ][INFO    ][18790] onlyif execution failed
2019-04-17 09:12:58,680 [salt.state       :1951][INFO    ][18790] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 09:12:58.680327 duration_in_ms=16.25
2019-04-17 09:12:58,681 [salt.state       :1780][INFO    ][18790] Running state [/etc/iptables/rules.v6] at time 09:12:58.681405
2019-04-17 09:12:58,681 [salt.state       :1813][INFO    ][18790] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-17 09:12:58,682 [salt.state       :300 ][INFO    ][18790] File /etc/iptables/rules.v6 is not present
2019-04-17 09:12:58,685 [salt.state       :1951][INFO    ][18790] Completed state [/etc/iptables/rules.v6] at time 09:12:58.682828 duration_in_ms=1.423
2019-04-17 09:12:58,686 [salt.state       :1780][INFO    ][18790] Running state [iptables_extra.flush_all] at time 09:12:58.686188
2019-04-17 09:12:58,687 [salt.state       :1813][INFO    ][18790] Executing state module.wait for [iptables_extra.flush_all]
2019-04-17 09:12:58,688 [salt.state       :300 ][INFO    ][18790] No changes made for iptables_extra.flush_all
2019-04-17 09:12:58,688 [salt.state       :1951][INFO    ][18790] Completed state [iptables_extra.flush_all] at time 09:12:58.688535 duration_in_ms=2.346
2019-04-17 09:12:58,693 [salt.minion      :1711][INFO    ][18790] Returning information for job: 20190417091247419474
2019-04-17 09:12:59,384 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command state.apply with jid 20190417091259375464
2019-04-17 09:12:59,401 [salt.minion      :1432][INFO    ][18865] Starting a new job with PID 18865
2019-04-17 09:13:00,350 [salt.state       :915 ][INFO    ][18865] Loading fresh modules for state activity
2019-04-17 09:13:01,679 [salt.state       :1780][INFO    ][18865] Running state [maas-rack-controller] at time 09:13:01.679378
2019-04-17 09:13:01,679 [salt.state       :1813][INFO    ][18865] Executing state pkg.installed for [maas-rack-controller]
2019-04-17 09:13:01,680 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18865] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-17 09:13:01,778 [salt.state       :300 ][INFO    ][18865] All specified packages are already installed
2019-04-17 09:13:01,778 [salt.state       :1951][INFO    ][18865] Completed state [maas-rack-controller] at time 09:13:01.778582 duration_in_ms=99.204
2019-04-17 09:13:01,780 [salt.state       :1780][INFO    ][18865] Running state [ipmitool] at time 09:13:01.780069
2019-04-17 09:13:01,780 [salt.state       :1813][INFO    ][18865] Executing state pkg.installed for [ipmitool]
2019-04-17 09:13:01,789 [salt.state       :300 ][INFO    ][18865] All specified packages are already installed
2019-04-17 09:13:01,789 [salt.state       :1951][INFO    ][18865] Completed state [ipmitool] at time 09:13:01.789644 duration_in_ms=9.575
2019-04-17 09:13:01,793 [salt.state       :1780][INFO    ][18865] Running state [/etc/maas/rackd.conf] at time 09:13:01.793813
2019-04-17 09:13:01,794 [salt.state       :1813][INFO    ][18865] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-17 09:13:01,795 [salt.state       :300 ][INFO    ][18865] No changes needed to be made
2019-04-17 09:13:01,795 [salt.state       :1951][INFO    ][18865] Completed state [/etc/maas/rackd.conf] at time 09:13:01.795337 duration_in_ms=1.524
2019-04-17 09:13:01,795 [salt.state       :1780][INFO    ][18865] Running state [/etc/maas/rackd.conf] at time 09:13:01.795624
2019-04-17 09:13:01,795 [salt.state       :1813][INFO    ][18865] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-17 09:13:01,796 [salt.loaded.int.states.file:2298][WARNING ][18865] 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-04-17 09:13:01,797 [salt.state       :300 ][INFO    ][18865] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-17 09:13:01,797 [salt.state       :1951][INFO    ][18865] Completed state [/etc/maas/rackd.conf] at time 09:13:01.797264 duration_in_ms=1.64
2019-04-17 09:13:01,798 [salt.state       :1780][INFO    ][18865] Running state [maas-rackd] at time 09:13:01.798329
2019-04-17 09:13:01,798 [salt.state       :1813][INFO    ][18865] Executing state service.running for [maas-rackd]
2019-04-17 09:13:01,799 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18865] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-17 09:13:01,826 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18865] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-17 09:13:01,842 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18865] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-17 09:13:01,855 [salt.state       :300 ][INFO    ][18865] The service maas-rackd is already running
2019-04-17 09:13:01,855 [salt.state       :1951][INFO    ][18865] Completed state [maas-rackd] at time 09:13:01.855838 duration_in_ms=57.508
2019-04-17 09:13:01,857 [salt.minion      :1711][INFO    ][18865] Returning information for job: 20190417091259375464
2019-04-17 09:13:02,582 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command state.apply with jid 20190417091302571624
2019-04-17 09:13:02,602 [salt.minion      :1432][INFO    ][18922] Starting a new job with PID 18922
2019-04-17 09:13:03,545 [salt.state       :915 ][INFO    ][18922] Loading fresh modules for state activity
2019-04-17 09:13:04,885 [salt.state       :1780][INFO    ][18922] Running state [maas-region-controller] at time 09:13:04.885772
2019-04-17 09:13:04,886 [salt.state       :1813][INFO    ][18922] Executing state pkg.installed for [maas-region-controller]
2019-04-17 09:13:04,887 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18922] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-17 09:13:05,004 [salt.state       :300 ][INFO    ][18922] All specified packages are already installed
2019-04-17 09:13:05,005 [salt.state       :1951][INFO    ][18922] Completed state [maas-region-controller] at time 09:13:05.004962 duration_in_ms=119.191
2019-04-17 09:13:05,005 [salt.state       :1780][INFO    ][18922] Running state [python-oauth] at time 09:13:05.005321
2019-04-17 09:13:05,005 [salt.state       :1813][INFO    ][18922] Executing state pkg.installed for [python-oauth]
2019-04-17 09:13:05,015 [salt.state       :300 ][INFO    ][18922] All specified packages are already installed
2019-04-17 09:13:05,015 [salt.state       :1951][INFO    ][18922] Completed state [python-oauth] at time 09:13:05.015559 duration_in_ms=10.237
2019-04-17 09:13:05,018 [salt.state       :1780][INFO    ][18922] Running state [/etc/maas/regiond.conf] at time 09:13:05.018708
2019-04-17 09:13:05,019 [salt.state       :1813][INFO    ][18922] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-17 09:13:05,024 [salt.state       :300 ][INFO    ][18922] No changes needed to be made
2019-04-17 09:13:05,024 [salt.state       :1951][INFO    ][18922] Completed state [/etc/maas/regiond.conf] at time 09:13:05.024213 duration_in_ms=5.505
2019-04-17 09:13:05,024 [salt.state       :1780][INFO    ][18922] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:13:05.024742
2019-04-17 09:13:05,025 [salt.state       :1813][INFO    ][18922] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-17 09:13:05,081 [salt.state       :300 ][INFO    ][18922] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-17 09:13:05,082 [salt.state       :1951][INFO    ][18922] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:13:05.081936 duration_in_ms=57.193
2019-04-17 09:13:05,082 [salt.state       :1780][INFO    ][18922] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:13:05.082469
2019-04-17 09:13:05,082 [salt.state       :1813][INFO    ][18922] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-17 09:13:05,087 [salt.state       :300 ][INFO    ][18922] No changes needed to be made
2019-04-17 09:13:05,087 [salt.state       :1951][INFO    ][18922] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:13:05.087889 duration_in_ms=5.42
2019-04-17 09:13:05,088 [salt.state       :1780][INFO    ][18922] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:13:05.088414
2019-04-17 09:13:05,088 [salt.state       :1813][INFO    ][18922] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-17 09:13:05,093 [salt.state       :300 ][INFO    ][18922] No changes needed to be made
2019-04-17 09:13:05,093 [salt.state       :1951][INFO    ][18922] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:13:05.093645 duration_in_ms=5.23
2019-04-17 09:13:05,094 [salt.state       :1780][INFO    ][18922] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:13:05.094164
2019-04-17 09:13:05,094 [salt.state       :1813][INFO    ][18922] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-17 09:13:05,122 [salt.state       :300 ][INFO    ][18922] No changes needed to be made
2019-04-17 09:13:05,122 [salt.state       :1951][INFO    ][18922] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:13:05.122738 duration_in_ms=28.574
2019-04-17 09:13:05,123 [salt.state       :1780][INFO    ][18922] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:13:05.123568
2019-04-17 09:13:05,123 [salt.state       :1813][INFO    ][18922] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-17 09:13:05,136 [salt.state       :300 ][INFO    ][18922] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-17 09:13:05,137 [salt.state       :1951][INFO    ][18922] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:13:05.136988 duration_in_ms=13.42
2019-04-17 09:13:05,138 [salt.state       :1780][INFO    ][18922] Running state [a2enmod headers] at time 09:13:05.138264
2019-04-17 09:13:05,138 [salt.state       :1813][INFO    ][18922] Executing state cmd.run for [a2enmod headers]
2019-04-17 09:13:05,140 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18922] Executing command 'a2enmod headers' in directory '/root'
2019-04-17 09:13:05,215 [salt.state       :300 ][INFO    ][18922] {'pid': 18942, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-17 09:13:05,216 [salt.state       :1951][INFO    ][18922] Completed state [a2enmod headers] at time 09:13:05.216197 duration_in_ms=77.932
2019-04-17 09:13:05,216 [salt.state       :1780][INFO    ][18922] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:13:05.216877
2019-04-17 09:13:05,217 [salt.state       :1813][INFO    ][18922] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-17 09:13:05,242 [salt.state       :300 ][INFO    ][18922] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-17 09:13:05,243 [salt.state       :1951][INFO    ][18922] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:13:05.243666 duration_in_ms=26.79
2019-04-17 09:13:05,244 [salt.state       :1780][INFO    ][18922] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:13:05.244578
2019-04-17 09:13:05,245 [salt.state       :1813][INFO    ][18922] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-17 09:13:05,308 [salt.state       :300 ][INFO    ][18922] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-17 09:13:05,309 [salt.state       :1951][INFO    ][18922] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:13:05.309254 duration_in_ms=64.676
2019-04-17 09:13:05,310 [salt.state       :1780][INFO    ][18922] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:13:05.310311
2019-04-17 09:13:05,310 [salt.state       :1813][INFO    ][18922] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-17 09:13:05,372 [salt.state       :300 ][INFO    ][18922] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-17 09:13:05,373 [salt.state       :1951][INFO    ][18922] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:13:05.373152 duration_in_ms=62.841
2019-04-17 09:13:05,373 [salt.state       :1780][INFO    ][18922] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:13:05.373855
2019-04-17 09:13:05,374 [salt.state       :1813][INFO    ][18922] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-17 09:13:05,445 [salt.state       :300 ][INFO    ][18922] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-17 09:13:05,445 [salt.state       :1951][INFO    ][18922] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:13:05.445268 duration_in_ms=71.412
2019-04-17 09:13:05,445 [salt.state       :1780][INFO    ][18922] Running state [/root/.pgpass] at time 09:13:05.445586
2019-04-17 09:13:05,445 [salt.state       :1813][INFO    ][18922] Executing state file.managed for [/root/.pgpass]
2019-04-17 09:13:05,500 [salt.state       :300 ][INFO    ][18922] File /root/.pgpass is in the correct state
2019-04-17 09:13:05,501 [salt.state       :1951][INFO    ][18922] Completed state [/root/.pgpass] at time 09:13:05.501153 duration_in_ms=55.567
2019-04-17 09:13:05,506 [salt.state       :1780][INFO    ][18922] Running state [maas-region syncdb --noinput] at time 09:13:05.506487
2019-04-17 09:13:05,506 [salt.state       :1813][INFO    ][18922] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-17 09:13:05,507 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18922] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-17 09:13:08,181 [salt.state       :300 ][INFO    ][18922] {'pid': 18956, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: metadataserver, auth, sessions, piston3, contenttypes, maasserver, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-17 09:13:08,182 [salt.state       :1951][INFO    ][18922] Completed state [maas-region syncdb --noinput] at time 09:13:08.182721 duration_in_ms=2676.234
2019-04-17 09:13:08,183 [salt.state       :2022][WARNING ][18922] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-17 09:13:08,188 [salt.state       :1780][INFO    ][18922] Running state [maas-regiond] at time 09:13:08.188221
2019-04-17 09:13:08,188 [salt.state       :1813][INFO    ][18922] Executing state service.running for [maas-regiond]
2019-04-17 09:13:08,189 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18922] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-17 09:13:08,227 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18922] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-17 09:13:08,239 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18922] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-17 09:13:08,251 [salt.state       :300 ][INFO    ][18922] The service maas-regiond is already running
2019-04-17 09:13:08,252 [salt.state       :1951][INFO    ][18922] Completed state [maas-regiond] at time 09:13:08.252251 duration_in_ms=64.03
2019-04-17 09:13:08,254 [salt.state       :1780][INFO    ][18922] Running state [bind9] at time 09:13:08.254119
2019-04-17 09:13:08,254 [salt.state       :1813][INFO    ][18922] Executing state service.running for [bind9]
2019-04-17 09:13:08,256 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18922] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-17 09:13:08,274 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18922] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-17 09:13:08,291 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18922] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-17 09:13:08,311 [salt.state       :300 ][INFO    ][18922] The service bind9 is already running
2019-04-17 09:13:08,312 [salt.state       :1951][INFO    ][18922] Completed state [bind9] at time 09:13:08.312188 duration_in_ms=58.069
2019-04-17 09:13:08,314 [salt.state       :1780][INFO    ][18922] Running state [apache2] at time 09:13:08.313977
2019-04-17 09:13:08,314 [salt.state       :1813][INFO    ][18922] Executing state service.running for [apache2]
2019-04-17 09:13:08,315 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18922] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-17 09:13:08,337 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18922] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-17 09:13:08,358 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18922] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-17 09:13:08,384 [salt.state       :300 ][INFO    ][18922] The service apache2 is already running
2019-04-17 09:13:08,385 [salt.state       :1951][INFO    ][18922] Completed state [apache2] at time 09:13:08.385084 duration_in_ms=71.106
2019-04-17 09:13:08,387 [salt.state       :1780][INFO    ][18922] Running state [maasng.wait_for_http_code] at time 09:13:08.387020
2019-04-17 09:13:08,387 [salt.state       :1813][INFO    ][18922] Executing state module.run for [maasng.wait_for_http_code]
2019-04-17 09:13:08,388 [salt.utils.decorators:613 ][WARNING ][18922] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 09:13:08,510 [salt.state       :300 ][INFO    ][18922] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-17 09:13:08,512 [salt.state       :1951][INFO    ][18922] Completed state [maasng.wait_for_http_code] at time 09:13:08.512342 duration_in_ms=125.321
2019-04-17 09:13:08,513 [salt.state       :1780][INFO    ][18922] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:13:08.513746
2019-04-17 09:13:08,514 [salt.state       :1813][INFO    ][18922] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-17 09:13:08,515 [salt.state       :300 ][INFO    ][18922] /var/lib/maas/.setup_admin exists
2019-04-17 09:13:08,515 [salt.state       :1951][INFO    ][18922] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:13:08.515848 duration_in_ms=2.103
2019-04-17 09:13:08,517 [salt.state       :1780][INFO    ][18922] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:13:08.517072
2019-04-17 09:13:08,517 [salt.state       :1813][INFO    ][18922] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-17 09:13:08,518 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18922] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-17 09:13:10,425 [salt.state       :300 ][INFO    ][18922] {'pid': 18978, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-17 09:13:10,425 [salt.state       :1951][INFO    ][18922] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:13:10.425691 duration_in_ms=1908.618
2019-04-17 09:13:10,433 [salt.state       :1780][INFO    ][18922] Running state [maas_region_boot_source_resources_mirror] at time 09:13:10.433389
2019-04-17 09:13:10,433 [salt.state       :1813][INFO    ][18922] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-17 09:13:10,498 [salt.state       :300 ][INFO    ][18922] {'changes': {}}
2019-04-17 09:13:10,498 [salt.state       :1951][INFO    ][18922] Completed state [maas_region_boot_source_resources_mirror] at time 09:13:10.498407 duration_in_ms=65.017
2019-04-17 09:13:10,499 [salt.state       :1780][INFO    ][18922] Running state [maasng.boot_resources_import] at time 09:13:10.499676
2019-04-17 09:13:10,500 [salt.state       :1813][INFO    ][18922] Executing state module.run for [maasng.boot_resources_import]
2019-04-17 09:13:10,500 [salt.utils.decorators:613 ][WARNING ][18922] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 09:13:10,569 [salt.loaded.ext.module.maasng:1600][INFO    ][18922] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-17 09:13:15,608 [salt.loaded.ext.module.maasng:1600][INFO    ][18922] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-17 09:13:17,684 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091317674662
2019-04-17 09:13:17,702 [salt.minion      :1432][INFO    ][19043] Starting a new job with PID 19043
2019-04-17 09:13:17,733 [salt.minion      :1711][INFO    ][19043] Returning information for job: 20190417091317674662
2019-04-17 09:13:20,701 [salt.state       :300 ][INFO    ][18922] {'ret': True}
2019-04-17 09:13:20,701 [salt.state       :1951][INFO    ][18922] Completed state [maasng.boot_resources_import] at time 09:13:20.701525 duration_in_ms=10201.848
2019-04-17 09:13:20,703 [salt.state       :1780][INFO    ][18922] Running state [maas_region_boot_sources_selection_xenial] at time 09:13:20.702798
2019-04-17 09:13:20,704 [salt.state       :1813][INFO    ][18922] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-17 09:13:20,868 [salt.state       :300 ][INFO    ][18922] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-17 09:13:20,868 [salt.state       :1951][INFO    ][18922] Completed state [maas_region_boot_sources_selection_xenial] at time 09:13:20.868733 duration_in_ms=165.934
2019-04-17 09:13:20,870 [salt.state       :1780][INFO    ][18922] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 09:13:20.870155
2019-04-17 09:13:20,870 [salt.state       :1813][INFO    ][18922] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-17 09:13:20,872 [salt.utils.decorators:613 ][WARNING ][18922] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 09:13:20,872 [salt.loaded.ext.module.maasng:1771][INFO    ][18922] boot-sources sync initiated for ALL Rack's
2019-04-17 09:13:21,830 [salt.state       :300 ][INFO    ][18922] {'ret': True}
2019-04-17 09:13:21,830 [salt.state       :1951][INFO    ][18922] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 09:13:21.830437 duration_in_ms=960.282
2019-04-17 09:13:21,833 [salt.state       :1780][INFO    ][18922] Running state [maas.process_maas_config] at time 09:13:21.833452
2019-04-17 09:13:21,834 [salt.state       :1813][INFO    ][18922] Executing state module.run for [maas.process_maas_config]
2019-04-17 09:13:21,834 [salt.utils.decorators:613 ][WARNING ][18922] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 09:13:21,835 [salt.loaded.ext.module.maas:92  ][INFO    ][18922] maasconfig name=enable_http_proxy value=True
2019-04-17 09:13:21,887 [salt.loaded.ext.module.maas:92  ][INFO    ][18922] maasconfig name=upstream_dns value=8.8.8.8
2019-04-17 09:13:21,936 [salt.loaded.ext.module.maas:92  ][INFO    ][18922] maasconfig name=commissioning_distro_series value=xenial
2019-04-17 09:13:21,993 [salt.loaded.ext.module.maas:92  ][INFO    ][18922] maasconfig name=default_osystem value=ubuntu
2019-04-17 09:13:28,574 [salt.loaded.ext.module.maas:92  ][INFO    ][18922] maasconfig name=active_discovery_interval value=600
2019-04-17 09:13:28,620 [salt.loaded.ext.module.maas:92  ][INFO    ][18922] maasconfig name=dnssec_validation value=no
2019-04-17 09:13:28,657 [salt.loaded.ext.module.maas:92  ][INFO    ][18922] maasconfig name=maas_name value=mas01
2019-04-17 09:13:28,697 [salt.loaded.ext.module.maas:92  ][INFO    ][18922] maasconfig name=network_discovery value=enabled
2019-04-17 09:13:28,793 [salt.loaded.ext.module.maas:92  ][INFO    ][18922] maasconfig name=enable_third_party_drivers value=True
2019-04-17 09:13:28,838 [salt.loaded.ext.module.maas:92  ][INFO    ][18922] maasconfig name=default_storage_layout value=lvm
2019-04-17 09:13:28,880 [salt.loaded.ext.module.maas:92  ][INFO    ][18922] maasconfig name=ntp_external_only value=True
2019-04-17 09:13:28,939 [salt.loaded.ext.module.maas:92  ][INFO    ][18922] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-17 09:13:28,984 [salt.loaded.ext.module.maas:92  ][INFO    ][18922] maasconfig name=default_distro_series value=xenial
2019-04-17 09:13:29,034 [salt.loaded.ext.module.maas:92  ][INFO    ][18922] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-17 09:13:29,142 [salt.state       :300 ][INFO    ][18922] {'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-04-17 09:13:29,142 [salt.state       :1951][INFO    ][18922] Completed state [maas.process_maas_config] at time 09:13:29.142751 duration_in_ms=7309.299
2019-04-17 09:13:29,143 [salt.state       :1780][INFO    ][18922] Running state [pxe_admin] at time 09:13:29.143820
2019-04-17 09:13:29,144 [salt.state       :1813][INFO    ][18922] Executing state maasng.fabric_present for [pxe_admin]
2019-04-17 09:13:29,193 [salt.loaded.ext.module.maasng:945 ][INFO    ][18922] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'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'fabric': u'fabric-2'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pqrf3k', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-04-17 09:13:29,248 [salt.loaded.ext.module.maasng:1008][WARNING ][18922] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-17 09:13:29,249 [salt.loaded.ext.module.maasng:1011][WARNING ][18922] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-17 09:13:29,309 [salt.state       :300 ][INFO    ][18922] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-17 09:13:29,310 [salt.state       :1951][INFO    ][18922] Completed state [pxe_admin] at time 09:13:29.310049 duration_in_ms=166.229
2019-04-17 09:13:29,310 [salt.state       :1780][INFO    ][18922] Running state [vlan 0] at time 09:13:29.310448
2019-04-17 09:13:29,310 [salt.state       :1813][INFO    ][18922] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-17 09:13:29,371 [salt.loaded.ext.module.maasng:945 ][INFO    ][18922] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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'primary_rack': u'pqrf3k', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-17 09:13:29,457 [salt.loaded.ext.module.maasng:945 ][INFO    ][18922] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pqrf3k', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-04-17 09:13:29,665 [salt.loaded.ext.module.maasng:945 ][INFO    ][18922] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'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'fabric': u'fabric-2'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pqrf3k', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-04-17 09:13:29,736 [salt.state       :300 ][INFO    ][18922] {'new': 'Vlan untagged was updated'}
2019-04-17 09:13:29,737 [salt.state       :1951][INFO    ][18922] Completed state [vlan 0] at time 09:13:29.737006 duration_in_ms=426.557
2019-04-17 09:13:29,738 [salt.state       :1780][INFO    ][18922] Running state [192.168.11.0/24] at time 09:13:29.738178
2019-04-17 09:13:29,738 [salt.state       :1813][INFO    ][18922] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-17 09:13:29,918 [salt.loaded.ext.module.maasng:945 ][INFO    ][18922] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'pqrf3k', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-17 09:13:29,920 [salt.loaded.ext.module.maasng:1235][WARNING ][18922] Ignoring parameter vlan:0
2019-04-17 09:13:29,983 [salt.state       :300 ][INFO    ][18922] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-17 09:13:29,984 [salt.state       :1951][INFO    ][18922] Completed state [192.168.11.0/24] at time 09:13:29.984185 duration_in_ms=246.006
2019-04-17 09:13:29,985 [salt.state       :1780][INFO    ][18922] Running state [maas_create_iprange_1] at time 09:13:29.985020
2019-04-17 09:13:29,985 [salt.state       :1813][INFO    ][18922] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-17 09:13:30,026 [salt.state       :300 ][INFO    ][18922] Iprange maas_create_iprange_1 already exist.
2019-04-17 09:13:30,028 [salt.state       :1951][INFO    ][18922] Completed state [maas_create_iprange_1] at time 09:13:30.028612 duration_in_ms=43.592
2019-04-17 09:13:30,028 [salt.state       :1780][INFO    ][18922] Running state [vlan 0] at time 09:13:30.028927
2019-04-17 09:13:30,029 [salt.state       :1813][INFO    ][18922] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-17 09:13:30,071 [salt.loaded.ext.module.maasng:945 ][INFO    ][18922] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pqrf3k', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-17 09:13:30,158 [salt.loaded.ext.module.maasng:945 ][INFO    ][18922] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'pqrf3k', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-17 09:13:30,364 [salt.loaded.ext.module.maasng:945 ][INFO    ][18922] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pqrf3k', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-17 09:13:30,452 [salt.state       :300 ][INFO    ][18922] {'new': 'Vlan untagged was updated'}
2019-04-17 09:13:30,453 [salt.state       :1951][INFO    ][18922] Completed state [vlan 0] at time 09:13:30.453325 duration_in_ms=424.398
2019-04-17 09:13:30,454 [salt.state       :1780][INFO    ][18922] Running state [opnfv] at time 09:13:30.454076
2019-04-17 09:13:30,454 [salt.state       :1813][INFO    ][18922] Executing state maasng.sshkey_present for [opnfv]
2019-04-17 09:13:30,500 [salt.loaded.ext.module.maasng:1903][INFO    ][18922] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-04-17 09:13:30,501 [salt.state       :300 ][INFO    ][18922] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-04-17 09:13:30,501 [salt.state       :1951][INFO    ][18922] Completed state [opnfv] at time 09:13:30.501584 duration_in_ms=47.507
2019-04-17 09:13:30,502 [salt.state       :1780][INFO    ][18922] Running state [maas.process_tags] at time 09:13:30.502296
2019-04-17 09:13:30,502 [salt.state       :1813][INFO    ][18922] Executing state module.run for [maas.process_tags]
2019-04-17 09:13:30,503 [salt.utils.decorators:613 ][WARNING ][18922] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 09:13:30,560 [salt.loaded.ext.module.maas:92  ][INFO    ][18922] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-04-17 09:13:30,626 [salt.state       :300 ][INFO    ][18922] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-04-17 09:13:30,626 [salt.state       :1951][INFO    ][18922] Completed state [maas.process_tags] at time 09:13:30.626738 duration_in_ms=124.441
2019-04-17 09:13:30,631 [salt.minion      :1711][INFO    ][18922] Returning information for job: 20190417091302571624
2019-04-17 09:13:31,393 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command state.apply with jid 20190417091331386234
2019-04-17 09:13:31,412 [salt.minion      :1432][INFO    ][19412] Starting a new job with PID 19412
2019-04-17 09:13:37,244 [salt.state       :915 ][INFO    ][19412] Loading fresh modules for state activity
2019-04-17 09:13:37,347 [salt.state       :1780][INFO    ][19412] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:13:37.347617
2019-04-17 09:13:37,348 [salt.state       :1813][INFO    ][19412] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-17 09:13:37,349 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19412] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-17 09:13:39,185 [salt.state       :300 ][INFO    ][19412] {'pid': 19436, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-17 09:13:39,186 [salt.state       :1951][INFO    ][19412] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:13:39.186407 duration_in_ms=1838.789
2019-04-17 09:13:39,189 [salt.state       :1780][INFO    ][19412] Running state [maas.process_machines] at time 09:13:39.189604
2019-04-17 09:13:39,190 [salt.state       :1813][INFO    ][19412] Executing state module.run for [maas.process_machines]
2019-04-17 09:13:39,191 [salt.utils.decorators:613 ][WARNING ][19412] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 09:13:39,724 [salt.loaded.ext.module.maas:412 ][WARNING ][19412] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-17 09:13:39,725 [salt.loaded.ext.module.maas:92  ][INFO    ][19412] 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=x3aptm architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-17 09:13:40,618 [salt.loaded.ext.module.maas:412 ][WARNING ][19412] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-17 09:13:40,619 [salt.loaded.ext.module.maas:92  ][INFO    ][19412] 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=8ydwhy architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-17 09:13:41,501 [salt.loaded.ext.module.maas:412 ][WARNING ][19412] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-17 09:13:41,502 [salt.loaded.ext.module.maas:92  ][INFO    ][19412] 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=qh8cnp architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-17 09:13:42,413 [salt.loaded.ext.module.maas:412 ][WARNING ][19412] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-17 09:13:42,413 [salt.loaded.ext.module.maas:92  ][INFO    ][19412] 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=bk4xg8 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-17 09:13:43,287 [salt.loaded.ext.module.maas:412 ][WARNING ][19412] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-17 09:13:43,288 [salt.loaded.ext.module.maas:92  ][INFO    ][19412] 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=s7mt44 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-17 09:13:44,208 [salt.state       :300 ][INFO    ][19412] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-17 09:13:44,208 [salt.state       :1951][INFO    ][19412] Completed state [maas.process_machines] at time 09:13:44.208601 duration_in_ms=5018.997
2019-04-17 09:13:44,212 [salt.minion      :1711][INFO    ][19412] Returning information for job: 20190417091331386234
2019-04-17 09:14:18,068 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command state.apply with jid 20190417091418058723
2019-04-17 09:14:18,086 [salt.minion      :1432][INFO    ][19728] Starting a new job with PID 19728
2019-04-17 09:14:23,891 [salt.state       :915 ][INFO    ][19728] Loading fresh modules for state activity
2019-04-17 09:14:23,985 [salt.state       :1780][INFO    ][19728] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:14:23.984921
2019-04-17 09:14:23,985 [salt.state       :1813][INFO    ][19728] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-17 09:14:23,987 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19728] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-17 09:14:25,839 [salt.state       :300 ][INFO    ][19728] {'pid': 19735, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-17 09:14:25,840 [salt.state       :1951][INFO    ][19728] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:14:25.840727 duration_in_ms=1855.804
2019-04-17 09:14:25,845 [salt.state       :1780][INFO    ][19728] Running state [maas.wait_for_machine_status] at time 09:14:25.844884
2019-04-17 09:14:25,845 [salt.state       :1813][INFO    ][19728] Executing state module.run for [maas.wait_for_machine_status]
2019-04-17 09:14:25,846 [salt.utils.decorators:613 ][WARNING ][19728] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 09:14:28,523 [salt.state       :300 ][INFO    ][19728] {'ret': True}
2019-04-17 09:14:28,523 [salt.state       :1951][INFO    ][19728] Completed state [maas.wait_for_machine_status] at time 09:14:28.523536 duration_in_ms=2678.652
2019-04-17 09:14:28,529 [salt.minion      :1711][INFO    ][19728] Returning information for job: 20190417091418058723
2019-04-17 09:14:29,215 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command state.apply with jid 20190417091429201343
2019-04-17 09:14:29,238 [salt.minion      :1432][INFO    ][19749] Starting a new job with PID 19749
2019-04-17 09:14:30,172 [salt.state       :915 ][INFO    ][19749] Loading fresh modules for state activity
2019-04-17 09:14:30,314 [salt.state       :1780][INFO    ][19749] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:14:30.314086
2019-04-17 09:14:30,314 [salt.state       :1813][INFO    ][19749] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-17 09:14:30,317 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19749] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-17 09:14:32,148 [salt.state       :300 ][INFO    ][19749] {'pid': 19756, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-17 09:14:32,149 [salt.state       :1951][INFO    ][19749] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:14:32.149103 duration_in_ms=1835.016
2019-04-17 09:14:32,153 [salt.state       :1780][INFO    ][19749] Running state [maas_machines_storage_cmp002_lvm] at time 09:14:32.153617
2019-04-17 09:14:32,154 [salt.state       :1813][INFO    ][19749] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-17 09:14:32,704 [salt.state       :300 ][INFO    ][19749] Machine cmp002 is not in Ready state.
2019-04-17 09:14:32,704 [salt.state       :1951][INFO    ][19749] Completed state [maas_machines_storage_cmp002_lvm] at time 09:14:32.704702 duration_in_ms=551.085
2019-04-17 09:14:32,705 [salt.state       :1780][INFO    ][19749] Running state [maas_machines_storage_cmp001_lvm] at time 09:14:32.705094
2019-04-17 09:14:32,705 [salt.state       :1813][INFO    ][19749] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-17 09:14:33,235 [salt.state       :300 ][INFO    ][19749] Machine cmp001 is not in Ready state.
2019-04-17 09:14:33,235 [salt.state       :1951][INFO    ][19749] Completed state [maas_machines_storage_cmp001_lvm] at time 09:14:33.235691 duration_in_ms=530.595
2019-04-17 09:14:33,240 [salt.minion      :1711][INFO    ][19749] Returning information for job: 20190417091429201343
2019-04-17 09:14:33,926 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command state.apply with jid 20190417091433918202
2019-04-17 09:14:33,946 [salt.minion      :1432][INFO    ][19773] Starting a new job with PID 19773
2019-04-17 09:14:34,876 [salt.state       :915 ][INFO    ][19773] Loading fresh modules for state activity
2019-04-17 09:14:34,970 [salt.state       :1780][INFO    ][19773] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:14:34.970606
2019-04-17 09:14:34,971 [salt.state       :1813][INFO    ][19773] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-17 09:14:34,973 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19773] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-17 09:14:36,814 [salt.state       :300 ][INFO    ][19773] {'pid': 19780, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-17 09:14:36,815 [salt.state       :1951][INFO    ][19773] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:14:36.815566 duration_in_ms=1844.959
2019-04-17 09:14:36,818 [salt.state       :1780][INFO    ][19773] Running state [maas.deploy_machines] at time 09:14:36.818602
2019-04-17 09:14:36,819 [salt.state       :1813][INFO    ][19773] Executing state module.run for [maas.deploy_machines]
2019-04-17 09:14:36,820 [salt.utils.decorators:613 ][WARNING ][19773] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 09:14:37,469 [salt.loaded.ext.module.maas:684 ][INFO    ][19773] deploymachines hwe_kernel=hwe-16.04 system_id=s7mt44 distro_series=xenial
2019-04-17 09:14:39,803 [salt.state       :300 ][INFO    ][19773] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-04-17 09:14:39,803 [salt.state       :1951][INFO    ][19773] Completed state [maas.deploy_machines] at time 09:14:39.803760 duration_in_ms=2985.158
2019-04-17 09:14:39,805 [salt.minion      :1711][INFO    ][19773] Returning information for job: 20190417091433918202
2019-04-17 09:14:40,486 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command state.apply with jid 20190417091440475906
2019-04-17 09:14:40,507 [salt.minion      :1432][INFO    ][19839] Starting a new job with PID 19839
2019-04-17 09:14:46,360 [salt.state       :915 ][INFO    ][19839] Loading fresh modules for state activity
2019-04-17 09:14:46,446 [salt.state       :1780][INFO    ][19839] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:14:46.446716
2019-04-17 09:14:46,447 [salt.state       :1813][INFO    ][19839] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-17 09:14:46,449 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19839] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-17 09:14:48,324 [salt.state       :300 ][INFO    ][19839] {'pid': 19869, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-17 09:14:48,325 [salt.state       :1951][INFO    ][19839] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:14:48.325385 duration_in_ms=1878.669
2019-04-17 09:14:48,327 [salt.state       :1780][INFO    ][19839] Running state [maas.wait_for_machine_status] at time 09:14:48.327845
2019-04-17 09:14:48,328 [salt.state       :1813][INFO    ][19839] Executing state module.run for [maas.wait_for_machine_status]
2019-04-17 09:14:48,328 [salt.utils.decorators:613 ][WARNING ][19839] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-17 09:14:50,698 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.64371586s left)
2019-04-17 09:14:55,592 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091455543545
2019-04-17 09:14:55,609 [salt.minion      :1432][INFO    ][19883] Starting a new job with PID 19883
2019-04-17 09:14:55,644 [salt.minion      :1711][INFO    ][19883] Returning information for job: 20190417091455543545
2019-04-17 09:15:23,346 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.9952569s left)
2019-04-17 09:15:25,664 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091525648151
2019-04-17 09:15:25,685 [salt.minion      :1432][INFO    ][19947] Starting a new job with PID 19947
2019-04-17 09:15:25,713 [salt.minion      :1711][INFO    ][19947] Returning information for job: 20190417091525648151
2019-04-17 09:15:55,636 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2182.70525789s left)
2019-04-17 09:15:55,716 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091555709465
2019-04-17 09:15:55,730 [salt.minion      :1432][INFO    ][19983] Starting a new job with PID 19983
2019-04-17 09:15:55,760 [salt.minion      :1711][INFO    ][19983] Returning information for job: 20190417091555709465
2019-04-17 09:16:25,901 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091625893663
2019-04-17 09:16:25,917 [salt.minion      :1432][INFO    ][20043] Starting a new job with PID 20043
2019-04-17 09:16:25,948 [salt.minion      :1711][INFO    ][20043] Returning information for job: 20190417091625893663
2019-04-17 09:16:28,379 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.96223593s left)
2019-04-17 09:16:55,967 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091655951645
2019-04-17 09:16:55,989 [salt.minion      :1432][INFO    ][20078] Starting a new job with PID 20078
2019-04-17 09:16:56,018 [salt.minion      :1711][INFO    ][20078] Returning information for job: 20190417091655951645
2019-04-17 09:17:00,779 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2117.56236291s left)
2019-04-17 09:17:26,034 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091726025661
2019-04-17 09:17:26,048 [salt.minion      :1432][INFO    ][20152] Starting a new job with PID 20152
2019-04-17 09:17:26,076 [salt.minion      :1711][INFO    ][20152] Returning information for job: 20190417091726025661
2019-04-17 09:17:33,526 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2084.8157959s left)
2019-04-17 09:17:56,107 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091756091705
2019-04-17 09:17:56,124 [salt.minion      :1432][INFO    ][20189] Starting a new job with PID 20189
2019-04-17 09:17:56,158 [salt.minion      :1711][INFO    ][20189] Returning information for job: 20190417091756091705
2019-04-17 09:18:05,822 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2052.519418s left)
2019-04-17 09:18:26,177 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091826163483
2019-04-17 09:18:26,195 [salt.minion      :1432][INFO    ][20254] Starting a new job with PID 20254
2019-04-17 09:18:26,224 [salt.minion      :1711][INFO    ][20254] Returning information for job: 20190417091826163483
2019-04-17 09:18:38,441 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2019.90027404s left)
2019-04-17 09:18:56,257 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091856250362
2019-04-17 09:18:56,269 [salt.minion      :1432][INFO    ][20315] Starting a new job with PID 20315
2019-04-17 09:18:56,300 [salt.minion      :1711][INFO    ][20315] Returning information for job: 20190417091856250362
2019-04-17 09:19:10,948 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1987.39295506s left)
2019-04-17 09:19:26,316 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091926300427
2019-04-17 09:19:26,338 [salt.minion      :1432][INFO    ][20411] Starting a new job with PID 20411
2019-04-17 09:19:26,368 [salt.minion      :1711][INFO    ][20411] Returning information for job: 20190417091926300427
2019-04-17 09:19:43,866 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1954.47556186s left)
2019-04-17 09:19:56,418 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417091956402220
2019-04-17 09:19:56,443 [salt.minion      :1432][INFO    ][20475] Starting a new job with PID 20475
2019-04-17 09:19:56,472 [salt.minion      :1711][INFO    ][20475] Returning information for job: 20190417091956402220
2019-04-17 09:20:16,674 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1921.66787505s left)
2019-04-17 09:20:26,534 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417092026523469
2019-04-17 09:20:26,553 [salt.minion      :1432][INFO    ][20654] Starting a new job with PID 20654
2019-04-17 09:20:26,584 [salt.minion      :1711][INFO    ][20654] Returning information for job: 20190417092026523469
2019-04-17 09:20:49,415 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1888.92613602s left)
2019-04-17 09:20:56,633 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417092056620239
2019-04-17 09:20:56,651 [salt.minion      :1432][INFO    ][20703] Starting a new job with PID 20703
2019-04-17 09:20:56,685 [salt.minion      :1711][INFO    ][20703] Returning information for job: 20190417092056620239
2019-04-17 09:21:21,801 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1856.54053497s left)
2019-04-17 09:21:26,661 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417092126647197
2019-04-17 09:21:26,683 [salt.minion      :1432][INFO    ][20785] Starting a new job with PID 20785
2019-04-17 09:21:26,712 [salt.minion      :1711][INFO    ][20785] Returning information for job: 20190417092126647197
2019-04-17 09:21:54,863 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1823.47810197s left)
2019-04-17 09:21:56,797 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417092156783337
2019-04-17 09:21:56,818 [salt.minion      :1432][INFO    ][20845] Starting a new job with PID 20845
2019-04-17 09:21:56,848 [salt.minion      :1711][INFO    ][20845] Returning information for job: 20190417092156783337
2019-04-17 09:22:26,936 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417092226927226
2019-04-17 09:22:26,949 [salt.minion      :1432][INFO    ][20964] Starting a new job with PID 20964
2019-04-17 09:22:26,978 [salt.minion      :1711][INFO    ][20964] Returning information for job: 20190417092226927226
2019-04-17 09:22:27,418 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1790.92368698s left)
2019-04-17 09:22:57,055 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417092257042639
2019-04-17 09:22:57,080 [salt.minion      :1432][INFO    ][21038] Starting a new job with PID 21038
2019-04-17 09:22:57,108 [salt.minion      :1711][INFO    ][21038] Returning information for job: 20190417092257042639
2019-04-17 09:23:00,186 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1758.15532899s left)
2019-04-17 09:23:27,197 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417092327183864
2019-04-17 09:23:27,218 [salt.minion      :1432][INFO    ][21168] Starting a new job with PID 21168
2019-04-17 09:23:27,249 [salt.minion      :1711][INFO    ][21168] Returning information for job: 20190417092327183864
2019-04-17 09:23:32,758 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1725.58354902s left)
2019-04-17 09:23:57,341 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417092357328660
2019-04-17 09:23:57,365 [salt.minion      :1432][INFO    ][21205] Starting a new job with PID 21205
2019-04-17 09:23:57,393 [salt.minion      :1711][INFO    ][21205] Returning information for job: 20190417092357328660
2019-04-17 09:24:05,506 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1692.83546495s left)
2019-04-17 09:24:27,499 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417092427485695
2019-04-17 09:24:27,524 [salt.minion      :1432][INFO    ][21272] Starting a new job with PID 21272
2019-04-17 09:24:27,554 [salt.minion      :1711][INFO    ][21272] Returning information for job: 20190417092427485695
2019-04-17 09:24:38,094 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1660.24775386s left)
2019-04-17 09:24:57,659 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417092457647639
2019-04-17 09:24:57,679 [salt.minion      :1432][INFO    ][21456] Starting a new job with PID 21456
2019-04-17 09:24:57,708 [salt.minion      :1711][INFO    ][21456] Returning information for job: 20190417092457647639
2019-04-17 09:25:10,762 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1627.57908988s left)
2019-04-17 09:25:27,823 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417092527816524
2019-04-17 09:25:27,840 [salt.minion      :1432][INFO    ][21522] Starting a new job with PID 21522
2019-04-17 09:25:27,870 [salt.minion      :1711][INFO    ][21522] Returning information for job: 20190417092527816524
2019-04-17 09:25:43,247 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1595.09405804s left)
2019-04-17 09:25:57,978 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417092557964202
2019-04-17 09:25:58,001 [salt.minion      :1432][INFO    ][21559] Starting a new job with PID 21559
2019-04-17 09:25:58,030 [salt.minion      :1711][INFO    ][21559] Returning information for job: 20190417092557964202
2019-04-17 09:26:16,078 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1562.26348686s left)
2019-04-17 09:26:28,169 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417092628153767
2019-04-17 09:26:28,188 [salt.minion      :1432][INFO    ][21628] Starting a new job with PID 21628
2019-04-17 09:26:28,220 [salt.minion      :1711][INFO    ][21628] Returning information for job: 20190417092628153767
2019-04-17 09:26:48,721 [salt.loaded.ext.module.maas:1023][INFO    ][19839] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1529.62041187s left)
2019-04-17 09:26:58,345 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command saltutil.find_job with jid 20190417092658332541
2019-04-17 09:26:58,363 [salt.minion      :1432][INFO    ][21700] Starting a new job with PID 21700
2019-04-17 09:26:58,393 [salt.minion      :1711][INFO    ][21700] Returning information for job: 20190417092658332541
2019-04-17 09:27:14,935 [salt.utils.schedule:1377][INFO    ][9657] Running scheduled job: __mine_interval
2019-04-17 09:27:21,740 [salt.state       :300 ][INFO    ][19839] {'ret': True}
2019-04-17 09:27:21,741 [salt.state       :1951][INFO    ][19839] Completed state [maas.wait_for_machine_status] at time 09:27:21.741376 duration_in_ms=753413.525
2019-04-17 09:27:21,746 [salt.minion      :1711][INFO    ][19839] Returning information for job: 20190417091440475906
2019-04-17 10:27:14,933 [salt.utils.schedule:1377][INFO    ][9657] Running scheduled job: __mine_interval
2019-04-17 10:58:29,406 [salt.minion      :1308][INFO    ][9657] User sudo_ubuntu Executing command cp.push_dir with jid 20190417105829397248
2019-04-17 10:58:29,421 [salt.minion      :1432][INFO    ][31109] Starting a new job with PID 31109
