2019-03-30 05:10:50,683 [salt.utils.decorators:613 ][WARNING ][2352] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:10:51,729 [salt.utils.decorators:613 ][WARNING ][2352] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:10:55,631 [salt.loaded.int.states.file:2298][WARNING ][2569] 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-03-30 05:11:16,919 [salt.state       :2022][WARNING ][2792] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-30 05:11:20,062 [salt.utils.decorators:613 ][WARNING ][2792] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:11:33,249 [salt.utils.decorators:613 ][WARNING ][2792] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:11:54,877 [salt.utils.decorators:613 ][WARNING ][2792] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:11:55,747 [salt.utils.decorators:613 ][WARNING ][2792] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:11:57,811 [salt.loaded.ext.module.maasng:1008][WARNING ][2792] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-03-30 05:11:57,812 [salt.loaded.ext.module.maasng:1011][WARNING ][2792] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-03-30 05:11:58,653 [salt.loaded.ext.module.maasng:1235][WARNING ][2792] Ignoring parameter vlan:0
2019-03-30 05:11:59,479 [salt.utils.decorators:613 ][WARNING ][2792] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:12:11,257 [salt.utils.decorators:613 ][WARNING ][5202] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:12:11,318 [salt.loaded.ext.module.maas:412 ][WARNING ][5202] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-30 05:12:12,601 [salt.loaded.ext.module.maas:412 ][WARNING ][5202] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-30 05:12:13,746 [salt.loaded.ext.module.maas:412 ][WARNING ][5202] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-30 05:12:14,905 [salt.loaded.ext.module.maas:412 ][WARNING ][5202] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-30 05:12:16,175 [salt.loaded.ext.module.maas:412 ][WARNING ][5202] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-30 05:12:22,752 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5805] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-30 05:12:22,788 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5805] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-30 05:12:22,834 [salt.utils.parsers:1051][WARNING ][387] Minion received a SIGTERM. Exiting.
2019-03-30 05:12:24,027 [salt.cli.daemons :293 ][INFO    ][5899] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-03-30 05:12:24,196 [salt.cli.daemons :82  ][INFO    ][5899] Starting up the Salt Minion
2019-03-30 05:12:24,196 [salt.utils.event :1017][INFO    ][5899] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-03-30 05:12:25,469 [salt.minion      :976 ][INFO    ][5899] Creating minion process manager
2019-03-30 05:12:27,595 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5899] Executing command ['date', '+%z'] in directory '/root'
2019-03-30 05:12:27,619 [salt.utils.schedule:568 ][INFO    ][5899] Updating job settings for scheduled job: __mine_interval
2019-03-30 05:12:27,621 [salt.minion      :1108][INFO    ][5899] Added mine.update to scheduler
2019-03-30 05:12:27,626 [salt.minion      :1975][INFO    ][5899] Minion is starting as user 'root'
2019-03-30 05:12:27,641 [salt.minion      :2336][INFO    ][5899] Minion is ready to receive requests!
2019-03-30 05:12:48,680 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command state.apply with jid 20190330051248669844
2019-03-30 05:12:48,701 [salt.minion      :1432][INFO    ][5997] Starting a new job with PID 5997
2019-03-30 05:12:56,707 [salt.state       :915 ][INFO    ][5997] Loading fresh modules for state activity
2019-03-30 05:12:56,765 [salt.fileclient  :1219][INFO    ][5997] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-30 05:12:56,812 [salt.state       :1780][INFO    ][5997] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:12:56.812322
2019-03-30 05:12:56,813 [salt.state       :1813][INFO    ][5997] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-30 05:12:56,816 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5997] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-30 05:12:58,678 [salt.state       :300 ][INFO    ][5997] {'pid': 6008, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-30 05:12:58,679 [salt.state       :1951][INFO    ][5997] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:12:58.679362 duration_in_ms=1867.042
2019-03-30 05:12:58,682 [salt.state       :1780][INFO    ][5997] Running state [maas.wait_for_machine_status] at time 05:12:58.682162
2019-03-30 05:12:58,682 [salt.state       :1813][INFO    ][5997] Executing state module.run for [maas.wait_for_machine_status]
2019-03-30 05:12:58,684 [salt.utils.decorators:613 ][WARNING ][5997] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:12:59,458 [salt.loaded.ext.module.maas:1023][INFO    ][5997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.23559999s left)
2019-03-30 05:13:03,769 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330051303761156
2019-03-30 05:13:03,789 [salt.minion      :1432][INFO    ][6031] Starting a new job with PID 6031
2019-03-30 05:13:03,816 [salt.minion      :1711][INFO    ][6031] Returning information for job: 20190330051303761156
2019-03-30 05:13:30,293 [salt.loaded.ext.module.maas:1023][INFO    ][5997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.40037179s left)
2019-03-30 05:13:33,800 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330051333780646
2019-03-30 05:13:33,821 [salt.minion      :1432][INFO    ][6101] Starting a new job with PID 6101
2019-03-30 05:13:33,889 [salt.minion      :1711][INFO    ][6101] Returning information for job: 20190330051333780646
2019-03-30 05:14:01,092 [salt.loaded.ext.module.maas:1023][INFO    ][5997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.60114789s left)
2019-03-30 05:14:03,924 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330051403910122
2019-03-30 05:14:03,944 [salt.minion      :1432][INFO    ][6130] Starting a new job with PID 6130
2019-03-30 05:14:03,972 [salt.minion      :1711][INFO    ][6130] Returning information for job: 20190330051403910122
2019-03-30 05:14:31,892 [salt.loaded.ext.module.maas:1023][INFO    ][5997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.80167389s left)
2019-03-30 05:14:33,977 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330051433964715
2019-03-30 05:14:33,995 [salt.minion      :1432][INFO    ][6202] Starting a new job with PID 6202
2019-03-30 05:14:34,025 [salt.minion      :1711][INFO    ][6202] Returning information for job: 20190330051433964715
2019-03-30 05:15:02,912 [salt.loaded.ext.module.maas:1023][INFO    ][5997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.78129697s left)
2019-03-30 05:15:04,041 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330051504028959
2019-03-30 05:15:04,061 [salt.minion      :1432][INFO    ][6254] Starting a new job with PID 6254
2019-03-30 05:15:04,088 [salt.minion      :1711][INFO    ][6254] Returning information for job: 20190330051504028959
2019-03-30 05:15:33,843 [salt.loaded.ext.module.maas:1023][INFO    ][5997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.850317s left)
2019-03-30 05:15:34,116 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330051534105753
2019-03-30 05:15:34,137 [salt.minion      :1432][INFO    ][6452] Starting a new job with PID 6452
2019-03-30 05:15:34,174 [salt.minion      :1711][INFO    ][6452] Returning information for job: 20190330051534105753
2019-03-30 05:16:04,148 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330051604134214
2019-03-30 05:16:04,173 [salt.minion      :1432][INFO    ][6530] Starting a new job with PID 6530
2019-03-30 05:16:04,204 [salt.minion      :1711][INFO    ][6530] Returning information for job: 20190330051604134214
2019-03-30 05:16:04,979 [salt.loaded.ext.module.maas:1023][INFO    ][5997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.71481991s left)
2019-03-30 05:16:34,194 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330051634186511
2019-03-30 05:16:34,209 [salt.minion      :1432][INFO    ][6870] Starting a new job with PID 6870
2019-03-30 05:16:34,235 [salt.minion      :1711][INFO    ][6870] Returning information for job: 20190330051634186511
2019-03-30 05:16:36,192 [salt.loaded.ext.module.maas:1023][INFO    ][5997] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.50120091s left)
2019-03-30 05:17:04,268 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330051704250585
2019-03-30 05:17:04,291 [salt.minion      :1432][INFO    ][6996] Starting a new job with PID 6996
2019-03-30 05:17:04,319 [salt.minion      :1711][INFO    ][6996] Returning information for job: 20190330051704250585
2019-03-30 05:17:07,940 [salt.loaded.ext.module.maas:1023][INFO    ][5997] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.75342393s left)
2019-03-30 05:17:34,401 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330051734384831
2019-03-30 05:17:34,423 [salt.minion      :1432][INFO    ][7366] Starting a new job with PID 7366
2019-03-30 05:17:34,451 [salt.minion      :1711][INFO    ][7366] Returning information for job: 20190330051734384831
2019-03-30 05:17:39,978 [salt.loaded.ext.module.maas:1023][INFO    ][5997] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.71549296s left)
2019-03-30 05:18:04,416 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330051804406186
2019-03-30 05:18:04,429 [salt.minion      :1432][INFO    ][7526] Starting a new job with PID 7526
2019-03-30 05:18:04,460 [salt.minion      :1711][INFO    ][7526] Returning information for job: 20190330051804406186
2019-03-30 05:18:12,245 [salt.loaded.ext.module.maas:1023][INFO    ][5997] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.448102s left)
2019-03-30 05:18:34,558 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330051834547333
2019-03-30 05:18:34,579 [salt.minion      :1432][INFO    ][7743] Starting a new job with PID 7743
2019-03-30 05:18:34,605 [salt.minion      :1711][INFO    ][7743] Returning information for job: 20190330051834547333
2019-03-30 05:18:44,336 [salt.loaded.ext.module.maas:1023][INFO    ][5997] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1154.35697293s left)
2019-03-30 05:19:04,673 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330051904658278
2019-03-30 05:19:04,692 [salt.minion      :1432][INFO    ][7897] Starting a new job with PID 7897
2019-03-30 05:19:04,722 [salt.minion      :1711][INFO    ][7897] Returning information for job: 20190330051904658278
2019-03-30 05:19:17,097 [salt.state       :300 ][INFO    ][5997] {'ret': True}
2019-03-30 05:19:17,097 [salt.state       :1951][INFO    ][5997] Completed state [maas.wait_for_machine_status] at time 05:19:17.097588 duration_in_ms=378415.426
2019-03-30 05:19:17,101 [salt.minion      :1711][INFO    ][5997] Returning information for job: 20190330051248669844
2019-03-30 05:19:17,800 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command state.apply with jid 20190330051917787174
2019-03-30 05:19:17,819 [salt.minion      :1432][INFO    ][7934] Starting a new job with PID 7934
2019-03-30 05:19:26,143 [salt.state       :915 ][INFO    ][7934] Loading fresh modules for state activity
2019-03-30 05:19:26,206 [salt.fileclient  :1219][INFO    ][7934] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-30 05:19:26,310 [salt.state       :1780][INFO    ][7934] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:26.310647
2019-03-30 05:19:26,311 [salt.state       :1813][INFO    ][7934] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-30 05:19:26,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7934] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-30 05:19:28,369 [salt.state       :300 ][INFO    ][7934] {'pid': 8025, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-30 05:19:28,370 [salt.state       :1951][INFO    ][7934] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:28.369927 duration_in_ms=2059.28
2019-03-30 05:19:28,373 [salt.state       :1780][INFO    ][7934] Running state [maas_machines_storage_cmp002_lvm] at time 05:19:28.373095
2019-03-30 05:19:28,373 [salt.state       :1813][INFO    ][7934] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-30 05:19:29,486 [salt.loaded.ext.module.maasng:610 ][INFO    ][7934] na7wmb
2019-03-30 05:19:29,489 [salt.loaded.ext.module.maasng:626 ][INFO    ][7934] sda
2019-03-30 05:19:30,041 [salt.loaded.ext.module.maasng:361 ][INFO    ][7934] na7wmb
2019-03-30 05:19:30,156 [salt.loaded.ext.module.maasng:367 ][INFO    ][7934] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'na7wmb', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id': 1, u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'11873e54-a9f4-47af-8e73-f05e21d0e926', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'na7wmb', u'device_id': 1, u'filesystem': {u'mount_options': None, u'uuid': u'd4222758-8c59-4f6b-ad2a-05bfe83b3b52', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/na7wmb/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}], u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/na7wmb/blockdevices/1/', u'name': u'sda'}, {u'size': 800097042432, u'model': None, u'block_size': 4096, u'uuid': u'3506cb0d-04b7-4439-be42-6f0a48bb221a', u'tags': [], u'used_size': 800097042432, u'filesystem': {u'mount_options': None, u'uuid': u'659e77b1-af86-4e64-8fcf-c6dcfadc1338', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'na7wmb', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id': 3, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/na7wmb/blockdevices/3/', u'name': u'vgroot-lvroot'}]
2019-03-30 05:19:30,156 [salt.loaded.ext.module.maasng:632 ][INFO    ][7934] vgroot
2019-03-30 05:19:30,157 [salt.loaded.ext.module.maasng:635 ][INFO    ][7934] lvroot
2019-03-30 05:19:30,157 [salt.loaded.ext.module.maasng:639 ][INFO    ][7934] 107374182400
2019-03-30 05:19:30,768 [salt.loaded.ext.module.maasng:645 ][INFO    ][7934] {u'hwe_kernel': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'owner_data': {}, u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'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'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'na7wmb', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/na7wmb/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'62d6d64d-630d-4e18-8089-d3792ebeb2fb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'na7wmb', u'filesystem': {u'label': None, u'uuid': u'ccb5e25b-4c5e-4c5d-a345-97632b7b11eb', 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/na7wmb/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/na7wmb/', u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'na7wmb', 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'disable_ipv4': False, u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'na7wmb', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/na7wmb/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'62d6d64d-630d-4e18-8089-d3792ebeb2fb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'na7wmb', u'filesystem': {u'label': None, u'uuid': u'ccb5e25b-4c5e-4c5d-a345-97632b7b11eb', 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/na7wmb/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'na7wmb', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'uuid': u'57e916bd-9787-4641-9207-af3b2cb15d62', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'label': u'root', u'uuid': u'3e46e683-40ca-4e7e-a5c8-a0fab33b54dc', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'na7wmb', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/na7wmb/blockdevices/11/', u'type': u'virtual', u'id': 11, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'gggsy8', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'gggsy8', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], 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'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'gggsy8', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'na7wmb', u'effective_mtu': 1500, u'id': 4, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/na7wmb/interfaces/4/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'gggsy8', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'gggsy8', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], 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'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'gggsy8', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'na7wmb', u'effective_mtu': 1500, u'id': 4, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/na7wmb/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'na7wmb', u'effective_mtu': 1500, u'id': 15, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/na7wmb/interfaces/15/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {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'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'na7wmb', u'effective_mtu': 1500, u'id': 13, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/na7wmb/interfaces/13/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {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'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'na7wmb', u'effective_mtu': 1500, u'id': 14, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/na7wmb/interfaces/14/'}, {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'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'gggsy8', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 22}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'gggsy8', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], 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'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'gggsy8', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'na7wmb', u'effective_mtu': 1500, u'id': 11, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/na7wmb/interfaces/11/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'na7wmb', u'effective_mtu': 1500, u'id': 12, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/na7wmb/interfaces/12/'}], u'address_ttl': None, u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'na7wmb', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/na7wmb/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'62d6d64d-630d-4e18-8089-d3792ebeb2fb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'na7wmb', u'filesystem': {u'label': None, u'uuid': u'ccb5e25b-4c5e-4c5d-a345-97632b7b11eb', 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/na7wmb/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}]}, {u'size': 107374182400, u'block_size': 4096, u'uuid': u'57e916bd-9787-4641-9207-af3b2cb15d62', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'label': u'root', u'uuid': u'3e46e683-40ca-4e7e-a5c8-a0fab33b54dc', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'na7wmb', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/na7wmb/blockdevices/11/', u'type': u'virtual', u'id': 11, u'serial': None, u'partitions': []}], u'distro_series': u'', u'memory_test_status': -1}
2019-03-30 05:19:30,773 [salt.state       :300 ][INFO    ][7934] {'new': {'storage_layout': 'lvm'}}
2019-03-30 05:19:30,773 [salt.state       :1951][INFO    ][7934] Completed state [maas_machines_storage_cmp002_lvm] at time 05:19:30.773445 duration_in_ms=2400.349
2019-03-30 05:19:30,773 [salt.state       :1780][INFO    ][7934] Running state [maas_machines_storage_cmp001_lvm] at time 05:19:30.773831
2019-03-30 05:19:30,774 [salt.state       :1813][INFO    ][7934] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-30 05:19:31,909 [salt.loaded.ext.module.maasng:610 ][INFO    ][7934] wdfmpm
2019-03-30 05:19:31,909 [salt.loaded.ext.module.maasng:626 ][INFO    ][7934] sda
2019-03-30 05:19:32,492 [salt.loaded.ext.module.maasng:361 ][INFO    ][7934] wdfmpm
2019-03-30 05:19:32,580 [salt.loaded.ext.module.maasng:367 ][INFO    ][7934] [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wdfmpm', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/blockdevices/2/', u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'a8ba0bf6-9c66-452f-aea7-736ff9c1d8ea', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wdfmpm', u'filesystem': {u'label': None, u'uuid': u'e2fd848d-efee-4c94-83d4-e2b4292e07d9', 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/wdfmpm/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'device_id': 2}]}, {u'size': 800097042432, u'block_size': 4096, u'uuid': u'86d42f0d-1d4b-4828-8c57-a3274863435f', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'filesystem': {u'label': u'root', u'uuid': u'6190456f-3d38-4307-98a0-c8d823ec8760', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wdfmpm', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/blockdevices/4/', u'type': u'virtual', u'id': 4, u'serial': None, u'partitions': []}]
2019-03-30 05:19:32,581 [salt.loaded.ext.module.maasng:632 ][INFO    ][7934] vgroot
2019-03-30 05:19:32,581 [salt.loaded.ext.module.maasng:635 ][INFO    ][7934] lvroot
2019-03-30 05:19:32,581 [salt.loaded.ext.module.maasng:639 ][INFO    ][7934] 107374182400
2019-03-30 05:19:32,887 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330051932877287
2019-03-30 05:19:32,905 [salt.minion      :1432][INFO    ][8053] Starting a new job with PID 8053
2019-03-30 05:19:32,932 [salt.minion      :1711][INFO    ][8053] Returning information for job: 20190330051932877287
2019-03-30 05:19:33,181 [salt.loaded.ext.module.maasng:645 ][INFO    ][7934] {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.39', u'192.168.11.44'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', 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'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wdfmpm', u'block_size': 4096, u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'6241a38c-a717-48f5-898d-c022e83dd094', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wdfmpm', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'76194773-84d8-48ab-b000-5458d4e269ea', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}, u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/wdfmpm/', u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'wdfmpm', 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'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'uuid': u'03d84cae-dc99-4801-99da-199233266b8a', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'uuid': u'1f694acd-43ae-44dc-ad12-6cf8ef1216d2', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wdfmpm', u'block_size': 4096, u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 12, u'partitions': []}], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'wdfmpm', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id': 2, u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'6241a38c-a717-48f5-898d-c022e83dd094', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wdfmpm', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'76194773-84d8-48ab-b000-5458d4e269ea', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/blockdevices/2/', u'name': u'sda'}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'1f694acd-43ae-44dc-ad12-6cf8ef1216d2', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'uuid': u'03d84cae-dc99-4801-99da-199233266b8a', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'wdfmpm', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id': 12, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/blockdevices/12/', u'name': u'vgroot-lvroot'}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'wdfmpm', u'id': 7}], 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gggsy8', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gggsy8', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gggsy8', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'wdfmpm', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/interfaces/5/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wdfmpm', u'block_size': 4096, u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'6241a38c-a717-48f5-898d-c022e83dd094', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wdfmpm', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'76194773-84d8-48ab-b000-5458d4e269ea', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gggsy8', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gggsy8', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gggsy8', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'wdfmpm', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'wdfmpm', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/interfaces/18/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'wdfmpm', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/interfaces/19/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gggsy8', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gggsy8', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gggsy8', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'wdfmpm', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/interfaces/16/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'wdfmpm', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/interfaces/17/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 28}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'wdfmpm', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/wdfmpm/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-03-30 05:19:33,185 [salt.state       :300 ][INFO    ][7934] {'new': {'storage_layout': 'lvm'}}
2019-03-30 05:19:33,186 [salt.state       :1951][INFO    ][7934] Completed state [maas_machines_storage_cmp001_lvm] at time 05:19:33.186286 duration_in_ms=2412.453
2019-03-30 05:19:33,190 [salt.minion      :1711][INFO    ][7934] Returning information for job: 20190330051917787174
2019-03-30 05:19:33,883 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command state.apply with jid 20190330051933872017
2019-03-30 05:19:33,906 [salt.minion      :1432][INFO    ][8064] Starting a new job with PID 8064
2019-03-30 05:19:34,999 [salt.state       :915 ][INFO    ][8064] Loading fresh modules for state activity
2019-03-30 05:19:35,051 [salt.fileclient  :1219][INFO    ][8064] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-30 05:19:35,092 [salt.state       :1780][INFO    ][8064] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:35.092699
2019-03-30 05:19:35,093 [salt.state       :1813][INFO    ][8064] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-30 05:19:35,094 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8064] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-30 05:19:36,944 [salt.state       :300 ][INFO    ][8064] {'pid': 8071, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-30 05:19:36,945 [salt.state       :1951][INFO    ][8064] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:36.945784 duration_in_ms=1853.085
2019-03-30 05:19:36,949 [salt.state       :1780][INFO    ][8064] Running state [maas.deploy_machines] at time 05:19:36.948999
2019-03-30 05:19:36,949 [salt.state       :1813][INFO    ][8064] Executing state module.run for [maas.deploy_machines]
2019-03-30 05:19:36,952 [salt.utils.decorators:613 ][WARNING ][8064] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:19:37,508 [salt.loaded.ext.module.maas:684 ][INFO    ][8064] deploymachines hwe_kernel=hwe-16.04 system_id=na7wmb distro_series=xenial
2019-03-30 05:19:39,924 [salt.loaded.ext.module.maas:684 ][INFO    ][8064] deploymachines hwe_kernel=hwe-16.04 system_id=wdfmpm distro_series=xenial
2019-03-30 05:19:42,266 [salt.loaded.ext.module.maas:684 ][INFO    ][8064] deploymachines hwe_kernel=hwe-16.04 system_id=6dea3a distro_series=xenial
2019-03-30 05:19:44,606 [salt.loaded.ext.module.maas:684 ][INFO    ][8064] deploymachines hwe_kernel=hwe-16.04 system_id=kexqes distro_series=xenial
2019-03-30 05:19:46,907 [salt.loaded.ext.module.maas:684 ][INFO    ][8064] deploymachines hwe_kernel=hwe-16.04 system_id=dmdxmm distro_series=xenial
2019-03-30 05:19:48,932 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330051948922397
2019-03-30 05:19:48,948 [salt.minion      :1432][INFO    ][8328] Starting a new job with PID 8328
2019-03-30 05:19:48,981 [salt.minion      :1711][INFO    ][8328] Returning information for job: 20190330051948922397
2019-03-30 05:19:49,302 [salt.state       :300 ][INFO    ][8064] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-30 05:19:49,303 [salt.state       :1951][INFO    ][8064] Completed state [maas.deploy_machines] at time 05:19:49.303666 duration_in_ms=12354.666
2019-03-30 05:19:49,306 [salt.minion      :1711][INFO    ][8064] Returning information for job: 20190330051933872017
2019-03-30 05:19:49,986 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command state.apply with jid 20190330051949975725
2019-03-30 05:19:50,002 [salt.minion      :1432][INFO    ][8343] Starting a new job with PID 8343
2019-03-30 05:19:58,115 [salt.state       :915 ][INFO    ][8343] Loading fresh modules for state activity
2019-03-30 05:19:58,176 [salt.fileclient  :1219][INFO    ][8343] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-30 05:19:58,230 [salt.state       :1780][INFO    ][8343] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:58.230331
2019-03-30 05:19:58,230 [salt.state       :1813][INFO    ][8343] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-30 05:19:58,232 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8343] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-30 05:20:00,060 [salt.state       :300 ][INFO    ][8343] {'pid': 8360, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-30 05:20:00,061 [salt.state       :1951][INFO    ][8343] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:20:00.061219 duration_in_ms=1830.887
2019-03-30 05:20:00,066 [salt.state       :1780][INFO    ][8343] Running state [maas.wait_for_machine_status] at time 05:20:00.066357
2019-03-30 05:20:00,067 [salt.state       :1813][INFO    ][8343] Executing state module.run for [maas.wait_for_machine_status]
2019-03-30 05:20:00,068 [salt.utils.decorators:613 ][WARNING ][8343] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:20:02,742 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.34109497s left)
2019-03-30 05:20:05,085 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052005072395
2019-03-30 05:20:05,099 [salt.minion      :1432][INFO    ][8390] Starting a new job with PID 8390
2019-03-30 05:20:05,132 [salt.minion      :1711][INFO    ][8390] Returning information for job: 20190330052005072395
2019-03-30 05:20:35,133 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052035121561
2019-03-30 05:20:35,148 [salt.minion      :1432][INFO    ][8468] Starting a new job with PID 8468
2019-03-30 05:20:35,178 [salt.minion      :1711][INFO    ][8468] Returning information for job: 20190330052035121561
2019-03-30 05:20:35,863 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.21971583s left)
2019-03-30 05:21:05,185 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052105177061
2019-03-30 05:21:05,202 [salt.minion      :1432][INFO    ][8497] Starting a new job with PID 8497
2019-03-30 05:21:05,228 [salt.minion      :1711][INFO    ][8497] Returning information for job: 20190330052105177061
2019-03-30 05:21:08,334 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.74894691s left)
2019-03-30 05:21:35,216 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052135205350
2019-03-30 05:21:35,229 [salt.minion      :1432][INFO    ][8585] Starting a new job with PID 8585
2019-03-30 05:21:35,257 [salt.minion      :1711][INFO    ][8585] Returning information for job: 20190330052135205350
2019-03-30 05:21:40,765 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2149.31734085s left)
2019-03-30 05:22:05,408 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052205400105
2019-03-30 05:22:05,422 [salt.minion      :1432][INFO    ][8614] Starting a new job with PID 8614
2019-03-30 05:22:05,448 [salt.minion      :1711][INFO    ][8614] Returning information for job: 20190330052205400105
2019-03-30 05:22:13,526 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2116.55697083s left)
2019-03-30 05:22:35,432 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052235418498
2019-03-30 05:22:35,455 [salt.minion      :1432][INFO    ][8711] Starting a new job with PID 8711
2019-03-30 05:22:35,482 [salt.minion      :1711][INFO    ][8711] Returning information for job: 20190330052235418498
2019-03-30 05:22:46,290 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.79270697s left)
2019-03-30 05:23:05,665 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052305645991
2019-03-30 05:23:05,686 [salt.minion      :1432][INFO    ][8789] Starting a new job with PID 8789
2019-03-30 05:23:05,719 [salt.minion      :1711][INFO    ][8789] Returning information for job: 20190330052305645991
2019-03-30 05:23:19,283 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.79952002s left)
2019-03-30 05:23:35,709 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052335701472
2019-03-30 05:23:35,735 [salt.minion      :1432][INFO    ][9107] Starting a new job with PID 9107
2019-03-30 05:23:35,773 [salt.minion      :1711][INFO    ][9107] Returning information for job: 20190330052335701472
2019-03-30 05:23:52,094 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.9886539s left)
2019-03-30 05:24:05,768 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052405759631
2019-03-30 05:24:05,784 [salt.minion      :1432][INFO    ][9179] Starting a new job with PID 9179
2019-03-30 05:24:05,812 [salt.minion      :1711][INFO    ][9179] Returning information for job: 20190330052405759631
2019-03-30 05:24:25,018 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1985.06455493s left)
2019-03-30 05:24:35,843 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052435832149
2019-03-30 05:24:35,864 [salt.minion      :1432][INFO    ][9432] Starting a new job with PID 9432
2019-03-30 05:24:35,896 [salt.minion      :1711][INFO    ][9432] Returning information for job: 20190330052435832149
2019-03-30 05:24:57,869 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1952.21396399s left)
2019-03-30 05:25:06,009 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052505990054
2019-03-30 05:25:06,024 [salt.minion      :1432][INFO    ][9492] Starting a new job with PID 9492
2019-03-30 05:25:06,053 [salt.minion      :1711][INFO    ][9492] Returning information for job: 20190330052505990054
2019-03-30 05:25:30,758 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1919.32463288s left)
2019-03-30 05:25:36,238 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052536154193
2019-03-30 05:25:36,262 [salt.minion      :1432][INFO    ][10034] Starting a new job with PID 10034
2019-03-30 05:25:36,290 [salt.minion      :1711][INFO    ][10034] Returning information for job: 20190330052536154193
2019-03-30 05:26:04,044 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1886.03869796s left)
2019-03-30 05:26:06,379 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052606357492
2019-03-30 05:26:06,396 [salt.minion      :1432][INFO    ][10072] Starting a new job with PID 10072
2019-03-30 05:26:06,430 [salt.minion      :1711][INFO    ][10072] Returning information for job: 20190330052606357492
2019-03-30 05:26:36,600 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052636573203
2019-03-30 05:26:36,619 [salt.minion      :1432][INFO    ][10419] Starting a new job with PID 10419
2019-03-30 05:26:36,650 [salt.minion      :1711][INFO    ][10419] Returning information for job: 20190330052636573203
2019-03-30 05:26:36,969 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1853.11363983s left)
2019-03-30 05:27:06,833 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052706775255
2019-03-30 05:27:06,853 [salt.minion      :1432][INFO    ][10453] Starting a new job with PID 10453
2019-03-30 05:27:06,886 [salt.minion      :1711][INFO    ][10453] Returning information for job: 20190330052706775255
2019-03-30 05:27:10,062 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1820.02107382s left)
2019-03-30 05:27:37,014 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052737002317
2019-03-30 05:27:37,032 [salt.minion      :1432][INFO    ][10787] Starting a new job with PID 10787
2019-03-30 05:27:37,062 [salt.minion      :1711][INFO    ][10787] Returning information for job: 20190330052737002317
2019-03-30 05:27:42,841 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1787.24226284s left)
2019-03-30 05:28:07,116 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052807108229
2019-03-30 05:28:07,134 [salt.minion      :1432][INFO    ][10845] Starting a new job with PID 10845
2019-03-30 05:28:07,159 [salt.minion      :1711][INFO    ][10845] Returning information for job: 20190330052807108229
2019-03-30 05:28:15,619 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1754.46405482s left)
2019-03-30 05:28:37,250 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052837238244
2019-03-30 05:28:37,268 [salt.minion      :1432][INFO    ][11071] Starting a new job with PID 11071
2019-03-30 05:28:37,292 [salt.minion      :1711][INFO    ][11071] Returning information for job: 20190330052837238244
2019-03-30 05:28:48,595 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1721.48767781s left)
2019-03-30 05:29:07,331 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052907318514
2019-03-30 05:29:07,355 [salt.minion      :1432][INFO    ][11138] Starting a new job with PID 11138
2019-03-30 05:29:07,388 [salt.minion      :1711][INFO    ][11138] Returning information for job: 20190330052907318514
2019-03-30 05:29:22,758 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1687.32455182s left)
2019-03-30 05:29:37,566 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330052937546970
2019-03-30 05:29:37,582 [salt.minion      :1432][INFO    ][11360] Starting a new job with PID 11360
2019-03-30 05:29:37,607 [salt.minion      :1711][INFO    ][11360] Returning information for job: 20190330052937546970
2019-03-30 05:29:55,753 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1654.32984304s left)
2019-03-30 05:30:07,734 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053007723041
2019-03-30 05:30:07,749 [salt.minion      :1432][INFO    ][11404] Starting a new job with PID 11404
2019-03-30 05:30:07,772 [salt.minion      :1711][INFO    ][11404] Returning information for job: 20190330053007723041
2019-03-30 05:30:28,817 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1621.26579595s left)
2019-03-30 05:30:37,898 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053037888146
2019-03-30 05:30:37,917 [salt.minion      :1432][INFO    ][11594] Starting a new job with PID 11594
2019-03-30 05:30:37,944 [salt.minion      :1711][INFO    ][11594] Returning information for job: 20190330053037888146
2019-03-30 05:31:02,057 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1588.02592397s left)
2019-03-30 05:31:08,072 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053108056919
2019-03-30 05:31:08,088 [salt.minion      :1432][INFO    ][11755] Starting a new job with PID 11755
2019-03-30 05:31:08,110 [salt.minion      :1711][INFO    ][11755] Returning information for job: 20190330053108056919
2019-03-30 05:31:34,805 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1555.27748203s left)
2019-03-30 05:31:38,310 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053138297391
2019-03-30 05:31:38,329 [salt.minion      :1432][INFO    ][11840] Starting a new job with PID 11840
2019-03-30 05:31:38,369 [salt.minion      :1711][INFO    ][11840] Returning information for job: 20190330053138297391
2019-03-30 05:32:07,709 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1522.37401581s left)
2019-03-30 05:32:08,384 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053208353836
2019-03-30 05:32:08,410 [salt.minion      :1432][INFO    ][11924] Starting a new job with PID 11924
2019-03-30 05:32:08,478 [salt.minion      :1711][INFO    ][11924] Returning information for job: 20190330053208353836
2019-03-30 05:32:38,569 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053238522815
2019-03-30 05:32:38,617 [salt.minion      :1432][INFO    ][12103] Starting a new job with PID 12103
2019-03-30 05:32:38,838 [salt.minion      :1711][INFO    ][12103] Returning information for job: 20190330053238522815
2019-03-30 05:32:40,722 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1489.36101294s left)
2019-03-30 05:33:08,754 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053308699802
2019-03-30 05:33:08,767 [salt.minion      :1432][INFO    ][12144] Starting a new job with PID 12144
2019-03-30 05:33:08,790 [salt.minion      :1711][INFO    ][12144] Returning information for job: 20190330053308699802
2019-03-30 05:33:13,325 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1456.75785589s left)
2019-03-30 05:33:38,965 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053338952805
2019-03-30 05:33:38,982 [salt.minion      :1432][INFO    ][12382] Starting a new job with PID 12382
2019-03-30 05:33:39,010 [salt.minion      :1711][INFO    ][12382] Returning information for job: 20190330053338952805
2019-03-30 05:33:46,556 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1423.52665496s left)
2019-03-30 05:34:09,165 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053409154403
2019-03-30 05:34:09,186 [salt.minion      :1432][INFO    ][12412] Starting a new job with PID 12412
2019-03-30 05:34:09,211 [salt.minion      :1711][INFO    ][12412] Returning information for job: 20190330053409154403
2019-03-30 05:34:19,582 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1390.50087881s left)
2019-03-30 05:34:39,404 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053439394921
2019-03-30 05:34:39,418 [salt.minion      :1432][INFO    ][12483] Starting a new job with PID 12483
2019-03-30 05:34:39,443 [salt.minion      :1711][INFO    ][12483] Returning information for job: 20190330053439394921
2019-03-30 05:34:52,582 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1357.50134301s left)
2019-03-30 05:35:09,605 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053509590705
2019-03-30 05:35:09,618 [salt.minion      :1432][INFO    ][12520] Starting a new job with PID 12520
2019-03-30 05:35:09,645 [salt.minion      :1711][INFO    ][12520] Returning information for job: 20190330053509590705
2019-03-30 05:35:25,714 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1324.36883903s left)
2019-03-30 05:35:39,971 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053539905771
2019-03-30 05:35:39,986 [salt.minion      :1432][INFO    ][12594] Starting a new job with PID 12594
2019-03-30 05:35:40,015 [salt.minion      :1711][INFO    ][12594] Returning information for job: 20190330053539905771
2019-03-30 05:35:58,749 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1291.33337283s left)
2019-03-30 05:36:10,077 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053610057175
2019-03-30 05:36:10,102 [salt.minion      :1432][INFO    ][12626] Starting a new job with PID 12626
2019-03-30 05:36:10,135 [salt.minion      :1711][INFO    ][12626] Returning information for job: 20190330053610057175
2019-03-30 05:36:31,561 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1258.52150583s left)
2019-03-30 05:36:40,229 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053640150701
2019-03-30 05:36:40,250 [salt.minion      :1432][INFO    ][12698] Starting a new job with PID 12698
2019-03-30 05:36:40,277 [salt.minion      :1711][INFO    ][12698] Returning information for job: 20190330053640150701
2019-03-30 05:37:05,681 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1224.40234184s left)
2019-03-30 05:37:10,349 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053710320016
2019-03-30 05:37:10,369 [salt.minion      :1432][INFO    ][12735] Starting a new job with PID 12735
2019-03-30 05:37:10,394 [salt.minion      :1711][INFO    ][12735] Returning information for job: 20190330053710320016
2019-03-30 05:37:40,428 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053740405074
2019-03-30 05:37:40,466 [salt.minion      :1432][INFO    ][12807] Starting a new job with PID 12807
2019-03-30 05:37:40,499 [salt.minion      :1711][INFO    ][12807] Returning information for job: 20190330053740405074
2019-03-30 05:37:41,789 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1188.29390097s left)
2019-03-30 05:38:10,549 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053810499068
2019-03-30 05:38:10,565 [salt.minion      :1432][INFO    ][12836] Starting a new job with PID 12836
2019-03-30 05:38:10,604 [salt.minion      :1711][INFO    ][12836] Returning information for job: 20190330053810499068
2019-03-30 05:38:15,009 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1155.07390404s left)
2019-03-30 05:38:40,716 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053840649759
2019-03-30 05:38:40,737 [salt.minion      :1432][INFO    ][12910] Starting a new job with PID 12910
2019-03-30 05:38:40,768 [salt.minion      :1711][INFO    ][12910] Returning information for job: 20190330053840649759
2019-03-30 05:38:49,308 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1120.77979684s left)
2019-03-30 05:39:10,885 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053910872603
2019-03-30 05:39:10,905 [salt.minion      :1432][INFO    ][12939] Starting a new job with PID 12939
2019-03-30 05:39:10,945 [salt.minion      :1711][INFO    ][12939] Returning information for job: 20190330053910872603
2019-03-30 05:39:25,436 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1084.65690398s left)
2019-03-30 05:39:41,036 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330053941010560
2019-03-30 05:39:41,053 [salt.minion      :1432][INFO    ][13010] Starting a new job with PID 13010
2019-03-30 05:39:41,085 [salt.minion      :1711][INFO    ][13010] Returning information for job: 20190330053941010560
2019-03-30 05:40:02,678 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1047.41077995s left)
2019-03-30 05:40:11,178 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054011125487
2019-03-30 05:40:11,194 [salt.minion      :1432][INFO    ][13040] Starting a new job with PID 13040
2019-03-30 05:40:11,224 [salt.minion      :1711][INFO    ][13040] Returning information for job: 20190330054011125487
2019-03-30 05:40:37,118 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1012.96537805s left)
2019-03-30 05:40:41,444 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054041419955
2019-03-30 05:40:41,458 [salt.minion      :1432][INFO    ][13111] Starting a new job with PID 13111
2019-03-30 05:40:41,483 [salt.minion      :1711][INFO    ][13111] Returning information for job: 20190330054041419955
2019-03-30 05:41:11,653 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054111628536
2019-03-30 05:41:11,675 [salt.minion      :1432][INFO    ][13151] Starting a new job with PID 13151
2019-03-30 05:41:11,702 [salt.minion      :1711][INFO    ][13151] Returning information for job: 20190330054111628536
2019-03-30 05:41:12,710 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (977.373960018s left)
2019-03-30 05:41:41,877 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054141861096
2019-03-30 05:41:41,893 [salt.minion      :1432][INFO    ][13234] Starting a new job with PID 13234
2019-03-30 05:41:41,921 [salt.minion      :1711][INFO    ][13234] Returning information for job: 20190330054141861096
2019-03-30 05:41:46,317 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (943.765619993s left)
2019-03-30 05:42:12,164 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054212128155
2019-03-30 05:42:12,185 [salt.minion      :1432][INFO    ][13264] Starting a new job with PID 13264
2019-03-30 05:42:12,214 [salt.minion      :1711][INFO    ][13264] Returning information for job: 20190330054212128155
2019-03-30 05:42:21,730 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (908.353164911s left)
2019-03-30 05:42:42,318 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054242307945
2019-03-30 05:42:42,335 [salt.minion      :1432][INFO    ][13338] Starting a new job with PID 13338
2019-03-30 05:42:42,367 [salt.minion      :1711][INFO    ][13338] Returning information for job: 20190330054242307945
2019-03-30 05:42:54,888 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (875.194772959s left)
2019-03-30 05:43:12,509 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054312498709
2019-03-30 05:43:12,526 [salt.minion      :1432][INFO    ][13370] Starting a new job with PID 13370
2019-03-30 05:43:12,555 [salt.minion      :1711][INFO    ][13370] Returning information for job: 20190330054312498709
2019-03-30 05:43:27,958 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (842.125030041s left)
2019-03-30 05:43:42,632 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054342618750
2019-03-30 05:43:42,649 [salt.minion      :1432][INFO    ][13441] Starting a new job with PID 13441
2019-03-30 05:43:42,680 [salt.minion      :1711][INFO    ][13441] Returning information for job: 20190330054342618750
2019-03-30 05:44:01,417 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (808.66650486s left)
2019-03-30 05:44:12,854 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054412843799
2019-03-30 05:44:12,870 [salt.minion      :1432][INFO    ][13471] Starting a new job with PID 13471
2019-03-30 05:44:12,893 [salt.minion      :1711][INFO    ][13471] Returning information for job: 20190330054412843799
2019-03-30 05:44:34,945 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (775.137334824s left)
2019-03-30 05:44:43,098 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054443073542
2019-03-30 05:44:43,114 [salt.minion      :1432][INFO    ][13544] Starting a new job with PID 13544
2019-03-30 05:44:43,140 [salt.minion      :1711][INFO    ][13544] Returning information for job: 20190330054443073542
2019-03-30 05:45:08,713 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (741.369961023s left)
2019-03-30 05:45:13,368 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054513327279
2019-03-30 05:45:13,389 [salt.minion      :1432][INFO    ][13574] Starting a new job with PID 13574
2019-03-30 05:45:13,415 [salt.minion      :1711][INFO    ][13574] Returning information for job: 20190330054513327279
2019-03-30 05:45:41,573 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (708.51016593s left)
2019-03-30 05:45:43,501 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054543485829
2019-03-30 05:45:43,528 [salt.minion      :1432][INFO    ][13647] Starting a new job with PID 13647
2019-03-30 05:45:43,560 [salt.minion      :1711][INFO    ][13647] Returning information for job: 20190330054543485829
2019-03-30 05:46:13,642 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054613621097
2019-03-30 05:46:13,661 [salt.minion      :1432][INFO    ][13678] Starting a new job with PID 13678
2019-03-30 05:46:13,688 [salt.minion      :1711][INFO    ][13678] Returning information for job: 20190330054613621097
2019-03-30 05:46:14,436 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (675.646682024s left)
2019-03-30 05:46:43,737 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054643717735
2019-03-30 05:46:43,759 [salt.minion      :1432][INFO    ][13747] Starting a new job with PID 13747
2019-03-30 05:46:43,785 [salt.minion      :1711][INFO    ][13747] Returning information for job: 20190330054643717735
2019-03-30 05:46:47,221 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (642.861968994s left)
2019-03-30 05:47:13,864 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054713847680
2019-03-30 05:47:13,886 [salt.minion      :1432][INFO    ][13779] Starting a new job with PID 13779
2019-03-30 05:47:13,915 [salt.minion      :1711][INFO    ][13779] Returning information for job: 20190330054713847680
2019-03-30 05:47:20,085 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (609.998060942s left)
2019-03-30 05:47:43,994 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054743978543
2019-03-30 05:47:44,015 [salt.minion      :1432][INFO    ][13854] Starting a new job with PID 13854
2019-03-30 05:47:44,045 [salt.minion      :1711][INFO    ][13854] Returning information for job: 20190330054743978543
2019-03-30 05:47:53,045 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (577.038067818s left)
2019-03-30 05:48:14,157 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054814141549
2019-03-30 05:48:14,182 [salt.minion      :1432][INFO    ][13884] Starting a new job with PID 13884
2019-03-30 05:48:14,212 [salt.minion      :1711][INFO    ][13884] Returning information for job: 20190330054814141549
2019-03-30 05:48:25,939 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (544.143841982s left)
2019-03-30 05:48:44,311 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054844302133
2019-03-30 05:48:44,326 [salt.minion      :1432][INFO    ][13956] Starting a new job with PID 13956
2019-03-30 05:48:44,351 [salt.minion      :1711][INFO    ][13956] Returning information for job: 20190330054844302133
2019-03-30 05:48:58,553 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (511.529852867s left)
2019-03-30 05:49:14,336 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054914324395
2019-03-30 05:49:14,356 [salt.minion      :1432][INFO    ][13986] Starting a new job with PID 13986
2019-03-30 05:49:14,381 [salt.minion      :1711][INFO    ][13986] Returning information for job: 20190330054914324395
2019-03-30 05:49:31,296 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (478.78736186s left)
2019-03-30 05:49:44,513 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330054944502223
2019-03-30 05:49:44,532 [salt.minion      :1432][INFO    ][14057] Starting a new job with PID 14057
2019-03-30 05:49:44,561 [salt.minion      :1711][INFO    ][14057] Returning information for job: 20190330054944502223
2019-03-30 05:50:04,231 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (445.851673841s left)
2019-03-30 05:50:14,569 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055014558784
2019-03-30 05:50:14,590 [salt.minion      :1432][INFO    ][14089] Starting a new job with PID 14089
2019-03-30 05:50:14,616 [salt.minion      :1711][INFO    ][14089] Returning information for job: 20190330055014558784
2019-03-30 05:50:37,066 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (413.017175913s left)
2019-03-30 05:50:44,781 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055044771596
2019-03-30 05:50:44,795 [salt.minion      :1432][INFO    ][14163] Starting a new job with PID 14163
2019-03-30 05:50:44,817 [salt.minion      :1711][INFO    ][14163] Returning information for job: 20190330055044771596
2019-03-30 05:51:09,884 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (380.198710918s left)
2019-03-30 05:51:14,852 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055114836605
2019-03-30 05:51:14,874 [salt.minion      :1432][INFO    ][14331] Starting a new job with PID 14331
2019-03-30 05:51:14,900 [salt.minion      :1711][INFO    ][14331] Returning information for job: 20190330055114836605
2019-03-30 05:51:42,657 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (347.426072836s left)
2019-03-30 05:51:44,929 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055144922620
2019-03-30 05:51:44,952 [salt.minion      :1432][INFO    ][14413] Starting a new job with PID 14413
2019-03-30 05:51:44,977 [salt.minion      :1711][INFO    ][14413] Returning information for job: 20190330055144922620
2019-03-30 05:52:14,984 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055214973863
2019-03-30 05:52:15,000 [salt.minion      :1432][INFO    ][14445] Starting a new job with PID 14445
2019-03-30 05:52:15,025 [salt.minion      :1711][INFO    ][14445] Returning information for job: 20190330055214973863
2019-03-30 05:52:15,380 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (314.702798843s left)
2019-03-30 05:52:45,130 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055245115888
2019-03-30 05:52:45,150 [salt.minion      :1432][INFO    ][14514] Starting a new job with PID 14514
2019-03-30 05:52:45,173 [salt.minion      :1711][INFO    ][14514] Returning information for job: 20190330055245115888
2019-03-30 05:52:48,069 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (282.014353991s left)
2019-03-30 05:53:15,229 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055315215246
2019-03-30 05:53:15,250 [salt.minion      :1432][INFO    ][14543] Starting a new job with PID 14543
2019-03-30 05:53:15,276 [salt.minion      :1711][INFO    ][14543] Returning information for job: 20190330055315215246
2019-03-30 05:53:21,045 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (249.037792921s left)
2019-03-30 05:53:45,413 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055345402519
2019-03-30 05:53:45,429 [salt.minion      :1432][INFO    ][14618] Starting a new job with PID 14618
2019-03-30 05:53:45,456 [salt.minion      :1711][INFO    ][14618] Returning information for job: 20190330055345402519
2019-03-30 05:53:53,851 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (216.231770039s left)
2019-03-30 05:54:15,548 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055415537031
2019-03-30 05:54:15,565 [salt.minion      :1432][INFO    ][14647] Starting a new job with PID 14647
2019-03-30 05:54:15,590 [salt.minion      :1711][INFO    ][14647] Returning information for job: 20190330055415537031
2019-03-30 05:54:26,574 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (183.508661032s left)
2019-03-30 05:54:45,765 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055445750320
2019-03-30 05:54:45,788 [salt.minion      :1432][INFO    ][14722] Starting a new job with PID 14722
2019-03-30 05:54:45,812 [salt.minion      :1711][INFO    ][14722] Returning information for job: 20190330055445750320
2019-03-30 05:54:59,203 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (150.879662991s left)
2019-03-30 05:55:15,944 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055515913262
2019-03-30 05:55:15,962 [salt.minion      :1432][INFO    ][14751] Starting a new job with PID 14751
2019-03-30 05:55:15,989 [salt.minion      :1711][INFO    ][14751] Returning information for job: 20190330055515913262
2019-03-30 05:55:32,164 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (117.918572903s left)
2019-03-30 05:55:46,172 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055546163766
2019-03-30 05:55:46,188 [salt.minion      :1432][INFO    ][14825] Starting a new job with PID 14825
2019-03-30 05:55:46,213 [salt.minion      :1711][INFO    ][14825] Returning information for job: 20190330055546163766
2019-03-30 05:56:04,930 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (85.152577877s left)
2019-03-30 05:56:16,394 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055616379620
2019-03-30 05:56:16,416 [salt.minion      :1432][INFO    ][14856] Starting a new job with PID 14856
2019-03-30 05:56:16,444 [salt.minion      :1711][INFO    ][14856] Returning information for job: 20190330055616379620
2019-03-30 05:56:37,668 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (52.4151258469s left)
2019-03-30 05:56:46,484 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055646467786
2019-03-30 05:56:46,505 [salt.minion      :1432][INFO    ][14933] Starting a new job with PID 14933
2019-03-30 05:56:46,530 [salt.minion      :1711][INFO    ][14933] Returning information for job: 20190330055646467786
2019-03-30 05:57:10,245 [salt.loaded.ext.module.maas:1023][INFO    ][8343] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (19.8380308151s left)
2019-03-30 05:57:16,589 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055716580720
2019-03-30 05:57:16,607 [salt.minion      :1432][INFO    ][14962] Starting a new job with PID 14962
2019-03-30 05:57:16,632 [salt.minion      :1711][INFO    ][14962] Returning information for job: 20190330055716580720
2019-03-30 05:57:42,809 [salt.state       :302 ][ERROR   ][8343] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-03-30 05:57:42,812 [salt.state       :1951][INFO    ][8343] Completed state [maas.wait_for_machine_status] at time 05:57:42.812164 duration_in_ms=2262745.649
2019-03-30 05:57:42,906 [salt.minion      :1711][INFO    ][8343] Returning information for job: 20190330051949975725
2019-03-30 05:57:54,817 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command pillar.get with jid 20190330055754802536
2019-03-30 05:57:54,839 [salt.minion      :1432][INFO    ][15041] Starting a new job with PID 15041
2019-03-30 05:57:54,854 [salt.minion      :1711][INFO    ][15041] Returning information for job: 20190330055754802536
2019-03-30 05:57:55,413 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command service.status with jid 20190330055755398508
2019-03-30 05:57:55,433 [salt.minion      :1432][INFO    ][15046] Starting a new job with PID 15046
2019-03-30 05:57:56,661 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15046] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-30 05:57:56,706 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15046] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-30 05:57:56,722 [salt.minion      :1711][INFO    ][15046] Returning information for job: 20190330055755398508
2019-03-30 05:57:57,274 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command state.apply with jid 20190330055757263664
2019-03-30 05:57:57,295 [salt.minion      :1432][INFO    ][15057] Starting a new job with PID 15057
2019-03-30 05:58:06,009 [salt.state       :915 ][INFO    ][15057] Loading fresh modules for state activity
2019-03-30 05:58:06,669 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15057] Executing command 'salt-minion --version' in directory '/root'
2019-03-30 05:58:07,085 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15057] Executing command 'salt-minion --version' in directory '/root'
2019-03-30 05:58:08,104 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15057] Executing command 'salt-minion --version' in directory '/root'
2019-03-30 05:58:08,410 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15057] Executing command 'salt-minion --version' in directory '/root'
2019-03-30 05:58:10,697 [salt.state       :1780][INFO    ][15057] Running state [salt-minion] at time 05:58:10.697442
2019-03-30 05:58:10,697 [salt.state       :1813][INFO    ][15057] Executing state pkg.installed for [salt-minion]
2019-03-30 05:58:10,698 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15057] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-30 05:58:10,825 [salt.state       :300 ][INFO    ][15057] All specified packages are already installed
2019-03-30 05:58:10,826 [salt.state       :1951][INFO    ][15057] Completed state [salt-minion] at time 05:58:10.826417 duration_in_ms=128.974
2019-03-30 05:58:10,828 [salt.state       :1780][INFO    ][15057] Running state [salt_minion_dependency_packages] at time 05:58:10.828429
2019-03-30 05:58:10,828 [salt.state       :1813][INFO    ][15057] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-30 05:58:10,842 [salt.state       :300 ][INFO    ][15057] All specified packages are already installed
2019-03-30 05:58:10,842 [salt.state       :1951][INFO    ][15057] Completed state [salt_minion_dependency_packages] at time 05:58:10.842682 duration_in_ms=14.254
2019-03-30 05:58:10,848 [salt.state       :1780][INFO    ][15057] Running state [/etc/salt/minion.d/minion.conf] at time 05:58:10.848266
2019-03-30 05:58:10,848 [salt.state       :1813][INFO    ][15057] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-30 05:58:11,149 [salt.state       :300 ][INFO    ][15057] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-30 05:58:11,150 [salt.state       :1951][INFO    ][15057] Completed state [/etc/salt/minion.d/minion.conf] at time 05:58:11.150208 duration_in_ms=301.942
2019-03-30 05:58:11,152 [salt.state       :1780][INFO    ][15057] Running state [python-netaddr] at time 05:58:11.150795
2019-03-30 05:58:11,152 [salt.state       :1813][INFO    ][15057] Executing state pkg.installed for [python-netaddr]
2019-03-30 05:58:11,161 [salt.state       :300 ][INFO    ][15057] All specified packages are already installed
2019-03-30 05:58:11,161 [salt.state       :1951][INFO    ][15057] Completed state [python-netaddr] at time 05:58:11.161371 duration_in_ms=10.577
2019-03-30 05:58:11,166 [salt.state       :1780][INFO    ][15057] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:58:11.166765
2019-03-30 05:58:11,167 [salt.state       :1813][INFO    ][15057] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-30 05:58:11,185 [salt.state       :300 ][INFO    ][15057] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-30 05:58:11,186 [salt.state       :1951][INFO    ][15057] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:58:11.185951 duration_in_ms=19.184
2019-03-30 05:58:11,190 [salt.state       :1780][INFO    ][15057] Running state [salt-minion] at time 05:58:11.190112
2019-03-30 05:58:11,190 [salt.state       :1813][INFO    ][15057] Executing state service.running for [salt-minion]
2019-03-30 05:58:11,191 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15057] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-30 05:58:11,229 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15057] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-30 05:58:11,246 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15057] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-30 05:58:11,265 [salt.state       :300 ][INFO    ][15057] The service salt-minion is already running
2019-03-30 05:58:11,266 [salt.state       :1951][INFO    ][15057] Completed state [salt-minion] at time 05:58:11.266021 duration_in_ms=75.908
2019-03-30 05:58:11,268 [salt.state       :1780][INFO    ][15057] Running state [/etc/salt/grains.d] at time 05:58:11.268463
2019-03-30 05:58:11,269 [salt.state       :1813][INFO    ][15057] Executing state file.directory for [/etc/salt/grains.d]
2019-03-30 05:58:11,273 [salt.state       :300 ][INFO    ][15057] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-30 05:58:11,273 [salt.state       :1951][INFO    ][15057] Completed state [/etc/salt/grains.d] at time 05:58:11.273558 duration_in_ms=5.095
2019-03-30 05:58:11,274 [salt.state       :1780][INFO    ][15057] Running state [/etc/salt/grains] at time 05:58:11.274223
2019-03-30 05:58:11,274 [salt.state       :1813][INFO    ][15057] Executing state file.managed for [/etc/salt/grains]
2019-03-30 05:58:11,275 [salt.state       :300 ][INFO    ][15057] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-30 05:58:11,275 [salt.state       :1951][INFO    ][15057] Completed state [/etc/salt/grains] at time 05:58:11.275244 duration_in_ms=1.021
2019-03-30 05:58:11,275 [salt.state       :1780][INFO    ][15057] Running state [/etc/salt/grains.d/placeholder] at time 05:58:11.275707
2019-03-30 05:58:11,275 [salt.state       :1813][INFO    ][15057] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-30 05:58:11,276 [salt.state       :300 ][INFO    ][15057] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-30 05:58:11,276 [salt.state       :1951][INFO    ][15057] Completed state [/etc/salt/grains.d/placeholder] at time 05:58:11.276724 duration_in_ms=1.017
2019-03-30 05:58:11,277 [salt.state       :1780][INFO    ][15057] Running state [/etc/salt/grains.d/sphinx] at time 05:58:11.277229
2019-03-30 05:58:11,277 [salt.state       :1813][INFO    ][15057] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-30 05:58:11,278 [salt.state       :300 ][INFO    ][15057] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-30 05:58:11,279 [salt.state       :1951][INFO    ][15057] Completed state [/etc/salt/grains.d/sphinx] at time 05:58:11.279327 duration_in_ms=2.098
2019-03-30 05:58:11,282 [salt.state       :1780][INFO    ][15057] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:58:11.281965
2019-03-30 05:58:11,282 [salt.state       :1813][INFO    ][15057] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-30 05:58:11,282 [salt.state       :300 ][INFO    ][15057] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-30 05:58:11,282 [salt.state       :1951][INFO    ][15057] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:58:11.282753 duration_in_ms=0.788
2019-03-30 05:58:11,285 [salt.state       :1780][INFO    ][15057] Running state [/etc/salt/grains.d/dns_records] at time 05:58:11.285163
2019-03-30 05:58:11,285 [salt.state       :1813][INFO    ][15057] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-30 05:58:11,286 [salt.state       :300 ][INFO    ][15057] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-30 05:58:11,286 [salt.state       :1951][INFO    ][15057] Completed state [/etc/salt/grains.d/dns_records] at time 05:58:11.286767 duration_in_ms=1.604
2019-03-30 05:58:11,287 [salt.state       :1780][INFO    ][15057] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:58:11.287736
2019-03-30 05:58:11,288 [salt.state       :1813][INFO    ][15057] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-03-30 05:58:11,288 [salt.state       :300 ][INFO    ][15057] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-30 05:58:11,288 [salt.state       :1951][INFO    ][15057] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:58:11.288590 duration_in_ms=0.854
2019-03-30 05:58:11,289 [salt.state       :1780][INFO    ][15057] Running state [/etc/salt/grains.d/salt] at time 05:58:11.289089
2019-03-30 05:58:11,289 [salt.state       :1813][INFO    ][15057] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-30 05:58:11,291 [salt.state       :300 ][INFO    ][15057] File /etc/salt/grains.d/salt is in the correct state
2019-03-30 05:58:11,291 [salt.state       :1951][INFO    ][15057] Completed state [/etc/salt/grains.d/salt] at time 05:58:11.291559 duration_in_ms=2.468
2019-03-30 05:58:11,294 [salt.state       :1780][INFO    ][15057] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:58:11.293955
2019-03-30 05:58:11,294 [salt.state       :1813][INFO    ][15057] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-30 05:58:11,298 [salt.state       :300 ][INFO    ][15057] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-30 05:58:11,298 [salt.state       :1951][INFO    ][15057] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:58:11.298425 duration_in_ms=4.47
2019-03-30 05:58:11,301 [salt.state       :1780][INFO    ][15057] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:58:11.301648
2019-03-30 05:58:11,302 [salt.state       :1813][INFO    ][15057] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-30 05:58:11,302 [salt.state       :300 ][INFO    ][15057] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-30 05:58:11,302 [salt.state       :1951][INFO    ][15057] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:58:11.302489 duration_in_ms=0.842
2019-03-30 05:58:11,303 [salt.state       :1780][INFO    ][15057] Running state [mine.update] at time 05:58:11.303160
2019-03-30 05:58:11,303 [salt.state       :1813][INFO    ][15057] Executing state module.wait for [mine.update]
2019-03-30 05:58:11,303 [salt.state       :300 ][INFO    ][15057] No changes made for mine.update
2019-03-30 05:58:11,304 [salt.state       :1951][INFO    ][15057] Completed state [mine.update] at time 05:58:11.303931 duration_in_ms=0.77
2019-03-30 05:58:11,304 [salt.state       :1780][INFO    ][15057] Running state [ca-certificates] at time 05:58:11.304308
2019-03-30 05:58:11,304 [salt.state       :1813][INFO    ][15057] Executing state pkg.installed for [ca-certificates]
2019-03-30 05:58:11,316 [salt.state       :300 ][INFO    ][15057] All specified packages are already installed
2019-03-30 05:58:11,316 [salt.state       :1951][INFO    ][15057] Completed state [ca-certificates] at time 05:58:11.316381 duration_in_ms=12.073
2019-03-30 05:58:11,317 [salt.state       :1780][INFO    ][15057] Running state [update-ca-certificates] at time 05:58:11.317061
2019-03-30 05:58:11,317 [salt.state       :1813][INFO    ][15057] Executing state cmd.wait for [update-ca-certificates]
2019-03-30 05:58:11,317 [salt.state       :300 ][INFO    ][15057] No changes made for update-ca-certificates
2019-03-30 05:58:11,317 [salt.state       :1951][INFO    ][15057] Completed state [update-ca-certificates] at time 05:58:11.317779 duration_in_ms=0.718
2019-03-30 05:58:11,318 [salt.state       :1780][INFO    ][15057] Running state [iptables] at time 05:58:11.318012
2019-03-30 05:58:11,318 [salt.state       :1813][INFO    ][15057] Executing state pkg.installed for [iptables]
2019-03-30 05:58:11,328 [salt.state       :300 ][INFO    ][15057] All specified packages are already installed
2019-03-30 05:58:11,328 [salt.state       :1951][INFO    ][15057] Completed state [iptables] at time 05:58:11.328635 duration_in_ms=10.622
2019-03-30 05:58:11,328 [salt.state       :1780][INFO    ][15057] Running state [iptables-persistent] at time 05:58:11.328870
2019-03-30 05:58:11,329 [salt.state       :1813][INFO    ][15057] Executing state pkg.installed for [iptables-persistent]
2019-03-30 05:58:11,341 [salt.state       :300 ][INFO    ][15057] All specified packages are already installed
2019-03-30 05:58:11,342 [salt.state       :1951][INFO    ][15057] Completed state [iptables-persistent] at time 05:58:11.342109 duration_in_ms=13.239
2019-03-30 05:58:11,344 [salt.state       :1780][INFO    ][15057] Running state [iptables_modules_v4_load] at time 05:58:11.344345
2019-03-30 05:58:11,344 [salt.state       :1813][INFO    ][15057] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-30 05:58:11,345 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15057] Executing command 'lsmod' in directory '/root'
2019-03-30 05:58:11,370 [salt.state       :300 ][INFO    ][15057] Kernel modules iptable_filter, ip_tables are already present
2019-03-30 05:58:11,370 [salt.state       :1951][INFO    ][15057] Completed state [iptables_modules_v4_load] at time 05:58:11.370482 duration_in_ms=26.137
2019-03-30 05:58:11,371 [salt.state       :1780][INFO    ][15057] Running state [/etc/iptables/rules.v4] at time 05:58:11.371800
2019-03-30 05:58:11,372 [salt.state       :1813][INFO    ][15057] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-30 05:58:11,479 [salt.state       :300 ][INFO    ][15057] File /etc/iptables/rules.v4 is in the correct state
2019-03-30 05:58:11,479 [salt.state       :1951][INFO    ][15057] Completed state [/etc/iptables/rules.v4] at time 05:58:11.479516 duration_in_ms=107.716
2019-03-30 05:58:11,480 [salt.state       :1780][INFO    ][15057] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:58:11.480632
2019-03-30 05:58:11,480 [salt.state       :1813][INFO    ][15057] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-30 05:58:11,481 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15057] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-30 05:58:11,505 [salt.state       :300 ][INFO    ][15057] onlyif execution failed
2019-03-30 05:58:11,506 [salt.state       :1951][INFO    ][15057] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:58:11.506113 duration_in_ms=25.48
2019-03-30 05:58:11,508 [salt.state       :1780][INFO    ][15057] Running state [netfilter-persistent] at time 05:58:11.508280
2019-03-30 05:58:11,508 [salt.state       :1813][INFO    ][15057] Executing state service.running for [netfilter-persistent]
2019-03-30 05:58:11,510 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15057] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-30 05:58:11,533 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15057] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-30 05:58:11,552 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15057] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-30 05:58:11,570 [salt.state       :300 ][INFO    ][15057] The service netfilter-persistent is already running
2019-03-30 05:58:11,570 [salt.state       :1951][INFO    ][15057] Completed state [netfilter-persistent] at time 05:58:11.570545 duration_in_ms=62.264
2019-03-30 05:58:11,574 [salt.state       :1780][INFO    ][15057] Running state [iptables_extra.remove_stale_tables] at time 05:58:11.574183
2019-03-30 05:58:11,574 [salt.state       :1813][INFO    ][15057] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-30 05:58:11,575 [salt.state       :300 ][INFO    ][15057] No changes made for iptables_extra.remove_stale_tables
2019-03-30 05:58:11,575 [salt.state       :1951][INFO    ][15057] Completed state [iptables_extra.remove_stale_tables] at time 05:58:11.575790 duration_in_ms=1.607
2019-03-30 05:58:11,576 [salt.state       :1780][INFO    ][15057] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:58:11.576188
2019-03-30 05:58:11,576 [salt.state       :1813][INFO    ][15057] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-30 05:58:11,577 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15057] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-30 05:58:11,592 [salt.state       :300 ][INFO    ][15057] onlyif execution failed
2019-03-30 05:58:11,592 [salt.state       :1951][INFO    ][15057] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:58:11.592534 duration_in_ms=16.347
2019-03-30 05:58:11,593 [salt.state       :1780][INFO    ][15057] Running state [/etc/iptables/rules.v6] at time 05:58:11.593540
2019-03-30 05:58:11,593 [salt.state       :1813][INFO    ][15057] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-30 05:58:11,594 [salt.state       :300 ][INFO    ][15057] File /etc/iptables/rules.v6 is not present
2019-03-30 05:58:11,594 [salt.state       :1951][INFO    ][15057] Completed state [/etc/iptables/rules.v6] at time 05:58:11.594556 duration_in_ms=1.016
2019-03-30 05:58:11,598 [salt.state       :1780][INFO    ][15057] Running state [iptables_extra.flush_all] at time 05:58:11.598336
2019-03-30 05:58:11,598 [salt.state       :1813][INFO    ][15057] Executing state module.wait for [iptables_extra.flush_all]
2019-03-30 05:58:11,599 [salt.state       :300 ][INFO    ][15057] No changes made for iptables_extra.flush_all
2019-03-30 05:58:11,599 [salt.state       :1951][INFO    ][15057] Completed state [iptables_extra.flush_all] at time 05:58:11.599444 duration_in_ms=1.109
2019-03-30 05:58:11,604 [salt.minion      :1711][INFO    ][15057] Returning information for job: 20190330055757263664
2019-03-30 05:58:12,177 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command state.apply with jid 20190330055812167632
2019-03-30 05:58:12,198 [salt.minion      :1432][INFO    ][15146] Starting a new job with PID 15146
2019-03-30 05:58:13,618 [salt.state       :915 ][INFO    ][15146] Loading fresh modules for state activity
2019-03-30 05:58:14,517 [salt.state       :1780][INFO    ][15146] Running state [maas-rack-controller] at time 05:58:14.517269
2019-03-30 05:58:14,517 [salt.state       :1813][INFO    ][15146] Executing state pkg.installed for [maas-rack-controller]
2019-03-30 05:58:14,518 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15146] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-30 05:58:14,629 [salt.state       :300 ][INFO    ][15146] All specified packages are already installed
2019-03-30 05:58:14,629 [salt.state       :1951][INFO    ][15146] Completed state [maas-rack-controller] at time 05:58:14.629632 duration_in_ms=112.365
2019-03-30 05:58:14,630 [salt.state       :1780][INFO    ][15146] Running state [ipmitool] at time 05:58:14.630009
2019-03-30 05:58:14,630 [salt.state       :1813][INFO    ][15146] Executing state pkg.installed for [ipmitool]
2019-03-30 05:58:14,640 [salt.state       :300 ][INFO    ][15146] All specified packages are already installed
2019-03-30 05:58:14,640 [salt.state       :1951][INFO    ][15146] Completed state [ipmitool] at time 05:58:14.640403 duration_in_ms=10.394
2019-03-30 05:58:14,645 [salt.state       :1780][INFO    ][15146] Running state [/etc/maas/rackd.conf] at time 05:58:14.645749
2019-03-30 05:58:14,646 [salt.state       :1813][INFO    ][15146] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-30 05:58:14,647 [salt.state       :300 ][INFO    ][15146] No changes needed to be made
2019-03-30 05:58:14,647 [salt.state       :1951][INFO    ][15146] Completed state [/etc/maas/rackd.conf] at time 05:58:14.647230 duration_in_ms=1.481
2019-03-30 05:58:14,647 [salt.state       :1780][INFO    ][15146] Running state [/etc/maas/rackd.conf] at time 05:58:14.647473
2019-03-30 05:58:14,647 [salt.state       :1813][INFO    ][15146] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-30 05:58:14,648 [salt.loaded.int.states.file:2298][WARNING ][15146] 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-03-30 05:58:14,649 [salt.state       :300 ][INFO    ][15146] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-30 05:58:14,649 [salt.state       :1951][INFO    ][15146] Completed state [/etc/maas/rackd.conf] at time 05:58:14.649476 duration_in_ms=2.003
2019-03-30 05:58:14,650 [salt.state       :1780][INFO    ][15146] Running state [maas-rackd] at time 05:58:14.650603
2019-03-30 05:58:14,651 [salt.state       :1813][INFO    ][15146] Executing state service.running for [maas-rackd]
2019-03-30 05:58:14,652 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15146] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-30 05:58:14,695 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15146] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-30 05:58:14,710 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15146] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-30 05:58:14,730 [salt.state       :300 ][INFO    ][15146] The service maas-rackd is already running
2019-03-30 05:58:14,731 [salt.state       :1951][INFO    ][15146] Completed state [maas-rackd] at time 05:58:14.731454 duration_in_ms=80.851
2019-03-30 05:58:14,732 [salt.minion      :1711][INFO    ][15146] Returning information for job: 20190330055812167632
2019-03-30 05:58:15,276 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command state.apply with jid 20190330055815268873
2019-03-30 05:58:15,299 [salt.minion      :1432][INFO    ][15169] Starting a new job with PID 15169
2019-03-30 05:58:16,615 [salt.state       :915 ][INFO    ][15169] Loading fresh modules for state activity
2019-03-30 05:58:17,630 [salt.state       :1780][INFO    ][15169] Running state [maas-region-controller] at time 05:58:17.630259
2019-03-30 05:58:17,630 [salt.state       :1813][INFO    ][15169] Executing state pkg.installed for [maas-region-controller]
2019-03-30 05:58:17,632 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15169] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-30 05:58:17,742 [salt.state       :300 ][INFO    ][15169] All specified packages are already installed
2019-03-30 05:58:17,743 [salt.state       :1951][INFO    ][15169] Completed state [maas-region-controller] at time 05:58:17.743163 duration_in_ms=112.904
2019-03-30 05:58:17,743 [salt.state       :1780][INFO    ][15169] Running state [python-oauth] at time 05:58:17.743489
2019-03-30 05:58:17,743 [salt.state       :1813][INFO    ][15169] Executing state pkg.installed for [python-oauth]
2019-03-30 05:58:17,754 [salt.state       :300 ][INFO    ][15169] All specified packages are already installed
2019-03-30 05:58:17,754 [salt.state       :1951][INFO    ][15169] Completed state [python-oauth] at time 05:58:17.754661 duration_in_ms=11.172
2019-03-30 05:58:17,757 [salt.state       :1780][INFO    ][15169] Running state [/etc/maas/regiond.conf] at time 05:58:17.757672
2019-03-30 05:58:17,757 [salt.state       :1813][INFO    ][15169] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-30 05:58:17,762 [salt.state       :300 ][INFO    ][15169] No changes needed to be made
2019-03-30 05:58:17,762 [salt.state       :1951][INFO    ][15169] Completed state [/etc/maas/regiond.conf] at time 05:58:17.762723 duration_in_ms=5.051
2019-03-30 05:58:17,764 [salt.state       :1780][INFO    ][15169] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:58:17.764053
2019-03-30 05:58:17,764 [salt.state       :1813][INFO    ][15169] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-30 05:58:17,821 [salt.state       :300 ][INFO    ][15169] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-30 05:58:17,822 [salt.state       :1951][INFO    ][15169] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:58:17.821976 duration_in_ms=57.923
2019-03-30 05:58:17,822 [salt.state       :1780][INFO    ][15169] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:58:17.822573
2019-03-30 05:58:17,824 [salt.state       :1813][INFO    ][15169] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-30 05:58:17,827 [salt.state       :300 ][INFO    ][15169] No changes needed to be made
2019-03-30 05:58:17,827 [salt.state       :1951][INFO    ][15169] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:58:17.827683 duration_in_ms=5.11
2019-03-30 05:58:17,828 [salt.state       :1780][INFO    ][15169] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:58:17.828175
2019-03-30 05:58:17,828 [salt.state       :1813][INFO    ][15169] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-30 05:58:17,831 [salt.state       :300 ][INFO    ][15169] No changes needed to be made
2019-03-30 05:58:17,832 [salt.state       :1951][INFO    ][15169] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:58:17.832006 duration_in_ms=3.831
2019-03-30 05:58:17,832 [salt.state       :1780][INFO    ][15169] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:58:17.832494
2019-03-30 05:58:17,832 [salt.state       :1813][INFO    ][15169] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-30 05:58:17,860 [salt.state       :300 ][INFO    ][15169] No changes needed to be made
2019-03-30 05:58:17,860 [salt.state       :1951][INFO    ][15169] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:58:17.860552 duration_in_ms=28.056
2019-03-30 05:58:17,861 [salt.state       :1780][INFO    ][15169] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:58:17.861108
2019-03-30 05:58:17,861 [salt.state       :1813][INFO    ][15169] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-30 05:58:17,874 [salt.state       :300 ][INFO    ][15169] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-30 05:58:17,875 [salt.state       :1951][INFO    ][15169] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:58:17.874810 duration_in_ms=13.702
2019-03-30 05:58:17,876 [salt.state       :1780][INFO    ][15169] Running state [a2enmod headers] at time 05:58:17.876288
2019-03-30 05:58:17,876 [salt.state       :1813][INFO    ][15169] Executing state cmd.run for [a2enmod headers]
2019-03-30 05:58:17,877 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15169] Executing command 'a2enmod headers' in directory '/root'
2019-03-30 05:58:17,944 [salt.state       :300 ][INFO    ][15169] {'pid': 15188, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-30 05:58:17,945 [salt.state       :1951][INFO    ][15169] Completed state [a2enmod headers] at time 05:58:17.945273 duration_in_ms=68.984
2019-03-30 05:58:17,946 [salt.state       :1780][INFO    ][15169] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:58:17.945930
2019-03-30 05:58:17,946 [salt.state       :1813][INFO    ][15169] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-30 05:58:17,964 [salt.state       :300 ][INFO    ][15169] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-30 05:58:17,965 [salt.state       :1951][INFO    ][15169] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:58:17.965206 duration_in_ms=19.277
2019-03-30 05:58:17,965 [salt.state       :1780][INFO    ][15169] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:58:17.965794
2019-03-30 05:58:17,966 [salt.state       :1813][INFO    ][15169] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-30 05:58:18,016 [salt.state       :300 ][INFO    ][15169] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-30 05:58:18,016 [salt.state       :1951][INFO    ][15169] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:58:18.016607 duration_in_ms=50.812
2019-03-30 05:58:18,017 [salt.state       :1780][INFO    ][15169] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:58:18.017296
2019-03-30 05:58:18,017 [salt.state       :1813][INFO    ][15169] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-30 05:58:18,071 [salt.state       :300 ][INFO    ][15169] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-30 05:58:18,072 [salt.state       :1951][INFO    ][15169] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:58:18.072176 duration_in_ms=54.88
2019-03-30 05:58:18,072 [salt.state       :1780][INFO    ][15169] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:58:18.072664
2019-03-30 05:58:18,072 [salt.state       :1813][INFO    ][15169] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-30 05:58:18,135 [salt.state       :300 ][INFO    ][15169] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-30 05:58:18,136 [salt.state       :1951][INFO    ][15169] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:58:18.136194 duration_in_ms=63.53
2019-03-30 05:58:18,136 [salt.state       :1780][INFO    ][15169] Running state [/root/.pgpass] at time 05:58:18.136450
2019-03-30 05:58:18,136 [salt.state       :1813][INFO    ][15169] Executing state file.managed for [/root/.pgpass]
2019-03-30 05:58:18,176 [salt.state       :300 ][INFO    ][15169] File /root/.pgpass is in the correct state
2019-03-30 05:58:18,176 [salt.state       :1951][INFO    ][15169] Completed state [/root/.pgpass] at time 05:58:18.176339 duration_in_ms=39.889
2019-03-30 05:58:18,186 [salt.state       :1780][INFO    ][15169] Running state [maas-region syncdb --noinput] at time 05:58:18.186044
2019-03-30 05:58:18,186 [salt.state       :1813][INFO    ][15169] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-30 05:58:18,186 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15169] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-30 05:58:20,974 [salt.state       :300 ][INFO    ][15169] {'pid': 15201, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: piston3, contenttypes, sites, auth, maasserver, sessions, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-30 05:58:20,974 [salt.state       :1951][INFO    ][15169] Completed state [maas-region syncdb --noinput] at time 05:58:20.974621 duration_in_ms=2788.576
2019-03-30 05:58:20,975 [salt.state       :2022][WARNING ][15169] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-30 05:58:20,977 [salt.state       :1780][INFO    ][15169] Running state [maas-regiond] at time 05:58:20.977265
2019-03-30 05:58:20,977 [salt.state       :1813][INFO    ][15169] Executing state service.running for [maas-regiond]
2019-03-30 05:58:20,978 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15169] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-30 05:58:21,026 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15169] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-30 05:58:21,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15169] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-30 05:58:21,082 [salt.state       :300 ][INFO    ][15169] The service maas-regiond is already running
2019-03-30 05:58:21,082 [salt.state       :1951][INFO    ][15169] Completed state [maas-regiond] at time 05:58:21.082717 duration_in_ms=105.45
2019-03-30 05:58:21,086 [salt.state       :1780][INFO    ][15169] Running state [bind9] at time 05:58:21.086154
2019-03-30 05:58:21,086 [salt.state       :1813][INFO    ][15169] Executing state service.running for [bind9]
2019-03-30 05:58:21,092 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15169] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-30 05:58:21,116 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15169] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-30 05:58:21,139 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15169] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-30 05:58:21,157 [salt.state       :300 ][INFO    ][15169] The service bind9 is already running
2019-03-30 05:58:21,157 [salt.state       :1951][INFO    ][15169] Completed state [bind9] at time 05:58:21.157819 duration_in_ms=71.665
2019-03-30 05:58:21,164 [salt.state       :1780][INFO    ][15169] Running state [apache2] at time 05:58:21.164673
2019-03-30 05:58:21,165 [salt.state       :1813][INFO    ][15169] Executing state service.running for [apache2]
2019-03-30 05:58:21,166 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15169] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-30 05:58:21,187 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15169] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-30 05:58:21,205 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15169] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-30 05:58:21,235 [salt.state       :300 ][INFO    ][15169] The service apache2 is already running
2019-03-30 05:58:21,236 [salt.state       :1951][INFO    ][15169] Completed state [apache2] at time 05:58:21.236032 duration_in_ms=71.359
2019-03-30 05:58:21,238 [salt.state       :1780][INFO    ][15169] Running state [maasng.wait_for_http_code] at time 05:58:21.237995
2019-03-30 05:58:21,238 [salt.state       :1813][INFO    ][15169] Executing state module.run for [maasng.wait_for_http_code]
2019-03-30 05:58:21,239 [salt.utils.decorators:613 ][WARNING ][15169] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:58:21,464 [salt.state       :300 ][INFO    ][15169] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-30 05:58:21,465 [salt.state       :1951][INFO    ][15169] Completed state [maasng.wait_for_http_code] at time 05:58:21.465143 duration_in_ms=227.149
2019-03-30 05:58:21,466 [salt.state       :1780][INFO    ][15169] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:58:21.465977
2019-03-30 05:58:21,466 [salt.state       :1813][INFO    ][15169] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-30 05:58:21,466 [salt.state       :300 ][INFO    ][15169] /var/lib/maas/.setup_admin exists
2019-03-30 05:58:21,467 [salt.state       :1951][INFO    ][15169] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:58:21.467190 duration_in_ms=1.213
2019-03-30 05:58:21,468 [salt.state       :1780][INFO    ][15169] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:21.468034
2019-03-30 05:58:21,468 [salt.state       :1813][INFO    ][15169] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-30 05:58:21,469 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15169] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-30 05:58:23,201 [salt.state       :300 ][INFO    ][15169] {'pid': 15267, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-30 05:58:23,201 [salt.state       :1951][INFO    ][15169] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:23.201666 duration_in_ms=1733.632
2019-03-30 05:58:23,206 [salt.state       :1780][INFO    ][15169] Running state [maas_region_boot_source_resources_mirror] at time 05:58:23.206286
2019-03-30 05:58:23,206 [salt.state       :1813][INFO    ][15169] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-30 05:58:23,282 [salt.state       :300 ][INFO    ][15169] {'changes': {}}
2019-03-30 05:58:23,285 [salt.state       :1951][INFO    ][15169] Completed state [maas_region_boot_source_resources_mirror] at time 05:58:23.284922 duration_in_ms=78.635
2019-03-30 05:58:23,285 [salt.state       :1780][INFO    ][15169] Running state [maasng.boot_resources_import] at time 05:58:23.285722
2019-03-30 05:58:23,286 [salt.state       :1813][INFO    ][15169] Executing state module.run for [maasng.boot_resources_import]
2019-03-30 05:58:23,286 [salt.utils.decorators:613 ][WARNING ][15169] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:58:23,363 [salt.loaded.ext.module.maasng:1600][INFO    ][15169] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-30 05:58:28,410 [salt.loaded.ext.module.maasng:1600][INFO    ][15169] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-30 05:58:30,348 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055830332777
2019-03-30 05:58:30,370 [salt.minion      :1432][INFO    ][15435] Starting a new job with PID 15435
2019-03-30 05:58:30,398 [salt.minion      :1711][INFO    ][15435] Returning information for job: 20190330055830332777
2019-03-30 05:58:33,489 [salt.state       :300 ][INFO    ][15169] {'ret': True}
2019-03-30 05:58:33,489 [salt.state       :1951][INFO    ][15169] Completed state [maasng.boot_resources_import] at time 05:58:33.489396 duration_in_ms=10203.673
2019-03-30 05:58:33,490 [salt.state       :1780][INFO    ][15169] Running state [maas_region_boot_sources_selection_xenial] at time 05:58:33.490152
2019-03-30 05:58:33,490 [salt.state       :1813][INFO    ][15169] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-30 05:58:33,650 [salt.state       :300 ][INFO    ][15169] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-30 05:58:33,650 [salt.state       :1951][INFO    ][15169] Completed state [maas_region_boot_sources_selection_xenial] at time 05:58:33.650455 duration_in_ms=160.303
2019-03-30 05:58:33,652 [salt.state       :1780][INFO    ][15169] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:58:33.652119
2019-03-30 05:58:33,652 [salt.state       :1813][INFO    ][15169] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-30 05:58:33,652 [salt.utils.decorators:613 ][WARNING ][15169] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:58:33,653 [salt.loaded.ext.module.maasng:1771][INFO    ][15169] boot-sources sync initiated for ALL Rack's
2019-03-30 05:58:34,575 [salt.state       :300 ][INFO    ][15169] {'ret': True}
2019-03-30 05:58:34,576 [salt.state       :1951][INFO    ][15169] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:58:34.576178 duration_in_ms=924.043
2019-03-30 05:58:34,578 [salt.state       :1780][INFO    ][15169] Running state [maas.process_maas_config] at time 05:58:34.578523
2019-03-30 05:58:34,579 [salt.state       :1813][INFO    ][15169] Executing state module.run for [maas.process_maas_config]
2019-03-30 05:58:34,580 [salt.utils.decorators:613 ][WARNING ][15169] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:58:34,581 [salt.loaded.ext.module.maas:92  ][INFO    ][15169] maasconfig name=enable_http_proxy value=True
2019-03-30 05:58:34,627 [salt.loaded.ext.module.maas:92  ][INFO    ][15169] maasconfig name=upstream_dns value=8.8.8.8
2019-03-30 05:58:34,676 [salt.loaded.ext.module.maas:92  ][INFO    ][15169] maasconfig name=commissioning_distro_series value=xenial
2019-03-30 05:58:34,742 [salt.loaded.ext.module.maas:92  ][INFO    ][15169] maasconfig name=default_osystem value=ubuntu
2019-03-30 05:58:34,809 [salt.loaded.ext.module.maas:92  ][INFO    ][15169] maasconfig name=active_discovery_interval value=600
2019-03-30 05:58:34,858 [salt.loaded.ext.module.maas:92  ][INFO    ][15169] maasconfig name=dnssec_validation value=no
2019-03-30 05:58:34,907 [salt.loaded.ext.module.maas:92  ][INFO    ][15169] maasconfig name=maas_name value=mas01
2019-03-30 05:58:35,923 [salt.loaded.ext.module.maas:92  ][INFO    ][15169] maasconfig name=network_discovery value=enabled
2019-03-30 05:58:36,012 [salt.loaded.ext.module.maas:92  ][INFO    ][15169] maasconfig name=enable_third_party_drivers value=True
2019-03-30 05:58:36,054 [salt.loaded.ext.module.maas:92  ][INFO    ][15169] maasconfig name=default_storage_layout value=lvm
2019-03-30 05:58:36,105 [salt.loaded.ext.module.maas:92  ][INFO    ][15169] maasconfig name=ntp_external_only value=True
2019-03-30 05:58:36,151 [salt.loaded.ext.module.maas:92  ][INFO    ][15169] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-30 05:58:36,196 [salt.loaded.ext.module.maas:92  ][INFO    ][15169] maasconfig name=default_distro_series value=xenial
2019-03-30 05:58:36,247 [salt.loaded.ext.module.maas:92  ][INFO    ][15169] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-03-30 05:58:36,357 [salt.state       :300 ][INFO    ][15169] {'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-03-30 05:58:36,357 [salt.state       :1951][INFO    ][15169] Completed state [maas.process_maas_config] at time 05:58:36.357301 duration_in_ms=1778.778
2019-03-30 05:58:36,358 [salt.state       :1780][INFO    ][15169] Running state [pxe_admin] at time 05:58:36.357973
2019-03-30 05:58:36,358 [salt.state       :1813][INFO    ][15169] Executing state maasng.fabric_present for [pxe_admin]
2019-03-30 05:58:36,429 [salt.loaded.ext.module.maasng:945 ][INFO    ][15169] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, 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': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'gggsy8', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-30 05:58:36,488 [salt.loaded.ext.module.maasng:1008][WARNING ][15169] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-30 05:58:36,488 [salt.loaded.ext.module.maasng:1011][WARNING ][15169] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-30 05:58:36,554 [salt.state       :300 ][INFO    ][15169] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-30 05:58:36,554 [salt.state       :1951][INFO    ][15169] Completed state [pxe_admin] at time 05:58:36.554587 duration_in_ms=196.613
2019-03-30 05:58:36,557 [salt.state       :1780][INFO    ][15169] Running state [vlan 0] at time 05:58:36.556946
2019-03-30 05:58:36,557 [salt.state       :1813][INFO    ][15169] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-30 05:58:36,619 [salt.loaded.ext.module.maasng:945 ][INFO    ][15169] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, 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': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'gggsy8', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-30 05:58:36,708 [salt.loaded.ext.module.maasng:945 ][INFO    ][15169] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gggsy8', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-03-30 05:58:36,968 [salt.loaded.ext.module.maasng:945 ][INFO    ][15169] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, 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': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'gggsy8', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-30 05:58:37,033 [salt.state       :300 ][INFO    ][15169] {'new': 'Vlan untagged was updated'}
2019-03-30 05:58:37,034 [salt.state       :1951][INFO    ][15169] Completed state [vlan 0] at time 05:58:37.033994 duration_in_ms=477.047
2019-03-30 05:58:37,037 [salt.state       :1780][INFO    ][15169] Running state [192.168.11.0/24] at time 05:58:37.037100
2019-03-30 05:58:37,037 [salt.state       :1813][INFO    ][15169] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-30 05:58:37,237 [salt.loaded.ext.module.maasng:945 ][INFO    ][15169] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, 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': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': u'gggsy8', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-30 05:58:37,238 [salt.loaded.ext.module.maasng:1235][WARNING ][15169] Ignoring parameter vlan:0
2019-03-30 05:58:37,296 [salt.state       :300 ][INFO    ][15169] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-30 05:58:37,296 [salt.state       :1951][INFO    ][15169] Completed state [192.168.11.0/24] at time 05:58:37.296682 duration_in_ms=259.582
2019-03-30 05:58:37,297 [salt.state       :1780][INFO    ][15169] Running state [maas_create_iprange_1] at time 05:58:37.297486
2019-03-30 05:58:37,297 [salt.state       :1813][INFO    ][15169] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-30 05:58:37,343 [salt.state       :300 ][INFO    ][15169] Iprange maas_create_iprange_1 already exist.
2019-03-30 05:58:37,343 [salt.state       :1951][INFO    ][15169] Completed state [maas_create_iprange_1] at time 05:58:37.343852 duration_in_ms=46.366
2019-03-30 05:58:37,344 [salt.state       :1780][INFO    ][15169] Running state [vlan 0] at time 05:58:37.344138
2019-03-30 05:58:37,344 [salt.state       :1813][INFO    ][15169] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-30 05:58:37,386 [salt.loaded.ext.module.maasng:945 ][INFO    ][15169] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gggsy8', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-03-30 05:58:37,465 [salt.loaded.ext.module.maasng:945 ][INFO    ][15169] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gggsy8', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-03-30 05:58:37,665 [salt.loaded.ext.module.maasng:945 ][INFO    ][15169] [{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': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gggsy8', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-03-30 05:58:37,749 [salt.state       :300 ][INFO    ][15169] {'new': 'Vlan untagged was updated'}
2019-03-30 05:58:37,749 [salt.state       :1951][INFO    ][15169] Completed state [vlan 0] at time 05:58:37.749721 duration_in_ms=405.583
2019-03-30 05:58:37,750 [salt.state       :1780][INFO    ][15169] Running state [opnfv] at time 05:58:37.750439
2019-03-30 05:58:37,751 [salt.state       :1813][INFO    ][15169] Executing state maasng.sshkey_present for [opnfv]
2019-03-30 05:58:37,811 [salt.loaded.ext.module.maasng:1903][INFO    ][15169] [{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-03-30 05:58:37,811 [salt.state       :300 ][INFO    ][15169] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-03-30 05:58:37,812 [salt.state       :1951][INFO    ][15169] Completed state [opnfv] at time 05:58:37.812193 duration_in_ms=61.753
2019-03-30 05:58:37,813 [salt.state       :1780][INFO    ][15169] Running state [maas.process_tags] at time 05:58:37.812961
2019-03-30 05:58:37,813 [salt.state       :1813][INFO    ][15169] Executing state module.run for [maas.process_tags]
2019-03-30 05:58:37,813 [salt.utils.decorators:613 ][WARNING ][15169] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:58:37,869 [salt.loaded.ext.module.maas:92  ][INFO    ][15169] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-03-30 05:58:37,951 [salt.state       :300 ][INFO    ][15169] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-03-30 05:58:37,952 [salt.state       :1951][INFO    ][15169] Completed state [maas.process_tags] at time 05:58:37.952043 duration_in_ms=139.08
2019-03-30 05:58:37,954 [salt.minion      :1711][INFO    ][15169] Returning information for job: 20190330055815268873
2019-03-30 05:58:38,602 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command state.apply with jid 20190330055838593333
2019-03-30 05:58:38,617 [salt.minion      :1432][INFO    ][15670] Starting a new job with PID 15670
2019-03-30 05:58:46,844 [salt.state       :915 ][INFO    ][15670] Loading fresh modules for state activity
2019-03-30 05:58:46,938 [salt.state       :1780][INFO    ][15670] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:46.938015
2019-03-30 05:58:46,938 [salt.state       :1813][INFO    ][15670] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-30 05:58:46,940 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15670] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-30 05:58:48,638 [salt.state       :300 ][INFO    ][15670] {'pid': 15694, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-30 05:58:48,638 [salt.state       :1951][INFO    ][15670] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:48.638494 duration_in_ms=1700.479
2019-03-30 05:58:48,641 [salt.state       :1780][INFO    ][15670] Running state [maas.process_machines] at time 05:58:48.641548
2019-03-30 05:58:48,641 [salt.state       :1813][INFO    ][15670] Executing state module.run for [maas.process_machines]
2019-03-30 05:58:48,642 [salt.utils.decorators:613 ][WARNING ][15670] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:58:49,132 [salt.loaded.ext.module.maas:412 ][WARNING ][15670] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-30 05:58:49,133 [salt.loaded.ext.module.maas:92  ][INFO    ][15670] 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=na7wmb architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-30 05:58:50,203 [salt.loaded.ext.module.maas:412 ][WARNING ][15670] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-30 05:58:50,204 [salt.loaded.ext.module.maas:92  ][INFO    ][15670] 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=wdfmpm architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-30 05:58:51,187 [salt.loaded.ext.module.maas:412 ][WARNING ][15670] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-30 05:58:51,188 [salt.loaded.ext.module.maas:92  ][INFO    ][15670] 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=6dea3a architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-30 05:58:52,256 [salt.loaded.ext.module.maas:412 ][WARNING ][15670] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-30 05:58:52,257 [salt.loaded.ext.module.maas:92  ][INFO    ][15670] 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=kexqes architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-30 05:58:53,207 [salt.loaded.ext.module.maas:412 ][WARNING ][15670] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-30 05:58:53,208 [salt.loaded.ext.module.maas:92  ][INFO    ][15670] 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=dmdxmm architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-30 05:58:53,694 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330055853682224
2019-03-30 05:58:53,711 [salt.minion      :1432][INFO    ][15906] Starting a new job with PID 15906
2019-03-30 05:58:53,737 [salt.minion      :1711][INFO    ][15906] Returning information for job: 20190330055853682224
2019-03-30 05:58:54,131 [salt.state       :300 ][INFO    ][15670] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-30 05:58:54,132 [salt.state       :1951][INFO    ][15670] Completed state [maas.process_machines] at time 05:58:54.131938 duration_in_ms=5490.389
2019-03-30 05:58:54,134 [salt.minion      :1711][INFO    ][15670] Returning information for job: 20190330055838593333
2019-03-30 05:59:27,157 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command state.apply with jid 20190330055927149560
2019-03-30 05:59:27,173 [salt.minion      :1432][INFO    ][15985] Starting a new job with PID 15985
2019-03-30 05:59:35,333 [salt.state       :915 ][INFO    ][15985] Loading fresh modules for state activity
2019-03-30 05:59:35,423 [salt.state       :1780][INFO    ][15985] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:35.423557
2019-03-30 05:59:35,423 [salt.state       :1813][INFO    ][15985] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-30 05:59:35,425 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15985] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-30 05:59:37,106 [salt.state       :300 ][INFO    ][15985] {'pid': 16002, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-30 05:59:37,108 [salt.state       :1951][INFO    ][15985] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:37.108141 duration_in_ms=1684.584
2019-03-30 05:59:37,111 [salt.state       :1780][INFO    ][15985] Running state [maas.wait_for_machine_status] at time 05:59:37.110797
2019-03-30 05:59:37,111 [salt.state       :1813][INFO    ][15985] Executing state module.run for [maas.wait_for_machine_status]
2019-03-30 05:59:37,112 [salt.utils.decorators:613 ][WARNING ][15985] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:59:39,776 [salt.state       :300 ][INFO    ][15985] {'ret': True}
2019-03-30 05:59:39,777 [salt.state       :1951][INFO    ][15985] Completed state [maas.wait_for_machine_status] at time 05:59:39.777060 duration_in_ms=2666.261
2019-03-30 05:59:39,781 [salt.minion      :1711][INFO    ][15985] Returning information for job: 20190330055927149560
2019-03-30 05:59:40,379 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command state.apply with jid 20190330055940307501
2019-03-30 05:59:40,399 [salt.minion      :1432][INFO    ][16017] Starting a new job with PID 16017
2019-03-30 05:59:41,590 [salt.state       :915 ][INFO    ][16017] Loading fresh modules for state activity
2019-03-30 05:59:41,743 [salt.state       :1780][INFO    ][16017] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:41.743833
2019-03-30 05:59:41,744 [salt.state       :1813][INFO    ][16017] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-30 05:59:41,745 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16017] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-30 05:59:43,427 [salt.state       :300 ][INFO    ][16017] {'pid': 16024, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-30 05:59:43,428 [salt.state       :1951][INFO    ][16017] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:43.428271 duration_in_ms=1684.438
2019-03-30 05:59:43,432 [salt.state       :1780][INFO    ][16017] Running state [maas_machines_storage_cmp002_lvm] at time 05:59:43.432222
2019-03-30 05:59:43,432 [salt.state       :1813][INFO    ][16017] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-30 05:59:43,914 [salt.state       :300 ][INFO    ][16017] Machine cmp002 is not in Ready state.
2019-03-30 05:59:43,914 [salt.state       :1951][INFO    ][16017] Completed state [maas_machines_storage_cmp002_lvm] at time 05:59:43.914797 duration_in_ms=482.575
2019-03-30 05:59:43,915 [salt.state       :1780][INFO    ][16017] Running state [maas_machines_storage_cmp001_lvm] at time 05:59:43.915123
2019-03-30 05:59:43,915 [salt.state       :1813][INFO    ][16017] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-30 05:59:44,377 [salt.state       :300 ][INFO    ][16017] Machine cmp001 is not in Ready state.
2019-03-30 05:59:44,377 [salt.state       :1951][INFO    ][16017] Completed state [maas_machines_storage_cmp001_lvm] at time 05:59:44.377803 duration_in_ms=462.679
2019-03-30 05:59:44,382 [salt.minion      :1711][INFO    ][16017] Returning information for job: 20190330055940307501
2019-03-30 05:59:44,921 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command state.apply with jid 20190330055944909352
2019-03-30 05:59:44,940 [salt.minion      :1432][INFO    ][16034] Starting a new job with PID 16034
2019-03-30 05:59:46,229 [salt.state       :915 ][INFO    ][16034] Loading fresh modules for state activity
2019-03-30 05:59:46,331 [salt.state       :1780][INFO    ][16034] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:46.331256
2019-03-30 05:59:46,331 [salt.state       :1813][INFO    ][16034] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-30 05:59:46,333 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16034] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-30 05:59:48,005 [salt.state       :300 ][INFO    ][16034] {'pid': 16042, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-30 05:59:48,006 [salt.state       :1951][INFO    ][16034] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:48.006580 duration_in_ms=1675.322
2019-03-30 05:59:48,010 [salt.state       :1780][INFO    ][16034] Running state [maas.deploy_machines] at time 05:59:48.009946
2019-03-30 05:59:48,010 [salt.state       :1813][INFO    ][16034] Executing state module.run for [maas.deploy_machines]
2019-03-30 05:59:48,011 [salt.utils.decorators:613 ][WARNING ][16034] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 05:59:48,559 [salt.loaded.ext.module.maas:684 ][INFO    ][16034] deploymachines hwe_kernel=hwe-16.04 system_id=dmdxmm distro_series=xenial
2019-03-30 05:59:50,803 [salt.state       :300 ][INFO    ][16034] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-03-30 05:59:50,803 [salt.state       :1951][INFO    ][16034] Completed state [maas.deploy_machines] at time 05:59:50.803854 duration_in_ms=2793.908
2019-03-30 05:59:50,806 [salt.minion      :1711][INFO    ][16034] Returning information for job: 20190330055944909352
2019-03-30 05:59:51,366 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command state.apply with jid 20190330055951352327
2019-03-30 05:59:51,389 [salt.minion      :1432][INFO    ][16107] Starting a new job with PID 16107
2019-03-30 05:59:59,455 [salt.state       :915 ][INFO    ][16107] Loading fresh modules for state activity
2019-03-30 05:59:59,541 [salt.state       :1780][INFO    ][16107] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:59.541083
2019-03-30 05:59:59,541 [salt.state       :1813][INFO    ][16107] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-30 05:59:59,543 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16107] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-30 06:00:01,229 [salt.state       :300 ][INFO    ][16107] {'pid': 16119, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-30 06:00:01,230 [salt.state       :1951][INFO    ][16107] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:00:01.230417 duration_in_ms=1689.333
2019-03-30 06:00:01,233 [salt.state       :1780][INFO    ][16107] Running state [maas.wait_for_machine_status] at time 06:00:01.233721
2019-03-30 06:00:01,234 [salt.state       :1813][INFO    ][16107] Executing state module.run for [maas.wait_for_machine_status]
2019-03-30 06:00:01,234 [salt.utils.decorators:613 ][WARNING ][16107] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-30 06:00:03,930 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.31780696s left)
2019-03-30 06:00:06,427 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060006415760
2019-03-30 06:00:06,450 [salt.minion      :1432][INFO    ][16144] Starting a new job with PID 16144
2019-03-30 06:00:06,475 [salt.minion      :1711][INFO    ][16144] Returning information for job: 20190330060006415760
2019-03-30 06:00:36,417 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.830621s left)
2019-03-30 06:00:36,461 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060036452810
2019-03-30 06:00:36,475 [salt.minion      :1432][INFO    ][16216] Starting a new job with PID 16216
2019-03-30 06:00:36,498 [salt.minion      :1711][INFO    ][16216] Returning information for job: 20190330060036452810
2019-03-30 06:01:06,665 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060106656337
2019-03-30 06:01:06,681 [salt.minion      :1432][INFO    ][16251] Starting a new job with PID 16251
2019-03-30 06:01:06,707 [salt.minion      :1711][INFO    ][16251] Returning information for job: 20190330060106656337
2019-03-30 06:01:09,151 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2182.09615088s left)
2019-03-30 06:01:36,878 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060136859200
2019-03-30 06:01:36,899 [salt.minion      :1432][INFO    ][16337] Starting a new job with PID 16337
2019-03-30 06:01:36,924 [salt.minion      :1711][INFO    ][16337] Returning information for job: 20190330060136859200
2019-03-30 06:01:41,574 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.67307782s left)
2019-03-30 06:02:06,937 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060206927382
2019-03-30 06:02:06,957 [salt.minion      :1432][INFO    ][16367] Starting a new job with PID 16367
2019-03-30 06:02:06,983 [salt.minion      :1711][INFO    ][16367] Returning information for job: 20190330060206927382
2019-03-30 06:02:14,209 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2117.03851485s left)
2019-03-30 06:02:37,033 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060237003566
2019-03-30 06:02:37,053 [salt.minion      :1432][INFO    ][16441] Starting a new job with PID 16441
2019-03-30 06:02:37,080 [salt.minion      :1711][INFO    ][16441] Returning information for job: 20190330060237003566
2019-03-30 06:02:46,660 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2084.58728695s left)
2019-03-30 06:03:07,097 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060307080818
2019-03-30 06:03:07,116 [salt.minion      :1432][INFO    ][16473] Starting a new job with PID 16473
2019-03-30 06:03:07,141 [salt.minion      :1711][INFO    ][16473] Returning information for job: 20190330060307080818
2019-03-30 06:03:19,513 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.73424602s left)
2019-03-30 06:03:37,173 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060337159679
2019-03-30 06:03:37,193 [salt.minion      :1432][INFO    ][16545] Starting a new job with PID 16545
2019-03-30 06:03:37,219 [salt.minion      :1711][INFO    ][16545] Returning information for job: 20190330060337159679
2019-03-30 06:03:51,849 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2019.39799595s left)
2019-03-30 06:04:07,365 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060407357545
2019-03-30 06:04:07,380 [salt.minion      :1432][INFO    ][16600] Starting a new job with PID 16600
2019-03-30 06:04:07,406 [salt.minion      :1711][INFO    ][16600] Returning information for job: 20190330060407357545
2019-03-30 06:04:24,752 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1986.49566197s left)
2019-03-30 06:04:37,569 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060437558077
2019-03-30 06:04:37,584 [salt.minion      :1432][INFO    ][16679] Starting a new job with PID 16679
2019-03-30 06:04:37,609 [salt.minion      :1711][INFO    ][16679] Returning information for job: 20190330060437558077
2019-03-30 06:04:57,192 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1954.05534482s left)
2019-03-30 06:05:07,763 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060507755631
2019-03-30 06:05:07,786 [salt.minion      :1432][INFO    ][16735] Starting a new job with PID 16735
2019-03-30 06:05:07,811 [salt.minion      :1711][INFO    ][16735] Returning information for job: 20190330060507755631
2019-03-30 06:05:29,963 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1921.28428197s left)
2019-03-30 06:05:37,789 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060537776492
2019-03-30 06:05:37,811 [salt.minion      :1432][INFO    ][16957] Starting a new job with PID 16957
2019-03-30 06:05:37,837 [salt.minion      :1711][INFO    ][16957] Returning information for job: 20190330060537776492
2019-03-30 06:06:02,513 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1888.73416305s left)
2019-03-30 06:06:07,862 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060607854071
2019-03-30 06:06:07,885 [salt.minion      :1432][INFO    ][16987] Starting a new job with PID 16987
2019-03-30 06:06:07,909 [salt.minion      :1711][INFO    ][16987] Returning information for job: 20190330060607854071
2019-03-30 06:06:35,465 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1855.7820859s left)
2019-03-30 06:06:37,920 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060637905226
2019-03-30 06:06:37,943 [salt.minion      :1432][INFO    ][17080] Starting a new job with PID 17080
2019-03-30 06:06:37,969 [salt.minion      :1711][INFO    ][17080] Returning information for job: 20190330060637905226
2019-03-30 06:07:07,942 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1823.30511093s left)
2019-03-30 06:07:08,129 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060708121336
2019-03-30 06:07:08,143 [salt.minion      :1432][INFO    ][17112] Starting a new job with PID 17112
2019-03-30 06:07:08,167 [salt.minion      :1711][INFO    ][17112] Returning information for job: 20190330060708121336
2019-03-30 06:07:38,184 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060738176386
2019-03-30 06:07:38,207 [salt.minion      :1432][INFO    ][17301] Starting a new job with PID 17301
2019-03-30 06:07:38,240 [salt.minion      :1711][INFO    ][17301] Returning information for job: 20190330060738176386
2019-03-30 06:07:40,714 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1790.53341484s left)
2019-03-30 06:08:08,196 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060808186822
2019-03-30 06:08:08,219 [salt.minion      :1432][INFO    ][17334] Starting a new job with PID 17334
2019-03-30 06:08:08,244 [salt.minion      :1711][INFO    ][17334] Returning information for job: 20190330060808186822
2019-03-30 06:08:13,074 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1758.17311883s left)
2019-03-30 06:08:38,354 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060838344890
2019-03-30 06:08:38,376 [salt.minion      :1432][INFO    ][17474] Starting a new job with PID 17474
2019-03-30 06:08:38,404 [salt.minion      :1711][INFO    ][17474] Returning information for job: 20190330060838344890
2019-03-30 06:08:45,899 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1725.34805584s left)
2019-03-30 06:09:08,444 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060908434794
2019-03-30 06:09:08,466 [salt.minion      :1432][INFO    ][17506] Starting a new job with PID 17506
2019-03-30 06:09:08,491 [salt.minion      :1711][INFO    ][17506] Returning information for job: 20190330060908434794
2019-03-30 06:09:18,609 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1692.638304s left)
2019-03-30 06:09:38,466 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330060938456373
2019-03-30 06:09:38,491 [salt.minion      :1432][INFO    ][17579] Starting a new job with PID 17579
2019-03-30 06:09:38,519 [salt.minion      :1711][INFO    ][17579] Returning information for job: 20190330060938456373
2019-03-30 06:09:51,398 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1659.84982204s left)
2019-03-30 06:10:08,498 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330061008491197
2019-03-30 06:10:08,519 [salt.minion      :1432][INFO    ][17609] Starting a new job with PID 17609
2019-03-30 06:10:08,548 [salt.minion      :1711][INFO    ][17609] Returning information for job: 20190330061008491197
2019-03-30 06:10:23,842 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1627.40581799s left)
2019-03-30 06:10:38,601 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330061038585931
2019-03-30 06:10:38,625 [salt.minion      :1432][INFO    ][17681] Starting a new job with PID 17681
2019-03-30 06:10:38,654 [salt.minion      :1711][INFO    ][17681] Returning information for job: 20190330061038585931
2019-03-30 06:10:56,810 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1594.43707991s left)
2019-03-30 06:11:08,789 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330061108776847
2019-03-30 06:11:08,804 [salt.minion      :1432][INFO    ][17718] Starting a new job with PID 17718
2019-03-30 06:11:08,835 [salt.minion      :1711][INFO    ][17718] Returning information for job: 20190330061108776847
2019-03-30 06:11:29,454 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1561.79337788s left)
2019-03-30 06:11:38,960 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330061138941406
2019-03-30 06:11:38,982 [salt.minion      :1432][INFO    ][17953] Starting a new job with PID 17953
2019-03-30 06:11:39,007 [salt.minion      :1711][INFO    ][17953] Returning information for job: 20190330061138941406
2019-03-30 06:12:02,248 [salt.loaded.ext.module.maas:1023][INFO    ][16107] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1529.00100684s left)
2019-03-30 06:12:09,116 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command saltutil.find_job with jid 20190330061209101025
2019-03-30 06:12:09,137 [salt.minion      :1432][INFO    ][18021] Starting a new job with PID 18021
2019-03-30 06:12:09,161 [salt.minion      :1711][INFO    ][18021] Returning information for job: 20190330061209101025
2019-03-30 06:12:28,645 [salt.utils.schedule:1377][INFO    ][5899] Running scheduled job: __mine_interval
2019-03-30 06:12:34,883 [salt.state       :300 ][INFO    ][16107] {'ret': True}
2019-03-30 06:12:34,883 [salt.state       :1951][INFO    ][16107] Completed state [maas.wait_for_machine_status] at time 06:12:34.883776 duration_in_ms=753650.053
2019-03-30 06:12:34,889 [salt.minion      :1711][INFO    ][16107] Returning information for job: 20190330055951352327
2019-03-30 07:12:28,645 [salt.utils.schedule:1377][INFO    ][5899] Running scheduled job: __mine_interval
2019-03-30 07:57:23,178 [salt.minion      :1308][INFO    ][5899] User sudo_ubuntu Executing command cp.push_dir with jid 20190330075723164418
2019-03-30 07:57:23,198 [salt.minion      :1432][INFO    ][29520] Starting a new job with PID 29520
