2019-05-09 12:35:18,957 [salt.utils.decorators:613 ][WARNING ][1946] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 12:35:19,933 [salt.utils.decorators:613 ][WARNING ][1946] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 12:35:22,916 [salt.loaded.int.states.file:2298][WARNING ][2563] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-09 12:35:54,499 [salt.state       :2022][WARNING ][3138] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-09 12:35:57,589 [salt.utils.decorators:613 ][WARNING ][3138] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 12:36:10,312 [salt.utils.decorators:613 ][WARNING ][3138] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 12:36:32,191 [salt.utils.decorators:613 ][WARNING ][3138] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 12:36:33,079 [salt.utils.decorators:613 ][WARNING ][3138] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 12:36:35,343 [salt.loaded.ext.module.maasng:1008][WARNING ][3138] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-05-09 12:36:35,343 [salt.loaded.ext.module.maasng:1011][WARNING ][3138] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-05-09 12:36:35,988 [salt.loaded.ext.module.maasng:1235][WARNING ][3138] Ignoring parameter vlan:0
2019-05-09 12:36:36,775 [salt.utils.decorators:613 ][WARNING ][3138] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 12:36:45,750 [salt.utils.decorators:613 ][WARNING ][5605] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 12:36:45,820 [salt.loaded.ext.module.maas:412 ][WARNING ][5605] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 12:36:47,024 [salt.loaded.ext.module.maas:412 ][WARNING ][5605] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 12:36:48,106 [salt.loaded.ext.module.maas:412 ][WARNING ][5605] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 12:36:49,293 [salt.loaded.ext.module.maas:412 ][WARNING ][5605] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 12:36:50,419 [salt.loaded.ext.module.maas:412 ][WARNING ][5605] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 12:36:55,044 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6196] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-09 12:36:55,065 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6196] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-09 12:36:55,119 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-05-09 12:36:56,086 [salt.cli.daemons :293 ][INFO    ][6248] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-05-09 12:36:56,202 [salt.cli.daemons :82  ][INFO    ][6248] Starting up the Salt Minion
2019-05-09 12:36:56,202 [salt.utils.event :1017][INFO    ][6248] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-05-09 12:36:57,211 [salt.minion      :976 ][INFO    ][6248] Creating minion process manager
2019-05-09 12:36:59,018 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6248] Executing command ['date', '+%z'] in directory '/root'
2019-05-09 12:36:59,035 [salt.utils.schedule:568 ][INFO    ][6248] Updating job settings for scheduled job: __mine_interval
2019-05-09 12:36:59,037 [salt.minion      :1108][INFO    ][6248] Added mine.update to scheduler
2019-05-09 12:36:59,041 [salt.minion      :1975][INFO    ][6248] Minion is starting as user 'root'
2019-05-09 12:36:59,053 [salt.minion      :2336][INFO    ][6248] Minion is ready to receive requests!
2019-05-09 12:37:22,640 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command state.apply with jid 20190509123722625356
2019-05-09 12:37:22,662 [salt.minion      :1432][INFO    ][6361] Starting a new job with PID 6361
2019-05-09 12:37:28,604 [salt.state       :915 ][INFO    ][6361] Loading fresh modules for state activity
2019-05-09 12:37:28,655 [salt.fileclient  :1219][INFO    ][6361] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-09 12:37:28,701 [salt.state       :1780][INFO    ][6361] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:37:28.701282
2019-05-09 12:37:28,701 [salt.state       :1813][INFO    ][6361] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 12:37:28,704 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6361] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 12:37:30,509 [salt.state       :300 ][INFO    ][6361] {'pid': 6385, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 12:37:30,510 [salt.state       :1951][INFO    ][6361] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:37:30.510628 duration_in_ms=1809.344
2019-05-09 12:37:30,514 [salt.state       :1780][INFO    ][6361] Running state [maas.wait_for_machine_status] at time 12:37:30.514530
2019-05-09 12:37:30,515 [salt.state       :1813][INFO    ][6361] Executing state module.run for [maas.wait_for_machine_status]
2019-05-09 12:37:30,516 [salt.utils.decorators:613 ][WARNING ][6361] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 12:37:31,700 [salt.loaded.ext.module.maas:1023][INFO    ][6361] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.82552981s left)
2019-05-09 12:37:37,672 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509123737663963
2019-05-09 12:37:37,693 [salt.minion      :1432][INFO    ][6397] Starting a new job with PID 6397
2019-05-09 12:37:37,716 [salt.minion      :1711][INFO    ][6397] Returning information for job: 20190509123737663963
2019-05-09 12:38:02,366 [salt.loaded.ext.module.maas:1023][INFO    ][6361] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.15882277s left)
2019-05-09 12:38:07,732 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509123807716552
2019-05-09 12:38:07,751 [salt.minion      :1432][INFO    ][6475] Starting a new job with PID 6475
2019-05-09 12:38:07,773 [salt.minion      :1711][INFO    ][6475] Returning information for job: 20190509123807716552
2019-05-09 12:38:33,226 [salt.loaded.ext.module.maas:1023][INFO    ][6361] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.29960179s left)
2019-05-09 12:38:37,775 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509123837760729
2019-05-09 12:38:37,792 [salt.minion      :1432][INFO    ][6499] Starting a new job with PID 6499
2019-05-09 12:38:37,815 [salt.minion      :1711][INFO    ][6499] Returning information for job: 20190509123837760729
2019-05-09 12:39:04,032 [salt.loaded.ext.module.maas:1023][INFO    ][6361] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.49330378s left)
2019-05-09 12:39:07,854 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509123907836947
2019-05-09 12:39:07,872 [salt.minion      :1432][INFO    ][6552] Starting a new job with PID 6552
2019-05-09 12:39:07,894 [salt.minion      :1711][INFO    ][6552] Returning information for job: 20190509123907836947
2019-05-09 12:39:35,088 [salt.loaded.ext.module.maas:1023][INFO    ][6361] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.437778s left)
2019-05-09 12:39:37,929 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509123937923047
2019-05-09 12:39:37,952 [salt.minion      :1432][INFO    ][6610] Starting a new job with PID 6610
2019-05-09 12:39:37,972 [salt.minion      :1711][INFO    ][6610] Returning information for job: 20190509123937923047
2019-05-09 12:40:06,334 [salt.loaded.ext.module.maas:1023][INFO    ][6361] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.19085479s left)
2019-05-09 12:40:08,047 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124008028906
2019-05-09 12:40:08,075 [salt.minion      :1432][INFO    ][6772] Starting a new job with PID 6772
2019-05-09 12:40:08,100 [salt.minion      :1711][INFO    ][6772] Returning information for job: 20190509124008028906
2019-05-09 12:40:37,532 [salt.loaded.ext.module.maas:1023][INFO    ][6361] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.99327397s left)
2019-05-09 12:40:38,153 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124038136214
2019-05-09 12:40:38,182 [salt.minion      :1432][INFO    ][6896] Starting a new job with PID 6896
2019-05-09 12:40:38,205 [salt.minion      :1711][INFO    ][6896] Returning information for job: 20190509124038136214
2019-05-09 12:41:08,287 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124108278498
2019-05-09 12:41:08,304 [salt.minion      :1432][INFO    ][7161] Starting a new job with PID 7161
2019-05-09 12:41:08,326 [salt.minion      :1711][INFO    ][7161] Returning information for job: 20190509124108278498
2019-05-09 12:41:08,919 [salt.loaded.ext.module.maas:1023][INFO    ][6361] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.60642791s left)
2019-05-09 12:41:38,369 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124138350468
2019-05-09 12:41:38,393 [salt.minion      :1432][INFO    ][7290] Starting a new job with PID 7290
2019-05-09 12:41:38,415 [salt.minion      :1711][INFO    ][7290] Returning information for job: 20190509124138350468
2019-05-09 12:41:40,715 [salt.loaded.ext.module.maas:1023][INFO    ][6361] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.80992198s left)
2019-05-09 12:42:08,515 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124208500313
2019-05-09 12:42:08,543 [salt.minion      :1432][INFO    ][7569] Starting a new job with PID 7569
2019-05-09 12:42:08,566 [salt.minion      :1711][INFO    ][7569] Returning information for job: 20190509124208500313
2019-05-09 12:42:12,750 [salt.loaded.ext.module.maas:1023][INFO    ][6361] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.77496099s left)
2019-05-09 12:42:38,641 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124238627155
2019-05-09 12:42:38,664 [salt.minion      :1432][INFO    ][7827] Starting a new job with PID 7827
2019-05-09 12:42:38,689 [salt.minion      :1711][INFO    ][7827] Returning information for job: 20190509124238627155
2019-05-09 12:42:45,014 [salt.loaded.ext.module.maas:1023][INFO    ][6361] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.51100779s left)
2019-05-09 12:43:08,780 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124308764132
2019-05-09 12:43:08,814 [salt.minion      :1432][INFO    ][7961] Starting a new job with PID 7961
2019-05-09 12:43:08,836 [salt.minion      :1711][INFO    ][7961] Returning information for job: 20190509124308764132
2019-05-09 12:43:17,127 [salt.loaded.ext.module.maas:1023][INFO    ][6361] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1153.39817691s left)
2019-05-09 12:43:38,898 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124338885383
2019-05-09 12:43:38,928 [salt.minion      :1432][INFO    ][8115] Starting a new job with PID 8115
2019-05-09 12:43:38,953 [salt.minion      :1711][INFO    ][8115] Returning information for job: 20190509124338885383
2019-05-09 12:43:50,266 [salt.state       :300 ][INFO    ][6361] {'ret': True}
2019-05-09 12:43:50,266 [salt.state       :1951][INFO    ][6361] Completed state [maas.wait_for_machine_status] at time 12:43:50.266591 duration_in_ms=379752.06
2019-05-09 12:43:50,272 [salt.minion      :1711][INFO    ][6361] Returning information for job: 20190509123722625356
2019-05-09 12:43:50,825 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command state.apply with jid 20190509124350814458
2019-05-09 12:43:50,850 [salt.minion      :1432][INFO    ][8165] Starting a new job with PID 8165
2019-05-09 12:43:56,752 [salt.state       :915 ][INFO    ][8165] Loading fresh modules for state activity
2019-05-09 12:43:56,812 [salt.fileclient  :1219][INFO    ][8165] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-09 12:43:56,907 [salt.state       :1780][INFO    ][8165] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:43:56.907365
2019-05-09 12:43:56,907 [salt.state       :1813][INFO    ][8165] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 12:43:56,909 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8165] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 12:43:58,870 [salt.state       :300 ][INFO    ][8165] {'pid': 8172, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 12:43:58,871 [salt.state       :1951][INFO    ][8165] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:43:58.871710 duration_in_ms=1964.344
2019-05-09 12:43:58,875 [salt.state       :1780][INFO    ][8165] Running state [maas_machines_storage_cmp002_lvm] at time 12:43:58.875526
2019-05-09 12:43:58,876 [salt.state       :1813][INFO    ][8165] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-09 12:44:00,105 [salt.loaded.ext.module.maasng:610 ][INFO    ][8165] pepfsn
2019-05-09 12:44:00,105 [salt.loaded.ext.module.maasng:626 ][INFO    ][8165] sda
2019-05-09 12:44:00,702 [salt.loaded.ext.module.maasng:361 ][INFO    ][8165] pepfsn
2019-05-09 12:44:00,797 [salt.loaded.ext.module.maasng:367 ][INFO    ][8165] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'5bdef2b8-8a4a-4837-a9a2-0782f5bc1986', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pepfsn', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a945496c-d742-4b12-95ff-71d6651d3883', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/blockdevices/1/partition/1'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pepfsn', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'uuid': None}, {u'size': 800097042432, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/blockdevices/3/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pepfsn', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'fe5642ef-c8b7-4647-99af-c35f5a52379d', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'block_size': 4096, u'used_size': 800097042432, u'id': 3, u'serial': None, u'uuid': u'b5704be3-648d-4985-8119-fc608678a726'}]
2019-05-09 12:44:00,798 [salt.loaded.ext.module.maasng:632 ][INFO    ][8165] vgroot
2019-05-09 12:44:00,798 [salt.loaded.ext.module.maasng:635 ][INFO    ][8165] lvroot
2019-05-09 12:44:00,799 [salt.loaded.ext.module.maasng:639 ][INFO    ][8165] 107374182400
2019-05-09 12:44:01,417 [salt.loaded.ext.module.maasng:645 ][INFO    ][8165] {u'hwe_kernel': u'', u'swap_size': None, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gdpwew', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gdpwew', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gdpwew', 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'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'pepfsn', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/interfaces/4/'}, u'disable_ipv4': False, 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'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/blockdevices/1/', u'type': u'physical', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pepfsn', 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'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'f59e3308-5efc-452f-a710-b47e24280421', u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pepfsn', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'uuid': u'2a840556-d462-460a-84f4-d102c6d7ab75'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'pepfsn', 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'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/blockdevices/1/', u'type': u'physical', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pepfsn', 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'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'f59e3308-5efc-452f-a710-b47e24280421', u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pepfsn', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'uuid': u'2a840556-d462-460a-84f4-d102c6d7ab75'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'7fc95ec5-c097-4b1d-bd5d-98a7b0d527af', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/blockdevices/11/', u'type': u'virtual', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pepfsn', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'uuid': u'ac879134-8b35-441a-8209-56c7aaf06b01'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/blockdevices/1/', u'type': u'physical', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pepfsn', 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'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'f59e3308-5efc-452f-a710-b47e24280421', u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pepfsn', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'uuid': u'2a840556-d462-460a-84f4-d102c6d7ab75'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'pepfsn', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': u'7fc95ec5-c097-4b1d-bd5d-98a7b0d527af', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/blockdevices/11/', u'type': u'virtual', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pepfsn', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'uuid': u'ac879134-8b35-441a-8209-56c7aaf06b01'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gdpwew', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gdpwew', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gdpwew', 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'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'pepfsn', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 15, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'pepfsn', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/interfaces/15/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 13, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'pepfsn', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/interfaces/13/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 11, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'pepfsn', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/interfaces/11/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 12, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'pepfsn', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/interfaces/12/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gdpwew', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gdpwew', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 14, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gdpwew', 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'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'pepfsn', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pepfsn/interfaces/14/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/pepfsn/'}
2019-05-09 12:44:01,419 [salt.state       :300 ][INFO    ][8165] {'new': {'storage_layout': 'lvm'}}
2019-05-09 12:44:01,420 [salt.state       :1951][INFO    ][8165] Completed state [maas_machines_storage_cmp002_lvm] at time 12:44:01.420268 duration_in_ms=2544.741
2019-05-09 12:44:01,420 [salt.state       :1780][INFO    ][8165] Running state [maas_machines_storage_cmp001_lvm] at time 12:44:01.420870
2019-05-09 12:44:01,421 [salt.state       :1813][INFO    ][8165] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-09 12:44:02,573 [salt.loaded.ext.module.maasng:610 ][INFO    ][8165] nkwgas
2019-05-09 12:44:02,573 [salt.loaded.ext.module.maasng:626 ][INFO    ][8165] sda
2019-05-09 12:44:03,119 [salt.loaded.ext.module.maasng:361 ][INFO    ][8165] nkwgas
2019-05-09 12:44:03,211 [salt.loaded.ext.module.maasng:367 ][INFO    ][8165] [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'id': 2, u'system_id': u'nkwgas', u'partition_table_type': u'MBR', 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'partitions': [{u'size': 800101236736, u'uuid': u'df94f1e2-54cd-4529-a341-856637de3d87', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nkwgas', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'dbc5dfa9-df11-47d9-9907-5c91811af851', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'device_id': 2}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/blockdevices/2/'}, {u'size': 800097042432, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'17c15e49-0a95-443d-9373-f2370ddd9ed6', u'fstype': u'ext4'}, u'uuid': u'0384f6ca-0507-4d7f-8aa4-3a08ea5bf5d9', u'id': 4, u'system_id': u'nkwgas', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/blockdevices/4/'}]
2019-05-09 12:44:03,211 [salt.loaded.ext.module.maasng:632 ][INFO    ][8165] vgroot
2019-05-09 12:44:03,212 [salt.loaded.ext.module.maasng:635 ][INFO    ][8165] lvroot
2019-05-09 12:44:03,212 [salt.loaded.ext.module.maasng:639 ][INFO    ][8165] 107374182400
2019-05-09 12:44:03,818 [salt.loaded.ext.module.maasng:645 ][INFO    ][8165] {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'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'cpu_count': 40, 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'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'gdpwew', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {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'gdpwew', 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'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'gdpwew', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'nkwgas', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/nkwgas/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'nkwgas', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'0e1e1eb3-725d-4c9c-bed8-861f803bf86d', u'fstype': u'ext4'}, u'uuid': u'18bd3a85-a7a6-4734-921d-fa9d6226080c', u'id': 12, u'system_id': u'nkwgas', 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'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/blockdevices/12/'}], u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'id': 2, u'system_id': u'nkwgas', u'partition_table_type': u'MBR', 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'partitions': [{u'size': 800101236736, u'uuid': u'2c4bcb1b-91cd-4418-9057-8025d690a940', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nkwgas', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e2f96bf1-e97c-47eb-84d4-f93310b1701c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/blockdevices/2/'}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'0e1e1eb3-725d-4c9c-bed8-861f803bf86d', u'fstype': u'ext4'}, u'uuid': u'18bd3a85-a7a6-4734-921d-fa9d6226080c', u'id': 12, u'system_id': u'nkwgas', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/blockdevices/12/'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'nkwgas', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'id': 2, u'system_id': u'nkwgas', u'partition_table_type': u'MBR', 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'partitions': [{u'size': 800101236736, u'uuid': u'2c4bcb1b-91cd-4418-9057-8025d690a940', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nkwgas', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e2f96bf1-e97c-47eb-84d4-f93310b1701c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/blockdevices/2/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'gdpwew', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {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'gdpwew', 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'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'gdpwew', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'nkwgas', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'nkwgas', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/interfaces/17/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 19, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'nkwgas', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/interfaces/19/'}, {u'name': u'ens2f0', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'enabled': True, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'nkwgas', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/interfaces/16/'}, {u'name': u'ens2f1', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'enabled': True, u'effective_mtu': 1500, u'id': 18, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'nkwgas', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/interfaces/18/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gdpwew', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gdpwew', 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'enabled': True, u'effective_mtu': 1500, u'id': 20, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gdpwew', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'nkwgas', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/interfaces/20/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'id': 2, u'system_id': u'nkwgas', u'partition_table_type': u'MBR', 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'partitions': [{u'size': 800101236736, u'uuid': u'2c4bcb1b-91cd-4418-9057-8025d690a940', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nkwgas', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e2f96bf1-e97c-47eb-84d4-f93310b1701c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/nkwgas/blockdevices/2/'}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'min_hwe_kernel': u'hwe-16.04', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-05-09 12:44:03,821 [salt.state       :300 ][INFO    ][8165] {'new': {'storage_layout': 'lvm'}}
2019-05-09 12:44:03,822 [salt.state       :1951][INFO    ][8165] Completed state [maas_machines_storage_cmp001_lvm] at time 12:44:03.822178 duration_in_ms=2401.31
2019-05-09 12:44:03,827 [salt.minion      :1711][INFO    ][8165] Returning information for job: 20190509124350814458
2019-05-09 12:44:04,401 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command state.apply with jid 20190509124404393116
2019-05-09 12:44:04,422 [salt.minion      :1432][INFO    ][8241] Starting a new job with PID 8241
2019-05-09 12:44:05,427 [salt.state       :915 ][INFO    ][8241] Loading fresh modules for state activity
2019-05-09 12:44:05,479 [salt.fileclient  :1219][INFO    ][8241] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-09 12:44:05,530 [salt.state       :1780][INFO    ][8241] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:44:05.530778
2019-05-09 12:44:05,531 [salt.state       :1813][INFO    ][8241] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 12:44:05,532 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8241] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 12:44:07,230 [salt.state       :300 ][INFO    ][8241] {'pid': 8248, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 12:44:07,231 [salt.state       :1951][INFO    ][8241] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:44:07.231341 duration_in_ms=1700.561
2019-05-09 12:44:07,234 [salt.state       :1780][INFO    ][8241] Running state [maas.deploy_machines] at time 12:44:07.234381
2019-05-09 12:44:07,235 [salt.state       :1813][INFO    ][8241] Executing state module.run for [maas.deploy_machines]
2019-05-09 12:44:07,236 [salt.utils.decorators:613 ][WARNING ][8241] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 12:44:07,853 [salt.loaded.ext.module.maas:684 ][INFO    ][8241] deploymachines hwe_kernel=hwe-16.04 system_id=pepfsn distro_series=xenial
2019-05-09 12:44:10,391 [salt.loaded.ext.module.maas:684 ][INFO    ][8241] deploymachines hwe_kernel=hwe-16.04 system_id=nkwgas distro_series=xenial
2019-05-09 12:44:12,906 [salt.loaded.ext.module.maas:684 ][INFO    ][8241] deploymachines hwe_kernel=hwe-16.04 system_id=k68hyx distro_series=xenial
2019-05-09 12:44:15,437 [salt.loaded.ext.module.maas:684 ][INFO    ][8241] deploymachines hwe_kernel=hwe-16.04 system_id=pbtpbn distro_series=xenial
2019-05-09 12:44:17,900 [salt.loaded.ext.module.maas:684 ][INFO    ][8241] deploymachines hwe_kernel=hwe-16.04 system_id=q6eger distro_series=xenial
2019-05-09 12:44:19,516 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124419499792
2019-05-09 12:44:19,540 [salt.minion      :1432][INFO    ][8507] Starting a new job with PID 8507
2019-05-09 12:44:19,564 [salt.minion      :1711][INFO    ][8507] Returning information for job: 20190509124419499792
2019-05-09 12:44:20,537 [salt.state       :300 ][INFO    ][8241] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-09 12:44:20,539 [salt.state       :1951][INFO    ][8241] Completed state [maas.deploy_machines] at time 12:44:20.538956 duration_in_ms=13304.575
2019-05-09 12:44:20,542 [salt.minion      :1711][INFO    ][8241] Returning information for job: 20190509124404393116
2019-05-09 12:44:21,104 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command state.apply with jid 20190509124421092184
2019-05-09 12:44:21,131 [salt.minion      :1432][INFO    ][8524] Starting a new job with PID 8524
2019-05-09 12:44:27,073 [salt.state       :915 ][INFO    ][8524] Loading fresh modules for state activity
2019-05-09 12:44:27,135 [salt.fileclient  :1219][INFO    ][8524] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-09 12:44:27,183 [salt.state       :1780][INFO    ][8524] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:44:27.183321
2019-05-09 12:44:27,183 [salt.state       :1813][INFO    ][8524] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 12:44:27,185 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8524] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 12:44:28,956 [salt.state       :300 ][INFO    ][8524] {'pid': 8545, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 12:44:28,957 [salt.state       :1951][INFO    ][8524] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:44:28.957190 duration_in_ms=1773.868
2019-05-09 12:44:28,962 [salt.state       :1780][INFO    ][8524] Running state [maas.wait_for_machine_status] at time 12:44:28.961657
2019-05-09 12:44:28,962 [salt.state       :1813][INFO    ][8524] Executing state module.run for [maas.wait_for_machine_status]
2019-05-09 12:44:28,963 [salt.utils.decorators:613 ][WARNING ][8524] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 12:44:31,852 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.12276602s left)
2019-05-09 12:44:36,142 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124436128359
2019-05-09 12:44:36,164 [salt.minion      :1432][INFO    ][8568] Starting a new job with PID 8568
2019-05-09 12:44:36,185 [salt.minion      :1711][INFO    ][8568] Returning information for job: 20190509124436128359
2019-05-09 12:45:04,853 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.12202787s left)
2019-05-09 12:45:06,202 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124506190504
2019-05-09 12:45:06,228 [salt.minion      :1432][INFO    ][8635] Starting a new job with PID 8635
2019-05-09 12:45:06,247 [salt.minion      :1711][INFO    ][8635] Returning information for job: 20190509124506190504
2019-05-09 12:45:36,282 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124536271044
2019-05-09 12:45:36,305 [salt.minion      :1432][INFO    ][8669] Starting a new job with PID 8669
2019-05-09 12:45:36,328 [salt.minion      :1711][INFO    ][8669] Returning information for job: 20190509124536271044
2019-05-09 12:45:37,824 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.15059996s left)
2019-05-09 12:46:06,351 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124606335397
2019-05-09 12:46:06,379 [salt.minion      :1432][INFO    ][8738] Starting a new job with PID 8738
2019-05-09 12:46:06,398 [salt.minion      :1711][INFO    ][8738] Returning information for job: 20190509124606335397
2019-05-09 12:46:10,701 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.27446103s left)
2019-05-09 12:46:36,423 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124636410579
2019-05-09 12:46:36,440 [salt.minion      :1432][INFO    ][8766] Starting a new job with PID 8766
2019-05-09 12:46:36,463 [salt.minion      :1711][INFO    ][8766] Returning information for job: 20190509124636410579
2019-05-09 12:46:43,684 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.29097486s left)
2019-05-09 12:47:06,499 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124706485548
2019-05-09 12:47:06,517 [salt.minion      :1432][INFO    ][8837] Starting a new job with PID 8837
2019-05-09 12:47:06,538 [salt.minion      :1711][INFO    ][8837] Returning information for job: 20190509124706485548
2019-05-09 12:47:16,663 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.31175303s left)
2019-05-09 12:47:36,548 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124736532512
2019-05-09 12:47:36,576 [salt.minion      :1432][INFO    ][8912] Starting a new job with PID 8912
2019-05-09 12:47:36,597 [salt.minion      :1711][INFO    ][8912] Returning information for job: 20190509124736532512
2019-05-09 12:47:49,609 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.36656594s left)
2019-05-09 12:48:06,643 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124806627646
2019-05-09 12:48:06,670 [salt.minion      :1432][INFO    ][9056] Starting a new job with PID 9056
2019-05-09 12:48:06,690 [salt.minion      :1711][INFO    ][9056] Returning information for job: 20190509124806627646
2019-05-09 12:48:22,546 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.429564s left)
2019-05-09 12:48:36,739 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124836728325
2019-05-09 12:48:36,766 [salt.minion      :1432][INFO    ][9139] Starting a new job with PID 9139
2019-05-09 12:48:36,788 [salt.minion      :1711][INFO    ][9139] Returning information for job: 20190509124836728325
2019-05-09 12:48:55,329 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.64624286s left)
2019-05-09 12:49:06,860 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124906849420
2019-05-09 12:49:06,883 [salt.minion      :1432][INFO    ][9372] Starting a new job with PID 9372
2019-05-09 12:49:06,905 [salt.minion      :1711][INFO    ][9372] Returning information for job: 20190509124906849420
2019-05-09 12:49:28,518 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.45697403s left)
2019-05-09 12:49:36,980 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509124936963358
2019-05-09 12:49:37,008 [salt.minion      :1432][INFO    ][9497] Starting a new job with PID 9497
2019-05-09 12:49:37,028 [salt.minion      :1711][INFO    ][9497] Returning information for job: 20190509124936963358
2019-05-09 12:50:02,470 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.50519085s left)
2019-05-09 12:50:07,150 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125007136018
2019-05-09 12:50:07,171 [salt.minion      :1432][INFO    ][9783] Starting a new job with PID 9783
2019-05-09 12:50:07,195 [salt.minion      :1711][INFO    ][9783] Returning information for job: 20190509125007136018
2019-05-09 12:50:35,394 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.58070183s left)
2019-05-09 12:50:37,267 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125037256846
2019-05-09 12:50:37,291 [salt.minion      :1432][INFO    ][9939] Starting a new job with PID 9939
2019-05-09 12:50:37,315 [salt.minion      :1711][INFO    ][9939] Returning information for job: 20190509125037256846
2019-05-09 12:51:07,424 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125107415621
2019-05-09 12:51:07,449 [salt.minion      :1432][INFO    ][10266] Starting a new job with PID 10266
2019-05-09 12:51:07,472 [salt.minion      :1711][INFO    ][10266] Returning information for job: 20190509125107415621
2019-05-09 12:51:08,373 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.60230994s left)
2019-05-09 12:51:37,560 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125137545951
2019-05-09 12:51:37,589 [salt.minion      :1432][INFO    ][10392] Starting a new job with PID 10392
2019-05-09 12:51:37,611 [salt.minion      :1711][INFO    ][10392] Returning information for job: 20190509125137545951
2019-05-09 12:51:41,565 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.41005397s left)
2019-05-09 12:52:07,741 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125207723739
2019-05-09 12:52:07,765 [salt.minion      :1432][INFO    ][10590] Starting a new job with PID 10590
2019-05-09 12:52:07,789 [salt.minion      :1711][INFO    ][10590] Returning information for job: 20190509125207723739
2019-05-09 12:52:14,648 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.32693601s left)
2019-05-09 12:52:37,889 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125237875471
2019-05-09 12:52:37,915 [salt.minion      :1432][INFO    ][10698] Starting a new job with PID 10698
2019-05-09 12:52:37,936 [salt.minion      :1711][INFO    ][10698] Returning information for job: 20190509125237875471
2019-05-09 12:52:47,692 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1751.28305197s left)
2019-05-09 12:53:08,073 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125308053342
2019-05-09 12:53:08,104 [salt.minion      :1432][INFO    ][10894] Starting a new job with PID 10894
2019-05-09 12:53:08,128 [salt.minion      :1711][INFO    ][10894] Returning information for job: 20190509125308053342
2019-05-09 12:53:20,656 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1718.31858683s left)
2019-05-09 12:53:38,239 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125338230688
2019-05-09 12:53:38,264 [salt.minion      :1432][INFO    ][10966] Starting a new job with PID 10966
2019-05-09 12:53:38,286 [salt.minion      :1711][INFO    ][10966] Returning information for job: 20190509125338230688
2019-05-09 12:53:53,538 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1685.43761683s left)
2019-05-09 12:54:08,372 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125408365007
2019-05-09 12:54:08,400 [salt.minion      :1432][INFO    ][11126] Starting a new job with PID 11126
2019-05-09 12:54:08,425 [salt.minion      :1711][INFO    ][11126] Returning information for job: 20190509125408365007
2019-05-09 12:54:26,734 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.24079084s left)
2019-05-09 12:54:38,427 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125438412113
2019-05-09 12:54:38,453 [salt.minion      :1432][INFO    ][11218] Starting a new job with PID 11218
2019-05-09 12:54:38,476 [salt.minion      :1711][INFO    ][11218] Returning information for job: 20190509125438412113
2019-05-09 12:55:00,204 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1618.77133584s left)
2019-05-09 12:55:08,621 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125508604607
2019-05-09 12:55:08,651 [salt.minion      :1432][INFO    ][11334] Starting a new job with PID 11334
2019-05-09 12:55:08,673 [salt.minion      :1711][INFO    ][11334] Returning information for job: 20190509125508604607
2019-05-09 12:55:33,064 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1585.91150904s left)
2019-05-09 12:55:38,805 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125538790987
2019-05-09 12:55:38,841 [salt.minion      :1432][INFO    ][11379] Starting a new job with PID 11379
2019-05-09 12:55:38,872 [salt.minion      :1711][INFO    ][11379] Returning information for job: 20190509125538790987
2019-05-09 12:56:06,119 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1552.85594988s left)
2019-05-09 12:56:09,020 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125609008068
2019-05-09 12:56:09,045 [salt.minion      :1432][INFO    ][11577] Starting a new job with PID 11577
2019-05-09 12:56:09,070 [salt.minion      :1711][INFO    ][11577] Returning information for job: 20190509125609008068
2019-05-09 12:56:38,939 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1520.03602386s left)
2019-05-09 12:56:39,220 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125639209592
2019-05-09 12:56:39,245 [salt.minion      :1432][INFO    ][11625] Starting a new job with PID 11625
2019-05-09 12:56:39,265 [salt.minion      :1711][INFO    ][11625] Returning information for job: 20190509125639209592
2019-05-09 12:57:09,249 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125709239615
2019-05-09 12:57:09,275 [salt.minion      :1432][INFO    ][11755] Starting a new job with PID 11755
2019-05-09 12:57:09,301 [salt.minion      :1711][INFO    ][11755] Returning information for job: 20190509125709239615
2019-05-09 12:57:12,008 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1486.96693993s left)
2019-05-09 12:57:39,413 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125739395634
2019-05-09 12:57:39,443 [salt.minion      :1432][INFO    ][11879] Starting a new job with PID 11879
2019-05-09 12:57:39,462 [salt.minion      :1711][INFO    ][11879] Returning information for job: 20190509125739395634
2019-05-09 12:57:44,859 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1454.11623883s left)
2019-05-09 12:58:09,646 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125809629030
2019-05-09 12:58:09,673 [salt.minion      :1432][INFO    ][12033] Starting a new job with PID 12033
2019-05-09 12:58:09,695 [salt.minion      :1711][INFO    ][12033] Returning information for job: 20190509125809629030
2019-05-09 12:58:17,698 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1421.27716303s left)
2019-05-09 12:58:39,680 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125839666457
2019-05-09 12:58:39,708 [salt.minion      :1432][INFO    ][12069] Starting a new job with PID 12069
2019-05-09 12:58:39,730 [salt.minion      :1711][INFO    ][12069] Returning information for job: 20190509125839666457
2019-05-09 12:58:50,634 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1388.34177589s left)
2019-05-09 12:59:09,712 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125909699241
2019-05-09 12:59:09,733 [salt.minion      :1432][INFO    ][12120] Starting a new job with PID 12120
2019-05-09 12:59:09,759 [salt.minion      :1711][INFO    ][12120] Returning information for job: 20190509125909699241
2019-05-09 12:59:23,624 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1355.35103488s left)
2019-05-09 12:59:39,775 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509125939763049
2019-05-09 12:59:39,805 [salt.minion      :1432][INFO    ][12144] Starting a new job with PID 12144
2019-05-09 12:59:39,831 [salt.minion      :1711][INFO    ][12144] Returning information for job: 20190509125939763049
2019-05-09 12:59:56,563 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1322.4121089s left)
2019-05-09 13:00:09,882 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130009863199
2019-05-09 13:00:09,908 [salt.minion      :1432][INFO    ][12206] Starting a new job with PID 12206
2019-05-09 13:00:09,930 [salt.minion      :1711][INFO    ][12206] Returning information for job: 20190509130009863199
2019-05-09 13:00:29,496 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1289.47873402s left)
2019-05-09 13:00:39,976 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130039964956
2019-05-09 13:00:40,003 [salt.minion      :1432][INFO    ][12230] Starting a new job with PID 12230
2019-05-09 13:00:40,025 [salt.minion      :1711][INFO    ][12230] Returning information for job: 20190509130039964956
2019-05-09 13:01:02,239 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1256.73630404s left)
2019-05-09 13:01:10,048 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130110031075
2019-05-09 13:01:10,073 [salt.minion      :1432][INFO    ][12287] Starting a new job with PID 12287
2019-05-09 13:01:10,091 [salt.minion      :1711][INFO    ][12287] Returning information for job: 20190509130110031075
2019-05-09 13:01:35,169 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1223.80590701s left)
2019-05-09 13:01:40,143 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130140127568
2019-05-09 13:01:40,170 [salt.minion      :1432][INFO    ][12316] Starting a new job with PID 12316
2019-05-09 13:01:40,191 [salt.minion      :1711][INFO    ][12316] Returning information for job: 20190509130140127568
2019-05-09 13:02:07,926 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1191.04867387s left)
2019-05-09 13:02:10,253 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130210240466
2019-05-09 13:02:10,279 [salt.minion      :1432][INFO    ][12366] Starting a new job with PID 12366
2019-05-09 13:02:10,301 [salt.minion      :1711][INFO    ][12366] Returning information for job: 20190509130210240466
2019-05-09 13:02:40,381 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130240367191
2019-05-09 13:02:40,408 [salt.minion      :1432][INFO    ][12392] Starting a new job with PID 12392
2019-05-09 13:02:40,432 [salt.minion      :1711][INFO    ][12392] Returning information for job: 20190509130240367191
2019-05-09 13:02:40,755 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1158.21985793s left)
2019-05-09 13:03:10,540 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130310523003
2019-05-09 13:03:10,568 [salt.minion      :1432][INFO    ][12446] Starting a new job with PID 12446
2019-05-09 13:03:10,592 [salt.minion      :1711][INFO    ][12446] Returning information for job: 20190509130310523003
2019-05-09 13:03:13,590 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1125.38515997s left)
2019-05-09 13:03:40,690 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130340679031
2019-05-09 13:03:40,716 [salt.minion      :1432][INFO    ][12470] Starting a new job with PID 12470
2019-05-09 13:03:40,740 [salt.minion      :1711][INFO    ][12470] Returning information for job: 20190509130340679031
2019-05-09 13:03:46,544 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1092.43125582s left)
2019-05-09 13:04:10,856 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130410840694
2019-05-09 13:04:10,884 [salt.minion      :1432][INFO    ][12522] Starting a new job with PID 12522
2019-05-09 13:04:10,909 [salt.minion      :1711][INFO    ][12522] Returning information for job: 20190509130410840694
2019-05-09 13:04:19,292 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1059.68274784s left)
2019-05-09 13:04:41,024 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130441012236
2019-05-09 13:04:41,048 [salt.minion      :1432][INFO    ][12547] Starting a new job with PID 12547
2019-05-09 13:04:41,070 [salt.minion      :1711][INFO    ][12547] Returning information for job: 20190509130441012236
2019-05-09 13:04:52,243 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1026.73248696s left)
2019-05-09 13:05:11,219 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130511204853
2019-05-09 13:05:11,249 [salt.minion      :1432][INFO    ][12603] Starting a new job with PID 12603
2019-05-09 13:05:11,274 [salt.minion      :1711][INFO    ][12603] Returning information for job: 20190509130511204853
2019-05-09 13:05:24,907 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (994.068202019s left)
2019-05-09 13:05:41,431 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130541416415
2019-05-09 13:05:41,458 [salt.minion      :1432][INFO    ][12631] Starting a new job with PID 12631
2019-05-09 13:05:41,480 [salt.minion      :1711][INFO    ][12631] Returning information for job: 20190509130541416415
2019-05-09 13:05:57,738 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (961.236971855s left)
2019-05-09 13:06:11,656 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130611646799
2019-05-09 13:06:11,682 [salt.minion      :1432][INFO    ][12706] Starting a new job with PID 12706
2019-05-09 13:06:11,707 [salt.minion      :1711][INFO    ][12706] Returning information for job: 20190509130611646799
2019-05-09 13:06:30,569 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (928.406294823s left)
2019-05-09 13:06:41,829 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130641819566
2019-05-09 13:06:41,857 [salt.minion      :1432][INFO    ][12734] Starting a new job with PID 12734
2019-05-09 13:06:41,877 [salt.minion      :1711][INFO    ][12734] Returning information for job: 20190509130641819566
2019-05-09 13:07:03,447 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (895.528156042s left)
2019-05-09 13:07:11,901 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130711886903
2019-05-09 13:07:11,929 [salt.minion      :1432][INFO    ][12788] Starting a new job with PID 12788
2019-05-09 13:07:11,952 [salt.minion      :1711][INFO    ][12788] Returning information for job: 20190509130711886903
2019-05-09 13:07:36,180 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (862.79544282s left)
2019-05-09 13:07:41,933 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130741918840
2019-05-09 13:07:41,958 [salt.minion      :1432][INFO    ][12812] Starting a new job with PID 12812
2019-05-09 13:07:41,980 [salt.minion      :1711][INFO    ][12812] Returning information for job: 20190509130741918840
2019-05-09 13:08:09,130 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (829.844645977s left)
2019-05-09 13:08:12,041 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130812025459
2019-05-09 13:08:12,070 [salt.minion      :1432][INFO    ][12868] Starting a new job with PID 12868
2019-05-09 13:08:12,094 [salt.minion      :1711][INFO    ][12868] Returning information for job: 20190509130812025459
2019-05-09 13:08:41,887 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (797.088531017s left)
2019-05-09 13:08:42,140 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130842129527
2019-05-09 13:08:42,163 [salt.minion      :1432][INFO    ][12892] Starting a new job with PID 12892
2019-05-09 13:08:42,185 [salt.minion      :1711][INFO    ][12892] Returning information for job: 20190509130842129527
2019-05-09 13:09:12,233 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130912219442
2019-05-09 13:09:12,258 [salt.minion      :1432][INFO    ][12943] Starting a new job with PID 12943
2019-05-09 13:09:12,281 [salt.minion      :1711][INFO    ][12943] Returning information for job: 20190509130912219442
2019-05-09 13:09:14,573 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (764.402318001s left)
2019-05-09 13:09:42,379 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509130942366867
2019-05-09 13:09:42,406 [salt.minion      :1432][INFO    ][12966] Starting a new job with PID 12966
2019-05-09 13:09:42,430 [salt.minion      :1711][INFO    ][12966] Returning information for job: 20190509130942366867
2019-05-09 13:09:47,254 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (731.721761942s left)
2019-05-09 13:10:12,573 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131012557305
2019-05-09 13:10:12,600 [salt.minion      :1432][INFO    ][13026] Starting a new job with PID 13026
2019-05-09 13:10:12,622 [salt.minion      :1711][INFO    ][13026] Returning information for job: 20190509131012557305
2019-05-09 13:10:20,082 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (698.892992973s left)
2019-05-09 13:10:42,760 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131042744758
2019-05-09 13:10:42,783 [salt.minion      :1432][INFO    ][13049] Starting a new job with PID 13049
2019-05-09 13:10:42,809 [salt.minion      :1711][INFO    ][13049] Returning information for job: 20190509131042744758
2019-05-09 13:10:52,788 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (666.186586857s left)
2019-05-09 13:11:12,941 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131112925084
2019-05-09 13:11:12,969 [salt.minion      :1432][INFO    ][13101] Starting a new job with PID 13101
2019-05-09 13:11:12,993 [salt.minion      :1711][INFO    ][13101] Returning information for job: 20190509131112925084
2019-05-09 13:11:25,599 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (633.376067877s left)
2019-05-09 13:11:43,108 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131143092906
2019-05-09 13:11:43,132 [salt.minion      :1432][INFO    ][13129] Starting a new job with PID 13129
2019-05-09 13:11:43,156 [salt.minion      :1711][INFO    ][13129] Returning information for job: 20190509131143092906
2019-05-09 13:11:58,452 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (600.523252964s left)
2019-05-09 13:12:13,204 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131213189179
2019-05-09 13:12:13,223 [salt.minion      :1432][INFO    ][13180] Starting a new job with PID 13180
2019-05-09 13:12:13,244 [salt.minion      :1711][INFO    ][13180] Returning information for job: 20190509131213189179
2019-05-09 13:12:31,444 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (567.531343937s left)
2019-05-09 13:12:43,384 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131243369064
2019-05-09 13:12:43,403 [salt.minion      :1432][INFO    ][13204] Starting a new job with PID 13204
2019-05-09 13:12:43,428 [salt.minion      :1711][INFO    ][13204] Returning information for job: 20190509131243369064
2019-05-09 13:13:04,312 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (534.662636995s left)
2019-05-09 13:13:13,571 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131313561184
2019-05-09 13:13:13,592 [salt.minion      :1432][INFO    ][13255] Starting a new job with PID 13255
2019-05-09 13:13:13,615 [salt.minion      :1711][INFO    ][13255] Returning information for job: 20190509131313561184
2019-05-09 13:13:36,964 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (502.011402845s left)
2019-05-09 13:13:43,633 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131343619106
2019-05-09 13:13:43,652 [salt.minion      :1432][INFO    ][13281] Starting a new job with PID 13281
2019-05-09 13:13:43,675 [salt.minion      :1711][INFO    ][13281] Returning information for job: 20190509131343619106
2019-05-09 13:14:09,499 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (469.475644827s left)
2019-05-09 13:14:13,691 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131413684135
2019-05-09 13:14:13,705 [salt.minion      :1432][INFO    ][13333] Starting a new job with PID 13333
2019-05-09 13:14:13,728 [salt.minion      :1711][INFO    ][13333] Returning information for job: 20190509131413684135
2019-05-09 13:14:42,136 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (436.839211941s left)
2019-05-09 13:14:43,760 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131443753298
2019-05-09 13:14:43,780 [salt.minion      :1432][INFO    ][13360] Starting a new job with PID 13360
2019-05-09 13:14:43,803 [salt.minion      :1711][INFO    ][13360] Returning information for job: 20190509131443753298
2019-05-09 13:15:13,875 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131513863665
2019-05-09 13:15:13,890 [salt.minion      :1432][INFO    ][13411] Starting a new job with PID 13411
2019-05-09 13:15:13,914 [salt.minion      :1711][INFO    ][13411] Returning information for job: 20190509131513863665
2019-05-09 13:15:14,991 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (403.984396935s left)
2019-05-09 13:15:43,968 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131543951522
2019-05-09 13:15:43,990 [salt.minion      :1432][INFO    ][13433] Starting a new job with PID 13433
2019-05-09 13:15:44,014 [salt.minion      :1711][INFO    ][13433] Returning information for job: 20190509131543951522
2019-05-09 13:15:47,836 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (371.139096022s left)
2019-05-09 13:16:14,125 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131614109489
2019-05-09 13:16:14,148 [salt.minion      :1432][INFO    ][13635] Starting a new job with PID 13635
2019-05-09 13:16:14,183 [salt.minion      :1711][INFO    ][13635] Returning information for job: 20190509131614109489
2019-05-09 13:16:20,443 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (338.531638861s left)
2019-05-09 13:16:44,243 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131644230185
2019-05-09 13:16:44,264 [salt.minion      :1432][INFO    ][13670] Starting a new job with PID 13670
2019-05-09 13:16:44,290 [salt.minion      :1711][INFO    ][13670] Returning information for job: 20190509131644230185
2019-05-09 13:16:53,192 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (305.782780886s left)
2019-05-09 13:17:14,374 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131714360902
2019-05-09 13:17:14,395 [salt.minion      :1432][INFO    ][13717] Starting a new job with PID 13717
2019-05-09 13:17:14,424 [salt.minion      :1711][INFO    ][13717] Returning information for job: 20190509131714360902
2019-05-09 13:17:25,855 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (273.120362043s left)
2019-05-09 13:17:44,480 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131744468319
2019-05-09 13:17:44,501 [salt.minion      :1432][INFO    ][13749] Starting a new job with PID 13749
2019-05-09 13:17:44,527 [salt.minion      :1711][INFO    ][13749] Returning information for job: 20190509131744468319
2019-05-09 13:17:58,597 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (240.378171921s left)
2019-05-09 13:18:14,515 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131814501358
2019-05-09 13:18:14,537 [salt.minion      :1432][INFO    ][13796] Starting a new job with PID 13796
2019-05-09 13:18:14,561 [salt.minion      :1711][INFO    ][13796] Returning information for job: 20190509131814501358
2019-05-09 13:18:31,339 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (207.635699987s left)
2019-05-09 13:18:44,698 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131844688184
2019-05-09 13:18:44,718 [salt.minion      :1432][INFO    ][13836] Starting a new job with PID 13836
2019-05-09 13:18:44,742 [salt.minion      :1711][INFO    ][13836] Returning information for job: 20190509131844688184
2019-05-09 13:19:04,080 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (174.895216942s left)
2019-05-09 13:19:14,799 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131914785080
2019-05-09 13:19:14,817 [salt.minion      :1432][INFO    ][13878] Starting a new job with PID 13878
2019-05-09 13:19:14,843 [salt.minion      :1711][INFO    ][13878] Returning information for job: 20190509131914785080
2019-05-09 13:19:36,653 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (142.321918011s left)
2019-05-09 13:19:44,995 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509131944986923
2019-05-09 13:19:45,017 [salt.minion      :1432][INFO    ][13910] Starting a new job with PID 13910
2019-05-09 13:19:45,046 [salt.minion      :1711][INFO    ][13910] Returning information for job: 20190509131944986923
2019-05-09 13:20:09,472 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (109.502758026s left)
2019-05-09 13:20:15,115 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132015104685
2019-05-09 13:20:15,132 [salt.minion      :1432][INFO    ][13956] Starting a new job with PID 13956
2019-05-09 13:20:15,156 [salt.minion      :1711][INFO    ][13956] Returning information for job: 20190509132015104685
2019-05-09 13:20:42,238 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (76.73675704s left)
2019-05-09 13:20:45,245 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132045237919
2019-05-09 13:20:45,260 [salt.minion      :1432][INFO    ][13989] Starting a new job with PID 13989
2019-05-09 13:20:45,282 [salt.minion      :1711][INFO    ][13989] Returning information for job: 20190509132045237919
2019-05-09 13:21:14,872 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (44.1029980183s left)
2019-05-09 13:21:15,340 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132115325168
2019-05-09 13:21:15,356 [salt.minion      :1432][INFO    ][14034] Starting a new job with PID 14034
2019-05-09 13:21:15,380 [salt.minion      :1711][INFO    ][14034] Returning information for job: 20190509132115325168
2019-05-09 13:21:45,533 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132145520158
2019-05-09 13:21:45,555 [salt.minion      :1432][INFO    ][14064] Starting a new job with PID 14064
2019-05-09 13:21:45,577 [salt.minion      :1711][INFO    ][14064] Returning information for job: 20190509132145520158
2019-05-09 13:21:47,471 [salt.loaded.ext.module.maas:1023][INFO    ][8524] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (11.5044019222s left)
2019-05-09 13:22:15,646 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132215638723
2019-05-09 13:22:15,668 [salt.minion      :1432][INFO    ][14104] Starting a new job with PID 14104
2019-05-09 13:22:15,689 [salt.minion      :1711][INFO    ][14104] Returning information for job: 20190509132215638723
2019-05-09 13:22:20,188 [salt.state       :302 ][ERROR   ][8524] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-09 13:22:20,189 [salt.state       :1951][INFO    ][8524] Completed state [maas.wait_for_machine_status] at time 13:22:20.189323 duration_in_ms=2271227.661
2019-05-09 13:22:20,200 [salt.minion      :1711][INFO    ][8524] Returning information for job: 20190509124421092184
2019-05-09 13:22:30,959 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command pillar.get with jid 20190509132230945859
2019-05-09 13:22:30,981 [salt.minion      :1432][INFO    ][14125] Starting a new job with PID 14125
2019-05-09 13:22:30,990 [salt.minion      :1711][INFO    ][14125] Returning information for job: 20190509132230945859
2019-05-09 13:22:31,542 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command service.status with jid 20190509132231531441
2019-05-09 13:22:31,557 [salt.minion      :1432][INFO    ][14130] Starting a new job with PID 14130
2019-05-09 13:22:32,060 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14130] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-09 13:22:32,096 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14130] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-09 13:22:32,115 [salt.minion      :1711][INFO    ][14130] Returning information for job: 20190509132231531441
2019-05-09 13:22:32,664 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command state.apply with jid 20190509132232652071
2019-05-09 13:22:32,683 [salt.minion      :1432][INFO    ][14142] Starting a new job with PID 14142
2019-05-09 13:22:38,466 [salt.state       :915 ][INFO    ][14142] Loading fresh modules for state activity
2019-05-09 13:22:39,017 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14142] Executing command 'salt-minion --version' in directory '/root'
2019-05-09 13:22:39,318 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14142] Executing command 'salt-minion --version' in directory '/root'
2019-05-09 13:22:40,260 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14142] Executing command 'salt-minion --version' in directory '/root'
2019-05-09 13:22:40,554 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14142] Executing command 'salt-minion --version' in directory '/root'
2019-05-09 13:22:42,283 [salt.state       :1780][INFO    ][14142] Running state [salt-minion] at time 13:22:42.283651
2019-05-09 13:22:42,284 [salt.state       :1813][INFO    ][14142] Executing state pkg.installed for [salt-minion]
2019-05-09 13:22:42,284 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14142] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-09 13:22:42,392 [salt.state       :300 ][INFO    ][14142] All specified packages are already installed
2019-05-09 13:22:42,393 [salt.state       :1951][INFO    ][14142] Completed state [salt-minion] at time 13:22:42.393034 duration_in_ms=109.384
2019-05-09 13:22:42,393 [salt.state       :1780][INFO    ][14142] Running state [salt_minion_dependency_packages] at time 13:22:42.393350
2019-05-09 13:22:42,393 [salt.state       :1813][INFO    ][14142] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-09 13:22:42,403 [salt.state       :300 ][INFO    ][14142] All specified packages are already installed
2019-05-09 13:22:42,403 [salt.state       :1951][INFO    ][14142] Completed state [salt_minion_dependency_packages] at time 13:22:42.403364 duration_in_ms=10.014
2019-05-09 13:22:42,406 [salt.state       :1780][INFO    ][14142] Running state [/etc/salt/minion.d/minion.conf] at time 13:22:42.406193
2019-05-09 13:22:42,406 [salt.state       :1813][INFO    ][14142] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-09 13:22:42,673 [salt.state       :300 ][INFO    ][14142] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-09 13:22:42,673 [salt.state       :1951][INFO    ][14142] Completed state [/etc/salt/minion.d/minion.conf] at time 13:22:42.673399 duration_in_ms=267.206
2019-05-09 13:22:42,673 [salt.state       :1780][INFO    ][14142] Running state [python-netaddr] at time 13:22:42.673648
2019-05-09 13:22:42,674 [salt.state       :1813][INFO    ][14142] Executing state pkg.installed for [python-netaddr]
2019-05-09 13:22:42,682 [salt.state       :300 ][INFO    ][14142] All specified packages are already installed
2019-05-09 13:22:42,682 [salt.state       :1951][INFO    ][14142] Completed state [python-netaddr] at time 13:22:42.682773 duration_in_ms=9.125
2019-05-09 13:22:42,686 [salt.state       :1780][INFO    ][14142] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 13:22:42.686349
2019-05-09 13:22:42,686 [salt.state       :1813][INFO    ][14142] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-09 13:22:42,696 [salt.state       :300 ][INFO    ][14142] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-09 13:22:42,697 [salt.state       :1951][INFO    ][14142] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 13:22:42.697171 duration_in_ms=10.822
2019-05-09 13:22:42,699 [salt.state       :1780][INFO    ][14142] Running state [salt-minion] at time 13:22:42.699559
2019-05-09 13:22:42,699 [salt.state       :1813][INFO    ][14142] Executing state service.running for [salt-minion]
2019-05-09 13:22:42,700 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14142] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-09 13:22:42,739 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14142] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-09 13:22:42,756 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14142] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-09 13:22:42,775 [salt.state       :300 ][INFO    ][14142] The service salt-minion is already running
2019-05-09 13:22:42,775 [salt.state       :1951][INFO    ][14142] Completed state [salt-minion] at time 13:22:42.775568 duration_in_ms=76.009
2019-05-09 13:22:42,778 [salt.state       :1780][INFO    ][14142] Running state [/etc/salt/grains.d] at time 13:22:42.778399
2019-05-09 13:22:42,778 [salt.state       :1813][INFO    ][14142] Executing state file.directory for [/etc/salt/grains.d]
2019-05-09 13:22:42,780 [salt.state       :300 ][INFO    ][14142] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-09 13:22:42,780 [salt.state       :1951][INFO    ][14142] Completed state [/etc/salt/grains.d] at time 13:22:42.780784 duration_in_ms=2.385
2019-05-09 13:22:42,783 [salt.state       :1780][INFO    ][14142] Running state [/etc/salt/grains] at time 13:22:42.783501
2019-05-09 13:22:42,783 [salt.state       :1813][INFO    ][14142] Executing state file.managed for [/etc/salt/grains]
2019-05-09 13:22:42,784 [salt.state       :300 ][INFO    ][14142] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-09 13:22:42,784 [salt.state       :1951][INFO    ][14142] Completed state [/etc/salt/grains] at time 13:22:42.784618 duration_in_ms=1.117
2019-05-09 13:22:42,785 [salt.state       :1780][INFO    ][14142] Running state [/etc/salt/grains.d/placeholder] at time 13:22:42.785147
2019-05-09 13:22:42,785 [salt.state       :1813][INFO    ][14142] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-09 13:22:42,786 [salt.state       :300 ][INFO    ][14142] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-09 13:22:42,786 [salt.state       :1951][INFO    ][14142] Completed state [/etc/salt/grains.d/placeholder] at time 13:22:42.786535 duration_in_ms=1.388
2019-05-09 13:22:42,787 [salt.state       :1780][INFO    ][14142] Running state [/etc/salt/grains.d/sphinx] at time 13:22:42.787071
2019-05-09 13:22:42,787 [salt.state       :1813][INFO    ][14142] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-09 13:22:42,788 [salt.state       :300 ][INFO    ][14142] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-09 13:22:42,788 [salt.state       :1951][INFO    ][14142] Completed state [/etc/salt/grains.d/sphinx] at time 13:22:42.788820 duration_in_ms=1.748
2019-05-09 13:22:42,791 [salt.state       :1780][INFO    ][14142] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 13:22:42.791379
2019-05-09 13:22:42,791 [salt.state       :1813][INFO    ][14142] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-09 13:22:42,792 [salt.state       :300 ][INFO    ][14142] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-09 13:22:42,792 [salt.state       :1951][INFO    ][14142] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 13:22:42.792257 duration_in_ms=0.878
2019-05-09 13:22:42,792 [salt.state       :1780][INFO    ][14142] Running state [/etc/salt/grains.d/dns_records] at time 13:22:42.792805
2019-05-09 13:22:42,793 [salt.state       :1813][INFO    ][14142] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-09 13:22:42,795 [salt.state       :300 ][INFO    ][14142] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-09 13:22:42,795 [salt.state       :1951][INFO    ][14142] Completed state [/etc/salt/grains.d/dns_records] at time 13:22:42.795439 duration_in_ms=2.634
2019-05-09 13:22:42,796 [salt.state       :1780][INFO    ][14142] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 13:22:42.796346
2019-05-09 13:22:42,796 [salt.state       :1813][INFO    ][14142] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-09 13:22:42,796 [salt.state       :300 ][INFO    ][14142] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-09 13:22:42,797 [salt.state       :1951][INFO    ][14142] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 13:22:42.797078 duration_in_ms=0.732
2019-05-09 13:22:42,797 [salt.state       :1780][INFO    ][14142] Running state [/etc/salt/grains.d/salt] at time 13:22:42.797548
2019-05-09 13:22:42,798 [salt.state       :1813][INFO    ][14142] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-09 13:22:42,799 [salt.state       :300 ][INFO    ][14142] File /etc/salt/grains.d/salt is in the correct state
2019-05-09 13:22:42,799 [salt.state       :1951][INFO    ][14142] Completed state [/etc/salt/grains.d/salt] at time 13:22:42.799189 duration_in_ms=1.641
2019-05-09 13:22:42,800 [salt.state       :1780][INFO    ][14142] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 13:22:42.800055
2019-05-09 13:22:42,800 [salt.state       :1813][INFO    ][14142] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-09 13:22:42,800 [salt.state       :300 ][INFO    ][14142] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-09 13:22:42,800 [salt.state       :1951][INFO    ][14142] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 13:22:42.800791 duration_in_ms=0.736
2019-05-09 13:22:42,803 [salt.state       :1780][INFO    ][14142] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 13:22:42.803011
2019-05-09 13:22:42,803 [salt.state       :1813][INFO    ][14142] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-09 13:22:42,803 [salt.state       :300 ][INFO    ][14142] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-09 13:22:42,803 [salt.state       :1951][INFO    ][14142] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 13:22:42.803767 duration_in_ms=0.756
2019-05-09 13:22:42,804 [salt.state       :1780][INFO    ][14142] Running state [mine.update] at time 13:22:42.804421
2019-05-09 13:22:42,804 [salt.state       :1813][INFO    ][14142] Executing state module.wait for [mine.update]
2019-05-09 13:22:42,804 [salt.state       :300 ][INFO    ][14142] No changes made for mine.update
2019-05-09 13:22:42,805 [salt.state       :1951][INFO    ][14142] Completed state [mine.update] at time 13:22:42.805118 duration_in_ms=0.697
2019-05-09 13:22:42,805 [salt.state       :1780][INFO    ][14142] Running state [ca-certificates] at time 13:22:42.805353
2019-05-09 13:22:42,805 [salt.state       :1813][INFO    ][14142] Executing state pkg.installed for [ca-certificates]
2019-05-09 13:22:42,815 [salt.state       :300 ][INFO    ][14142] All specified packages are already installed
2019-05-09 13:22:42,815 [salt.state       :1951][INFO    ][14142] Completed state [ca-certificates] at time 13:22:42.815925 duration_in_ms=10.572
2019-05-09 13:22:42,816 [salt.state       :1780][INFO    ][14142] Running state [update-ca-certificates] at time 13:22:42.816601
2019-05-09 13:22:42,816 [salt.state       :1813][INFO    ][14142] Executing state cmd.wait for [update-ca-certificates]
2019-05-09 13:22:42,817 [salt.state       :300 ][INFO    ][14142] No changes made for update-ca-certificates
2019-05-09 13:22:42,817 [salt.state       :1951][INFO    ][14142] Completed state [update-ca-certificates] at time 13:22:42.817307 duration_in_ms=0.705
2019-05-09 13:22:42,817 [salt.state       :1780][INFO    ][14142] Running state [iptables] at time 13:22:42.817539
2019-05-09 13:22:42,818 [salt.state       :1813][INFO    ][14142] Executing state pkg.installed for [iptables]
2019-05-09 13:22:42,827 [salt.state       :300 ][INFO    ][14142] All specified packages are already installed
2019-05-09 13:22:42,827 [salt.state       :1951][INFO    ][14142] Completed state [iptables] at time 13:22:42.827634 duration_in_ms=10.095
2019-05-09 13:22:42,827 [salt.state       :1780][INFO    ][14142] Running state [iptables-persistent] at time 13:22:42.827870
2019-05-09 13:22:42,828 [salt.state       :1813][INFO    ][14142] Executing state pkg.installed for [iptables-persistent]
2019-05-09 13:22:42,837 [salt.state       :300 ][INFO    ][14142] All specified packages are already installed
2019-05-09 13:22:42,837 [salt.state       :1951][INFO    ][14142] Completed state [iptables-persistent] at time 13:22:42.837408 duration_in_ms=9.538
2019-05-09 13:22:42,838 [salt.state       :1780][INFO    ][14142] Running state [iptables_modules_v4_load] at time 13:22:42.838369
2019-05-09 13:22:42,838 [salt.state       :1813][INFO    ][14142] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-09 13:22:42,839 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14142] Executing command 'lsmod' in directory '/root'
2019-05-09 13:22:42,858 [salt.state       :300 ][INFO    ][14142] Kernel modules iptable_filter, ip_tables are already present
2019-05-09 13:22:42,859 [salt.state       :1951][INFO    ][14142] Completed state [iptables_modules_v4_load] at time 13:22:42.859189 duration_in_ms=20.819
2019-05-09 13:22:42,860 [salt.state       :1780][INFO    ][14142] Running state [/etc/iptables/rules.v4] at time 13:22:42.860419
2019-05-09 13:22:42,861 [salt.state       :1813][INFO    ][14142] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-09 13:22:42,959 [salt.state       :300 ][INFO    ][14142] File /etc/iptables/rules.v4 is in the correct state
2019-05-09 13:22:42,959 [salt.state       :1951][INFO    ][14142] Completed state [/etc/iptables/rules.v4] at time 13:22:42.959375 duration_in_ms=98.956
2019-05-09 13:22:42,960 [salt.state       :1780][INFO    ][14142] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 13:22:42.960443
2019-05-09 13:22:42,960 [salt.state       :1813][INFO    ][14142] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-09 13:22:42,961 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14142] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-09 13:22:42,980 [salt.state       :300 ][INFO    ][14142] onlyif execution failed
2019-05-09 13:22:42,981 [salt.state       :1951][INFO    ][14142] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 13:22:42.980922 duration_in_ms=20.479
2019-05-09 13:22:42,983 [salt.state       :1780][INFO    ][14142] Running state [netfilter-persistent] at time 13:22:42.983664
2019-05-09 13:22:42,984 [salt.state       :1813][INFO    ][14142] Executing state service.running for [netfilter-persistent]
2019-05-09 13:22:42,985 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14142] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-09 13:22:43,013 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14142] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-09 13:22:43,032 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14142] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-09 13:22:43,050 [salt.state       :300 ][INFO    ][14142] The service netfilter-persistent is already running
2019-05-09 13:22:43,051 [salt.state       :1951][INFO    ][14142] Completed state [netfilter-persistent] at time 13:22:43.051371 duration_in_ms=67.706
2019-05-09 13:22:43,052 [salt.state       :1780][INFO    ][14142] Running state [iptables_extra.remove_stale_tables] at time 13:22:43.052683
2019-05-09 13:22:43,053 [salt.state       :1813][INFO    ][14142] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-09 13:22:43,053 [salt.state       :300 ][INFO    ][14142] No changes made for iptables_extra.remove_stale_tables
2019-05-09 13:22:43,054 [salt.state       :1951][INFO    ][14142] Completed state [iptables_extra.remove_stale_tables] at time 13:22:43.054155 duration_in_ms=1.472
2019-05-09 13:22:43,054 [salt.state       :1780][INFO    ][14142] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 13:22:43.054399
2019-05-09 13:22:43,054 [salt.state       :1813][INFO    ][14142] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-09 13:22:43,055 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14142] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-09 13:22:43,073 [salt.state       :300 ][INFO    ][14142] onlyif execution failed
2019-05-09 13:22:43,074 [salt.state       :1951][INFO    ][14142] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 13:22:43.073929 duration_in_ms=19.53
2019-05-09 13:22:43,075 [salt.state       :1780][INFO    ][14142] Running state [/etc/iptables/rules.v6] at time 13:22:43.075442
2019-05-09 13:22:43,075 [salt.state       :1813][INFO    ][14142] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-09 13:22:43,076 [salt.state       :300 ][INFO    ][14142] File /etc/iptables/rules.v6 is not present
2019-05-09 13:22:43,077 [salt.state       :1951][INFO    ][14142] Completed state [/etc/iptables/rules.v6] at time 13:22:43.077001 duration_in_ms=1.559
2019-05-09 13:22:43,078 [salt.state       :1780][INFO    ][14142] Running state [iptables_extra.flush_all] at time 13:22:43.078153
2019-05-09 13:22:43,078 [salt.state       :1813][INFO    ][14142] Executing state module.wait for [iptables_extra.flush_all]
2019-05-09 13:22:43,079 [salt.state       :300 ][INFO    ][14142] No changes made for iptables_extra.flush_all
2019-05-09 13:22:43,079 [salt.state       :1951][INFO    ][14142] Completed state [iptables_extra.flush_all] at time 13:22:43.079412 duration_in_ms=1.26
2019-05-09 13:22:43,085 [salt.minion      :1711][INFO    ][14142] Returning information for job: 20190509132232652071
2019-05-09 13:22:43,615 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command state.apply with jid 20190509132243604586
2019-05-09 13:22:43,633 [salt.minion      :1432][INFO    ][14220] Starting a new job with PID 14220
2019-05-09 13:22:44,605 [salt.state       :915 ][INFO    ][14220] Loading fresh modules for state activity
2019-05-09 13:22:45,455 [salt.state       :1780][INFO    ][14220] Running state [maas-rack-controller] at time 13:22:45.455546
2019-05-09 13:22:45,455 [salt.state       :1813][INFO    ][14220] Executing state pkg.installed for [maas-rack-controller]
2019-05-09 13:22:45,456 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14220] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-09 13:22:45,566 [salt.state       :300 ][INFO    ][14220] All specified packages are already installed
2019-05-09 13:22:45,567 [salt.state       :1951][INFO    ][14220] Completed state [maas-rack-controller] at time 13:22:45.567015 duration_in_ms=111.468
2019-05-09 13:22:45,567 [salt.state       :1780][INFO    ][14220] Running state [ipmitool] at time 13:22:45.567387
2019-05-09 13:22:45,567 [salt.state       :1813][INFO    ][14220] Executing state pkg.installed for [ipmitool]
2019-05-09 13:22:45,576 [salt.state       :300 ][INFO    ][14220] All specified packages are already installed
2019-05-09 13:22:45,576 [salt.state       :1951][INFO    ][14220] Completed state [ipmitool] at time 13:22:45.576491 duration_in_ms=9.104
2019-05-09 13:22:45,581 [salt.state       :1780][INFO    ][14220] Running state [/etc/maas/rackd.conf] at time 13:22:45.581783
2019-05-09 13:22:45,582 [salt.state       :1813][INFO    ][14220] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-09 13:22:45,583 [salt.state       :300 ][INFO    ][14220] No changes needed to be made
2019-05-09 13:22:45,583 [salt.state       :1951][INFO    ][14220] Completed state [/etc/maas/rackd.conf] at time 13:22:45.583197 duration_in_ms=1.414
2019-05-09 13:22:45,583 [salt.state       :1780][INFO    ][14220] Running state [/etc/maas/rackd.conf] at time 13:22:45.583438
2019-05-09 13:22:45,583 [salt.state       :1813][INFO    ][14220] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-09 13:22:45,584 [salt.loaded.int.states.file:2298][WARNING ][14220] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-09 13:22:45,584 [salt.state       :300 ][INFO    ][14220] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-09 13:22:45,584 [salt.state       :1951][INFO    ][14220] Completed state [/etc/maas/rackd.conf] at time 13:22:45.584928 duration_in_ms=1.489
2019-05-09 13:22:45,585 [salt.state       :1780][INFO    ][14220] Running state [maas-rackd] at time 13:22:45.585935
2019-05-09 13:22:45,586 [salt.state       :1813][INFO    ][14220] Executing state service.running for [maas-rackd]
2019-05-09 13:22:45,586 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14220] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-09 13:22:45,620 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14220] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-09 13:22:45,637 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14220] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-09 13:22:45,656 [salt.state       :300 ][INFO    ][14220] The service maas-rackd is already running
2019-05-09 13:22:45,656 [salt.state       :1951][INFO    ][14220] Completed state [maas-rackd] at time 13:22:45.656837 duration_in_ms=70.901
2019-05-09 13:22:45,659 [salt.minion      :1711][INFO    ][14220] Returning information for job: 20190509132243604586
2019-05-09 13:22:46,195 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command state.apply with jid 20190509132246183096
2019-05-09 13:22:46,211 [salt.minion      :1432][INFO    ][14255] Starting a new job with PID 14255
2019-05-09 13:22:47,116 [salt.state       :915 ][INFO    ][14255] Loading fresh modules for state activity
2019-05-09 13:22:48,119 [salt.state       :1780][INFO    ][14255] Running state [maas-region-controller] at time 13:22:48.119601
2019-05-09 13:22:48,120 [salt.state       :1813][INFO    ][14255] Executing state pkg.installed for [maas-region-controller]
2019-05-09 13:22:48,120 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14255] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-09 13:22:48,227 [salt.state       :300 ][INFO    ][14255] All specified packages are already installed
2019-05-09 13:22:48,228 [salt.state       :1951][INFO    ][14255] Completed state [maas-region-controller] at time 13:22:48.228123 duration_in_ms=108.523
2019-05-09 13:22:48,228 [salt.state       :1780][INFO    ][14255] Running state [python-oauth] at time 13:22:48.228434
2019-05-09 13:22:48,228 [salt.state       :1813][INFO    ][14255] Executing state pkg.installed for [python-oauth]
2019-05-09 13:22:48,238 [salt.state       :300 ][INFO    ][14255] All specified packages are already installed
2019-05-09 13:22:48,238 [salt.state       :1951][INFO    ][14255] Completed state [python-oauth] at time 13:22:48.238515 duration_in_ms=10.081
2019-05-09 13:22:48,241 [salt.state       :1780][INFO    ][14255] Running state [/etc/maas/regiond.conf] at time 13:22:48.241531
2019-05-09 13:22:48,242 [salt.state       :1813][INFO    ][14255] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-09 13:22:48,246 [salt.state       :300 ][INFO    ][14255] No changes needed to be made
2019-05-09 13:22:48,246 [salt.state       :1951][INFO    ][14255] Completed state [/etc/maas/regiond.conf] at time 13:22:48.246879 duration_in_ms=5.348
2019-05-09 13:22:48,247 [salt.state       :1780][INFO    ][14255] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 13:22:48.247356
2019-05-09 13:22:48,247 [salt.state       :1813][INFO    ][14255] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-09 13:22:48,307 [salt.state       :300 ][INFO    ][14255] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-09 13:22:48,307 [salt.state       :1951][INFO    ][14255] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 13:22:48.307547 duration_in_ms=60.191
2019-05-09 13:22:48,308 [salt.state       :1780][INFO    ][14255] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 13:22:48.308046
2019-05-09 13:22:48,308 [salt.state       :1813][INFO    ][14255] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-09 13:22:48,311 [salt.state       :300 ][INFO    ][14255] No changes needed to be made
2019-05-09 13:22:48,312 [salt.state       :1951][INFO    ][14255] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 13:22:48.312102 duration_in_ms=4.056
2019-05-09 13:22:48,312 [salt.state       :1780][INFO    ][14255] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 13:22:48.312587
2019-05-09 13:22:48,312 [salt.state       :1813][INFO    ][14255] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-09 13:22:48,317 [salt.state       :300 ][INFO    ][14255] No changes needed to be made
2019-05-09 13:22:48,317 [salt.state       :1951][INFO    ][14255] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 13:22:48.317316 duration_in_ms=4.729
2019-05-09 13:22:48,317 [salt.state       :1780][INFO    ][14255] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 13:22:48.317795
2019-05-09 13:22:48,318 [salt.state       :1813][INFO    ][14255] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-09 13:22:48,341 [salt.state       :300 ][INFO    ][14255] No changes needed to be made
2019-05-09 13:22:48,341 [salt.state       :1951][INFO    ][14255] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 13:22:48.341392 duration_in_ms=23.597
2019-05-09 13:22:48,342 [salt.state       :1780][INFO    ][14255] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 13:22:48.342159
2019-05-09 13:22:48,342 [salt.state       :1813][INFO    ][14255] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-09 13:22:48,355 [salt.state       :300 ][INFO    ][14255] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-09 13:22:48,356 [salt.state       :1951][INFO    ][14255] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 13:22:48.356205 duration_in_ms=14.045
2019-05-09 13:22:48,357 [salt.state       :1780][INFO    ][14255] Running state [a2enmod headers] at time 13:22:48.357561
2019-05-09 13:22:48,358 [salt.state       :1813][INFO    ][14255] Executing state cmd.run for [a2enmod headers]
2019-05-09 13:22:48,358 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14255] Executing command 'a2enmod headers' in directory '/root'
2019-05-09 13:22:48,424 [salt.state       :300 ][INFO    ][14255] {'pid': 14274, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-09 13:22:48,425 [salt.state       :1951][INFO    ][14255] Completed state [a2enmod headers] at time 13:22:48.425116 duration_in_ms=67.556
2019-05-09 13:22:48,425 [salt.state       :1780][INFO    ][14255] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 13:22:48.425602
2019-05-09 13:22:48,426 [salt.state       :1813][INFO    ][14255] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-09 13:22:48,447 [salt.state       :300 ][INFO    ][14255] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-09 13:22:48,448 [salt.state       :1951][INFO    ][14255] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 13:22:48.448166 duration_in_ms=22.564
2019-05-09 13:22:48,448 [salt.state       :1780][INFO    ][14255] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 13:22:48.448838
2019-05-09 13:22:48,449 [salt.state       :1813][INFO    ][14255] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-09 13:22:48,505 [salt.state       :300 ][INFO    ][14255] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-09 13:22:48,505 [salt.state       :1951][INFO    ][14255] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 13:22:48.505321 duration_in_ms=56.483
2019-05-09 13:22:48,507 [salt.state       :1780][INFO    ][14255] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 13:22:48.507102
2019-05-09 13:22:48,507 [salt.state       :1813][INFO    ][14255] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-09 13:22:48,564 [salt.state       :300 ][INFO    ][14255] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-09 13:22:48,565 [salt.state       :1951][INFO    ][14255] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 13:22:48.565118 duration_in_ms=58.015
2019-05-09 13:22:48,565 [salt.state       :1780][INFO    ][14255] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 13:22:48.565645
2019-05-09 13:22:48,567 [salt.state       :1813][INFO    ][14255] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-09 13:22:48,639 [salt.state       :300 ][INFO    ][14255] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-09 13:22:48,640 [salt.state       :1951][INFO    ][14255] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 13:22:48.640119 duration_in_ms=74.474
2019-05-09 13:22:48,640 [salt.state       :1780][INFO    ][14255] Running state [/root/.pgpass] at time 13:22:48.640367
2019-05-09 13:22:48,640 [salt.state       :1813][INFO    ][14255] Executing state file.managed for [/root/.pgpass]
2019-05-09 13:22:48,690 [salt.state       :300 ][INFO    ][14255] File /root/.pgpass is in the correct state
2019-05-09 13:22:48,690 [salt.state       :1951][INFO    ][14255] Completed state [/root/.pgpass] at time 13:22:48.690466 duration_in_ms=50.098
2019-05-09 13:22:48,695 [salt.state       :1780][INFO    ][14255] Running state [maas-region syncdb --noinput] at time 13:22:48.695194
2019-05-09 13:22:48,695 [salt.state       :1813][INFO    ][14255] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-09 13:22:48,696 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14255] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-09 13:22:51,222 [salt.state       :300 ][INFO    ][14255] {'pid': 14287, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: maasserver, piston3, metadataserver, contenttypes, sessions, sites, auth\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-09 13:22:51,223 [salt.state       :1951][INFO    ][14255] Completed state [maas-region syncdb --noinput] at time 13:22:51.223267 duration_in_ms=2528.07
2019-05-09 13:22:51,224 [salt.state       :2022][WARNING ][14255] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-09 13:22:51,228 [salt.state       :1780][INFO    ][14255] Running state [maas-regiond] at time 13:22:51.228678
2019-05-09 13:22:51,229 [salt.state       :1813][INFO    ][14255] Executing state service.running for [maas-regiond]
2019-05-09 13:22:51,232 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14255] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-09 13:22:51,270 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14255] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-09 13:22:51,287 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14255] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-09 13:22:51,306 [salt.state       :300 ][INFO    ][14255] The service maas-regiond is already running
2019-05-09 13:22:51,307 [salt.state       :1951][INFO    ][14255] Completed state [maas-regiond] at time 13:22:51.307162 duration_in_ms=78.484
2019-05-09 13:22:51,310 [salt.state       :1780][INFO    ][14255] Running state [bind9] at time 13:22:51.310441
2019-05-09 13:22:51,311 [salt.state       :1813][INFO    ][14255] Executing state service.running for [bind9]
2019-05-09 13:22:51,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14255] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-09 13:22:51,334 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14255] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-09 13:22:51,356 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14255] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-09 13:22:51,376 [salt.state       :300 ][INFO    ][14255] The service bind9 is already running
2019-05-09 13:22:51,376 [salt.state       :1951][INFO    ][14255] Completed state [bind9] at time 13:22:51.376763 duration_in_ms=66.321
2019-05-09 13:22:51,380 [salt.state       :1780][INFO    ][14255] Running state [apache2] at time 13:22:51.380277
2019-05-09 13:22:51,380 [salt.state       :1813][INFO    ][14255] Executing state service.running for [apache2]
2019-05-09 13:22:51,382 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14255] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-09 13:22:51,404 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14255] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-09 13:22:51,420 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14255] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-09 13:22:51,445 [salt.state       :300 ][INFO    ][14255] The service apache2 is already running
2019-05-09 13:22:51,445 [salt.state       :1951][INFO    ][14255] Completed state [apache2] at time 13:22:51.445460 duration_in_ms=65.183
2019-05-09 13:22:51,448 [salt.state       :1780][INFO    ][14255] Running state [maasng.wait_for_http_code] at time 13:22:51.448908
2019-05-09 13:22:51,449 [salt.state       :1813][INFO    ][14255] Executing state module.run for [maasng.wait_for_http_code]
2019-05-09 13:22:51,450 [salt.utils.decorators:613 ][WARNING ][14255] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 13:22:51,566 [salt.state       :300 ][INFO    ][14255] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-09 13:22:51,566 [salt.state       :1951][INFO    ][14255] Completed state [maasng.wait_for_http_code] at time 13:22:51.566695 duration_in_ms=117.787
2019-05-09 13:22:51,567 [salt.state       :1780][INFO    ][14255] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 13:22:51.567644
2019-05-09 13:22:51,568 [salt.state       :1813][INFO    ][14255] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-09 13:22:51,568 [salt.state       :300 ][INFO    ][14255] /var/lib/maas/.setup_admin exists
2019-05-09 13:22:51,569 [salt.state       :1951][INFO    ][14255] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 13:22:51.568957 duration_in_ms=1.312
2019-05-09 13:22:51,569 [salt.state       :1780][INFO    ][14255] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:22:51.569867
2019-05-09 13:22:51,570 [salt.state       :1813][INFO    ][14255] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 13:22:51,571 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14255] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 13:22:53,351 [salt.state       :300 ][INFO    ][14255] {'pid': 14309, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 13:22:53,352 [salt.state       :1951][INFO    ][14255] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:22:53.352074 duration_in_ms=1782.205
2019-05-09 13:22:53,360 [salt.state       :1780][INFO    ][14255] Running state [maas_region_boot_source_resources_mirror] at time 13:22:53.360315
2019-05-09 13:22:53,360 [salt.state       :1813][INFO    ][14255] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-09 13:22:53,447 [salt.state       :300 ][INFO    ][14255] {'changes': {}}
2019-05-09 13:22:53,448 [salt.state       :1951][INFO    ][14255] Completed state [maas_region_boot_source_resources_mirror] at time 13:22:53.448115 duration_in_ms=87.8
2019-05-09 13:22:53,449 [salt.state       :1780][INFO    ][14255] Running state [maasng.boot_resources_import] at time 13:22:53.448992
2019-05-09 13:22:53,449 [salt.state       :1813][INFO    ][14255] Executing state module.run for [maasng.boot_resources_import]
2019-05-09 13:22:53,451 [salt.utils.decorators:613 ][WARNING ][14255] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 13:22:53,527 [salt.loaded.ext.module.maasng:1600][INFO    ][14255] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-09 13:22:58,564 [salt.loaded.ext.module.maasng:1600][INFO    ][14255] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-09 13:23:01,272 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132301260089
2019-05-09 13:23:01,293 [salt.minion      :1432][INFO    ][14493] Starting a new job with PID 14493
2019-05-09 13:23:01,316 [salt.minion      :1711][INFO    ][14493] Returning information for job: 20190509132301260089
2019-05-09 13:23:03,639 [salt.state       :300 ][INFO    ][14255] {'ret': True}
2019-05-09 13:23:03,639 [salt.state       :1951][INFO    ][14255] Completed state [maasng.boot_resources_import] at time 13:23:03.639676 duration_in_ms=10190.683
2019-05-09 13:23:03,640 [salt.state       :1780][INFO    ][14255] Running state [maas_region_boot_sources_selection_xenial] at time 13:23:03.640771
2019-05-09 13:23:03,641 [salt.state       :1813][INFO    ][14255] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-09 13:23:03,775 [salt.state       :300 ][INFO    ][14255] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-09 13:23:03,775 [salt.state       :1951][INFO    ][14255] Completed state [maas_region_boot_sources_selection_xenial] at time 13:23:03.775845 duration_in_ms=135.073
2019-05-09 13:23:03,776 [salt.state       :1780][INFO    ][14255] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 13:23:03.776693
2019-05-09 13:23:03,776 [salt.state       :1813][INFO    ][14255] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-09 13:23:03,777 [salt.utils.decorators:613 ][WARNING ][14255] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 13:23:03,777 [salt.loaded.ext.module.maasng:1771][INFO    ][14255] boot-sources sync initiated for ALL Rack's
2019-05-09 13:23:04,698 [salt.state       :300 ][INFO    ][14255] {'ret': True}
2019-05-09 13:23:04,698 [salt.state       :1951][INFO    ][14255] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 13:23:04.698916 duration_in_ms=922.222
2019-05-09 13:23:04,700 [salt.state       :1780][INFO    ][14255] Running state [maas.process_maas_config] at time 13:23:04.700235
2019-05-09 13:23:04,700 [salt.state       :1813][INFO    ][14255] Executing state module.run for [maas.process_maas_config]
2019-05-09 13:23:04,700 [salt.utils.decorators:613 ][WARNING ][14255] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 13:23:04,701 [salt.loaded.ext.module.maas:92  ][INFO    ][14255] maasconfig name=enable_http_proxy value=True
2019-05-09 13:23:04,744 [salt.loaded.ext.module.maas:92  ][INFO    ][14255] maasconfig name=upstream_dns value=8.8.8.8
2019-05-09 13:23:06,116 [salt.loaded.ext.module.maas:92  ][INFO    ][14255] maasconfig name=commissioning_distro_series value=xenial
2019-05-09 13:23:06,172 [salt.loaded.ext.module.maas:92  ][INFO    ][14255] maasconfig name=default_osystem value=ubuntu
2019-05-09 13:23:06,221 [salt.loaded.ext.module.maas:92  ][INFO    ][14255] maasconfig name=active_discovery_interval value=600
2019-05-09 13:23:06,269 [salt.loaded.ext.module.maas:92  ][INFO    ][14255] maasconfig name=dnssec_validation value=no
2019-05-09 13:23:06,328 [salt.loaded.ext.module.maas:92  ][INFO    ][14255] maasconfig name=maas_name value=mas01
2019-05-09 13:23:06,368 [salt.loaded.ext.module.maas:92  ][INFO    ][14255] maasconfig name=network_discovery value=enabled
2019-05-09 13:23:06,450 [salt.loaded.ext.module.maas:92  ][INFO    ][14255] maasconfig name=enable_third_party_drivers value=True
2019-05-09 13:23:06,516 [salt.loaded.ext.module.maas:92  ][INFO    ][14255] maasconfig name=default_storage_layout value=lvm
2019-05-09 13:23:06,555 [salt.loaded.ext.module.maas:92  ][INFO    ][14255] maasconfig name=ntp_external_only value=True
2019-05-09 13:23:06,600 [salt.loaded.ext.module.maas:92  ][INFO    ][14255] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-09 13:23:06,640 [salt.loaded.ext.module.maas:92  ][INFO    ][14255] maasconfig name=default_distro_series value=xenial
2019-05-09 13:23:06,683 [salt.loaded.ext.module.maas:92  ][INFO    ][14255] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-09 13:23:06,774 [salt.state       :300 ][INFO    ][14255] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-09 13:23:06,774 [salt.state       :1951][INFO    ][14255] Completed state [maas.process_maas_config] at time 13:23:06.774700 duration_in_ms=2074.465
2019-05-09 13:23:06,775 [salt.state       :1780][INFO    ][14255] Running state [pxe_admin] at time 13:23:06.775383
2019-05-09 13:23:06,775 [salt.state       :1813][INFO    ][14255] Executing state maasng.fabric_present for [pxe_admin]
2019-05-09 13:23:06,833 [salt.loaded.ext.module.maasng:945 ][INFO    ][14255] [{u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gdpwew', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-09 13:23:06,897 [salt.loaded.ext.module.maasng:1008][WARNING ][14255] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-09 13:23:06,898 [salt.loaded.ext.module.maasng:1011][WARNING ][14255] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-09 13:23:06,960 [salt.state       :300 ][INFO    ][14255] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-09 13:23:06,961 [salt.state       :1951][INFO    ][14255] Completed state [pxe_admin] at time 13:23:06.960953 duration_in_ms=185.569
2019-05-09 13:23:06,961 [salt.state       :1780][INFO    ][14255] Running state [vlan 0] at time 13:23:06.961287
2019-05-09 13:23:06,961 [salt.state       :1813][INFO    ][14255] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-09 13:23:07,032 [salt.loaded.ext.module.maasng:945 ][INFO    ][14255] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'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'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'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'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gdpwew', u'mtu': 1500, 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-05-09 13:23:07,115 [salt.loaded.ext.module.maasng:945 ][INFO    ][14255] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'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'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'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'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gdpwew', u'mtu': 1500, 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-05-09 13:23:07,343 [salt.loaded.ext.module.maasng:945 ][INFO    ][14255] [{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'gdpwew', 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-05-09 13:23:07,414 [salt.state       :300 ][INFO    ][14255] {'new': 'Vlan untagged was updated'}
2019-05-09 13:23:07,414 [salt.state       :1951][INFO    ][14255] Completed state [vlan 0] at time 13:23:07.414487 duration_in_ms=453.2
2019-05-09 13:23:07,415 [salt.state       :1780][INFO    ][14255] Running state [192.168.11.0/24] at time 13:23:07.415601
2019-05-09 13:23:07,415 [salt.state       :1813][INFO    ][14255] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-09 13:23:07,558 [salt.loaded.ext.module.maasng:945 ][INFO    ][14255] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'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'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'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'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gdpwew', u'mtu': 1500, 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-05-09 13:23:07,558 [salt.loaded.ext.module.maasng:1235][WARNING ][14255] Ignoring parameter vlan:0
2019-05-09 13:23:07,651 [salt.state       :300 ][INFO    ][14255] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-09 13:23:07,652 [salt.state       :1951][INFO    ][14255] Completed state [192.168.11.0/24] at time 13:23:07.652064 duration_in_ms=236.462
2019-05-09 13:23:07,652 [salt.state       :1780][INFO    ][14255] Running state [maas_create_iprange_1] at time 13:23:07.652853
2019-05-09 13:23:07,653 [salt.state       :1813][INFO    ][14255] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-09 13:23:07,705 [salt.state       :300 ][INFO    ][14255] Iprange maas_create_iprange_1 already exist.
2019-05-09 13:23:07,705 [salt.state       :1951][INFO    ][14255] Completed state [maas_create_iprange_1] at time 13:23:07.705415 duration_in_ms=52.561
2019-05-09 13:23:07,707 [salt.state       :1780][INFO    ][14255] Running state [vlan 0] at time 13:23:07.707778
2019-05-09 13:23:07,708 [salt.state       :1813][INFO    ][14255] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-09 13:23:07,751 [salt.loaded.ext.module.maasng:945 ][INFO    ][14255] [{u'name': u'fabric-0', 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'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'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'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'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'gdpwew', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-05-09 13:23:07,853 [salt.loaded.ext.module.maasng:945 ][INFO    ][14255] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'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'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'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'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gdpwew', u'mtu': 1500, 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-05-09 13:23:08,072 [salt.loaded.ext.module.maasng:945 ][INFO    ][14255] [{u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gdpwew', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-09 13:23:08,157 [salt.state       :300 ][INFO    ][14255] {'new': 'Vlan untagged was updated'}
2019-05-09 13:23:08,157 [salt.state       :1951][INFO    ][14255] Completed state [vlan 0] at time 13:23:08.157639 duration_in_ms=449.86
2019-05-09 13:23:08,158 [salt.state       :1780][INFO    ][14255] Running state [opnfv] at time 13:23:08.158702
2019-05-09 13:23:08,159 [salt.state       :1813][INFO    ][14255] Executing state maasng.sshkey_present for [opnfv]
2019-05-09 13:23:08,214 [salt.loaded.ext.module.maasng:1903][INFO    ][14255] [{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-05-09 13:23:08,214 [salt.state       :300 ][INFO    ][14255] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-09 13:23:08,215 [salt.state       :1951][INFO    ][14255] Completed state [opnfv] at time 13:23:08.215000 duration_in_ms=56.297
2019-05-09 13:23:08,215 [salt.state       :1780][INFO    ][14255] Running state [maas.process_tags] at time 13:23:08.215644
2019-05-09 13:23:08,216 [salt.state       :1813][INFO    ][14255] Executing state module.run for [maas.process_tags]
2019-05-09 13:23:08,216 [salt.utils.decorators:613 ][WARNING ][14255] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 13:23:08,261 [salt.loaded.ext.module.maas:92  ][INFO    ][14255] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-09 13:23:08,304 [salt.state       :300 ][INFO    ][14255] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-09 13:23:08,305 [salt.state       :1951][INFO    ][14255] Completed state [maas.process_tags] at time 13:23:08.305121 duration_in_ms=89.476
2019-05-09 13:23:08,310 [salt.minion      :1711][INFO    ][14255] Returning information for job: 20190509132246183096
2019-05-09 13:23:08,941 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command state.apply with jid 20190509132308933622
2019-05-09 13:23:08,959 [salt.minion      :1432][INFO    ][14717] Starting a new job with PID 14717
2019-05-09 13:23:14,912 [salt.state       :915 ][INFO    ][14717] Loading fresh modules for state activity
2019-05-09 13:23:15,002 [salt.state       :1780][INFO    ][14717] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:23:15.002635
2019-05-09 13:23:15,002 [salt.state       :1813][INFO    ][14717] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 13:23:15,004 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14717] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 13:23:16,794 [salt.state       :300 ][INFO    ][14717] {'pid': 14745, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 13:23:16,795 [salt.state       :1951][INFO    ][14717] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:23:16.795458 duration_in_ms=1792.821
2019-05-09 13:23:16,798 [salt.state       :1780][INFO    ][14717] Running state [maas.process_machines] at time 13:23:16.798212
2019-05-09 13:23:16,798 [salt.state       :1813][INFO    ][14717] Executing state module.run for [maas.process_machines]
2019-05-09 13:23:16,800 [salt.utils.decorators:613 ][WARNING ][14717] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 13:23:17,458 [salt.loaded.ext.module.maas:412 ][WARNING ][14717] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 13:23:17,459 [salt.loaded.ext.module.maas:92  ][INFO    ][14717] 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=pepfsn architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-09 13:23:18,575 [salt.loaded.ext.module.maas:412 ][WARNING ][14717] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 13:23:18,576 [salt.loaded.ext.module.maas:92  ][INFO    ][14717] 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=nkwgas architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-09 13:23:19,626 [salt.loaded.ext.module.maas:412 ][WARNING ][14717] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 13:23:19,627 [salt.loaded.ext.module.maas:92  ][INFO    ][14717] 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=k68hyx architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-09 13:23:20,760 [salt.loaded.ext.module.maas:412 ][WARNING ][14717] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 13:23:20,760 [salt.loaded.ext.module.maas:92  ][INFO    ][14717] 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=pbtpbn architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-09 13:23:21,865 [salt.loaded.ext.module.maas:412 ][WARNING ][14717] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 13:23:21,865 [salt.loaded.ext.module.maas:92  ][INFO    ][14717] 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=q6eger architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-09 13:23:22,890 [salt.state       :300 ][INFO    ][14717] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-09 13:23:22,891 [salt.state       :1951][INFO    ][14717] Completed state [maas.process_machines] at time 13:23:22.891241 duration_in_ms=6093.028
2019-05-09 13:23:22,895 [salt.minion      :1711][INFO    ][14717] Returning information for job: 20190509132308933622
2019-05-09 13:23:55,789 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command state.apply with jid 20190509132355775905
2019-05-09 13:23:55,810 [salt.minion      :1432][INFO    ][14982] Starting a new job with PID 14982
2019-05-09 13:24:01,789 [salt.state       :915 ][INFO    ][14982] Loading fresh modules for state activity
2019-05-09 13:24:01,873 [salt.state       :1780][INFO    ][14982] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:24:01.873572
2019-05-09 13:24:01,875 [salt.state       :1813][INFO    ][14982] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 13:24:01,876 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14982] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 13:24:03,647 [salt.state       :300 ][INFO    ][14982] {'pid': 15018, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 13:24:03,647 [salt.state       :1951][INFO    ][14982] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:24:03.647797 duration_in_ms=1774.226
2019-05-09 13:24:03,651 [salt.state       :1780][INFO    ][14982] Running state [maas.wait_for_machine_status] at time 13:24:03.649686
2019-05-09 13:24:03,651 [salt.state       :1813][INFO    ][14982] Executing state module.run for [maas.wait_for_machine_status]
2019-05-09 13:24:03,651 [salt.utils.decorators:613 ][WARNING ][14982] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 13:24:05,911 [salt.state       :300 ][INFO    ][14982] {'ret': True}
2019-05-09 13:24:05,911 [salt.state       :1951][INFO    ][14982] Completed state [maas.wait_for_machine_status] at time 13:24:05.911846 duration_in_ms=2262.16
2019-05-09 13:24:05,914 [salt.minion      :1711][INFO    ][14982] Returning information for job: 20190509132355775905
2019-05-09 13:24:06,428 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command state.apply with jid 20190509132406420404
2019-05-09 13:24:06,441 [salt.minion      :1432][INFO    ][15031] Starting a new job with PID 15031
2019-05-09 13:24:07,345 [salt.state       :915 ][INFO    ][15031] Loading fresh modules for state activity
2019-05-09 13:24:07,478 [salt.state       :1780][INFO    ][15031] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:24:07.478121
2019-05-09 13:24:07,478 [salt.state       :1813][INFO    ][15031] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 13:24:07,479 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15031] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 13:24:09,250 [salt.state       :300 ][INFO    ][15031] {'pid': 15038, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 13:24:09,251 [salt.state       :1951][INFO    ][15031] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:24:09.250963 duration_in_ms=1772.841
2019-05-09 13:24:09,255 [salt.state       :1780][INFO    ][15031] Running state [maas_machines_storage_cmp002_lvm] at time 13:24:09.255279
2019-05-09 13:24:09,255 [salt.state       :1813][INFO    ][15031] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-09 13:24:09,772 [salt.state       :300 ][INFO    ][15031] Machine cmp002 is not in Ready state.
2019-05-09 13:24:09,772 [salt.state       :1951][INFO    ][15031] Completed state [maas_machines_storage_cmp002_lvm] at time 13:24:09.772649 duration_in_ms=517.37
2019-05-09 13:24:09,773 [salt.state       :1780][INFO    ][15031] Running state [maas_machines_storage_cmp001_lvm] at time 13:24:09.772997
2019-05-09 13:24:09,773 [salt.state       :1813][INFO    ][15031] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-09 13:24:10,230 [salt.state       :300 ][INFO    ][15031] Machine cmp001 is not in Ready state.
2019-05-09 13:24:10,231 [salt.state       :1951][INFO    ][15031] Completed state [maas_machines_storage_cmp001_lvm] at time 13:24:10.231037 duration_in_ms=458.039
2019-05-09 13:24:10,235 [salt.minion      :1711][INFO    ][15031] Returning information for job: 20190509132406420404
2019-05-09 13:24:10,833 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command state.apply with jid 20190509132410785488
2019-05-09 13:24:10,857 [salt.minion      :1432][INFO    ][15051] Starting a new job with PID 15051
2019-05-09 13:24:11,846 [salt.state       :915 ][INFO    ][15051] Loading fresh modules for state activity
2019-05-09 13:24:11,937 [salt.state       :1780][INFO    ][15051] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:24:11.937904
2019-05-09 13:24:11,938 [salt.state       :1813][INFO    ][15051] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 13:24:11,939 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15051] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 13:24:13,742 [salt.state       :300 ][INFO    ][15051] {'pid': 15058, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 13:24:13,744 [salt.state       :1951][INFO    ][15051] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:24:13.743906 duration_in_ms=1806.001
2019-05-09 13:24:13,747 [salt.state       :1780][INFO    ][15051] Running state [maas.deploy_machines] at time 13:24:13.747329
2019-05-09 13:24:13,748 [salt.state       :1813][INFO    ][15051] Executing state module.run for [maas.deploy_machines]
2019-05-09 13:24:13,748 [salt.utils.decorators:613 ][WARNING ][15051] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 13:24:14,319 [salt.loaded.ext.module.maas:684 ][INFO    ][15051] deploymachines hwe_kernel=hwe-16.04 system_id=q6eger distro_series=xenial
2019-05-09 13:24:16,714 [salt.state       :300 ][INFO    ][15051] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-09 13:24:16,714 [salt.state       :1951][INFO    ][15051] Completed state [maas.deploy_machines] at time 13:24:16.714411 duration_in_ms=2967.082
2019-05-09 13:24:16,717 [salt.minion      :1711][INFO    ][15051] Returning information for job: 20190509132410785488
2019-05-09 13:24:17,273 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command state.apply with jid 20190509132417262589
2019-05-09 13:24:17,290 [salt.minion      :1432][INFO    ][15122] Starting a new job with PID 15122
2019-05-09 13:24:18,232 [salt.state       :915 ][INFO    ][15122] Loading fresh modules for state activity
2019-05-09 13:24:18,321 [salt.state       :1780][INFO    ][15122] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:24:18.321486
2019-05-09 13:24:18,321 [salt.state       :1813][INFO    ][15122] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 13:24:18,323 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15122] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 13:24:20,128 [salt.state       :300 ][INFO    ][15122] {'pid': 15129, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 13:24:20,130 [salt.state       :1951][INFO    ][15122] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:24:20.129710 duration_in_ms=1808.223
2019-05-09 13:24:20,133 [salt.state       :1780][INFO    ][15122] Running state [maas.wait_for_machine_status] at time 13:24:20.133294
2019-05-09 13:24:20,134 [salt.state       :1813][INFO    ][15122] Executing state module.run for [maas.wait_for_machine_status]
2019-05-09 13:24:20,135 [salt.utils.decorators:613 ][WARNING ][15122] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 13:24:22,794 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.35295081s left)
2019-05-09 13:24:32,400 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132432386429
2019-05-09 13:24:32,421 [salt.minion      :1432][INFO    ][15155] Starting a new job with PID 15155
2019-05-09 13:24:32,444 [salt.minion      :1711][INFO    ][15155] Returning information for job: 20190509132432386429
2019-05-09 13:24:55,411 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.73663282s left)
2019-05-09 13:25:02,470 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132502455027
2019-05-09 13:25:02,492 [salt.minion      :1432][INFO    ][15207] Starting a new job with PID 15207
2019-05-09 13:25:02,516 [salt.minion      :1711][INFO    ][15207] Returning information for job: 20190509132502455027
2019-05-09 13:25:28,050 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2182.09726787s left)
2019-05-09 13:25:32,526 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132532511381
2019-05-09 13:25:32,548 [salt.minion      :1432][INFO    ][15232] Starting a new job with PID 15232
2019-05-09 13:25:32,571 [salt.minion      :1711][INFO    ][15232] Returning information for job: 20190509132532511381
2019-05-09 13:26:01,001 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.14685583s left)
2019-05-09 13:26:02,609 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132602594899
2019-05-09 13:26:02,633 [salt.minion      :1432][INFO    ][15297] Starting a new job with PID 15297
2019-05-09 13:26:02,654 [salt.minion      :1711][INFO    ][15297] Returning information for job: 20190509132602594899
2019-05-09 13:26:32,653 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132632645447
2019-05-09 13:26:32,668 [salt.minion      :1432][INFO    ][15328] Starting a new job with PID 15328
2019-05-09 13:26:32,689 [salt.minion      :1711][INFO    ][15328] Returning information for job: 20190509132632645447
2019-05-09 13:26:33,894 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.253438s left)
2019-05-09 13:27:02,724 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132702707760
2019-05-09 13:27:02,746 [salt.minion      :1432][INFO    ][15380] Starting a new job with PID 15380
2019-05-09 13:27:02,769 [salt.minion      :1711][INFO    ][15380] Returning information for job: 20190509132702707760
2019-05-09 13:27:06,394 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.75327492s left)
2019-05-09 13:27:32,787 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132732769411
2019-05-09 13:27:32,805 [salt.minion      :1432][INFO    ][15405] Starting a new job with PID 15405
2019-05-09 13:27:32,829 [salt.minion      :1711][INFO    ][15405] Returning information for job: 20190509132732769411
2019-05-09 13:27:39,027 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.11994004s left)
2019-05-09 13:28:02,863 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132802853230
2019-05-09 13:28:02,884 [salt.minion      :1432][INFO    ][15459] Starting a new job with PID 15459
2019-05-09 13:28:02,905 [salt.minion      :1711][INFO    ][15459] Returning information for job: 20190509132802853230
2019-05-09 13:28:11,745 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.40270495s left)
2019-05-09 13:28:32,928 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132832920445
2019-05-09 13:28:32,949 [salt.minion      :1432][INFO    ][15510] Starting a new job with PID 15510
2019-05-09 13:28:32,972 [salt.minion      :1711][INFO    ][15510] Returning information for job: 20190509132832920445
2019-05-09 13:28:44,488 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.65959501s left)
2019-05-09 13:29:02,991 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132902981697
2019-05-09 13:29:03,008 [salt.minion      :1432][INFO    ][15568] Starting a new job with PID 15568
2019-05-09 13:29:03,030 [salt.minion      :1711][INFO    ][15568] Returning information for job: 20190509132902981697
2019-05-09 13:29:17,367 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.78029799s left)
2019-05-09 13:29:33,064 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509132933056067
2019-05-09 13:29:33,078 [salt.minion      :1432][INFO    ][15643] Starting a new job with PID 15643
2019-05-09 13:29:33,097 [salt.minion      :1711][INFO    ][15643] Returning information for job: 20190509132933056067
2019-05-09 13:29:50,084 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1920.06336689s left)
2019-05-09 13:30:03,155 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509133003147081
2019-05-09 13:30:03,176 [salt.minion      :1432][INFO    ][15807] Starting a new job with PID 15807
2019-05-09 13:30:03,200 [salt.minion      :1711][INFO    ][15807] Returning information for job: 20190509133003147081
2019-05-09 13:30:22,720 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1887.42747903s left)
2019-05-09 13:30:33,210 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509133033201236
2019-05-09 13:30:33,223 [salt.minion      :1432][INFO    ][15852] Starting a new job with PID 15852
2019-05-09 13:30:33,245 [salt.minion      :1711][INFO    ][15852] Returning information for job: 20190509133033201236
2019-05-09 13:30:55,469 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1854.67852902s left)
2019-05-09 13:31:03,426 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509133103418430
2019-05-09 13:31:03,443 [salt.minion      :1432][INFO    ][15909] Starting a new job with PID 15909
2019-05-09 13:31:03,464 [salt.minion      :1711][INFO    ][15909] Returning information for job: 20190509133103418430
2019-05-09 13:31:28,194 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1821.95341802s left)
2019-05-09 13:31:33,451 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509133133443889
2019-05-09 13:31:33,466 [salt.minion      :1432][INFO    ][15961] Starting a new job with PID 15961
2019-05-09 13:31:33,488 [salt.minion      :1711][INFO    ][15961] Returning information for job: 20190509133133443889
2019-05-09 13:32:01,314 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1788.83294582s left)
2019-05-09 13:32:03,515 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509133203501544
2019-05-09 13:32:03,537 [salt.minion      :1432][INFO    ][16112] Starting a new job with PID 16112
2019-05-09 13:32:03,560 [salt.minion      :1711][INFO    ][16112] Returning information for job: 20190509133203501544
2019-05-09 13:32:33,570 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509133233563257
2019-05-09 13:32:33,583 [salt.minion      :1432][INFO    ][16168] Starting a new job with PID 16168
2019-05-09 13:32:33,603 [salt.minion      :1711][INFO    ][16168] Returning information for job: 20190509133233563257
2019-05-09 13:32:33,977 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1756.170614s left)
2019-05-09 13:33:03,632 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509133303616901
2019-05-09 13:33:03,656 [salt.minion      :1432][INFO    ][16243] Starting a new job with PID 16243
2019-05-09 13:33:03,678 [salt.minion      :1711][INFO    ][16243] Returning information for job: 20190509133303616901
2019-05-09 13:33:06,643 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1723.50392485s left)
2019-05-09 13:33:33,767 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509133333760241
2019-05-09 13:33:33,785 [salt.minion      :1432][INFO    ][16267] Starting a new job with PID 16267
2019-05-09 13:33:33,805 [salt.minion      :1711][INFO    ][16267] Returning information for job: 20190509133333760241
2019-05-09 13:33:39,460 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1690.68736792s left)
2019-05-09 13:34:03,884 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509133403875650
2019-05-09 13:34:03,906 [salt.minion      :1432][INFO    ][16319] Starting a new job with PID 16319
2019-05-09 13:34:03,929 [salt.minion      :1711][INFO    ][16319] Returning information for job: 20190509133403875650
2019-05-09 13:34:12,265 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1657.882581s left)
2019-05-09 13:34:34,016 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509133434005555
2019-05-09 13:34:34,038 [salt.minion      :1432][INFO    ][16344] Starting a new job with PID 16344
2019-05-09 13:34:34,061 [salt.minion      :1711][INFO    ][16344] Returning information for job: 20190509133434005555
2019-05-09 13:34:44,971 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1625.17637992s left)
2019-05-09 13:35:04,158 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509133504151457
2019-05-09 13:35:04,173 [salt.minion      :1432][INFO    ][16399] Starting a new job with PID 16399
2019-05-09 13:35:04,196 [salt.minion      :1711][INFO    ][16399] Returning information for job: 20190509133504151457
2019-05-09 13:35:17,841 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1592.30598402s left)
2019-05-09 13:35:34,324 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509133534311668
2019-05-09 13:35:34,349 [salt.minion      :1432][INFO    ][16424] Starting a new job with PID 16424
2019-05-09 13:35:34,372 [salt.minion      :1711][INFO    ][16424] Returning information for job: 20190509133534311668
2019-05-09 13:35:50,439 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1559.70822001s left)
2019-05-09 13:36:04,496 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509133604485300
2019-05-09 13:36:04,509 [salt.minion      :1432][INFO    ][16514] Starting a new job with PID 16514
2019-05-09 13:36:04,533 [salt.minion      :1711][INFO    ][16514] Returning information for job: 20190509133604485300
2019-05-09 13:36:23,265 [salt.loaded.ext.module.maas:1023][INFO    ][15122] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1526.88247681s left)
2019-05-09 13:36:34,645 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509133634637638
2019-05-09 13:36:34,660 [salt.minion      :1432][INFO    ][16705] Starting a new job with PID 16705
2019-05-09 13:36:34,686 [salt.minion      :1711][INFO    ][16705] Returning information for job: 20190509133634637638
2019-05-09 13:36:56,300 [salt.state       :300 ][INFO    ][15122] {'ret': True}
2019-05-09 13:36:56,301 [salt.state       :1951][INFO    ][15122] Completed state [maas.wait_for_machine_status] at time 13:36:56.301559 duration_in_ms=756168.259
2019-05-09 13:36:56,309 [salt.minion      :1711][INFO    ][15122] Returning information for job: 20190509132417262589
2019-05-09 13:37:00,058 [salt.utils.schedule:1377][INFO    ][6248] Running scheduled job: __mine_interval
2019-05-09 14:37:00,058 [salt.utils.schedule:1377][INFO    ][6248] Running scheduled job: __mine_interval
2019-05-09 14:58:00,583 [salt.minion      :1308][INFO    ][6248] User sudo_ubuntu Executing command cp.push_dir with jid 20190509145800567932
2019-05-09 14:58:00,612 [salt.minion      :1432][INFO    ][22570] Starting a new job with PID 22570
