2019-04-04 10:47:02,936 [salt.utils.decorators:613 ][WARNING ][1991] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 10:47:03,889 [salt.utils.decorators:613 ][WARNING ][1991] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 10:47:06,791 [salt.loaded.int.states.file:2298][WARNING ][2333] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-04 10:47:37,708 [salt.state       :2022][WARNING ][2868] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-04 10:47:40,711 [salt.utils.decorators:613 ][WARNING ][2868] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 10:47:58,450 [salt.utils.decorators:613 ][WARNING ][2868] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 10:48:20,125 [salt.utils.decorators:613 ][WARNING ][2868] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 10:48:21,043 [salt.utils.decorators:613 ][WARNING ][2868] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 10:48:23,004 [salt.loaded.ext.module.maasng:1008][WARNING ][2868] Detected cidr:192.168.11.0/24 in fabric:fabric-3
2019-04-04 10:48:23,004 [salt.loaded.ext.module.maasng:1011][WARNING ][2868] Guessing, that fabric with current name:fabric-3
 should be renamed to:pxe_admin
2019-04-04 10:48:23,743 [salt.loaded.ext.module.maasng:1235][WARNING ][2868] Ignoring parameter vlan:0
2019-04-04 10:48:33,326 [salt.utils.decorators:613 ][WARNING ][5426] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 10:48:33,404 [salt.loaded.ext.module.maas:412 ][WARNING ][5426] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-04 10:48:34,740 [salt.loaded.ext.module.maas:412 ][WARNING ][5426] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-04 10:48:35,821 [salt.loaded.ext.module.maas:412 ][WARNING ][5426] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-04 10:48:36,896 [salt.loaded.ext.module.maas:412 ][WARNING ][5426] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-04 10:48:37,958 [salt.loaded.ext.module.maas:412 ][WARNING ][5426] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-04 10:48:42,683 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6010] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-04 10:48:42,715 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6010] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-04 10:48:42,757 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-04-04 10:48:43,754 [salt.cli.daemons :293 ][INFO    ][6103] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-04-04 10:48:43,870 [salt.cli.daemons :82  ][INFO    ][6103] Starting up the Salt Minion
2019-04-04 10:48:43,871 [salt.utils.event :1017][INFO    ][6103] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-04-04 10:48:45,009 [salt.minion      :976 ][INFO    ][6103] Creating minion process manager
2019-04-04 10:48:46,879 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6103] Executing command ['date', '+%z'] in directory '/root'
2019-04-04 10:48:46,898 [salt.utils.schedule:568 ][INFO    ][6103] Updating job settings for scheduled job: __mine_interval
2019-04-04 10:48:46,901 [salt.minion      :1108][INFO    ][6103] Added mine.update to scheduler
2019-04-04 10:48:46,906 [salt.minion      :1975][INFO    ][6103] Minion is starting as user 'root'
2019-04-04 10:48:46,918 [salt.minion      :2336][INFO    ][6103] Minion is ready to receive requests!
2019-04-04 10:49:20,685 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command state.apply with jid 20190404104920672921
2019-04-04 10:49:20,704 [salt.minion      :1432][INFO    ][6213] Starting a new job with PID 6213
2019-04-04 10:49:26,508 [salt.state       :915 ][INFO    ][6213] Loading fresh modules for state activity
2019-04-04 10:49:26,560 [salt.fileclient  :1219][INFO    ][6213] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-04 10:49:26,604 [salt.state       :1780][INFO    ][6213] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:49:26.604813
2019-04-04 10:49:26,605 [salt.state       :1813][INFO    ][6213] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-04 10:49:26,606 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6213] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-04 10:49:28,388 [salt.state       :300 ][INFO    ][6213] {'pid': 6220, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-04 10:49:28,389 [salt.state       :1951][INFO    ][6213] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:49:28.388909 duration_in_ms=1784.096
2019-04-04 10:49:28,392 [salt.state       :1780][INFO    ][6213] Running state [maas.wait_for_machine_status] at time 10:49:28.392446
2019-04-04 10:49:28,393 [salt.state       :1813][INFO    ][6213] Executing state module.run for [maas.wait_for_machine_status]
2019-04-04 10:49:28,393 [salt.utils.decorators:613 ][WARNING ][6213] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 10:49:29,266 [salt.loaded.ext.module.maas:1023][INFO    ][6213] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.13709521s left)
2019-04-04 10:49:35,817 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404104935800498
2019-04-04 10:49:35,839 [salt.minion      :1432][INFO    ][6243] Starting a new job with PID 6243
2019-04-04 10:49:35,860 [salt.minion      :1711][INFO    ][6243] Returning information for job: 20190404104935800498
2019-04-04 10:50:00,073 [salt.loaded.ext.module.maas:1023][INFO    ][6213] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.3297472s left)
2019-04-04 10:50:06,063 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105006012698
2019-04-04 10:50:06,082 [salt.minion      :1432][INFO    ][6319] Starting a new job with PID 6319
2019-04-04 10:50:06,105 [salt.minion      :1711][INFO    ][6319] Returning information for job: 20190404105006012698
2019-04-04 10:50:30,879 [salt.loaded.ext.module.maas:1023][INFO    ][6213] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.524369s left)
2019-04-04 10:50:36,130 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105036122731
2019-04-04 10:50:36,152 [salt.minion      :1432][INFO    ][6348] Starting a new job with PID 6348
2019-04-04 10:50:36,179 [salt.minion      :1711][INFO    ][6348] Returning information for job: 20190404105036122731
2019-04-04 10:51:01,903 [salt.loaded.ext.module.maas:1023][INFO    ][6213] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.50034118s left)
2019-04-04 10:51:06,200 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105106188978
2019-04-04 10:51:06,218 [salt.minion      :1432][INFO    ][6447] Starting a new job with PID 6447
2019-04-04 10:51:06,240 [salt.minion      :1711][INFO    ][6447] Returning information for job: 20190404105106188978
2019-04-04 10:51:32,805 [salt.loaded.ext.module.maas:1023][INFO    ][6213] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.5979712s left)
2019-04-04 10:51:36,233 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105136222206
2019-04-04 10:51:36,254 [salt.minion      :1432][INFO    ][6515] Starting a new job with PID 6515
2019-04-04 10:51:36,276 [salt.minion      :1711][INFO    ][6515] Returning information for job: 20190404105136222206
2019-04-04 10:52:03,884 [salt.loaded.ext.module.maas:1023][INFO    ][6213] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.51855612s left)
2019-04-04 10:52:06,309 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105206294168
2019-04-04 10:52:06,331 [salt.minion      :1432][INFO    ][6694] Starting a new job with PID 6694
2019-04-04 10:52:06,350 [salt.minion      :1711][INFO    ][6694] Returning information for job: 20190404105206294168
2019-04-04 10:52:35,089 [salt.loaded.ext.module.maas:1023][INFO    ][6213] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.31340098s left)
2019-04-04 10:52:36,385 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105236371324
2019-04-04 10:52:36,406 [salt.minion      :1432][INFO    ][6765] Starting a new job with PID 6765
2019-04-04 10:52:36,431 [salt.minion      :1711][INFO    ][6765] Returning information for job: 20190404105236371324
2019-04-04 10:53:06,443 [salt.loaded.ext.module.maas:1023][INFO    ][6213] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.95964408s left)
2019-04-04 10:53:06,466 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105306453985
2019-04-04 10:53:06,486 [salt.minion      :1432][INFO    ][7103] Starting a new job with PID 7103
2019-04-04 10:53:06,506 [salt.minion      :1711][INFO    ][7103] Returning information for job: 20190404105306453985
2019-04-04 10:53:36,566 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105336559445
2019-04-04 10:53:36,583 [salt.minion      :1432][INFO    ][7231] Starting a new job with PID 7231
2019-04-04 10:53:36,607 [salt.minion      :1711][INFO    ][7231] Returning information for job: 20190404105336559445
2019-04-04 10:53:38,116 [salt.loaded.ext.module.maas:1023][INFO    ][6213] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.28678918s left)
2019-04-04 10:54:06,661 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105406653662
2019-04-04 10:54:06,673 [salt.minion      :1432][INFO    ][7679] Starting a new job with PID 7679
2019-04-04 10:54:06,694 [salt.minion      :1711][INFO    ][7679] Returning information for job: 20190404105406653662
2019-04-04 10:54:10,039 [salt.loaded.ext.module.maas:1023][INFO    ][6213] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.36325312s left)
2019-04-04 10:54:36,751 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105436739936
2019-04-04 10:54:36,770 [salt.minion      :1432][INFO    ][7745] Starting a new job with PID 7745
2019-04-04 10:54:36,792 [salt.minion      :1711][INFO    ][7745] Returning information for job: 20190404105436739936
2019-04-04 10:54:43,092 [salt.loaded.ext.module.maas:1023][INFO    ][6213] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.31079698s left)
2019-04-04 10:55:06,895 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105506883980
2019-04-04 10:55:06,911 [salt.minion      :1432][INFO    ][7998] Starting a new job with PID 7998
2019-04-04 10:55:06,933 [salt.minion      :1711][INFO    ][7998] Returning information for job: 20190404105506883980
2019-04-04 10:55:15,906 [salt.state       :300 ][INFO    ][6213] {'ret': True}
2019-04-04 10:55:15,906 [salt.state       :1951][INFO    ][6213] Completed state [maas.wait_for_machine_status] at time 10:55:15.906423 duration_in_ms=347513.977
2019-04-04 10:55:15,909 [salt.minion      :1711][INFO    ][6213] Returning information for job: 20190404104920672921
2019-04-04 10:55:16,450 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command state.apply with jid 20190404105516441689
2019-04-04 10:55:16,470 [salt.minion      :1432][INFO    ][8067] Starting a new job with PID 8067
2019-04-04 10:55:22,396 [salt.state       :915 ][INFO    ][8067] Loading fresh modules for state activity
2019-04-04 10:55:22,447 [salt.fileclient  :1219][INFO    ][8067] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-04 10:55:22,550 [salt.state       :1780][INFO    ][8067] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:55:22.550753
2019-04-04 10:55:22,551 [salt.state       :1813][INFO    ][8067] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-04 10:55:22,552 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8067] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-04 10:55:24,209 [salt.state       :300 ][INFO    ][8067] {'pid': 8075, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-04 10:55:24,210 [salt.state       :1951][INFO    ][8067] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:55:24.210263 duration_in_ms=1659.51
2019-04-04 10:55:24,214 [salt.state       :1780][INFO    ][8067] Running state [maas_machines_storage_cmp002_lvm] at time 10:55:24.214478
2019-04-04 10:55:24,215 [salt.state       :1813][INFO    ][8067] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-04 10:55:25,258 [salt.loaded.ext.module.maasng:610 ][INFO    ][8067] 7hgdsr
2019-04-04 10:55:25,258 [salt.loaded.ext.module.maasng:626 ][INFO    ][8067] sda
2019-04-04 10:55:25,810 [salt.loaded.ext.module.maasng:361 ][INFO    ][8067] 7hgdsr
2019-04-04 10:55:25,902 [salt.loaded.ext.module.maasng:367 ][INFO    ][8067] [{u'size': 800109715456, u'partition_table_type': u'MBR', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'74851867-8e28-444f-9b92-07454d971f68', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7hgdsr', u'filesystem': {u'uuid': u'474a2b3e-0387-4ac5-8dfc-a4e9fc1d62bc', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}], u'id': 1, u'system_id': u'7hgdsr', u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'name': u'sda'}, {u'size': 800097042432, u'partition_table_type': None, u'available_size': 0, u'uuid': u'5ef4d296-940a-4d34-b7e6-3ea38f2f5aad', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'partitions': [], u'id': 3, u'system_id': u'7hgdsr', u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/blockdevices/3/', u'filesystem': {u'uuid': u'5b363104-447a-4b59-ab6e-c39efe035a01', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'name': u'vgroot-lvroot'}]
2019-04-04 10:55:25,902 [salt.loaded.ext.module.maasng:632 ][INFO    ][8067] vgroot
2019-04-04 10:55:25,903 [salt.loaded.ext.module.maasng:635 ][INFO    ][8067] lvroot
2019-04-04 10:55:25,903 [salt.loaded.ext.module.maasng:639 ][INFO    ][8067] 107374182400
2019-04-04 10:55:26,497 [salt.loaded.ext.module.maasng:645 ][INFO    ][8067] {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'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.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'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/blockdevices/1/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'7bafa283-fe5c-42ba-8c10-135790dae233', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7hgdsr', u'filesystem': {u'uuid': u'1a33fe57-3173-48eb-9995-556fa8ba1ffa', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'id': 1, u'system_id': u'7hgdsr', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'name': u'sda'}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/7hgdsr/', u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'address_ttl': None, u'system_id': u'7hgdsr', 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'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/blockdevices/11/', u'available_size': 0, u'uuid': u'662f0bb8-4918-45f3-958a-6068f4bd656a', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'id': 11, u'system_id': u'7hgdsr', u'partition_table_type': None, u'filesystem': {u'uuid': u'2c264874-d215-40f0-9cf1-f09d57065646', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'size': 800109715456, u'partition_table_type': u'MBR', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'7bafa283-fe5c-42ba-8c10-135790dae233', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7hgdsr', u'filesystem': {u'uuid': u'1a33fe57-3173-48eb-9995-556fa8ba1ffa', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'id': 1, u'system_id': u'7hgdsr', u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'name': u'sda'}, {u'size': 107374182400, u'partition_table_type': None, u'available_size': 0, u'uuid': u'662f0bb8-4918-45f3-958a-6068f4bd656a', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'id': 11, u'system_id': u'7hgdsr', u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/blockdevices/11/', u'filesystem': {u'uuid': u'2c264874-d215-40f0-9cf1-f09d57065646', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'name': u'vgroot-lvroot'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/blockdevices/1/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'7bafa283-fe5c-42ba-8c10-135790dae233', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7hgdsr', u'filesystem': {u'uuid': u'1a33fe57-3173-48eb-9995-556fa8ba1ffa', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'id': 1, u'system_id': u'7hgdsr', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'name': u'sda'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'7hgdsr', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', 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'dhcp_on': True, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 22}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, u'enabled': True, u'effective_mtu': 1500, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'7hgdsr', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/interfaces/4/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', 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'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'dhcp_on': True, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 22}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, u'enabled': True, u'effective_mtu': 1500, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'7hgdsr', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 14, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'7hgdsr', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/interfaces/14/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'7hgdsr', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/interfaces/13/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 23}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 15, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'7hgdsr', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/interfaces/15/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'7hgdsr', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/interfaces/11/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, 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'dhcp_on': True, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 12, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'7hgdsr', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7hgdsr/interfaces/12/'}], u'commissioning_status_name': u'Passed', u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-04-04 10:55:26,500 [salt.state       :300 ][INFO    ][8067] {'new': {'storage_layout': 'lvm'}}
2019-04-04 10:55:26,500 [salt.state       :1951][INFO    ][8067] Completed state [maas_machines_storage_cmp002_lvm] at time 10:55:26.500324 duration_in_ms=2285.847
2019-04-04 10:55:26,500 [salt.state       :1780][INFO    ][8067] Running state [maas_machines_storage_cmp001_lvm] at time 10:55:26.500706
2019-04-04 10:55:26,501 [salt.state       :1813][INFO    ][8067] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-04 10:55:27,682 [salt.loaded.ext.module.maasng:610 ][INFO    ][8067] a8qkey
2019-04-04 10:55:27,683 [salt.loaded.ext.module.maasng:626 ][INFO    ][8067] sda
2019-04-04 10:55:28,224 [salt.loaded.ext.module.maasng:361 ][INFO    ][8067] a8qkey
2019-04-04 10:55:28,303 [salt.loaded.ext.module.maasng:367 ][INFO    ][8067] [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'1d7e30b5-f179-49ea-bba5-dd78f4894712', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'a8qkey', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'22a930b2-93ff-4739-9b3b-8de3f0062666', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/blockdevices/2/partition/2'}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'a8qkey', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/blockdevices/2/'}, {u'block_size': 4096, u'uuid': u'3cadce83-fd42-4de1-8af8-5c5149f195ad', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'id': 4, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'a8qkey', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'2f3d2ac3-f10e-48e2-917b-b2a2166a0a82', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 800097042432, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/blockdevices/4/'}]
2019-04-04 10:55:28,303 [salt.loaded.ext.module.maasng:632 ][INFO    ][8067] vgroot
2019-04-04 10:55:28,304 [salt.loaded.ext.module.maasng:635 ][INFO    ][8067] lvroot
2019-04-04 10:55:28,304 [salt.loaded.ext.module.maasng:639 ][INFO    ][8067] 107374182400
2019-04-04 10:55:28,884 [salt.loaded.ext.module.maasng:645 ][INFO    ][8067] {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'status_action': u'', u'swap_size': None, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.39'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 28}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'system_id': u'a8qkey', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/blockdevices/2/', u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'tags': [u'ssd'], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'a8qkey', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'7c54d8ed-ece2-4ac3-ba9d-8717ba0c91ac', u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'a8qkey', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'5bdfd15a-9567-4105-a295-a436aa7be73f', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, u'blockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/blockdevices/2/', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'a8qkey', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'7c54d8ed-ece2-4ac3-ba9d-8717ba0c91ac', u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'a8qkey', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'5bdfd15a-9567-4105-a295-a436aa7be73f', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/blockdevices/12/', u'uuid': u'809527aa-9fc0-41c4-898e-1caae5cb77fc', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'a8qkey', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'18043d13-0835-44ff-b818-87f815b1686c', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'partitions': []}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/a8qkey/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'a8qkey', 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'owner_data': {}, u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'physicalblockdevice_set': [{u'block_size': 4096, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/blockdevices/2/', u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'tags': [u'ssd'], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'a8qkey', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'7c54d8ed-ece2-4ac3-ba9d-8717ba0c91ac', u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'a8qkey', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'5bdfd15a-9567-4105-a295-a436aa7be73f', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'a8qkey', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'block_size': 4096, u'uuid': u'809527aa-9fc0-41c4-898e-1caae5cb77fc', u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/blockdevices/12/', u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'tags': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'a8qkey', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'18043d13-0835-44ff-b818-87f815b1686c', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'architecture': u'amd64/generic', u'interface_set': [{u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.39'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 28}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'system_id': u'a8qkey', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/interfaces/5/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'system_id': u'a8qkey', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/interfaces/16/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'system_id': u'a8qkey', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/interfaces/17/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', 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'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'system_id': u'a8qkey', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/interfaces/18/'}, {u'discovered': None, u'name': u'ens2f1', u'links': [{u'id': 30, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', 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'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'system_id': u'a8qkey', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/interfaces/19/'}, {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.44'}], u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'mode': u'link_up', u'id': 31}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'system_id': u'a8qkey', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/a8qkey/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-04-04 10:55:28,888 [salt.state       :300 ][INFO    ][8067] {'new': {'storage_layout': 'lvm'}}
2019-04-04 10:55:28,888 [salt.state       :1951][INFO    ][8067] Completed state [maas_machines_storage_cmp001_lvm] at time 10:55:28.888905 duration_in_ms=2388.198
2019-04-04 10:55:28,892 [salt.minion      :1711][INFO    ][8067] Returning information for job: 20190404105516441689
2019-04-04 10:55:29,432 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command state.apply with jid 20190404105529417650
2019-04-04 10:55:29,455 [salt.minion      :1432][INFO    ][8113] Starting a new job with PID 8113
2019-04-04 10:55:30,448 [salt.state       :915 ][INFO    ][8113] Loading fresh modules for state activity
2019-04-04 10:55:30,505 [salt.fileclient  :1219][INFO    ][8113] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-04 10:55:30,549 [salt.state       :1780][INFO    ][8113] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:55:30.549053
2019-04-04 10:55:30,549 [salt.state       :1813][INFO    ][8113] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-04 10:55:30,551 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8113] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-04 10:55:32,237 [salt.state       :300 ][INFO    ][8113] {'pid': 8127, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-04 10:55:32,238 [salt.state       :1951][INFO    ][8113] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:55:32.238488 duration_in_ms=1689.435
2019-04-04 10:55:32,240 [salt.state       :1780][INFO    ][8113] Running state [maas.deploy_machines] at time 10:55:32.240748
2019-04-04 10:55:32,241 [salt.state       :1813][INFO    ][8113] Executing state module.run for [maas.deploy_machines]
2019-04-04 10:55:32,241 [salt.utils.decorators:613 ][WARNING ][8113] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 10:55:32,801 [salt.loaded.ext.module.maas:684 ][INFO    ][8113] deploymachines hwe_kernel=hwe-16.04 system_id=7hgdsr distro_series=xenial
2019-04-04 10:55:35,064 [salt.loaded.ext.module.maas:684 ][INFO    ][8113] deploymachines hwe_kernel=hwe-16.04 system_id=a8qkey distro_series=xenial
2019-04-04 10:55:37,248 [salt.loaded.ext.module.maas:684 ][INFO    ][8113] deploymachines hwe_kernel=hwe-16.04 system_id=fhm4qw distro_series=xenial
2019-04-04 10:55:39,585 [salt.loaded.ext.module.maas:684 ][INFO    ][8113] deploymachines hwe_kernel=hwe-16.04 system_id=raa87f distro_series=xenial
2019-04-04 10:55:42,097 [salt.loaded.ext.module.maas:684 ][INFO    ][8113] deploymachines hwe_kernel=hwe-16.04 system_id=eqamnn distro_series=xenial
2019-04-04 10:55:44,480 [salt.state       :300 ][INFO    ][8113] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-04 10:55:44,481 [salt.state       :1951][INFO    ][8113] Completed state [maas.deploy_machines] at time 10:55:44.481219 duration_in_ms=12240.47
2019-04-04 10:55:44,484 [salt.minion      :1711][INFO    ][8113] Returning information for job: 20190404105529417650
2019-04-04 10:55:45,032 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command state.apply with jid 20190404105545016884
2019-04-04 10:55:45,054 [salt.minion      :1432][INFO    ][8453] Starting a new job with PID 8453
2019-04-04 10:55:51,057 [salt.state       :915 ][INFO    ][8453] Loading fresh modules for state activity
2019-04-04 10:55:51,114 [salt.fileclient  :1219][INFO    ][8453] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-04 10:55:51,160 [salt.state       :1780][INFO    ][8453] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:55:51.160916
2019-04-04 10:55:51,161 [salt.state       :1813][INFO    ][8453] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-04 10:55:51,163 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8453] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-04 10:55:52,847 [salt.state       :300 ][INFO    ][8453] {'pid': 8480, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-04 10:55:52,849 [salt.state       :1951][INFO    ][8453] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:55:52.848942 duration_in_ms=1688.025
2019-04-04 10:55:52,852 [salt.state       :1780][INFO    ][8453] Running state [maas.wait_for_machine_status] at time 10:55:52.852888
2019-04-04 10:55:52,853 [salt.state       :1813][INFO    ][8453] Executing state module.run for [maas.wait_for_machine_status]
2019-04-04 10:55:52,853 [salt.utils.decorators:613 ][WARNING ][8453] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 10:55:55,901 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.95910096s left)
2019-04-04 10:56:00,108 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105600100169
2019-04-04 10:56:00,129 [salt.minion      :1432][INFO    ][8495] Starting a new job with PID 8495
2019-04-04 10:56:00,150 [salt.minion      :1711][INFO    ][8495] Returning information for job: 20190404105600100169
2019-04-04 10:56:28,745 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.11498189s left)
2019-04-04 10:56:30,310 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105630297637
2019-04-04 10:56:30,332 [salt.minion      :1432][INFO    ][8533] Starting a new job with PID 8533
2019-04-04 10:56:30,359 [salt.minion      :1711][INFO    ][8533] Returning information for job: 20190404105630297637
2019-04-04 10:57:00,361 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105700349641
2019-04-04 10:57:00,374 [salt.minion      :1432][INFO    ][8604] Starting a new job with PID 8604
2019-04-04 10:57:00,395 [salt.minion      :1711][INFO    ][8604] Returning information for job: 20190404105700349641
2019-04-04 10:57:01,523 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.33701301s left)
2019-04-04 10:57:30,560 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105730551204
2019-04-04 10:57:30,583 [salt.minion      :1432][INFO    ][8635] Starting a new job with PID 8635
2019-04-04 10:57:30,602 [salt.minion      :1711][INFO    ][8635] Returning information for job: 20190404105730551204
2019-04-04 10:57:34,358 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.50215101s left)
2019-04-04 10:58:00,609 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105800597321
2019-04-04 10:58:00,633 [salt.minion      :1432][INFO    ][8724] Starting a new job with PID 8724
2019-04-04 10:58:00,654 [salt.minion      :1711][INFO    ][8724] Returning information for job: 20190404105800597321
2019-04-04 10:58:07,324 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.53785706s left)
2019-04-04 10:58:30,660 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105830653230
2019-04-04 10:58:30,673 [salt.minion      :1432][INFO    ][8774] Starting a new job with PID 8774
2019-04-04 10:58:30,693 [salt.minion      :1711][INFO    ][8774] Returning information for job: 20190404105830653230
2019-04-04 10:58:40,111 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.74889302s left)
2019-04-04 10:59:00,742 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105900731886
2019-04-04 10:59:00,758 [salt.minion      :1432][INFO    ][8945] Starting a new job with PID 8945
2019-04-04 10:59:00,780 [salt.minion      :1711][INFO    ][8945] Returning information for job: 20190404105900731886
2019-04-04 10:59:13,004 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.85633802s left)
2019-04-04 10:59:30,816 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404105930805389
2019-04-04 10:59:30,834 [salt.minion      :1432][INFO    ][9009] Starting a new job with PID 9009
2019-04-04 10:59:30,854 [salt.minion      :1711][INFO    ][9009] Returning information for job: 20190404105930805389
2019-04-04 10:59:45,848 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.01211405s left)
2019-04-04 11:00:00,942 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110000928051
2019-04-04 11:00:00,958 [salt.minion      :1432][INFO    ][9348] Starting a new job with PID 9348
2019-04-04 11:00:00,980 [salt.minion      :1711][INFO    ][9348] Returning information for job: 20190404110000928051
2019-04-04 11:00:18,710 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1984.14999795s left)
2019-04-04 11:00:31,032 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110031018286
2019-04-04 11:00:31,053 [salt.minion      :1432][INFO    ][9453] Starting a new job with PID 9453
2019-04-04 11:00:31,078 [salt.minion      :1711][INFO    ][9453] Returning information for job: 20190404110031018286
2019-04-04 11:00:51,730 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1951.13053703s left)
2019-04-04 11:01:01,172 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110101162811
2019-04-04 11:01:01,195 [salt.minion      :1432][INFO    ][9749] Starting a new job with PID 9749
2019-04-04 11:01:01,217 [salt.minion      :1711][INFO    ][9749] Returning information for job: 20190404110101162811
2019-04-04 11:01:24,513 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.34771395s left)
2019-04-04 11:01:31,249 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110131234982
2019-04-04 11:01:31,269 [salt.minion      :1432][INFO    ][9781] Starting a new job with PID 9781
2019-04-04 11:01:31,291 [salt.minion      :1711][INFO    ][9781] Returning information for job: 20190404110131234982
2019-04-04 11:01:57,636 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1885.2241199s left)
2019-04-04 11:02:01,401 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110201389281
2019-04-04 11:02:01,424 [salt.minion      :1432][INFO    ][10316] Starting a new job with PID 10316
2019-04-04 11:02:01,444 [salt.minion      :1711][INFO    ][10316] Returning information for job: 20190404110201389281
2019-04-04 11:02:30,292 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1852.56985593s left)
2019-04-04 11:02:31,508 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110231498794
2019-04-04 11:02:31,530 [salt.minion      :1432][INFO    ][10353] Starting a new job with PID 10353
2019-04-04 11:02:31,552 [salt.minion      :1711][INFO    ][10353] Returning information for job: 20190404110231498794
2019-04-04 11:03:01,670 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110301659938
2019-04-04 11:03:01,688 [salt.minion      :1432][INFO    ][10630] Starting a new job with PID 10630
2019-04-04 11:03:01,709 [salt.minion      :1711][INFO    ][10630] Returning information for job: 20190404110301659938
2019-04-04 11:03:03,124 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1819.73625398s left)
2019-04-04 11:03:31,796 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110331784108
2019-04-04 11:03:31,814 [salt.minion      :1432][INFO    ][10658] Starting a new job with PID 10658
2019-04-04 11:03:31,836 [salt.minion      :1711][INFO    ][10658] Returning information for job: 20190404110331784108
2019-04-04 11:03:35,774 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1787.08631897s left)
2019-04-04 11:04:01,974 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110401959579
2019-04-04 11:04:01,997 [salt.minion      :1432][INFO    ][10970] Starting a new job with PID 10970
2019-04-04 11:04:02,017 [salt.minion      :1711][INFO    ][10970] Returning information for job: 20190404110401959579
2019-04-04 11:04:08,497 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1754.363585s left)
2019-04-04 11:04:32,120 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110432106718
2019-04-04 11:04:32,142 [salt.minion      :1432][INFO    ][11036] Starting a new job with PID 11036
2019-04-04 11:04:32,161 [salt.minion      :1711][INFO    ][11036] Returning information for job: 20190404110432106718
2019-04-04 11:04:42,014 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1720.84623098s left)
2019-04-04 11:05:02,161 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110502151878
2019-04-04 11:05:02,179 [salt.minion      :1432][INFO    ][11256] Starting a new job with PID 11256
2019-04-04 11:05:02,203 [salt.minion      :1711][INFO    ][11256] Returning information for job: 20190404110502151878
2019-04-04 11:05:14,729 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1688.13115001s left)
2019-04-04 11:05:32,268 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110532256478
2019-04-04 11:05:32,288 [salt.minion      :1432][INFO    ][11299] Starting a new job with PID 11299
2019-04-04 11:05:32,311 [salt.minion      :1711][INFO    ][11299] Returning information for job: 20190404110532256478
2019-04-04 11:05:47,401 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1655.45879197s left)
2019-04-04 11:06:02,377 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110602369014
2019-04-04 11:06:02,393 [salt.minion      :1432][INFO    ][11572] Starting a new job with PID 11572
2019-04-04 11:06:02,413 [salt.minion      :1711][INFO    ][11572] Returning information for job: 20190404110602369014
2019-04-04 11:06:20,177 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1622.68348408s left)
2019-04-04 11:06:32,409 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110632401418
2019-04-04 11:06:32,424 [salt.minion      :1432][INFO    ][11601] Starting a new job with PID 11601
2019-04-04 11:06:32,444 [salt.minion      :1711][INFO    ][11601] Returning information for job: 20190404110632401418
2019-04-04 11:06:52,752 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1590.10800409s left)
2019-04-04 11:07:02,458 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110702449960
2019-04-04 11:07:02,474 [salt.minion      :1432][INFO    ][11741] Starting a new job with PID 11741
2019-04-04 11:07:02,495 [salt.minion      :1711][INFO    ][11741] Returning information for job: 20190404110702449960
2019-04-04 11:07:25,609 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1557.25077391s left)
2019-04-04 11:07:32,533 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110732526116
2019-04-04 11:07:32,556 [salt.minion      :1432][INFO    ][11902] Starting a new job with PID 11902
2019-04-04 11:07:32,578 [salt.minion      :1711][INFO    ][11902] Returning information for job: 20190404110732526116
2019-04-04 11:07:58,502 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1524.3583281s left)
2019-04-04 11:08:02,625 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110802617966
2019-04-04 11:08:02,642 [salt.minion      :1432][INFO    ][12029] Starting a new job with PID 12029
2019-04-04 11:08:02,664 [salt.minion      :1711][INFO    ][12029] Returning information for job: 20190404110802617966
2019-04-04 11:08:31,064 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1491.7966001s left)
2019-04-04 11:08:32,693 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110832684617
2019-04-04 11:08:32,708 [salt.minion      :1432][INFO    ][12089] Starting a new job with PID 12089
2019-04-04 11:08:32,731 [salt.minion      :1711][INFO    ][12089] Returning information for job: 20190404110832684617
2019-04-04 11:09:02,826 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110902817240
2019-04-04 11:09:02,843 [salt.minion      :1432][INFO    ][12397] Starting a new job with PID 12397
2019-04-04 11:09:02,865 [salt.minion      :1711][INFO    ][12397] Returning information for job: 20190404110902817240
2019-04-04 11:09:03,986 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1458.874403s left)
2019-04-04 11:09:32,925 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404110932917120
2019-04-04 11:09:32,948 [salt.minion      :1432][INFO    ][12424] Starting a new job with PID 12424
2019-04-04 11:09:32,975 [salt.minion      :1711][INFO    ][12424] Returning information for job: 20190404110932917120
2019-04-04 11:09:36,913 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1425.9473381s left)
2019-04-04 11:10:03,043 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111003035278
2019-04-04 11:10:03,058 [salt.minion      :1432][INFO    ][12496] Starting a new job with PID 12496
2019-04-04 11:10:03,082 [salt.minion      :1711][INFO    ][12496] Returning information for job: 20190404111003035278
2019-04-04 11:10:09,610 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1393.25076389s left)
2019-04-04 11:10:33,181 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111033170752
2019-04-04 11:10:33,200 [salt.minion      :1432][INFO    ][12525] Starting a new job with PID 12525
2019-04-04 11:10:33,221 [salt.minion      :1711][INFO    ][12525] Returning information for job: 20190404111033170752
2019-04-04 11:10:42,560 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1360.30059195s left)
2019-04-04 11:11:03,300 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111103290617
2019-04-04 11:11:03,317 [salt.minion      :1432][INFO    ][12604] Starting a new job with PID 12604
2019-04-04 11:11:03,337 [salt.minion      :1711][INFO    ][12604] Returning information for job: 20190404111103290617
2019-04-04 11:11:15,211 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1327.64962792s left)
2019-04-04 11:11:33,428 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111133420037
2019-04-04 11:11:33,441 [salt.minion      :1432][INFO    ][12633] Starting a new job with PID 12633
2019-04-04 11:11:33,463 [salt.minion      :1711][INFO    ][12633] Returning information for job: 20190404111133420037
2019-04-04 11:11:47,683 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1295.17687201s left)
2019-04-04 11:12:03,525 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111203517905
2019-04-04 11:12:03,541 [salt.minion      :1432][INFO    ][12709] Starting a new job with PID 12709
2019-04-04 11:12:03,564 [salt.minion      :1711][INFO    ][12709] Returning information for job: 20190404111203517905
2019-04-04 11:12:20,232 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1262.62815404s left)
2019-04-04 11:12:33,613 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111233606228
2019-04-04 11:12:33,629 [salt.minion      :1432][INFO    ][12747] Starting a new job with PID 12747
2019-04-04 11:12:33,649 [salt.minion      :1711][INFO    ][12747] Returning information for job: 20190404111233606228
2019-04-04 11:12:52,856 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1230.0042789s left)
2019-04-04 11:13:03,739 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111303730752
2019-04-04 11:13:03,754 [salt.minion      :1432][INFO    ][12820] Starting a new job with PID 12820
2019-04-04 11:13:03,779 [salt.minion      :1711][INFO    ][12820] Returning information for job: 20190404111303730752
2019-04-04 11:13:25,329 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1197.53136897s left)
2019-04-04 11:13:33,874 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111333860964
2019-04-04 11:13:33,894 [salt.minion      :1432][INFO    ][12851] Starting a new job with PID 12851
2019-04-04 11:13:33,923 [salt.minion      :1711][INFO    ][12851] Returning information for job: 20190404111333860964
2019-04-04 11:13:57,976 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1164.88375807s left)
2019-04-04 11:14:04,036 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111404028248
2019-04-04 11:14:04,052 [salt.minion      :1432][INFO    ][12924] Starting a new job with PID 12924
2019-04-04 11:14:04,082 [salt.minion      :1711][INFO    ][12924] Returning information for job: 20190404111404028248
2019-04-04 11:14:30,484 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1132.37613797s left)
2019-04-04 11:14:34,235 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111434220090
2019-04-04 11:14:34,256 [salt.minion      :1432][INFO    ][12953] Starting a new job with PID 12953
2019-04-04 11:14:34,285 [salt.minion      :1711][INFO    ][12953] Returning information for job: 20190404111434220090
2019-04-04 11:15:02,906 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1099.95443606s left)
2019-04-04 11:15:04,264 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111504257472
2019-04-04 11:15:04,280 [salt.minion      :1432][INFO    ][13029] Starting a new job with PID 13029
2019-04-04 11:15:04,312 [salt.minion      :1711][INFO    ][13029] Returning information for job: 20190404111504257472
2019-04-04 11:15:34,438 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111534430088
2019-04-04 11:15:34,454 [salt.minion      :1432][INFO    ][13057] Starting a new job with PID 13057
2019-04-04 11:15:34,487 [salt.minion      :1711][INFO    ][13057] Returning information for job: 20190404111534430088
2019-04-04 11:15:35,293 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1067.56725502s left)
2019-04-04 11:16:04,474 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111604466617
2019-04-04 11:16:04,490 [salt.minion      :1432][INFO    ][13127] Starting a new job with PID 13127
2019-04-04 11:16:04,520 [salt.minion      :1711][INFO    ][13127] Returning information for job: 20190404111604466617
2019-04-04 11:16:08,050 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1034.81066704s left)
2019-04-04 11:16:34,685 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111634678403
2019-04-04 11:16:34,702 [salt.minion      :1432][INFO    ][13158] Starting a new job with PID 13158
2019-04-04 11:16:34,733 [salt.minion      :1711][INFO    ][13158] Returning information for job: 20190404111634678403
2019-04-04 11:16:40,957 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1001.9035759s left)
2019-04-04 11:17:04,865 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111704857452
2019-04-04 11:17:04,880 [salt.minion      :1432][INFO    ][13235] Starting a new job with PID 13235
2019-04-04 11:17:04,913 [salt.minion      :1711][INFO    ][13235] Returning information for job: 20190404111704857452
2019-04-04 11:17:13,520 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (969.339767933s left)
2019-04-04 11:17:35,033 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111735025614
2019-04-04 11:17:35,049 [salt.minion      :1432][INFO    ][13272] Starting a new job with PID 13272
2019-04-04 11:17:35,082 [salt.minion      :1711][INFO    ][13272] Returning information for job: 20190404111735025614
2019-04-04 11:17:46,121 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (936.73930788s left)
2019-04-04 11:18:05,209 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111805197961
2019-04-04 11:18:05,232 [salt.minion      :1432][INFO    ][13361] Starting a new job with PID 13361
2019-04-04 11:18:05,264 [salt.minion      :1711][INFO    ][13361] Returning information for job: 20190404111805197961
2019-04-04 11:18:18,528 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (904.331846952s left)
2019-04-04 11:18:35,412 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111835402860
2019-04-04 11:18:35,430 [salt.minion      :1432][INFO    ][13390] Starting a new job with PID 13390
2019-04-04 11:18:35,464 [salt.minion      :1711][INFO    ][13390] Returning information for job: 20190404111835402860
2019-04-04 11:18:51,166 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (871.694488049s left)
2019-04-04 11:19:05,614 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111905607549
2019-04-04 11:19:05,630 [salt.minion      :1432][INFO    ][13463] Starting a new job with PID 13463
2019-04-04 11:19:05,662 [salt.minion      :1711][INFO    ][13463] Returning information for job: 20190404111905607549
2019-04-04 11:19:23,914 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (838.945852995s left)
2019-04-04 11:19:35,729 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404111935714484
2019-04-04 11:19:35,750 [salt.minion      :1432][INFO    ][13494] Starting a new job with PID 13494
2019-04-04 11:19:35,788 [salt.minion      :1711][INFO    ][13494] Returning information for job: 20190404111935714484
2019-04-04 11:19:56,590 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (806.269957066s left)
2019-04-04 11:20:05,839 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112005828992
2019-04-04 11:20:05,862 [salt.minion      :1432][INFO    ][13567] Starting a new job with PID 13567
2019-04-04 11:20:05,893 [salt.minion      :1711][INFO    ][13567] Returning information for job: 20190404112005828992
2019-04-04 11:20:29,250 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (773.610433102s left)
2019-04-04 11:20:36,043 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112036031777
2019-04-04 11:20:36,065 [salt.minion      :1432][INFO    ][13598] Starting a new job with PID 13598
2019-04-04 11:20:36,094 [salt.minion      :1711][INFO    ][13598] Returning information for job: 20190404112036031777
2019-04-04 11:21:02,020 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (740.839817047s left)
2019-04-04 11:21:06,117 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112106105408
2019-04-04 11:21:06,137 [salt.minion      :1432][INFO    ][13673] Starting a new job with PID 13673
2019-04-04 11:21:06,167 [salt.minion      :1711][INFO    ][13673] Returning information for job: 20190404112106105408
2019-04-04 11:21:34,511 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (708.349236012s left)
2019-04-04 11:21:36,197 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112136181509
2019-04-04 11:21:36,221 [salt.minion      :1432][INFO    ][13704] Starting a new job with PID 13704
2019-04-04 11:21:36,250 [salt.minion      :1711][INFO    ][13704] Returning information for job: 20190404112136181509
2019-04-04 11:22:06,362 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112206354762
2019-04-04 11:22:06,380 [salt.minion      :1432][INFO    ][13779] Starting a new job with PID 13779
2019-04-04 11:22:06,416 [salt.minion      :1711][INFO    ][13779] Returning information for job: 20190404112206354762
2019-04-04 11:22:07,406 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (675.454602003s left)
2019-04-04 11:22:36,422 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112236408822
2019-04-04 11:22:36,442 [salt.minion      :1432][INFO    ][13804] Starting a new job with PID 13804
2019-04-04 11:22:36,479 [salt.minion      :1711][INFO    ][13804] Returning information for job: 20190404112236408822
2019-04-04 11:22:39,886 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (642.974392891s left)
2019-04-04 11:23:06,494 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112306487428
2019-04-04 11:23:06,515 [salt.minion      :1432][INFO    ][13877] Starting a new job with PID 13877
2019-04-04 11:23:06,553 [salt.minion      :1711][INFO    ][13877] Returning information for job: 20190404112306487428
2019-04-04 11:23:12,449 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (610.411190033s left)
2019-04-04 11:23:36,646 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112336637158
2019-04-04 11:23:36,661 [salt.minion      :1432][INFO    ][13908] Starting a new job with PID 13908
2019-04-04 11:23:36,695 [salt.minion      :1711][INFO    ][13908] Returning information for job: 20190404112336637158
2019-04-04 11:23:44,923 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (577.93717289s left)
2019-04-04 11:24:06,743 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112406736733
2019-04-04 11:24:06,757 [salt.minion      :1432][INFO    ][13983] Starting a new job with PID 13983
2019-04-04 11:24:06,788 [salt.minion      :1711][INFO    ][13983] Returning information for job: 20190404112406736733
2019-04-04 11:24:17,541 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (545.319562912s left)
2019-04-04 11:24:36,841 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112436832747
2019-04-04 11:24:36,861 [salt.minion      :1432][INFO    ][14012] Starting a new job with PID 14012
2019-04-04 11:24:36,901 [salt.minion      :1711][INFO    ][14012] Returning information for job: 20190404112436832747
2019-04-04 11:24:50,101 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (512.759083033s left)
2019-04-04 11:25:07,033 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112507023449
2019-04-04 11:25:07,053 [salt.minion      :1432][INFO    ][14085] Starting a new job with PID 14085
2019-04-04 11:25:07,090 [salt.minion      :1711][INFO    ][14085] Returning information for job: 20190404112507023449
2019-04-04 11:25:22,621 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (480.239470005s left)
2019-04-04 11:25:37,193 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112537177486
2019-04-04 11:25:37,212 [salt.minion      :1432][INFO    ][14116] Starting a new job with PID 14116
2019-04-04 11:25:37,245 [salt.minion      :1711][INFO    ][14116] Returning information for job: 20190404112537177486
2019-04-04 11:25:55,398 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (447.46209693s left)
2019-04-04 11:26:07,339 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112607328384
2019-04-04 11:26:07,361 [salt.minion      :1432][INFO    ][14191] Starting a new job with PID 14191
2019-04-04 11:26:07,391 [salt.minion      :1711][INFO    ][14191] Returning information for job: 20190404112607328384
2019-04-04 11:26:27,965 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (414.894949913s left)
2019-04-04 11:26:37,484 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112637473288
2019-04-04 11:26:37,508 [salt.minion      :1432][INFO    ][14220] Starting a new job with PID 14220
2019-04-04 11:26:37,539 [salt.minion      :1711][INFO    ][14220] Returning information for job: 20190404112637473288
2019-04-04 11:27:00,473 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (382.387408018s left)
2019-04-04 11:27:07,636 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112707628811
2019-04-04 11:27:07,650 [salt.minion      :1432][INFO    ][14296] Starting a new job with PID 14296
2019-04-04 11:27:07,679 [salt.minion      :1711][INFO    ][14296] Returning information for job: 20190404112707628811
2019-04-04 11:27:33,006 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (349.854419947s left)
2019-04-04 11:27:37,701 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112737687112
2019-04-04 11:27:37,723 [salt.minion      :1432][INFO    ][14461] Starting a new job with PID 14461
2019-04-04 11:27:37,752 [salt.minion      :1711][INFO    ][14461] Returning information for job: 20190404112737687112
2019-04-04 11:28:05,327 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (317.533849955s left)
2019-04-04 11:28:07,849 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112807842698
2019-04-04 11:28:07,862 [salt.minion      :1432][INFO    ][14546] Starting a new job with PID 14546
2019-04-04 11:28:07,894 [salt.minion      :1711][INFO    ][14546] Returning information for job: 20190404112807842698
2019-04-04 11:28:38,047 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (284.813215971s left)
2019-04-04 11:28:38,053 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112838045399
2019-04-04 11:28:38,076 [salt.minion      :1432][INFO    ][14575] Starting a new job with PID 14575
2019-04-04 11:28:38,109 [salt.minion      :1711][INFO    ][14575] Returning information for job: 20190404112838045399
2019-04-04 11:29:08,276 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112908267933
2019-04-04 11:29:08,292 [salt.minion      :1432][INFO    ][14644] Starting a new job with PID 14644
2019-04-04 11:29:08,322 [salt.minion      :1711][INFO    ][14644] Returning information for job: 20190404112908267933
2019-04-04 11:29:10,869 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (251.991426945s left)
2019-04-04 11:29:38,465 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404112938457245
2019-04-04 11:29:38,481 [salt.minion      :1432][INFO    ][14672] Starting a new job with PID 14672
2019-04-04 11:29:38,517 [salt.minion      :1711][INFO    ][14672] Returning information for job: 20190404112938457245
2019-04-04 11:29:43,636 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (219.224340916s left)
2019-04-04 11:30:08,503 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113008496227
2019-04-04 11:30:08,517 [salt.minion      :1432][INFO    ][14748] Starting a new job with PID 14748
2019-04-04 11:30:08,545 [salt.minion      :1711][INFO    ][14748] Returning information for job: 20190404113008496227
2019-04-04 11:30:16,205 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (186.65487504s left)
2019-04-04 11:30:38,564 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113038555904
2019-04-04 11:30:38,578 [salt.minion      :1432][INFO    ][14779] Starting a new job with PID 14779
2019-04-04 11:30:38,610 [salt.minion      :1711][INFO    ][14779] Returning information for job: 20190404113038555904
2019-04-04 11:30:48,670 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (154.190026999s left)
2019-04-04 11:31:08,646 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113108638376
2019-04-04 11:31:08,670 [salt.minion      :1432][INFO    ][14852] Starting a new job with PID 14852
2019-04-04 11:31:08,705 [salt.minion      :1711][INFO    ][14852] Returning information for job: 20190404113108638376
2019-04-04 11:31:21,353 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (121.507719994s left)
2019-04-04 11:31:38,696 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113138685098
2019-04-04 11:31:38,720 [salt.minion      :1432][INFO    ][14881] Starting a new job with PID 14881
2019-04-04 11:31:38,753 [salt.minion      :1711][INFO    ][14881] Returning information for job: 20190404113138685098
2019-04-04 11:31:53,866 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (88.9946460724s left)
2019-04-04 11:32:08,733 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113208726649
2019-04-04 11:32:08,753 [salt.minion      :1432][INFO    ][14956] Starting a new job with PID 14956
2019-04-04 11:32:08,780 [salt.minion      :1711][INFO    ][14956] Returning information for job: 20190404113208726649
2019-04-04 11:32:26,182 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (56.678401947s left)
2019-04-04 11:32:38,810 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113238796331
2019-04-04 11:32:38,829 [salt.minion      :1432][INFO    ][14987] Starting a new job with PID 14987
2019-04-04 11:32:38,862 [salt.minion      :1711][INFO    ][14987] Returning information for job: 20190404113238796331
2019-04-04 11:32:58,825 [salt.loaded.ext.module.maas:1023][INFO    ][8453] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (24.0357050896s left)
2019-04-04 11:33:08,922 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113308907561
2019-04-04 11:33:08,942 [salt.minion      :1432][INFO    ][15063] Starting a new job with PID 15063
2019-04-04 11:33:08,975 [salt.minion      :1711][INFO    ][15063] Returning information for job: 20190404113308907561
2019-04-04 11:33:31,230 [salt.state       :302 ][ERROR   ][8453] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-04-04 11:33:31,230 [salt.state       :1951][INFO    ][8453] Completed state [maas.wait_for_machine_status] at time 11:33:31.230737 duration_in_ms=2258377.846
2019-04-04 11:33:31,234 [salt.minion      :1711][INFO    ][8453] Returning information for job: 20190404105545016884
2019-04-04 11:33:42,155 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command pillar.get with jid 20190404113342147998
2019-04-04 11:33:42,170 [salt.minion      :1432][INFO    ][15135] Starting a new job with PID 15135
2019-04-04 11:33:42,180 [salt.minion      :1711][INFO    ][15135] Returning information for job: 20190404113342147998
2019-04-04 11:33:42,863 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command service.status with jid 20190404113342856815
2019-04-04 11:33:42,878 [salt.minion      :1432][INFO    ][15140] Starting a new job with PID 15140
2019-04-04 11:33:43,657 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15140] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-04 11:33:43,682 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15140] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-04 11:33:43,694 [salt.minion      :1711][INFO    ][15140] Returning information for job: 20190404113342856815
2019-04-04 11:33:44,373 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command state.apply with jid 20190404113344365492
2019-04-04 11:33:44,395 [salt.minion      :1432][INFO    ][15151] Starting a new job with PID 15151
2019-04-04 11:33:50,379 [salt.state       :915 ][INFO    ][15151] Loading fresh modules for state activity
2019-04-04 11:33:50,903 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15151] Executing command 'salt-minion --version' in directory '/root'
2019-04-04 11:33:51,247 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15151] Executing command 'salt-minion --version' in directory '/root'
2019-04-04 11:33:52,211 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15151] Executing command 'salt-minion --version' in directory '/root'
2019-04-04 11:33:52,550 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15151] Executing command 'salt-minion --version' in directory '/root'
2019-04-04 11:33:54,696 [salt.state       :1780][INFO    ][15151] Running state [salt-minion] at time 11:33:54.696273
2019-04-04 11:33:54,696 [salt.state       :1813][INFO    ][15151] Executing state pkg.installed for [salt-minion]
2019-04-04 11:33:54,697 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15151] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-04 11:33:54,815 [salt.state       :300 ][INFO    ][15151] All specified packages are already installed
2019-04-04 11:33:54,815 [salt.state       :1951][INFO    ][15151] Completed state [salt-minion] at time 11:33:54.815785 duration_in_ms=119.512
2019-04-04 11:33:54,816 [salt.state       :1780][INFO    ][15151] Running state [salt_minion_dependency_packages] at time 11:33:54.816148
2019-04-04 11:33:54,816 [salt.state       :1813][INFO    ][15151] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-04 11:33:54,826 [salt.state       :300 ][INFO    ][15151] All specified packages are already installed
2019-04-04 11:33:54,826 [salt.state       :1951][INFO    ][15151] Completed state [salt_minion_dependency_packages] at time 11:33:54.826592 duration_in_ms=10.443
2019-04-04 11:33:54,830 [salt.state       :1780][INFO    ][15151] Running state [/etc/salt/minion.d/minion.conf] at time 11:33:54.830622
2019-04-04 11:33:54,831 [salt.state       :1813][INFO    ][15151] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-04 11:33:55,102 [salt.state       :300 ][INFO    ][15151] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-04 11:33:55,102 [salt.state       :1951][INFO    ][15151] Completed state [/etc/salt/minion.d/minion.conf] at time 11:33:55.102448 duration_in_ms=271.826
2019-04-04 11:33:55,102 [salt.state       :1780][INFO    ][15151] Running state [python-netaddr] at time 11:33:55.102735
2019-04-04 11:33:55,103 [salt.state       :1813][INFO    ][15151] Executing state pkg.installed for [python-netaddr]
2019-04-04 11:33:55,112 [salt.state       :300 ][INFO    ][15151] All specified packages are already installed
2019-04-04 11:33:55,112 [salt.state       :1951][INFO    ][15151] Completed state [python-netaddr] at time 11:33:55.112630 duration_in_ms=9.893
2019-04-04 11:33:55,116 [salt.state       :1780][INFO    ][15151] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 11:33:55.116701
2019-04-04 11:33:55,117 [salt.state       :1813][INFO    ][15151] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-04 11:33:55,128 [salt.state       :300 ][INFO    ][15151] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-04 11:33:55,128 [salt.state       :1951][INFO    ][15151] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 11:33:55.128533 duration_in_ms=11.833
2019-04-04 11:33:55,129 [salt.state       :1780][INFO    ][15151] Running state [salt-minion] at time 11:33:55.129685
2019-04-04 11:33:55,130 [salt.state       :1813][INFO    ][15151] Executing state service.running for [salt-minion]
2019-04-04 11:33:55,130 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15151] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-04 11:33:55,175 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15151] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-04 11:33:55,194 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15151] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-04 11:33:55,210 [salt.state       :300 ][INFO    ][15151] The service salt-minion is already running
2019-04-04 11:33:55,211 [salt.state       :1951][INFO    ][15151] Completed state [salt-minion] at time 11:33:55.210963 duration_in_ms=81.278
2019-04-04 11:33:55,212 [salt.state       :1780][INFO    ][15151] Running state [/etc/salt/grains.d] at time 11:33:55.212703
2019-04-04 11:33:55,213 [salt.state       :1813][INFO    ][15151] Executing state file.directory for [/etc/salt/grains.d]
2019-04-04 11:33:55,214 [salt.state       :300 ][INFO    ][15151] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-04 11:33:55,214 [salt.state       :1951][INFO    ][15151] Completed state [/etc/salt/grains.d] at time 11:33:55.214435 duration_in_ms=1.733
2019-04-04 11:33:55,217 [salt.state       :1780][INFO    ][15151] Running state [/etc/salt/grains] at time 11:33:55.217118
2019-04-04 11:33:55,217 [salt.state       :1813][INFO    ][15151] Executing state file.managed for [/etc/salt/grains]
2019-04-04 11:33:55,218 [salt.state       :300 ][INFO    ][15151] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-04 11:33:55,218 [salt.state       :1951][INFO    ][15151] Completed state [/etc/salt/grains] at time 11:33:55.218237 duration_in_ms=1.118
2019-04-04 11:33:55,218 [salt.state       :1780][INFO    ][15151] Running state [/etc/salt/grains.d/placeholder] at time 11:33:55.218741
2019-04-04 11:33:55,219 [salt.state       :1813][INFO    ][15151] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-04 11:33:55,219 [salt.state       :300 ][INFO    ][15151] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-04 11:33:55,219 [salt.state       :1951][INFO    ][15151] Completed state [/etc/salt/grains.d/placeholder] at time 11:33:55.219846 duration_in_ms=1.105
2019-04-04 11:33:55,220 [salt.state       :1780][INFO    ][15151] Running state [/etc/salt/grains.d/sphinx] at time 11:33:55.220359
2019-04-04 11:33:55,220 [salt.state       :1813][INFO    ][15151] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-04 11:33:55,221 [salt.state       :300 ][INFO    ][15151] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-04 11:33:55,222 [salt.state       :1951][INFO    ][15151] Completed state [/etc/salt/grains.d/sphinx] at time 11:33:55.222173 duration_in_ms=1.814
2019-04-04 11:33:55,224 [salt.state       :1780][INFO    ][15151] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 11:33:55.224748
2019-04-04 11:33:55,225 [salt.state       :1813][INFO    ][15151] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-04 11:33:55,225 [salt.state       :300 ][INFO    ][15151] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-04 11:33:55,225 [salt.state       :1951][INFO    ][15151] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 11:33:55.225600 duration_in_ms=0.852
2019-04-04 11:33:55,226 [salt.state       :1780][INFO    ][15151] Running state [/etc/salt/grains.d/dns_records] at time 11:33:55.226117
2019-04-04 11:33:55,226 [salt.state       :1813][INFO    ][15151] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-04 11:33:55,228 [salt.state       :300 ][INFO    ][15151] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-04 11:33:55,229 [salt.state       :1951][INFO    ][15151] Completed state [/etc/salt/grains.d/dns_records] at time 11:33:55.229127 duration_in_ms=3.01
2019-04-04 11:33:55,230 [salt.state       :1780][INFO    ][15151] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 11:33:55.230100
2019-04-04 11:33:55,230 [salt.state       :1813][INFO    ][15151] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-04 11:33:55,230 [salt.state       :300 ][INFO    ][15151] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-04 11:33:55,231 [salt.state       :1951][INFO    ][15151] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 11:33:55.231249 duration_in_ms=1.149
2019-04-04 11:33:55,231 [salt.state       :1780][INFO    ][15151] Running state [/etc/salt/grains.d/salt] at time 11:33:55.231760
2019-04-04 11:33:55,232 [salt.state       :1813][INFO    ][15151] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-04 11:33:55,233 [salt.state       :300 ][INFO    ][15151] File /etc/salt/grains.d/salt is in the correct state
2019-04-04 11:33:55,233 [salt.state       :1951][INFO    ][15151] Completed state [/etc/salt/grains.d/salt] at time 11:33:55.233334 duration_in_ms=1.574
2019-04-04 11:33:55,234 [salt.state       :1780][INFO    ][15151] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 11:33:55.234248
2019-04-04 11:33:55,234 [salt.state       :1813][INFO    ][15151] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-04 11:33:55,235 [salt.state       :300 ][INFO    ][15151] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-04 11:33:55,235 [salt.state       :1951][INFO    ][15151] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 11:33:55.235529 duration_in_ms=1.28
2019-04-04 11:33:55,241 [salt.state       :1780][INFO    ][15151] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 11:33:55.241123
2019-04-04 11:33:55,241 [salt.state       :1813][INFO    ][15151] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-04 11:33:55,242 [salt.state       :300 ][INFO    ][15151] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-04 11:33:55,242 [salt.state       :1951][INFO    ][15151] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 11:33:55.242332 duration_in_ms=1.209
2019-04-04 11:33:55,243 [salt.state       :1780][INFO    ][15151] Running state [mine.update] at time 11:33:55.243885
2019-04-04 11:33:55,244 [salt.state       :1813][INFO    ][15151] Executing state module.wait for [mine.update]
2019-04-04 11:33:55,244 [salt.state       :300 ][INFO    ][15151] No changes made for mine.update
2019-04-04 11:33:55,245 [salt.state       :1951][INFO    ][15151] Completed state [mine.update] at time 11:33:55.244993 duration_in_ms=1.108
2019-04-04 11:33:55,245 [salt.state       :1780][INFO    ][15151] Running state [ca-certificates] at time 11:33:55.245369
2019-04-04 11:33:55,245 [salt.state       :1813][INFO    ][15151] Executing state pkg.installed for [ca-certificates]
2019-04-04 11:33:55,258 [salt.state       :300 ][INFO    ][15151] All specified packages are already installed
2019-04-04 11:33:55,259 [salt.state       :1951][INFO    ][15151] Completed state [ca-certificates] at time 11:33:55.259135 duration_in_ms=13.767
2019-04-04 11:33:55,259 [salt.state       :1780][INFO    ][15151] Running state [update-ca-certificates] at time 11:33:55.259874
2019-04-04 11:33:55,260 [salt.state       :1813][INFO    ][15151] Executing state cmd.wait for [update-ca-certificates]
2019-04-04 11:33:55,260 [salt.state       :300 ][INFO    ][15151] No changes made for update-ca-certificates
2019-04-04 11:33:55,260 [salt.state       :1951][INFO    ][15151] Completed state [update-ca-certificates] at time 11:33:55.260679 duration_in_ms=0.805
2019-04-04 11:33:55,261 [salt.state       :1780][INFO    ][15151] Running state [iptables] at time 11:33:55.260950
2019-04-04 11:33:55,261 [salt.state       :1813][INFO    ][15151] Executing state pkg.installed for [iptables]
2019-04-04 11:33:55,271 [salt.state       :300 ][INFO    ][15151] All specified packages are already installed
2019-04-04 11:33:55,271 [salt.state       :1951][INFO    ][15151] Completed state [iptables] at time 11:33:55.271930 duration_in_ms=10.98
2019-04-04 11:33:55,272 [salt.state       :1780][INFO    ][15151] Running state [iptables-persistent] at time 11:33:55.272204
2019-04-04 11:33:55,272 [salt.state       :1813][INFO    ][15151] Executing state pkg.installed for [iptables-persistent]
2019-04-04 11:33:55,282 [salt.state       :300 ][INFO    ][15151] All specified packages are already installed
2019-04-04 11:33:55,282 [salt.state       :1951][INFO    ][15151] Completed state [iptables-persistent] at time 11:33:55.282558 duration_in_ms=10.354
2019-04-04 11:33:55,283 [salt.state       :1780][INFO    ][15151] Running state [iptables_modules_v4_load] at time 11:33:55.283618
2019-04-04 11:33:55,283 [salt.state       :1813][INFO    ][15151] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-04 11:33:55,284 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15151] Executing command 'lsmod' in directory '/root'
2019-04-04 11:33:55,305 [salt.state       :300 ][INFO    ][15151] Kernel modules iptable_filter, ip_tables are already present
2019-04-04 11:33:55,305 [salt.state       :1951][INFO    ][15151] Completed state [iptables_modules_v4_load] at time 11:33:55.305609 duration_in_ms=21.989
2019-04-04 11:33:55,307 [salt.state       :1780][INFO    ][15151] Running state [/etc/iptables/rules.v4] at time 11:33:55.307049
2019-04-04 11:33:55,307 [salt.state       :1813][INFO    ][15151] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-04 11:33:55,411 [salt.state       :300 ][INFO    ][15151] File /etc/iptables/rules.v4 is in the correct state
2019-04-04 11:33:55,412 [salt.state       :1951][INFO    ][15151] Completed state [/etc/iptables/rules.v4] at time 11:33:55.412191 duration_in_ms=105.142
2019-04-04 11:33:55,413 [salt.state       :1780][INFO    ][15151] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 11:33:55.413509
2019-04-04 11:33:55,413 [salt.state       :1813][INFO    ][15151] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-04 11:33:55,414 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15151] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-04 11:33:55,434 [salt.state       :300 ][INFO    ][15151] onlyif execution failed
2019-04-04 11:33:55,435 [salt.state       :1951][INFO    ][15151] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 11:33:55.435149 duration_in_ms=21.641
2019-04-04 11:33:55,437 [salt.state       :1780][INFO    ][15151] Running state [netfilter-persistent] at time 11:33:55.437289
2019-04-04 11:33:55,438 [salt.state       :1813][INFO    ][15151] Executing state service.running for [netfilter-persistent]
2019-04-04 11:33:55,439 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15151] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-04 11:33:55,462 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15151] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-04 11:33:55,484 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15151] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-04 11:33:55,507 [salt.state       :300 ][INFO    ][15151] The service netfilter-persistent is already running
2019-04-04 11:33:55,507 [salt.state       :1951][INFO    ][15151] Completed state [netfilter-persistent] at time 11:33:55.507821 duration_in_ms=70.532
2019-04-04 11:33:55,508 [salt.state       :1780][INFO    ][15151] Running state [iptables_extra.remove_stale_tables] at time 11:33:55.508804
2019-04-04 11:33:55,509 [salt.state       :1813][INFO    ][15151] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-04 11:33:55,509 [salt.state       :300 ][INFO    ][15151] No changes made for iptables_extra.remove_stale_tables
2019-04-04 11:33:55,509 [salt.state       :1951][INFO    ][15151] Completed state [iptables_extra.remove_stale_tables] at time 11:33:55.509774 duration_in_ms=0.97
2019-04-04 11:33:55,510 [salt.state       :1780][INFO    ][15151] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 11:33:55.510056
2019-04-04 11:33:55,510 [salt.state       :1813][INFO    ][15151] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-04 11:33:55,511 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15151] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-04 11:33:55,531 [salt.state       :300 ][INFO    ][15151] onlyif execution failed
2019-04-04 11:33:55,531 [salt.state       :1951][INFO    ][15151] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 11:33:55.531381 duration_in_ms=21.325
2019-04-04 11:33:55,532 [salt.state       :1780][INFO    ][15151] Running state [/etc/iptables/rules.v6] at time 11:33:55.532359
2019-04-04 11:33:55,532 [salt.state       :1813][INFO    ][15151] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-04 11:33:55,533 [salt.state       :300 ][INFO    ][15151] File /etc/iptables/rules.v6 is not present
2019-04-04 11:33:55,533 [salt.state       :1951][INFO    ][15151] Completed state [/etc/iptables/rules.v6] at time 11:33:55.533606 duration_in_ms=1.247
2019-04-04 11:33:55,534 [salt.state       :1780][INFO    ][15151] Running state [iptables_extra.flush_all] at time 11:33:55.534420
2019-04-04 11:33:55,534 [salt.state       :1813][INFO    ][15151] Executing state module.wait for [iptables_extra.flush_all]
2019-04-04 11:33:55,535 [salt.state       :300 ][INFO    ][15151] No changes made for iptables_extra.flush_all
2019-04-04 11:33:55,535 [salt.state       :1951][INFO    ][15151] Completed state [iptables_extra.flush_all] at time 11:33:55.535267 duration_in_ms=0.847
2019-04-04 11:33:55,541 [salt.minion      :1711][INFO    ][15151] Returning information for job: 20190404113344365492
2019-04-04 11:33:56,218 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command state.apply with jid 20190404113356206828
2019-04-04 11:33:56,240 [salt.minion      :1432][INFO    ][15244] Starting a new job with PID 15244
2019-04-04 11:33:57,197 [salt.state       :915 ][INFO    ][15244] Loading fresh modules for state activity
2019-04-04 11:33:58,401 [salt.state       :1780][INFO    ][15244] Running state [maas-rack-controller] at time 11:33:58.401216
2019-04-04 11:33:58,401 [salt.state       :1813][INFO    ][15244] Executing state pkg.installed for [maas-rack-controller]
2019-04-04 11:33:58,403 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15244] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-04 11:33:58,525 [salt.state       :300 ][INFO    ][15244] All specified packages are already installed
2019-04-04 11:33:58,525 [salt.state       :1951][INFO    ][15244] Completed state [maas-rack-controller] at time 11:33:58.525434 duration_in_ms=124.218
2019-04-04 11:33:58,525 [salt.state       :1780][INFO    ][15244] Running state [ipmitool] at time 11:33:58.525791
2019-04-04 11:33:58,526 [salt.state       :1813][INFO    ][15244] Executing state pkg.installed for [ipmitool]
2019-04-04 11:33:58,535 [salt.state       :300 ][INFO    ][15244] All specified packages are already installed
2019-04-04 11:33:58,536 [salt.state       :1951][INFO    ][15244] Completed state [ipmitool] at time 11:33:58.535991 duration_in_ms=10.2
2019-04-04 11:33:58,539 [salt.state       :1780][INFO    ][15244] Running state [/etc/maas/rackd.conf] at time 11:33:58.539469
2019-04-04 11:33:58,539 [salt.state       :1813][INFO    ][15244] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-04 11:33:58,540 [salt.state       :300 ][INFO    ][15244] No changes needed to be made
2019-04-04 11:33:58,540 [salt.state       :1951][INFO    ][15244] Completed state [/etc/maas/rackd.conf] at time 11:33:58.540891 duration_in_ms=1.421
2019-04-04 11:33:58,541 [salt.state       :1780][INFO    ][15244] Running state [/etc/maas/rackd.conf] at time 11:33:58.541168
2019-04-04 11:33:58,541 [salt.state       :1813][INFO    ][15244] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-04 11:33:58,541 [salt.loaded.int.states.file:2298][WARNING ][15244] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-04 11:33:58,542 [salt.state       :300 ][INFO    ][15244] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-04 11:33:58,542 [salt.state       :1951][INFO    ][15244] Completed state [/etc/maas/rackd.conf] at time 11:33:58.542747 duration_in_ms=1.578
2019-04-04 11:33:58,543 [salt.state       :1780][INFO    ][15244] Running state [maas-rackd] at time 11:33:58.543766
2019-04-04 11:33:58,544 [salt.state       :1813][INFO    ][15244] Executing state service.running for [maas-rackd]
2019-04-04 11:33:58,544 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15244] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-04 11:33:58,578 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15244] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-04 11:33:58,593 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15244] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-04 11:33:58,615 [salt.state       :300 ][INFO    ][15244] The service maas-rackd is already running
2019-04-04 11:33:58,615 [salt.state       :1951][INFO    ][15244] Completed state [maas-rackd] at time 11:33:58.615595 duration_in_ms=71.828
2019-04-04 11:33:58,617 [salt.minion      :1711][INFO    ][15244] Returning information for job: 20190404113356206828
2019-04-04 11:33:59,298 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command state.apply with jid 20190404113359287482
2019-04-04 11:33:59,317 [salt.minion      :1432][INFO    ][15271] Starting a new job with PID 15271
2019-04-04 11:34:00,289 [salt.state       :915 ][INFO    ][15271] Loading fresh modules for state activity
2019-04-04 11:34:01,647 [salt.state       :1780][INFO    ][15271] Running state [maas-region-controller] at time 11:34:01.647375
2019-04-04 11:34:01,647 [salt.state       :1813][INFO    ][15271] Executing state pkg.installed for [maas-region-controller]
2019-04-04 11:34:01,648 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15271] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-04 11:34:01,762 [salt.state       :300 ][INFO    ][15271] All specified packages are already installed
2019-04-04 11:34:01,763 [salt.state       :1951][INFO    ][15271] Completed state [maas-region-controller] at time 11:34:01.763091 duration_in_ms=115.716
2019-04-04 11:34:01,763 [salt.state       :1780][INFO    ][15271] Running state [python-oauth] at time 11:34:01.763452
2019-04-04 11:34:01,763 [salt.state       :1813][INFO    ][15271] Executing state pkg.installed for [python-oauth]
2019-04-04 11:34:01,773 [salt.state       :300 ][INFO    ][15271] All specified packages are already installed
2019-04-04 11:34:01,773 [salt.state       :1951][INFO    ][15271] Completed state [python-oauth] at time 11:34:01.773461 duration_in_ms=10.008
2019-04-04 11:34:01,776 [salt.state       :1780][INFO    ][15271] Running state [/etc/maas/regiond.conf] at time 11:34:01.776669
2019-04-04 11:34:01,776 [salt.state       :1813][INFO    ][15271] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-04 11:34:01,782 [salt.state       :300 ][INFO    ][15271] No changes needed to be made
2019-04-04 11:34:01,783 [salt.state       :1951][INFO    ][15271] Completed state [/etc/maas/regiond.conf] at time 11:34:01.783229 duration_in_ms=6.56
2019-04-04 11:34:01,783 [salt.state       :1780][INFO    ][15271] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 11:34:01.783741
2019-04-04 11:34:01,784 [salt.state       :1813][INFO    ][15271] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-04 11:34:01,842 [salt.state       :300 ][INFO    ][15271] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-04 11:34:01,842 [salt.state       :1951][INFO    ][15271] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 11:34:01.842628 duration_in_ms=58.887
2019-04-04 11:34:01,843 [salt.state       :1780][INFO    ][15271] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:34:01.843412
2019-04-04 11:34:01,843 [salt.state       :1813][INFO    ][15271] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-04 11:34:01,849 [salt.state       :300 ][INFO    ][15271] No changes needed to be made
2019-04-04 11:34:01,850 [salt.state       :1951][INFO    ][15271] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:34:01.850252 duration_in_ms=6.841
2019-04-04 11:34:01,851 [salt.state       :1780][INFO    ][15271] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:34:01.851828
2019-04-04 11:34:01,852 [salt.state       :1813][INFO    ][15271] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-04 11:34:01,856 [salt.state       :300 ][INFO    ][15271] No changes needed to be made
2019-04-04 11:34:01,856 [salt.state       :1951][INFO    ][15271] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:34:01.856440 duration_in_ms=4.612
2019-04-04 11:34:01,857 [salt.state       :1780][INFO    ][15271] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 11:34:01.856947
2019-04-04 11:34:01,857 [salt.state       :1813][INFO    ][15271] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-04 11:34:01,881 [salt.state       :300 ][INFO    ][15271] No changes needed to be made
2019-04-04 11:34:01,881 [salt.state       :1951][INFO    ][15271] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 11:34:01.881228 duration_in_ms=24.281
2019-04-04 11:34:01,881 [salt.state       :1780][INFO    ][15271] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 11:34:01.881740
2019-04-04 11:34:01,882 [salt.state       :1813][INFO    ][15271] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-04 11:34:01,893 [salt.state       :300 ][INFO    ][15271] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-04 11:34:01,893 [salt.state       :1951][INFO    ][15271] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 11:34:01.893495 duration_in_ms=11.755
2019-04-04 11:34:01,894 [salt.state       :1780][INFO    ][15271] Running state [a2enmod headers] at time 11:34:01.894797
2019-04-04 11:34:01,895 [salt.state       :1813][INFO    ][15271] Executing state cmd.run for [a2enmod headers]
2019-04-04 11:34:01,895 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15271] Executing command 'a2enmod headers' in directory '/root'
2019-04-04 11:34:01,965 [salt.state       :300 ][INFO    ][15271] {'pid': 15291, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-04 11:34:01,965 [salt.state       :1951][INFO    ][15271] Completed state [a2enmod headers] at time 11:34:01.965699 duration_in_ms=70.901
2019-04-04 11:34:01,966 [salt.state       :1780][INFO    ][15271] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 11:34:01.966370
2019-04-04 11:34:01,967 [salt.state       :1813][INFO    ][15271] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-04 11:34:01,987 [salt.state       :300 ][INFO    ][15271] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-04 11:34:01,987 [salt.state       :1951][INFO    ][15271] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 11:34:01.987796 duration_in_ms=21.425
2019-04-04 11:34:01,988 [salt.state       :1780][INFO    ][15271] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 11:34:01.988407
2019-04-04 11:34:01,988 [salt.state       :1813][INFO    ][15271] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-04 11:34:02,045 [salt.state       :300 ][INFO    ][15271] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-04 11:34:02,046 [salt.state       :1951][INFO    ][15271] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 11:34:02.045973 duration_in_ms=57.565
2019-04-04 11:34:02,047 [salt.state       :1780][INFO    ][15271] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 11:34:02.047244
2019-04-04 11:34:02,047 [salt.state       :1813][INFO    ][15271] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-04 11:34:02,111 [salt.state       :300 ][INFO    ][15271] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-04 11:34:02,111 [salt.state       :1951][INFO    ][15271] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 11:34:02.111858 duration_in_ms=64.614
2019-04-04 11:34:02,112 [salt.state       :1780][INFO    ][15271] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 11:34:02.112375
2019-04-04 11:34:02,112 [salt.state       :1813][INFO    ][15271] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-04 11:34:02,177 [salt.state       :300 ][INFO    ][15271] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-04 11:34:02,177 [salt.state       :1951][INFO    ][15271] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 11:34:02.177530 duration_in_ms=65.155
2019-04-04 11:34:02,177 [salt.state       :1780][INFO    ][15271] Running state [/root/.pgpass] at time 11:34:02.177833
2019-04-04 11:34:02,178 [salt.state       :1813][INFO    ][15271] Executing state file.managed for [/root/.pgpass]
2019-04-04 11:34:02,225 [salt.state       :300 ][INFO    ][15271] File /root/.pgpass is in the correct state
2019-04-04 11:34:02,225 [salt.state       :1951][INFO    ][15271] Completed state [/root/.pgpass] at time 11:34:02.225522 duration_in_ms=47.689
2019-04-04 11:34:02,230 [salt.state       :1780][INFO    ][15271] Running state [maas-region syncdb --noinput] at time 11:34:02.230727
2019-04-04 11:34:02,231 [salt.state       :1813][INFO    ][15271] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-04 11:34:02,231 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15271] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-04 11:34:04,853 [salt.state       :300 ][INFO    ][15271] {'pid': 15304, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, maasserver, contenttypes, auth, sites, piston3, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-04 11:34:04,854 [salt.state       :1951][INFO    ][15271] Completed state [maas-region syncdb --noinput] at time 11:34:04.854334 duration_in_ms=2623.605
2019-04-04 11:34:04,855 [salt.state       :2022][WARNING ][15271] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-04 11:34:04,858 [salt.state       :1780][INFO    ][15271] Running state [maas-regiond] at time 11:34:04.858203
2019-04-04 11:34:04,858 [salt.state       :1813][INFO    ][15271] Executing state service.running for [maas-regiond]
2019-04-04 11:34:04,860 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15271] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-04 11:34:04,897 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15271] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-04 11:34:04,916 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15271] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-04 11:34:04,932 [salt.state       :300 ][INFO    ][15271] The service maas-regiond is already running
2019-04-04 11:34:04,933 [salt.state       :1951][INFO    ][15271] Completed state [maas-regiond] at time 11:34:04.933250 duration_in_ms=75.047
2019-04-04 11:34:04,937 [salt.state       :1780][INFO    ][15271] Running state [bind9] at time 11:34:04.937645
2019-04-04 11:34:04,938 [salt.state       :1813][INFO    ][15271] Executing state service.running for [bind9]
2019-04-04 11:34:04,938 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15271] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-04 11:34:04,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15271] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-04 11:34:04,985 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15271] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-04 11:34:05,010 [salt.state       :300 ][INFO    ][15271] The service bind9 is already running
2019-04-04 11:34:05,010 [salt.state       :1951][INFO    ][15271] Completed state [bind9] at time 11:34:05.010523 duration_in_ms=72.878
2019-04-04 11:34:05,012 [salt.state       :1780][INFO    ][15271] Running state [apache2] at time 11:34:05.012292
2019-04-04 11:34:05,012 [salt.state       :1813][INFO    ][15271] Executing state service.running for [apache2]
2019-04-04 11:34:05,013 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15271] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-04 11:34:05,038 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15271] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-04 11:34:05,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15271] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-04 11:34:05,085 [salt.state       :300 ][INFO    ][15271] The service apache2 is already running
2019-04-04 11:34:05,085 [salt.state       :1951][INFO    ][15271] Completed state [apache2] at time 11:34:05.085642 duration_in_ms=73.349
2019-04-04 11:34:05,087 [salt.state       :1780][INFO    ][15271] Running state [maasng.wait_for_http_code] at time 11:34:05.087489
2019-04-04 11:34:05,088 [salt.state       :1813][INFO    ][15271] Executing state module.run for [maasng.wait_for_http_code]
2019-04-04 11:34:05,088 [salt.utils.decorators:613 ][WARNING ][15271] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 11:34:05,210 [salt.state       :300 ][INFO    ][15271] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-04 11:34:05,211 [salt.state       :1951][INFO    ][15271] Completed state [maasng.wait_for_http_code] at time 11:34:05.211220 duration_in_ms=123.731
2019-04-04 11:34:05,212 [salt.state       :1780][INFO    ][15271] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 11:34:05.212256
2019-04-04 11:34:05,212 [salt.state       :1813][INFO    ][15271] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-04 11:34:05,213 [salt.state       :300 ][INFO    ][15271] /var/lib/maas/.setup_admin exists
2019-04-04 11:34:05,213 [salt.state       :1951][INFO    ][15271] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 11:34:05.213286 duration_in_ms=1.03
2019-04-04 11:34:05,214 [salt.state       :1780][INFO    ][15271] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:34:05.214002
2019-04-04 11:34:05,214 [salt.state       :1813][INFO    ][15271] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-04 11:34:05,215 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15271] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-04 11:34:07,061 [salt.state       :300 ][INFO    ][15271] {'pid': 15327, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-04 11:34:07,062 [salt.state       :1951][INFO    ][15271] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:34:07.062207 duration_in_ms=1848.204
2019-04-04 11:34:07,068 [salt.state       :1780][INFO    ][15271] Running state [maas_region_boot_source_resources_mirror] at time 11:34:07.068113
2019-04-04 11:34:07,068 [salt.state       :1813][INFO    ][15271] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-04 11:34:07,154 [salt.state       :300 ][INFO    ][15271] {'changes': {}}
2019-04-04 11:34:07,154 [salt.state       :1951][INFO    ][15271] Completed state [maas_region_boot_source_resources_mirror] at time 11:34:07.154622 duration_in_ms=86.508
2019-04-04 11:34:07,156 [salt.state       :1780][INFO    ][15271] Running state [maasng.boot_resources_import] at time 11:34:07.156385
2019-04-04 11:34:07,156 [salt.state       :1813][INFO    ][15271] Executing state module.run for [maasng.boot_resources_import]
2019-04-04 11:34:07,157 [salt.utils.decorators:613 ][WARNING ][15271] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 11:34:08,557 [salt.loaded.ext.module.maasng:1600][INFO    ][15271] Waiting boot-resources import done
sleep for:5s Left:899.0/900s
2019-04-04 11:34:13,657 [salt.state       :300 ][INFO    ][15271] {'ret': True}
2019-04-04 11:34:13,658 [salt.state       :1951][INFO    ][15271] Completed state [maasng.boot_resources_import] at time 11:34:13.658189 duration_in_ms=6501.804
2019-04-04 11:34:13,659 [salt.state       :1780][INFO    ][15271] Running state [maas_region_boot_sources_selection_xenial] at time 11:34:13.659700
2019-04-04 11:34:13,660 [salt.state       :1813][INFO    ][15271] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-04 11:34:13,826 [salt.state       :300 ][INFO    ][15271] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-04 11:34:13,833 [salt.state       :1951][INFO    ][15271] Completed state [maas_region_boot_sources_selection_xenial] at time 11:34:13.833714 duration_in_ms=174.014
2019-04-04 11:34:13,835 [salt.state       :1780][INFO    ][15271] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 11:34:13.834995
2019-04-04 11:34:13,835 [salt.state       :1813][INFO    ][15271] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-04 11:34:13,835 [salt.utils.decorators:613 ][WARNING ][15271] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 11:34:13,836 [salt.loaded.ext.module.maasng:1771][INFO    ][15271] boot-sources sync initiated for ALL Rack's
2019-04-04 11:34:14,341 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113414329104
2019-04-04 11:34:14,361 [salt.minion      :1432][INFO    ][15494] Starting a new job with PID 15494
2019-04-04 11:34:14,393 [salt.minion      :1711][INFO    ][15494] Returning information for job: 20190404113414329104
2019-04-04 11:34:14,822 [salt.state       :300 ][INFO    ][15271] {'ret': True}
2019-04-04 11:34:14,823 [salt.state       :1951][INFO    ][15271] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 11:34:14.823109 duration_in_ms=987.841
2019-04-04 11:34:14,826 [salt.state       :1780][INFO    ][15271] Running state [maas.process_maas_config] at time 11:34:14.825928
2019-04-04 11:34:14,827 [salt.state       :1813][INFO    ][15271] Executing state module.run for [maas.process_maas_config]
2019-04-04 11:34:14,827 [salt.utils.decorators:613 ][WARNING ][15271] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 11:34:14,828 [salt.loaded.ext.module.maas:92  ][INFO    ][15271] maasconfig name=enable_http_proxy value=True
2019-04-04 11:34:14,881 [salt.loaded.ext.module.maas:92  ][INFO    ][15271] maasconfig name=upstream_dns value=8.8.8.8
2019-04-04 11:34:16,053 [salt.loaded.ext.module.maas:92  ][INFO    ][15271] maasconfig name=commissioning_distro_series value=xenial
2019-04-04 11:34:16,108 [salt.loaded.ext.module.maas:92  ][INFO    ][15271] maasconfig name=default_osystem value=ubuntu
2019-04-04 11:34:16,153 [salt.loaded.ext.module.maas:92  ][INFO    ][15271] maasconfig name=active_discovery_interval value=600
2019-04-04 11:34:16,195 [salt.loaded.ext.module.maas:92  ][INFO    ][15271] maasconfig name=dnssec_validation value=no
2019-04-04 11:34:16,229 [salt.loaded.ext.module.maas:92  ][INFO    ][15271] maasconfig name=maas_name value=mas01
2019-04-04 11:34:16,266 [salt.loaded.ext.module.maas:92  ][INFO    ][15271] maasconfig name=network_discovery value=enabled
2019-04-04 11:34:16,357 [salt.loaded.ext.module.maas:92  ][INFO    ][15271] maasconfig name=enable_third_party_drivers value=True
2019-04-04 11:34:16,401 [salt.loaded.ext.module.maas:92  ][INFO    ][15271] maasconfig name=default_storage_layout value=lvm
2019-04-04 11:34:16,475 [salt.loaded.ext.module.maas:92  ][INFO    ][15271] maasconfig name=ntp_external_only value=True
2019-04-04 11:34:16,526 [salt.loaded.ext.module.maas:92  ][INFO    ][15271] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-04 11:34:16,580 [salt.loaded.ext.module.maas:92  ][INFO    ][15271] maasconfig name=default_distro_series value=xenial
2019-04-04 11:34:16,639 [salt.loaded.ext.module.maas:92  ][INFO    ][15271] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-04 11:34:16,776 [salt.state       :300 ][INFO    ][15271] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-04-04 11:34:16,776 [salt.state       :1951][INFO    ][15271] Completed state [maas.process_maas_config] at time 11:34:16.776757 duration_in_ms=1950.814
2019-04-04 11:34:16,778 [salt.state       :1780][INFO    ][15271] Running state [pxe_admin] at time 11:34:16.777991
2019-04-04 11:34:16,778 [salt.state       :1813][INFO    ][15271] Executing state maasng.fabric_present for [pxe_admin]
2019-04-04 11:34:16,840 [salt.loaded.ext.module.maasng:945 ][INFO    ][15271] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-4', u'relay_vlan': None, u'primary_rack': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'id': 4}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'id': 3}]
2019-04-04 11:34:16,901 [salt.loaded.ext.module.maasng:1008][WARNING ][15271] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-04 11:34:16,902 [salt.loaded.ext.module.maasng:1011][WARNING ][15271] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-04 11:34:16,963 [salt.state       :300 ][INFO    ][15271] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-04 11:34:16,963 [salt.state       :1951][INFO    ][15271] Completed state [pxe_admin] at time 11:34:16.963728 duration_in_ms=185.737
2019-04-04 11:34:16,964 [salt.state       :1780][INFO    ][15271] Running state [vlan 0] at time 11:34:16.964089
2019-04-04 11:34:16,964 [salt.state       :1813][INFO    ][15271] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-04 11:34:17,015 [salt.loaded.ext.module.maasng:945 ][INFO    ][15271] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-4', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'f6q7c4', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}]
2019-04-04 11:34:17,124 [salt.loaded.ext.module.maasng:945 ][INFO    ][15271] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-4', u'relay_vlan': None, u'primary_rack': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'id': 4}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'id': 3}]
2019-04-04 11:34:17,351 [salt.loaded.ext.module.maasng:945 ][INFO    ][15271] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-4', u'relay_vlan': None, u'primary_rack': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'id': 4}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'id': 3}]
2019-04-04 11:34:17,429 [salt.state       :300 ][INFO    ][15271] {'new': 'Vlan untagged was updated'}
2019-04-04 11:34:17,430 [salt.state       :1951][INFO    ][15271] Completed state [vlan 0] at time 11:34:17.430018 duration_in_ms=465.928
2019-04-04 11:34:17,432 [salt.state       :1780][INFO    ][15271] Running state [192.168.11.0/24] at time 11:34:17.432264
2019-04-04 11:34:17,432 [salt.state       :1813][INFO    ][15271] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-04 11:34:17,612 [salt.loaded.ext.module.maasng:945 ][INFO    ][15271] [{u'id': 0, 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 4, u'class_type': None, u'vlans': [{u'fabric': u'fabric-4', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}, {u'id': 3, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': u'f6q7c4', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}]
2019-04-04 11:34:17,613 [salt.loaded.ext.module.maasng:1235][WARNING ][15271] Ignoring parameter vlan:0
2019-04-04 11:34:17,672 [salt.state       :300 ][INFO    ][15271] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-04 11:34:17,673 [salt.state       :1951][INFO    ][15271] Completed state [192.168.11.0/24] at time 11:34:17.673236 duration_in_ms=240.972
2019-04-04 11:34:17,674 [salt.state       :1780][INFO    ][15271] Running state [maas_create_iprange_1] at time 11:34:17.674081
2019-04-04 11:34:17,674 [salt.state       :1813][INFO    ][15271] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-04 11:34:17,724 [salt.state       :300 ][INFO    ][15271] Iprange maas_create_iprange_1 already exist.
2019-04-04 11:34:17,724 [salt.state       :1951][INFO    ][15271] Completed state [maas_create_iprange_1] at time 11:34:17.724414 duration_in_ms=50.333
2019-04-04 11:34:17,724 [salt.state       :1780][INFO    ][15271] Running state [vlan 0] at time 11:34:17.724760
2019-04-04 11:34:17,725 [salt.state       :1813][INFO    ][15271] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-04 11:34:17,773 [salt.loaded.ext.module.maasng:945 ][INFO    ][15271] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 4, u'vlans': [{u'fabric': u'fabric-4', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'class_type': None}, {u'id': 3, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'class_type': u''}]
2019-04-04 11:34:17,861 [salt.loaded.ext.module.maasng:945 ][INFO    ][15271] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-4', u'relay_vlan': None, u'primary_rack': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'id': 4}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'f6q7c4', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'id': 3}]
2019-04-04 11:34:18,237 [salt.loaded.ext.module.maasng:945 ][INFO    ][15271] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-4', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': u'f6q7c4', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}]
2019-04-04 11:34:18,330 [salt.state       :300 ][INFO    ][15271] {'new': 'Vlan untagged was updated'}
2019-04-04 11:34:18,331 [salt.state       :1951][INFO    ][15271] Completed state [vlan 0] at time 11:34:18.330758 duration_in_ms=605.998
2019-04-04 11:34:18,332 [salt.state       :1780][INFO    ][15271] Running state [opnfv] at time 11:34:18.332860
2019-04-04 11:34:18,333 [salt.state       :1813][INFO    ][15271] Executing state maasng.sshkey_present for [opnfv]
2019-04-04 11:34:18,394 [salt.loaded.ext.module.maasng:1903][INFO    ][15271] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-04-04 11:34:18,394 [salt.state       :300 ][INFO    ][15271] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-04-04 11:34:18,394 [salt.state       :1951][INFO    ][15271] Completed state [opnfv] at time 11:34:18.394677 duration_in_ms=61.817
2019-04-04 11:34:18,397 [salt.minion      :1711][INFO    ][15271] Returning information for job: 20190404113359287482
2019-04-04 11:34:19,122 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command state.apply with jid 20190404113419114217
2019-04-04 11:34:19,137 [salt.minion      :1432][INFO    ][15582] Starting a new job with PID 15582
2019-04-04 11:34:25,099 [salt.state       :915 ][INFO    ][15582] Loading fresh modules for state activity
2019-04-04 11:34:25,208 [salt.state       :1780][INFO    ][15582] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:34:25.208491
2019-04-04 11:34:25,208 [salt.state       :1813][INFO    ][15582] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-04 11:34:25,210 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15582] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-04 11:34:27,156 [salt.state       :300 ][INFO    ][15582] {'pid': 15606, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-04 11:34:27,157 [salt.state       :1951][INFO    ][15582] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:34:27.157371 duration_in_ms=1948.88
2019-04-04 11:34:27,159 [salt.state       :1780][INFO    ][15582] Running state [maas.process_machines] at time 11:34:27.159774
2019-04-04 11:34:27,160 [salt.state       :1813][INFO    ][15582] Executing state module.run for [maas.process_machines]
2019-04-04 11:34:27,160 [salt.utils.decorators:613 ][WARNING ][15582] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 11:34:27,630 [salt.loaded.ext.module.maas:412 ][WARNING ][15582] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-04 11:34:27,631 [salt.loaded.ext.module.maas:92  ][INFO    ][15582] 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=7hgdsr architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-04 11:34:28,572 [salt.loaded.ext.module.maas:412 ][WARNING ][15582] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-04 11:34:28,573 [salt.loaded.ext.module.maas:92  ][INFO    ][15582] 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=a8qkey architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-04 11:34:29,531 [salt.loaded.ext.module.maas:412 ][WARNING ][15582] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-04 11:34:29,532 [salt.loaded.ext.module.maas:92  ][INFO    ][15582] 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=fhm4qw architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-04 11:34:30,476 [salt.loaded.ext.module.maas:412 ][WARNING ][15582] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-04 11:34:30,476 [salt.loaded.ext.module.maas:92  ][INFO    ][15582] 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=raa87f architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-04 11:34:31,375 [salt.loaded.ext.module.maas:412 ][WARNING ][15582] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-04 11:34:31,376 [salt.loaded.ext.module.maas:92  ][INFO    ][15582] 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=eqamnn architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-04 11:34:32,284 [salt.state       :300 ][INFO    ][15582] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-04 11:34:32,284 [salt.state       :1951][INFO    ][15582] Completed state [maas.process_machines] at time 11:34:32.284841 duration_in_ms=5125.066
2019-04-04 11:34:32,288 [salt.minion      :1711][INFO    ][15582] Returning information for job: 20190404113419114217
2019-04-04 11:35:06,166 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command state.apply with jid 20190404113506155480
2019-04-04 11:35:06,189 [salt.minion      :1432][INFO    ][15896] Starting a new job with PID 15896
2019-04-04 11:35:12,147 [salt.state       :915 ][INFO    ][15896] Loading fresh modules for state activity
2019-04-04 11:35:12,257 [salt.state       :1780][INFO    ][15896] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:35:12.257037
2019-04-04 11:35:12,257 [salt.state       :1813][INFO    ][15896] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-04 11:35:12,259 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15896] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-04 11:35:14,200 [salt.state       :300 ][INFO    ][15896] {'pid': 15908, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-04 11:35:14,201 [salt.state       :1951][INFO    ][15896] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:35:14.201507 duration_in_ms=1944.47
2019-04-04 11:35:14,204 [salt.state       :1780][INFO    ][15896] Running state [maas.wait_for_machine_status] at time 11:35:14.204163
2019-04-04 11:35:14,204 [salt.state       :1813][INFO    ][15896] Executing state module.run for [maas.wait_for_machine_status]
2019-04-04 11:35:14,204 [salt.utils.decorators:613 ][WARNING ][15896] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 11:35:16,722 [salt.state       :300 ][INFO    ][15896] {'ret': True}
2019-04-04 11:35:16,723 [salt.state       :1951][INFO    ][15896] Completed state [maas.wait_for_machine_status] at time 11:35:16.722790 duration_in_ms=2518.626
2019-04-04 11:35:16,726 [salt.minion      :1711][INFO    ][15896] Returning information for job: 20190404113506155480
2019-04-04 11:35:17,429 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command state.apply with jid 20190404113517413534
2019-04-04 11:35:17,449 [salt.minion      :1432][INFO    ][15921] Starting a new job with PID 15921
2019-04-04 11:35:18,392 [salt.state       :915 ][INFO    ][15921] Loading fresh modules for state activity
2019-04-04 11:35:18,539 [salt.state       :1780][INFO    ][15921] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:35:18.539131
2019-04-04 11:35:18,539 [salt.state       :1813][INFO    ][15921] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-04 11:35:18,541 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15921] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-04 11:35:20,458 [salt.state       :300 ][INFO    ][15921] {'pid': 15928, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-04 11:35:20,459 [salt.state       :1951][INFO    ][15921] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:35:20.458965 duration_in_ms=1919.834
2019-04-04 11:35:20,461 [salt.state       :1780][INFO    ][15921] Running state [maas_machines_storage_cmp002_lvm] at time 11:35:20.460973
2019-04-04 11:35:20,461 [salt.state       :1813][INFO    ][15921] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-04 11:35:21,111 [salt.state       :300 ][INFO    ][15921] Machine cmp002 is not in Ready state.
2019-04-04 11:35:21,112 [salt.state       :1951][INFO    ][15921] Completed state [maas_machines_storage_cmp002_lvm] at time 11:35:21.112209 duration_in_ms=651.235
2019-04-04 11:35:21,112 [salt.state       :1780][INFO    ][15921] Running state [maas_machines_storage_cmp001_lvm] at time 11:35:21.112615
2019-04-04 11:35:21,112 [salt.state       :1813][INFO    ][15921] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-04 11:35:21,652 [salt.state       :300 ][INFO    ][15921] Machine cmp001 is not in Ready state.
2019-04-04 11:35:21,652 [salt.state       :1951][INFO    ][15921] Completed state [maas_machines_storage_cmp001_lvm] at time 11:35:21.652374 duration_in_ms=539.759
2019-04-04 11:35:21,655 [salt.minion      :1711][INFO    ][15921] Returning information for job: 20190404113517413534
2019-04-04 11:35:22,330 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command state.apply with jid 20190404113522319283
2019-04-04 11:35:22,352 [salt.minion      :1432][INFO    ][15939] Starting a new job with PID 15939
2019-04-04 11:35:23,356 [salt.state       :915 ][INFO    ][15939] Loading fresh modules for state activity
2019-04-04 11:35:23,454 [salt.state       :1780][INFO    ][15939] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:35:23.454339
2019-04-04 11:35:23,455 [salt.state       :1813][INFO    ][15939] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-04 11:35:23,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15939] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-04 11:35:25,241 [salt.state       :300 ][INFO    ][15939] {'pid': 15946, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-04 11:35:25,242 [salt.state       :1951][INFO    ][15939] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:35:25.242522 duration_in_ms=1788.182
2019-04-04 11:35:25,245 [salt.state       :1780][INFO    ][15939] Running state [maas.deploy_machines] at time 11:35:25.245750
2019-04-04 11:35:25,246 [salt.state       :1813][INFO    ][15939] Executing state module.run for [maas.deploy_machines]
2019-04-04 11:35:25,247 [salt.utils.decorators:613 ][WARNING ][15939] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 11:35:25,797 [salt.loaded.ext.module.maas:684 ][INFO    ][15939] deploymachines hwe_kernel=hwe-16.04 system_id=eqamnn distro_series=xenial
2019-04-04 11:35:28,148 [salt.state       :300 ][INFO    ][15939] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-04-04 11:35:28,148 [salt.state       :1951][INFO    ][15939] Completed state [maas.deploy_machines] at time 11:35:28.148884 duration_in_ms=2903.133
2019-04-04 11:35:28,151 [salt.minion      :1711][INFO    ][15939] Returning information for job: 20190404113522319283
2019-04-04 11:35:28,840 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command state.apply with jid 20190404113528831779
2019-04-04 11:35:28,858 [salt.minion      :1432][INFO    ][16019] Starting a new job with PID 16019
2019-04-04 11:35:34,815 [salt.state       :915 ][INFO    ][16019] Loading fresh modules for state activity
2019-04-04 11:35:34,901 [salt.state       :1780][INFO    ][16019] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:35:34.901560
2019-04-04 11:35:34,901 [salt.state       :1813][INFO    ][16019] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-04 11:35:34,903 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16019] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-04 11:35:36,717 [salt.state       :300 ][INFO    ][16019] {'pid': 16030, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-04 11:35:36,718 [salt.state       :1951][INFO    ][16019] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:35:36.718013 duration_in_ms=1816.453
2019-04-04 11:35:36,720 [salt.state       :1780][INFO    ][16019] Running state [maas.wait_for_machine_status] at time 11:35:36.720040
2019-04-04 11:35:36,720 [salt.state       :1813][INFO    ][16019] Executing state module.run for [maas.wait_for_machine_status]
2019-04-04 11:35:36,721 [salt.utils.decorators:613 ][WARNING ][16019] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-04 11:35:39,178 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.55788612s left)
2019-04-04 11:35:43,922 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113543860441
2019-04-04 11:35:43,944 [salt.minion      :1432][INFO    ][16088] Starting a new job with PID 16088
2019-04-04 11:35:43,973 [salt.minion      :1711][INFO    ][16088] Returning information for job: 20190404113543860441
2019-04-04 11:36:11,888 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.84832501s left)
2019-04-04 11:36:13,963 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113613949722
2019-04-04 11:36:13,979 [salt.minion      :1432][INFO    ][16124] Starting a new job with PID 16124
2019-04-04 11:36:14,008 [salt.minion      :1711][INFO    ][16124] Returning information for job: 20190404113613949722
2019-04-04 11:36:44,034 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113644024538
2019-04-04 11:36:44,048 [salt.minion      :1432][INFO    ][16189] Starting a new job with PID 16189
2019-04-04 11:36:44,076 [salt.minion      :1711][INFO    ][16189] Returning information for job: 20190404113644024538
2019-04-04 11:36:44,221 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2182.51483917s left)
2019-04-04 11:37:14,109 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113714098204
2019-04-04 11:37:14,126 [salt.minion      :1432][INFO    ][16222] Starting a new job with PID 16222
2019-04-04 11:37:14,161 [salt.minion      :1711][INFO    ][16222] Returning information for job: 20190404113714098204
2019-04-04 11:37:17,143 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.59362602s left)
2019-04-04 11:37:44,207 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113744193403
2019-04-04 11:37:44,230 [salt.minion      :1432][INFO    ][16301] Starting a new job with PID 16301
2019-04-04 11:37:44,267 [salt.minion      :1711][INFO    ][16301] Returning information for job: 20190404113744193403
2019-04-04 11:37:49,588 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2117.14780402s left)
2019-04-04 11:38:14,284 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113814270441
2019-04-04 11:38:14,310 [salt.minion      :1432][INFO    ][16345] Starting a new job with PID 16345
2019-04-04 11:38:14,340 [salt.minion      :1711][INFO    ][16345] Returning information for job: 20190404113814270441
2019-04-04 11:38:22,313 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2084.42342401s left)
2019-04-04 11:38:44,384 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113844370734
2019-04-04 11:38:44,406 [salt.minion      :1432][INFO    ][16417] Starting a new job with PID 16417
2019-04-04 11:38:44,434 [salt.minion      :1711][INFO    ][16417] Returning information for job: 20190404113844370734
2019-04-04 11:38:54,620 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2052.11584806s left)
2019-04-04 11:39:14,455 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113914442711
2019-04-04 11:39:14,479 [salt.minion      :1432][INFO    ][16453] Starting a new job with PID 16453
2019-04-04 11:39:14,510 [salt.minion      :1711][INFO    ][16453] Returning information for job: 20190404113914442711
2019-04-04 11:39:27,300 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2019.43638897s left)
2019-04-04 11:39:44,489 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404113944476328
2019-04-04 11:39:44,507 [salt.minion      :1432][INFO    ][16541] Starting a new job with PID 16541
2019-04-04 11:39:44,539 [salt.minion      :1711][INFO    ][16541] Returning information for job: 20190404113944476328
2019-04-04 11:39:59,689 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1987.04743004s left)
2019-04-04 11:40:14,573 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404114014558485
2019-04-04 11:40:14,591 [salt.minion      :1432][INFO    ][16581] Starting a new job with PID 16581
2019-04-04 11:40:14,621 [salt.minion      :1711][INFO    ][16581] Returning information for job: 20190404114014558485
2019-04-04 11:40:32,541 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1954.19496417s left)
2019-04-04 11:40:44,699 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404114044689972
2019-04-04 11:40:44,720 [salt.minion      :1432][INFO    ][16787] Starting a new job with PID 16787
2019-04-04 11:40:44,757 [salt.minion      :1711][INFO    ][16787] Returning information for job: 20190404114044689972
2019-04-04 11:41:04,917 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1921.81876111s left)
2019-04-04 11:41:14,923 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404114114909278
2019-04-04 11:41:14,947 [salt.minion      :1432][INFO    ][16828] Starting a new job with PID 16828
2019-04-04 11:41:14,975 [salt.minion      :1711][INFO    ][16828] Returning information for job: 20190404114114909278
2019-04-04 11:41:37,928 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1888.80873299s left)
2019-04-04 11:41:45,032 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404114145022608
2019-04-04 11:41:45,052 [salt.minion      :1432][INFO    ][16948] Starting a new job with PID 16948
2019-04-04 11:41:45,080 [salt.minion      :1711][INFO    ][16948] Returning information for job: 20190404114145022608
2019-04-04 11:42:10,281 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1856.45484996s left)
2019-04-04 11:42:15,134 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404114215127698
2019-04-04 11:42:15,155 [salt.minion      :1432][INFO    ][16986] Starting a new job with PID 16986
2019-04-04 11:42:15,188 [salt.minion      :1711][INFO    ][16986] Returning information for job: 20190404114215127698
2019-04-04 11:42:43,921 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1822.81562114s left)
2019-04-04 11:42:45,284 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404114245274758
2019-04-04 11:42:45,302 [salt.minion      :1432][INFO    ][17170] Starting a new job with PID 17170
2019-04-04 11:42:45,332 [salt.minion      :1711][INFO    ][17170] Returning information for job: 20190404114245274758
2019-04-04 11:43:15,409 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404114315401860
2019-04-04 11:43:15,421 [salt.minion      :1432][INFO    ][17212] Starting a new job with PID 17212
2019-04-04 11:43:15,452 [salt.minion      :1711][INFO    ][17212] Returning information for job: 20190404114315401860
2019-04-04 11:43:16,454 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1790.28232598s left)
2019-04-04 11:43:45,505 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404114345491398
2019-04-04 11:43:45,528 [salt.minion      :1432][INFO    ][17341] Starting a new job with PID 17341
2019-04-04 11:43:45,563 [salt.minion      :1711][INFO    ][17341] Returning information for job: 20190404114345491398
2019-04-04 11:43:49,295 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1757.44097018s left)
2019-04-04 11:44:15,678 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404114415663731
2019-04-04 11:44:15,697 [salt.minion      :1432][INFO    ][17377] Starting a new job with PID 17377
2019-04-04 11:44:15,728 [salt.minion      :1711][INFO    ][17377] Returning information for job: 20190404114415663731
2019-04-04 11:44:21,674 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1725.06238103s left)
2019-04-04 11:44:45,787 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404114445773700
2019-04-04 11:44:45,809 [salt.minion      :1432][INFO    ][17445] Starting a new job with PID 17445
2019-04-04 11:44:45,837 [salt.minion      :1711][INFO    ][17445] Returning information for job: 20190404114445773700
2019-04-04 11:44:54,550 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1692.18586302s left)
2019-04-04 11:45:15,946 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404114515939574
2019-04-04 11:45:15,968 [salt.minion      :1432][INFO    ][17485] Starting a new job with PID 17485
2019-04-04 11:45:15,998 [salt.minion      :1711][INFO    ][17485] Returning information for job: 20190404114515939574
2019-04-04 11:45:27,055 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1659.68130708s left)
2019-04-04 11:45:46,100 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404114546092903
2019-04-04 11:45:46,126 [salt.minion      :1432][INFO    ][17552] Starting a new job with PID 17552
2019-04-04 11:45:46,157 [salt.minion      :1711][INFO    ][17552] Returning information for job: 20190404114546092903
2019-04-04 11:45:59,821 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1626.91562319s left)
2019-04-04 11:46:16,235 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404114616220152
2019-04-04 11:46:16,255 [salt.minion      :1432][INFO    ][17588] Starting a new job with PID 17588
2019-04-04 11:46:16,285 [salt.minion      :1711][INFO    ][17588] Returning information for job: 20190404114616220152
2019-04-04 11:46:32,170 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1594.56653619s left)
2019-04-04 11:46:46,400 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404114646384164
2019-04-04 11:46:46,425 [salt.minion      :1432][INFO    ][17653] Starting a new job with PID 17653
2019-04-04 11:46:46,456 [salt.minion      :1711][INFO    ][17653] Returning information for job: 20190404114646384164
2019-04-04 11:47:05,246 [salt.loaded.ext.module.maas:1023][INFO    ][16019] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1561.49018097s left)
2019-04-04 11:47:16,589 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command saltutil.find_job with jid 20190404114716573637
2019-04-04 11:47:16,608 [salt.minion      :1432][INFO    ][17712] Starting a new job with PID 17712
2019-04-04 11:47:16,641 [salt.minion      :1711][INFO    ][17712] Returning information for job: 20190404114716573637
2019-04-04 11:47:37,814 [salt.state       :300 ][INFO    ][16019] {'ret': True}
2019-04-04 11:47:37,816 [salt.state       :1951][INFO    ][16019] Completed state [maas.wait_for_machine_status] at time 11:47:37.816175 duration_in_ms=721096.134
2019-04-04 11:47:37,818 [salt.minion      :1711][INFO    ][16019] Returning information for job: 20190404113528831779
2019-04-04 11:48:47,920 [salt.utils.schedule:1377][INFO    ][6103] Running scheduled job: __mine_interval
2019-04-04 12:48:47,920 [salt.utils.schedule:1377][INFO    ][6103] Running scheduled job: __mine_interval
2019-04-04 13:27:16,641 [salt.minion      :1308][INFO    ][6103] User sudo_ubuntu Executing command cp.push_dir with jid 20190404132716632815
2019-04-04 13:27:16,665 [salt.minion      :1432][INFO    ][28037] Starting a new job with PID 28037
