2019-06-21 02:04:10,340 [salt.utils.decorators:613 ][WARNING ][1945] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:04:11,382 [salt.utils.decorators:613 ][WARNING ][1945] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:04:15,074 [salt.loaded.int.states.file:2298][WARNING ][2584] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-21 02:04:45,329 [salt.state       :2022][WARNING ][2988] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-21 02:04:48,537 [salt.utils.decorators:613 ][WARNING ][2988] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:05:04,009 [salt.utils.decorators:613 ][WARNING ][2988] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:05:30,934 [salt.utils.decorators:613 ][WARNING ][2988] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:05:32,075 [salt.utils.decorators:613 ][WARNING ][2988] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:05:34,233 [salt.loaded.ext.module.maasng:1008][WARNING ][2988] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-06-21 02:05:34,233 [salt.loaded.ext.module.maasng:1011][WARNING ][2988] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-06-21 02:05:34,908 [salt.loaded.ext.module.maasng:1235][WARNING ][2988] Ignoring parameter vlan:0
2019-06-21 02:05:47,952 [salt.utils.decorators:613 ][WARNING ][5509] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:05:48,005 [salt.loaded.ext.module.maas:412 ][WARNING ][5509] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-21 02:05:49,416 [salt.loaded.ext.module.maas:412 ][WARNING ][5509] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-21 02:05:50,889 [salt.loaded.ext.module.maas:412 ][WARNING ][5509] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-21 02:05:52,101 [salt.loaded.ext.module.maas:412 ][WARNING ][5509] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-21 02:05:53,313 [salt.loaded.ext.module.maas:412 ][WARNING ][5509] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-21 02:05:59,039 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-21 02:05:59,076 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-21 02:05:59,131 [salt.utils.parsers:1051][WARNING ][387] Minion received a SIGTERM. Exiting.
2019-06-21 02:06:00,280 [salt.cli.daemons :293 ][INFO    ][6171] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-06-21 02:06:00,460 [salt.cli.daemons :82  ][INFO    ][6171] Starting up the Salt Minion
2019-06-21 02:06:00,461 [salt.utils.event :1017][INFO    ][6171] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-06-21 02:06:01,770 [salt.minion      :976 ][INFO    ][6171] Creating minion process manager
2019-06-21 02:06:04,028 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6171] Executing command ['date', '+%z'] in directory '/root'
2019-06-21 02:06:04,062 [salt.utils.schedule:568 ][INFO    ][6171] Updating job settings for scheduled job: __mine_interval
2019-06-21 02:06:04,064 [salt.minion      :1108][INFO    ][6171] Added mine.update to scheduler
2019-06-21 02:06:04,073 [salt.minion      :1975][INFO    ][6171] Minion is starting as user 'root'
2019-06-21 02:06:04,098 [salt.minion      :2336][INFO    ][6171] Minion is ready to receive requests!
2019-06-21 02:06:26,144 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command state.apply with jid 20190621020626132962
2019-06-21 02:06:26,166 [salt.minion      :1432][INFO    ][6272] Starting a new job with PID 6272
2019-06-21 02:06:35,164 [salt.state       :915 ][INFO    ][6272] Loading fresh modules for state activity
2019-06-21 02:06:35,220 [salt.fileclient  :1219][INFO    ][6272] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-21 02:06:35,272 [salt.state       :1780][INFO    ][6272] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:35.272493
2019-06-21 02:06:35,273 [salt.state       :1813][INFO    ][6272] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-21 02:06:35,276 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6272] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-21 02:06:37,171 [salt.state       :300 ][INFO    ][6272] {'pid': 6291, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-21 02:06:37,172 [salt.state       :1951][INFO    ][6272] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:37.171949 duration_in_ms=1899.456
2019-06-21 02:06:37,174 [salt.state       :1780][INFO    ][6272] Running state [maas.wait_for_machine_status] at time 02:06:37.174672
2019-06-21 02:06:37,175 [salt.state       :1813][INFO    ][6272] Executing state module.run for [maas.wait_for_machine_status]
2019-06-21 02:06:37,176 [salt.utils.decorators:613 ][WARNING ][6272] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:06:37,965 [salt.loaded.ext.module.maas:1023][INFO    ][6272] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.22357702s left)
2019-06-21 02:06:41,252 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621020641235628
2019-06-21 02:06:41,274 [salt.minion      :1432][INFO    ][6303] Starting a new job with PID 6303
2019-06-21 02:06:41,301 [salt.minion      :1711][INFO    ][6303] Returning information for job: 20190621020641235628
2019-06-21 02:07:08,843 [salt.loaded.ext.module.maas:1023][INFO    ][6272] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.34545684s left)
2019-06-21 02:07:11,349 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621020711336533
2019-06-21 02:07:11,377 [salt.minion      :1432][INFO    ][6348] Starting a new job with PID 6348
2019-06-21 02:07:11,416 [salt.minion      :1711][INFO    ][6348] Returning information for job: 20190621020711336533
2019-06-21 02:07:39,803 [salt.loaded.ext.module.maas:1023][INFO    ][6272] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.38593388s left)
2019-06-21 02:07:41,480 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621020741472041
2019-06-21 02:07:41,504 [salt.minion      :1432][INFO    ][6379] Starting a new job with PID 6379
2019-06-21 02:07:41,533 [salt.minion      :1711][INFO    ][6379] Returning information for job: 20190621020741472041
2019-06-21 02:08:10,665 [salt.loaded.ext.module.maas:1023][INFO    ][6272] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.52380586s left)
2019-06-21 02:08:11,554 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621020811545353
2019-06-21 02:08:11,581 [salt.minion      :1432][INFO    ][6427] Starting a new job with PID 6427
2019-06-21 02:08:11,612 [salt.minion      :1711][INFO    ][6427] Returning information for job: 20190621020811545353
2019-06-21 02:08:41,624 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621020841615384
2019-06-21 02:08:41,645 [salt.minion      :1432][INFO    ][6488] Starting a new job with PID 6488
2019-06-21 02:08:41,674 [salt.minion      :1711][INFO    ][6488] Returning information for job: 20190621020841615384
2019-06-21 02:08:41,791 [salt.loaded.ext.module.maas:1023][INFO    ][6272] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.39751601s left)
2019-06-21 02:09:11,732 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621020911711254
2019-06-21 02:09:11,761 [salt.minion      :1432][INFO    ][6633] Starting a new job with PID 6633
2019-06-21 02:09:11,790 [salt.minion      :1711][INFO    ][6633] Returning information for job: 20190621020911711254
2019-06-21 02:09:12,943 [salt.loaded.ext.module.maas:1023][INFO    ][6272] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.24540305s left)
2019-06-21 02:09:41,865 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621020941848007
2019-06-21 02:09:41,896 [salt.minion      :1432][INFO    ][6721] Starting a new job with PID 6721
2019-06-21 02:09:41,926 [salt.minion      :1711][INFO    ][6721] Returning information for job: 20190621020941848007
2019-06-21 02:09:44,255 [salt.loaded.ext.module.maas:1023][INFO    ][6272] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.93303394s left)
2019-06-21 02:10:11,991 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021011981700
2019-06-21 02:10:12,010 [salt.minion      :1432][INFO    ][7012] Starting a new job with PID 7012
2019-06-21 02:10:12,037 [salt.minion      :1711][INFO    ][7012] Returning information for job: 20190621021011981700
2019-06-21 02:10:15,544 [salt.loaded.ext.module.maas:1023][INFO    ][6272] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.64475989s left)
2019-06-21 02:10:42,115 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021042097017
2019-06-21 02:10:42,142 [salt.minion      :1432][INFO    ][7168] Starting a new job with PID 7168
2019-06-21 02:10:42,176 [salt.minion      :1711][INFO    ][7168] Returning information for job: 20190621021042097017
2019-06-21 02:10:47,433 [salt.loaded.ext.module.maas:1023][INFO    ][6272] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.75556087s left)
2019-06-21 02:11:12,294 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021112269292
2019-06-21 02:11:12,318 [salt.minion      :1432][INFO    ][7421] Starting a new job with PID 7421
2019-06-21 02:11:12,349 [salt.minion      :1711][INFO    ][7421] Returning information for job: 20190621021112269292
2019-06-21 02:11:19,767 [salt.loaded.ext.module.maas:1023][INFO    ][6272] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.42119694s left)
2019-06-21 02:11:42,420 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021142402610
2019-06-21 02:11:42,450 [salt.minion      :1432][INFO    ][7579] Starting a new job with PID 7579
2019-06-21 02:11:42,480 [salt.minion      :1711][INFO    ][7579] Returning information for job: 20190621021142402610
2019-06-21 02:11:52,367 [salt.loaded.ext.module.maas:1023][INFO    ][6272] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.82111192s left)
2019-06-21 02:12:12,580 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021212563412
2019-06-21 02:12:12,610 [salt.minion      :1432][INFO    ][7759] Starting a new job with PID 7759
2019-06-21 02:12:12,639 [salt.minion      :1711][INFO    ][7759] Returning information for job: 20190621021212563412
2019-06-21 02:12:25,530 [salt.loaded.ext.module.maas:1023][INFO    ][6272] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:1500s (1151.6587379s left)
2019-06-21 02:12:42,732 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021242717320
2019-06-21 02:12:42,761 [salt.minion      :1432][INFO    ][7879] Starting a new job with PID 7879
2019-06-21 02:12:42,789 [salt.minion      :1711][INFO    ][7879] Returning information for job: 20190621021242717320
2019-06-21 02:12:58,459 [salt.state       :300 ][INFO    ][6272] {'ret': True}
2019-06-21 02:12:58,460 [salt.state       :1951][INFO    ][6272] Completed state [maas.wait_for_machine_status] at time 02:12:58.460314 duration_in_ms=381285.641
2019-06-21 02:12:58,465 [salt.minion      :1711][INFO    ][6272] Returning information for job: 20190621020626132962
2019-06-21 02:12:59,252 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command state.apply with jid 20190621021259235419
2019-06-21 02:12:59,278 [salt.minion      :1432][INFO    ][7949] Starting a new job with PID 7949
2019-06-21 02:13:07,621 [salt.state       :915 ][INFO    ][7949] Loading fresh modules for state activity
2019-06-21 02:13:07,689 [salt.fileclient  :1219][INFO    ][7949] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-21 02:13:07,798 [salt.state       :1780][INFO    ][7949] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:07.798629
2019-06-21 02:13:07,799 [salt.state       :1813][INFO    ][7949] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-21 02:13:07,803 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7949] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-21 02:13:09,695 [salt.state       :300 ][INFO    ][7949] {'pid': 7960, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-21 02:13:09,696 [salt.state       :1951][INFO    ][7949] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:09.696204 duration_in_ms=1897.576
2019-06-21 02:13:09,702 [salt.state       :1780][INFO    ][7949] Running state [maas_machines_storage_cmp002_lvm] at time 02:13:09.702854
2019-06-21 02:13:09,703 [salt.state       :1813][INFO    ][7949] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-21 02:13:10,991 [salt.loaded.ext.module.maasng:610 ][INFO    ][7949] rdndx8
2019-06-21 02:13:10,992 [salt.loaded.ext.module.maasng:626 ][INFO    ][7949] sda
2019-06-21 02:13:11,596 [salt.loaded.ext.module.maasng:361 ][INFO    ][7949] rdndx8
2019-06-21 02:13:11,698 [salt.loaded.ext.module.maasng:367 ][INFO    ][7949] [{u'size': 800109715456, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'cd5e3d4c-71bf-4411-85d2-5ed9cb19f69f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rdndx8', u'filesystem': {u'uuid': u'502379f8-d27a-47d0-98d4-0a2bc94a7481', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/blockdevices/1/partition/1'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rdndx8', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/blockdevices/1/'}, {u'size': 800097042432, u'available_size': 0, u'uuid': u'38e3bb71-95ad-43e0-b46b-e61708db0280', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rdndx8', u'partition_table_type': None, u'filesystem': {u'uuid': u'ea3480ca-7add-43b2-9462-0700bc1d9761', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 3, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/blockdevices/3/'}]
2019-06-21 02:13:11,698 [salt.loaded.ext.module.maasng:632 ][INFO    ][7949] vgroot
2019-06-21 02:13:11,699 [salt.loaded.ext.module.maasng:635 ][INFO    ][7949] lvroot
2019-06-21 02:13:11,699 [salt.loaded.ext.module.maasng:639 ][INFO    ][7949] 107374182400
2019-06-21 02:13:12,325 [salt.loaded.ext.module.maasng:645 ][INFO    ][7949] {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'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/blockdevices/1/', u'system_id': u'rdndx8', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'929b6da5-5832-451c-badb-3498c7d04506', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rdndx8', u'filesystem': {u'uuid': u'55508fcd-9317-4013-b9ee-4fd2a792b2b3', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}]}, u'blockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/blockdevices/1/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'rdndx8', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'929b6da5-5832-451c-badb-3498c7d04506', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rdndx8', u'filesystem': {u'uuid': u'55508fcd-9317-4013-b9ee-4fd2a792b2b3', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}]}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 11, u'uuid': u'69d9eedf-5712-4bc6-8241-e50940bde20f', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/blockdevices/11/', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'rdndx8', u'partition_table_type': None, u'filesystem': {u'uuid': u'b1adffd7-2eca-4cfa-9ecd-f0ad1518bf53', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'type': u'virtual', u'serial': None, u'partitions': []}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'address_ttl': None, u'system_id': u'rdndx8', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 11, u'uuid': u'69d9eedf-5712-4bc6-8241-e50940bde20f', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/blockdevices/11/', u'system_id': u'rdndx8', u'partition_table_type': None, u'filesystem': {u'uuid': u'b1adffd7-2eca-4cfa-9ecd-f0ad1518bf53', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'type': u'virtual', u'serial': None, u'partitions': []}], u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/blockdevices/1/', u'system_id': u'rdndx8', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'929b6da5-5832-451c-badb-3498c7d04506', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rdndx8', u'filesystem': {u'uuid': u'55508fcd-9317-4013-b9ee-4fd2a792b2b3', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}]}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'rdndx8', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', 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'status_action': u'', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n7kf4t', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n7kf4t', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n7kf4t', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'system_id': u'rdndx8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n7kf4t', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n7kf4t', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n7kf4t', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'system_id': u'rdndx8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'system_id': u'rdndx8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/interfaces/13/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 14, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'system_id': u'rdndx8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n7kf4t', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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': 19}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n7kf4t', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'id': 15, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n7kf4t', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'system_id': u'rdndx8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/interfaces/15/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'system_id': u'rdndx8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/interfaces/11/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'system_id': u'rdndx8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rdndx8/interfaces/12/'}], u'commissioning_status_name': u'Passed', u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/rdndx8/'}
2019-06-21 02:13:12,329 [salt.state       :300 ][INFO    ][7949] {'new': {'storage_layout': 'lvm'}}
2019-06-21 02:13:12,330 [salt.state       :1951][INFO    ][7949] Completed state [maas_machines_storage_cmp002_lvm] at time 02:13:12.330262 duration_in_ms=2627.406
2019-06-21 02:13:12,331 [salt.state       :1780][INFO    ][7949] Running state [maas_machines_storage_cmp001_lvm] at time 02:13:12.330978
2019-06-21 02:13:12,331 [salt.state       :1813][INFO    ][7949] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-21 02:13:13,448 [salt.loaded.ext.module.maasng:610 ][INFO    ][7949] r4rxfc
2019-06-21 02:13:13,449 [salt.loaded.ext.module.maasng:626 ][INFO    ][7949] sda
2019-06-21 02:13:14,012 [salt.loaded.ext.module.maasng:361 ][INFO    ][7949] r4rxfc
2019-06-21 02:13:14,102 [salt.loaded.ext.module.maasng:367 ][INFO    ][7949] [{u'size': 800109715456, u'partition_table_type': u'MBR', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'r4rxfc', u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'911d64f0-49a7-4d5b-99ea-5c9cde81d34f', u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r4rxfc', u'filesystem': {u'uuid': u'decd81c5-00fc-4a6c-aa4b-9de43ab5957e', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}, {u'size': 800097042432, u'partition_table_type': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'c2e9d8d9-1a8b-4ef5-a067-fa67954df785', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'r4rxfc', u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/blockdevices/4/', u'filesystem': {u'uuid': u'6b88deca-a39e-4b12-881c-9a4630e48014', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'partitions': [], u'used_size': 800097042432, u'id': 4, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-06-21 02:13:14,103 [salt.loaded.ext.module.maasng:632 ][INFO    ][7949] vgroot
2019-06-21 02:13:14,103 [salt.loaded.ext.module.maasng:635 ][INFO    ][7949] lvroot
2019-06-21 02:13:14,103 [salt.loaded.ext.module.maasng:639 ][INFO    ][7949] 107374182400
2019-06-21 02:13:14,369 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021314355595
2019-06-21 02:13:14,389 [salt.minion      :1432][INFO    ][7977] Starting a new job with PID 7977
2019-06-21 02:13:14,419 [salt.minion      :1711][INFO    ][7977] Returning information for job: 20190621021314355595
2019-06-21 02:13:14,714 [salt.loaded.ext.module.maasng:645 ][INFO    ][7949] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'r4rxfc', 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'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/blockdevices/12/', u'available_size': 0, u'uuid': u'b8416604-0908-469d-a6d1-5c333e338b17', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'r4rxfc', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'uuid': u'd26d5c15-2e81-4860-8c5e-a2c99cba63b2', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'partitions': [], u'type': u'virtual', u'id': 12, u'serial': None, u'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'size': 800109715456, u'partition_table_type': u'MBR', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'r4rxfc', u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'5d7f04f7-e671-4b9a-8d8e-c2727e07165f', u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r4rxfc', u'filesystem': {u'uuid': u'd9f8524a-671d-47b0-9b96-28d8a975ac8e', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}, {u'size': 107374182400, u'partition_table_type': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'b8416604-0908-469d-a6d1-5c333e338b17', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'r4rxfc', u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/blockdevices/12/', u'filesystem': {u'uuid': u'd26d5c15-2e81-4860-8c5e-a2c99cba63b2', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 12, u'serial': None, u'name': u'vgroot-lvroot'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'r4rxfc', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/blockdevices/2/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'r4rxfc', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'5d7f04f7-e671-4b9a-8d8e-c2727e07165f', u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r4rxfc', u'filesystem': {u'uuid': u'd9f8524a-671d-47b0-9b96-28d8a975ac8e', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}, 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'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/blockdevices/2/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'r4rxfc', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'5d7f04f7-e671-4b9a-8d8e-c2727e07165f', u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r4rxfc', u'filesystem': {u'uuid': u'd9f8524a-671d-47b0-9b96-28d8a975ac8e', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n7kf4t', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n7kf4t', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n7kf4t', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'r4rxfc', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/interfaces/5/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n7kf4t', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n7kf4t', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n7kf4t', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'r4rxfc', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'r4rxfc', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/interfaces/16/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'r4rxfc', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/interfaces/20/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'r4rxfc', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/interfaces/17/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n7kf4t', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n7kf4t', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n7kf4t', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'r4rxfc', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'r4rxfc', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/r4rxfc/interfaces/19/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/r4rxfc/'}
2019-06-21 02:13:14,716 [salt.state       :300 ][INFO    ][7949] {'new': {'storage_layout': 'lvm'}}
2019-06-21 02:13:14,717 [salt.state       :1951][INFO    ][7949] Completed state [maas_machines_storage_cmp001_lvm] at time 02:13:14.717198 duration_in_ms=2386.219
2019-06-21 02:13:14,720 [salt.minion      :1711][INFO    ][7949] Returning information for job: 20190621021259235419
2019-06-21 02:13:15,451 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command state.apply with jid 20190621021315436939
2019-06-21 02:13:15,478 [salt.minion      :1432][INFO    ][7984] Starting a new job with PID 7984
2019-06-21 02:13:16,655 [salt.state       :915 ][INFO    ][7984] Loading fresh modules for state activity
2019-06-21 02:13:16,717 [salt.fileclient  :1219][INFO    ][7984] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-21 02:13:16,774 [salt.state       :1780][INFO    ][7984] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:16.774601
2019-06-21 02:13:16,774 [salt.state       :1813][INFO    ][7984] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-21 02:13:16,776 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7984] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-21 02:13:18,718 [salt.state       :300 ][INFO    ][7984] {'pid': 7993, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-21 02:13:18,719 [salt.state       :1951][INFO    ][7984] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:18.719687 duration_in_ms=1945.085
2019-06-21 02:13:18,724 [salt.state       :1780][INFO    ][7984] Running state [maas.deploy_machines] at time 02:13:18.724254
2019-06-21 02:13:18,724 [salt.state       :1813][INFO    ][7984] Executing state module.run for [maas.deploy_machines]
2019-06-21 02:13:18,725 [salt.utils.decorators:613 ][WARNING ][7984] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:13:19,303 [salt.loaded.ext.module.maas:684 ][INFO    ][7984] deploymachines hwe_kernel=ga-16.04 system_id=rdndx8 distro_series=xenial
2019-06-21 02:13:21,716 [salt.loaded.ext.module.maas:684 ][INFO    ][7984] deploymachines hwe_kernel=ga-16.04 system_id=r4rxfc distro_series=xenial
2019-06-21 02:13:24,114 [salt.loaded.ext.module.maas:684 ][INFO    ][7984] deploymachines hwe_kernel=ga-16.04 system_id=7bfp8s distro_series=xenial
2019-06-21 02:13:26,445 [salt.loaded.ext.module.maas:684 ][INFO    ][7984] deploymachines hwe_kernel=ga-16.04 system_id=c3a86t distro_series=xenial
2019-06-21 02:13:29,000 [salt.loaded.ext.module.maas:684 ][INFO    ][7984] deploymachines hwe_kernel=ga-16.04 system_id=6rwp4t distro_series=xenial
2019-06-21 02:13:30,512 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021330499115
2019-06-21 02:13:30,536 [salt.minion      :1432][INFO    ][8251] Starting a new job with PID 8251
2019-06-21 02:13:30,564 [salt.minion      :1711][INFO    ][8251] Returning information for job: 20190621021330499115
2019-06-21 02:13:31,580 [salt.state       :300 ][INFO    ][7984] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-21 02:13:31,581 [salt.state       :1951][INFO    ][7984] Completed state [maas.deploy_machines] at time 02:13:31.581295 duration_in_ms=12857.039
2019-06-21 02:13:31,584 [salt.minion      :1711][INFO    ][7984] Returning information for job: 20190621021315436939
2019-06-21 02:13:32,325 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command state.apply with jid 20190621021332312319
2019-06-21 02:13:32,350 [salt.minion      :1432][INFO    ][8282] Starting a new job with PID 8282
2019-06-21 02:13:40,756 [salt.state       :915 ][INFO    ][8282] Loading fresh modules for state activity
2019-06-21 02:13:40,815 [salt.fileclient  :1219][INFO    ][8282] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-21 02:13:40,874 [salt.state       :1780][INFO    ][8282] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:40.874683
2019-06-21 02:13:40,875 [salt.state       :1813][INFO    ][8282] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-21 02:13:40,876 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8282] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-21 02:13:42,873 [salt.state       :300 ][INFO    ][8282] {'pid': 8299, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-21 02:13:42,875 [salt.state       :1951][INFO    ][8282] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:42.875027 duration_in_ms=2000.344
2019-06-21 02:13:42,880 [salt.state       :1780][INFO    ][8282] Running state [maas.wait_for_machine_status] at time 02:13:42.880225
2019-06-21 02:13:42,880 [salt.state       :1813][INFO    ][8282] Executing state module.run for [maas.wait_for_machine_status]
2019-06-21 02:13:42,881 [salt.utils.decorators:613 ][WARNING ][8282] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:13:45,964 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.94041204s left)
2019-06-21 02:13:47,405 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021347388188
2019-06-21 02:13:47,435 [salt.minion      :1432][INFO    ][8310] Starting a new job with PID 8310
2019-06-21 02:13:47,464 [salt.minion      :1711][INFO    ][8310] Returning information for job: 20190621021347388188
2019-06-21 02:14:17,504 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021417493920
2019-06-21 02:14:17,525 [salt.minion      :1432][INFO    ][8359] Starting a new job with PID 8359
2019-06-21 02:14:17,555 [salt.minion      :1711][INFO    ][8359] Returning information for job: 20190621021417493920
2019-06-21 02:14:18,880 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.02445292s left)
2019-06-21 02:14:47,601 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021447591826
2019-06-21 02:14:47,628 [salt.minion      :1432][INFO    ][8406] Starting a new job with PID 8406
2019-06-21 02:14:47,659 [salt.minion      :1711][INFO    ][8406] Returning information for job: 20190621021447591826
2019-06-21 02:14:52,028 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.87648296s left)
2019-06-21 02:15:17,680 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021517671406
2019-06-21 02:15:17,706 [salt.minion      :1432][INFO    ][8462] Starting a new job with PID 8462
2019-06-21 02:15:17,734 [salt.minion      :1711][INFO    ][8462] Returning information for job: 20190621021517671406
2019-06-21 02:15:24,896 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.00783491s left)
2019-06-21 02:15:47,767 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021547751271
2019-06-21 02:15:47,796 [salt.minion      :1432][INFO    ][8491] Starting a new job with PID 8491
2019-06-21 02:15:47,824 [salt.minion      :1711][INFO    ][8491] Returning information for job: 20190621021547751271
2019-06-21 02:15:57,971 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.93319893s left)
2019-06-21 02:16:17,852 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021617832436
2019-06-21 02:16:17,881 [salt.minion      :1432][INFO    ][8586] Starting a new job with PID 8586
2019-06-21 02:16:17,928 [salt.minion      :1711][INFO    ][8586] Returning information for job: 20190621021617832436
2019-06-21 02:16:30,918 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.98557305s left)
2019-06-21 02:16:47,983 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021647972619
2019-06-21 02:16:48,010 [salt.minion      :1432][INFO    ][8668] Starting a new job with PID 8668
2019-06-21 02:16:48,037 [salt.minion      :1711][INFO    ][8668] Returning information for job: 20190621021647972619
2019-06-21 02:17:03,796 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.10799289s left)
2019-06-21 02:17:18,129 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021718112485
2019-06-21 02:17:18,162 [salt.minion      :1432][INFO    ][8877] Starting a new job with PID 8877
2019-06-21 02:17:18,197 [salt.minion      :1711][INFO    ][8877] Returning information for job: 20190621021718112485
2019-06-21 02:17:36,703 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.20145988s left)
2019-06-21 02:17:48,209 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021748195606
2019-06-21 02:17:48,234 [salt.minion      :1432][INFO    ][8950] Starting a new job with PID 8950
2019-06-21 02:17:48,263 [salt.minion      :1711][INFO    ][8950] Returning information for job: 20190621021748195606
2019-06-21 02:18:09,923 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.98111105s left)
2019-06-21 02:18:18,360 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021818342066
2019-06-21 02:18:18,394 [salt.minion      :1432][INFO    ][9173] Starting a new job with PID 9173
2019-06-21 02:18:18,427 [salt.minion      :1711][INFO    ][9173] Returning information for job: 20190621021818342066
2019-06-21 02:18:43,122 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.78227496s left)
2019-06-21 02:18:48,488 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021848468147
2019-06-21 02:18:48,517 [salt.minion      :1432][INFO    ][9239] Starting a new job with PID 9239
2019-06-21 02:18:48,550 [salt.minion      :1711][INFO    ][9239] Returning information for job: 20190621021848468147
2019-06-21 02:19:16,157 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.74703908s left)
2019-06-21 02:19:18,681 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021918667732
2019-06-21 02:19:18,708 [salt.minion      :1432][INFO    ][9625] Starting a new job with PID 9625
2019-06-21 02:19:18,748 [salt.minion      :1711][INFO    ][9625] Returning information for job: 20190621021918667732
2019-06-21 02:19:48,826 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621021948811175
2019-06-21 02:19:48,850 [salt.minion      :1432][INFO    ][9691] Starting a new job with PID 9691
2019-06-21 02:19:48,889 [salt.minion      :1711][INFO    ][9691] Returning information for job: 20190621021948811175
2019-06-21 02:19:49,451 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.4526279s left)
2019-06-21 02:20:19,023 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022019007549
2019-06-21 02:20:19,049 [salt.minion      :1432][INFO    ][9952] Starting a new job with PID 9952
2019-06-21 02:20:19,079 [salt.minion      :1711][INFO    ][9952] Returning information for job: 20190621022019007549
2019-06-21 02:20:22,456 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.44854093s left)
2019-06-21 02:20:49,172 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022049158053
2019-06-21 02:20:49,198 [salt.minion      :1432][INFO    ][10032] Starting a new job with PID 10032
2019-06-21 02:20:49,259 [salt.minion      :1711][INFO    ][10032] Returning information for job: 20190621022049158053
2019-06-21 02:20:55,351 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.55276108s left)
2019-06-21 02:21:19,205 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022119187173
2019-06-21 02:21:19,232 [salt.minion      :1432][INFO    ][10342] Starting a new job with PID 10342
2019-06-21 02:21:19,267 [salt.minion      :1711][INFO    ][10342] Returning information for job: 20190621022119187173
2019-06-21 02:21:28,512 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.39175105s left)
2019-06-21 02:21:49,402 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022149379362
2019-06-21 02:21:49,437 [salt.minion      :1432][INFO    ][10483] Starting a new job with PID 10483
2019-06-21 02:21:49,495 [salt.minion      :1711][INFO    ][10483] Returning information for job: 20190621022149379362
2019-06-21 02:22:01,515 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1751.38900709s left)
2019-06-21 02:22:19,421 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022219406661
2019-06-21 02:22:19,448 [salt.minion      :1432][INFO    ][10709] Starting a new job with PID 10709
2019-06-21 02:22:19,479 [salt.minion      :1711][INFO    ][10709] Returning information for job: 20190621022219406661
2019-06-21 02:22:34,432 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1718.47224092s left)
2019-06-21 02:22:49,606 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022249591591
2019-06-21 02:22:49,632 [salt.minion      :1432][INFO    ][10762] Starting a new job with PID 10762
2019-06-21 02:22:49,664 [salt.minion      :1711][INFO    ][10762] Returning information for job: 20190621022249591591
2019-06-21 02:23:07,265 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1685.63893008s left)
2019-06-21 02:23:19,762 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022319750145
2019-06-21 02:23:19,783 [salt.minion      :1432][INFO    ][10823] Starting a new job with PID 10823
2019-06-21 02:23:19,811 [salt.minion      :1711][INFO    ][10823] Returning information for job: 20190621022319750145
2019-06-21 02:23:40,227 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.67725992s left)
2019-06-21 02:23:49,809 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022349796199
2019-06-21 02:23:49,836 [salt.minion      :1432][INFO    ][10957] Starting a new job with PID 10957
2019-06-21 02:23:49,873 [salt.minion      :1711][INFO    ][10957] Returning information for job: 20190621022349796199
2019-06-21 02:24:13,181 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1619.72342491s left)
2019-06-21 02:24:19,963 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022419951481
2019-06-21 02:24:19,991 [salt.minion      :1432][INFO    ][11022] Starting a new job with PID 11022
2019-06-21 02:24:20,023 [salt.minion      :1711][INFO    ][11022] Returning information for job: 20190621022419951481
2019-06-21 02:24:46,325 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1586.57913589s left)
2019-06-21 02:24:50,165 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022450152100
2019-06-21 02:24:50,191 [salt.minion      :1432][INFO    ][11341] Starting a new job with PID 11341
2019-06-21 02:24:50,229 [salt.minion      :1711][INFO    ][11341] Returning information for job: 20190621022450152100
2019-06-21 02:25:19,222 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1553.682019s left)
2019-06-21 02:25:20,368 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022520352605
2019-06-21 02:25:20,393 [salt.minion      :1432][INFO    ][11376] Starting a new job with PID 11376
2019-06-21 02:25:20,423 [salt.minion      :1711][INFO    ][11376] Returning information for job: 20190621022520352605
2019-06-21 02:25:50,569 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022550559601
2019-06-21 02:25:50,598 [salt.minion      :1432][INFO    ][11472] Starting a new job with PID 11472
2019-06-21 02:25:50,631 [salt.minion      :1711][INFO    ][11472] Returning information for job: 20190621022550559601
2019-06-21 02:25:52,281 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1520.62336087s left)
2019-06-21 02:26:20,800 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022620785271
2019-06-21 02:26:20,827 [salt.minion      :1432][INFO    ][11527] Starting a new job with PID 11527
2019-06-21 02:26:20,868 [salt.minion      :1711][INFO    ][11527] Returning information for job: 20190621022620785271
2019-06-21 02:26:25,338 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1487.56593394s left)
2019-06-21 02:26:50,864 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022650851897
2019-06-21 02:26:50,891 [salt.minion      :1432][INFO    ][11797] Starting a new job with PID 11797
2019-06-21 02:26:50,928 [salt.minion      :1711][INFO    ][11797] Returning information for job: 20190621022650851897
2019-06-21 02:26:58,315 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1454.58940887s left)
2019-06-21 02:27:20,899 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022720885708
2019-06-21 02:27:20,926 [salt.minion      :1432][INFO    ][11827] Starting a new job with PID 11827
2019-06-21 02:27:20,960 [salt.minion      :1711][INFO    ][11827] Returning information for job: 20190621022720885708
2019-06-21 02:27:31,205 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1421.69880199s left)
2019-06-21 02:27:50,921 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022750907998
2019-06-21 02:27:50,946 [salt.minion      :1432][INFO    ][11881] Starting a new job with PID 11881
2019-06-21 02:27:50,975 [salt.minion      :1711][INFO    ][11881] Returning information for job: 20190621022750907998
2019-06-21 02:28:04,379 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1388.52480102s left)
2019-06-21 02:28:20,984 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022820972640
2019-06-21 02:28:21,016 [salt.minion      :1432][INFO    ][11905] Starting a new job with PID 11905
2019-06-21 02:28:21,048 [salt.minion      :1711][INFO    ][11905] Returning information for job: 20190621022820972640
2019-06-21 02:28:37,288 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1355.61627102s left)
2019-06-21 02:28:51,029 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022851013352
2019-06-21 02:28:51,055 [salt.minion      :1432][INFO    ][11964] Starting a new job with PID 11964
2019-06-21 02:28:51,089 [salt.minion      :1711][INFO    ][11964] Returning information for job: 20190621022851013352
2019-06-21 02:29:10,088 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1322.81591105s left)
2019-06-21 02:29:21,101 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022921087482
2019-06-21 02:29:21,130 [salt.minion      :1432][INFO    ][11995] Starting a new job with PID 11995
2019-06-21 02:29:21,159 [salt.minion      :1711][INFO    ][11995] Returning information for job: 20190621022921087482
2019-06-21 02:29:43,019 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1289.8851409s left)
2019-06-21 02:29:51,172 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621022951161127
2019-06-21 02:29:51,192 [salt.minion      :1432][INFO    ][12051] Starting a new job with PID 12051
2019-06-21 02:29:51,221 [salt.minion      :1711][INFO    ][12051] Returning information for job: 20190621022951161127
2019-06-21 02:30:15,883 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1257.02146292s left)
2019-06-21 02:30:21,247 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023021228880
2019-06-21 02:30:21,275 [salt.minion      :1432][INFO    ][12071] Starting a new job with PID 12071
2019-06-21 02:30:21,307 [salt.minion      :1711][INFO    ][12071] Returning information for job: 20190621023021228880
2019-06-21 02:30:48,691 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1224.21307492s left)
2019-06-21 02:30:51,355 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023051340469
2019-06-21 02:30:51,382 [salt.minion      :1432][INFO    ][12137] Starting a new job with PID 12137
2019-06-21 02:30:51,411 [salt.minion      :1711][INFO    ][12137] Returning information for job: 20190621023051340469
2019-06-21 02:31:21,405 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1191.49903393s left)
2019-06-21 02:31:21,472 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023121456733
2019-06-21 02:31:21,502 [salt.minion      :1432][INFO    ][12158] Starting a new job with PID 12158
2019-06-21 02:31:21,531 [salt.minion      :1711][INFO    ][12158] Returning information for job: 20190621023121456733
2019-06-21 02:31:51,598 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023151589589
2019-06-21 02:31:51,622 [salt.minion      :1432][INFO    ][12212] Starting a new job with PID 12212
2019-06-21 02:31:51,651 [salt.minion      :1711][INFO    ][12212] Returning information for job: 20190621023151589589
2019-06-21 02:31:54,369 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1158.53470206s left)
2019-06-21 02:32:21,744 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023221726511
2019-06-21 02:32:21,775 [salt.minion      :1432][INFO    ][12236] Starting a new job with PID 12236
2019-06-21 02:32:21,798 [salt.minion      :1711][INFO    ][12236] Returning information for job: 20190621023221726511
2019-06-21 02:32:27,137 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1125.76668596s left)
2019-06-21 02:32:51,899 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023251882950
2019-06-21 02:32:51,929 [salt.minion      :1432][INFO    ][12292] Starting a new job with PID 12292
2019-06-21 02:32:51,956 [salt.minion      :1711][INFO    ][12292] Returning information for job: 20190621023251882950
2019-06-21 02:33:00,061 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1092.84296894s left)
2019-06-21 02:33:22,056 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023322039011
2019-06-21 02:33:22,086 [salt.minion      :1432][INFO    ][12314] Starting a new job with PID 12314
2019-06-21 02:33:22,111 [salt.minion      :1711][INFO    ][12314] Returning information for job: 20190621023322039011
2019-06-21 02:33:32,963 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1059.94063997s left)
2019-06-21 02:33:52,242 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023352228286
2019-06-21 02:33:52,271 [salt.minion      :1432][INFO    ][12370] Starting a new job with PID 12370
2019-06-21 02:33:52,295 [salt.minion      :1711][INFO    ][12370] Returning information for job: 20190621023352228286
2019-06-21 02:34:05,881 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1027.02302504s left)
2019-06-21 02:34:22,447 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023422431277
2019-06-21 02:34:22,473 [salt.minion      :1432][INFO    ][12392] Starting a new job with PID 12392
2019-06-21 02:34:22,497 [salt.minion      :1711][INFO    ][12392] Returning information for job: 20190621023422431277
2019-06-21 02:34:38,650 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (994.25383091s left)
2019-06-21 02:34:52,660 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023452643704
2019-06-21 02:34:52,686 [salt.minion      :1432][INFO    ][12465] Starting a new job with PID 12465
2019-06-21 02:34:52,709 [salt.minion      :1711][INFO    ][12465] Returning information for job: 20190621023452643704
2019-06-21 02:35:11,568 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (961.335890055s left)
2019-06-21 02:35:22,848 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023522840648
2019-06-21 02:35:22,871 [salt.minion      :1432][INFO    ][12490] Starting a new job with PID 12490
2019-06-21 02:35:22,895 [salt.minion      :1711][INFO    ][12490] Returning information for job: 20190621023522840648
2019-06-21 02:35:44,423 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (928.481061935s left)
2019-06-21 02:35:52,880 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023552863784
2019-06-21 02:35:52,908 [salt.minion      :1432][INFO    ][12548] Starting a new job with PID 12548
2019-06-21 02:35:52,930 [salt.minion      :1711][INFO    ][12548] Returning information for job: 20190621023552863784
2019-06-21 02:36:17,249 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (895.655427933s left)
2019-06-21 02:36:22,927 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023622908554
2019-06-21 02:36:22,957 [salt.minion      :1432][INFO    ][12571] Starting a new job with PID 12571
2019-06-21 02:36:22,987 [salt.minion      :1711][INFO    ][12571] Returning information for job: 20190621023622908554
2019-06-21 02:36:50,215 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (862.688920975s left)
2019-06-21 02:36:52,965 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023652951697
2019-06-21 02:36:52,996 [salt.minion      :1432][INFO    ][12629] Starting a new job with PID 12629
2019-06-21 02:36:53,034 [salt.minion      :1711][INFO    ][12629] Returning information for job: 20190621023652951697
2019-06-21 02:37:23,059 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023723043727
2019-06-21 02:37:23,082 [salt.minion      :1432][INFO    ][12654] Starting a new job with PID 12654
2019-06-21 02:37:23,107 [salt.minion      :1711][INFO    ][12654] Returning information for job: 20190621023723043727
2019-06-21 02:37:23,169 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (829.735393047s left)
2019-06-21 02:37:53,172 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023753160317
2019-06-21 02:37:53,201 [salt.minion      :1432][INFO    ][12705] Starting a new job with PID 12705
2019-06-21 02:37:53,230 [salt.minion      :1711][INFO    ][12705] Returning information for job: 20190621023753160317
2019-06-21 02:37:56,060 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (796.844387054s left)
2019-06-21 02:38:23,264 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023823249038
2019-06-21 02:38:23,293 [salt.minion      :1432][INFO    ][12727] Starting a new job with PID 12727
2019-06-21 02:38:23,320 [salt.minion      :1711][INFO    ][12727] Returning information for job: 20190621023823249038
2019-06-21 02:38:28,764 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (764.140186071s left)
2019-06-21 02:38:53,386 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023853367598
2019-06-21 02:38:53,418 [salt.minion      :1432][INFO    ][12782] Starting a new job with PID 12782
2019-06-21 02:38:53,441 [salt.minion      :1711][INFO    ][12782] Returning information for job: 20190621023853367598
2019-06-21 02:39:01,590 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (731.314321041s left)
2019-06-21 02:39:23,539 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023923522622
2019-06-21 02:39:23,565 [salt.minion      :1432][INFO    ][12810] Starting a new job with PID 12810
2019-06-21 02:39:23,588 [salt.minion      :1711][INFO    ][12810] Returning information for job: 20190621023923522622
2019-06-21 02:39:34,377 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (698.527568102s left)
2019-06-21 02:39:53,682 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621023953666520
2019-06-21 02:39:53,710 [salt.minion      :1432][INFO    ][12863] Starting a new job with PID 12863
2019-06-21 02:39:53,742 [salt.minion      :1711][INFO    ][12863] Returning information for job: 20190621023953666520
2019-06-21 02:40:07,320 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (665.583986998s left)
2019-06-21 02:40:23,896 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024023880985
2019-06-21 02:40:23,927 [salt.minion      :1432][INFO    ][12886] Starting a new job with PID 12886
2019-06-21 02:40:23,948 [salt.minion      :1711][INFO    ][12886] Returning information for job: 20190621024023880985
2019-06-21 02:40:40,193 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (632.711172104s left)
2019-06-21 02:40:54,104 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024054088345
2019-06-21 02:40:54,135 [salt.minion      :1432][INFO    ][12939] Starting a new job with PID 12939
2019-06-21 02:40:54,157 [salt.minion      :1711][INFO    ][12939] Returning information for job: 20190621024054088345
2019-06-21 02:41:13,065 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (599.839349031s left)
2019-06-21 02:41:24,163 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024124147984
2019-06-21 02:41:24,193 [salt.minion      :1432][INFO    ][12961] Starting a new job with PID 12961
2019-06-21 02:41:24,222 [salt.minion      :1711][INFO    ][12961] Returning information for job: 20190621024124147984
2019-06-21 02:41:45,832 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (567.071563959s left)
2019-06-21 02:41:54,175 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024154166339
2019-06-21 02:41:54,199 [salt.minion      :1432][INFO    ][13013] Starting a new job with PID 13013
2019-06-21 02:41:54,222 [salt.minion      :1711][INFO    ][13013] Returning information for job: 20190621024154166339
2019-06-21 02:42:18,800 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (534.10412693s left)
2019-06-21 02:42:24,220 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024224204469
2019-06-21 02:42:24,254 [salt.minion      :1432][INFO    ][13036] Starting a new job with PID 13036
2019-06-21 02:42:24,277 [salt.minion      :1711][INFO    ][13036] Returning information for job: 20190621024224204469
2019-06-21 02:42:51,749 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (501.155107021s left)
2019-06-21 02:42:54,319 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024254307280
2019-06-21 02:42:54,344 [salt.minion      :1432][INFO    ][13088] Starting a new job with PID 13088
2019-06-21 02:42:54,368 [salt.minion      :1711][INFO    ][13088] Returning information for job: 20190621024254307280
2019-06-21 02:43:24,360 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024324346293
2019-06-21 02:43:24,388 [salt.minion      :1432][INFO    ][13111] Starting a new job with PID 13111
2019-06-21 02:43:24,409 [salt.minion      :1711][INFO    ][13111] Returning information for job: 20190621024324346293
2019-06-21 02:43:24,548 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (468.356534004s left)
2019-06-21 02:43:54,507 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024354487672
2019-06-21 02:43:54,536 [salt.minion      :1432][INFO    ][13159] Starting a new job with PID 13159
2019-06-21 02:43:54,559 [salt.minion      :1711][INFO    ][13159] Returning information for job: 20190621024354487672
2019-06-21 02:43:57,451 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (435.45284605s left)
2019-06-21 02:44:24,613 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024424600013
2019-06-21 02:44:24,643 [salt.minion      :1432][INFO    ][13180] Starting a new job with PID 13180
2019-06-21 02:44:24,668 [salt.minion      :1711][INFO    ][13180] Returning information for job: 20190621024424600013
2019-06-21 02:44:30,280 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (402.624013901s left)
2019-06-21 02:44:54,786 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024454772910
2019-06-21 02:44:54,814 [salt.minion      :1432][INFO    ][13380] Starting a new job with PID 13380
2019-06-21 02:44:54,841 [salt.minion      :1711][INFO    ][13380] Returning information for job: 20190621024454772910
2019-06-21 02:45:03,068 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (369.836257935s left)
2019-06-21 02:45:24,946 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024524934159
2019-06-21 02:45:24,973 [salt.minion      :1432][INFO    ][13406] Starting a new job with PID 13406
2019-06-21 02:45:24,997 [salt.minion      :1711][INFO    ][13406] Returning information for job: 20190621024524934159
2019-06-21 02:45:35,764 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (337.139967918s left)
2019-06-21 02:45:55,167 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024555151682
2019-06-21 02:45:55,197 [salt.minion      :1432][INFO    ][13463] Starting a new job with PID 13463
2019-06-21 02:45:55,221 [salt.minion      :1711][INFO    ][13463] Returning information for job: 20190621024555151682
2019-06-21 02:46:08,836 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (304.068319082s left)
2019-06-21 02:46:25,362 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024625347695
2019-06-21 02:46:25,390 [salt.minion      :1432][INFO    ][13486] Starting a new job with PID 13486
2019-06-21 02:46:25,413 [salt.minion      :1711][INFO    ][13486] Returning information for job: 20190621024625347695
2019-06-21 02:46:41,698 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (271.205933094s left)
2019-06-21 02:46:55,385 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024655367906
2019-06-21 02:46:55,413 [salt.minion      :1432][INFO    ][13545] Starting a new job with PID 13545
2019-06-21 02:46:55,436 [salt.minion      :1711][INFO    ][13545] Returning information for job: 20190621024655367906
2019-06-21 02:47:14,584 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (238.319848061s left)
2019-06-21 02:47:25,603 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024725590363
2019-06-21 02:47:25,626 [salt.minion      :1432][INFO    ][13569] Starting a new job with PID 13569
2019-06-21 02:47:25,648 [salt.minion      :1711][INFO    ][13569] Returning information for job: 20190621024725590363
2019-06-21 02:47:47,368 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (205.535866976s left)
2019-06-21 02:47:55,660 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024755641613
2019-06-21 02:47:55,692 [salt.minion      :1432][INFO    ][13622] Starting a new job with PID 13622
2019-06-21 02:47:55,716 [salt.minion      :1711][INFO    ][13622] Returning information for job: 20190621024755641613
2019-06-21 02:48:20,353 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (172.551215887s left)
2019-06-21 02:48:25,772 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024825755421
2019-06-21 02:48:25,801 [salt.minion      :1432][INFO    ][13643] Starting a new job with PID 13643
2019-06-21 02:48:25,825 [salt.minion      :1711][INFO    ][13643] Returning information for job: 20190621024825755421
2019-06-21 02:48:53,142 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (139.761782885s left)
2019-06-21 02:48:55,828 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024855813587
2019-06-21 02:48:55,860 [salt.minion      :1432][INFO    ][13698] Starting a new job with PID 13698
2019-06-21 02:48:55,887 [salt.minion      :1711][INFO    ][13698] Returning information for job: 20190621024855813587
2019-06-21 02:49:25,907 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (106.996993065s left)
2019-06-21 02:49:25,979 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024925966579
2019-06-21 02:49:26,008 [salt.minion      :1432][INFO    ][13721] Starting a new job with PID 13721
2019-06-21 02:49:26,033 [salt.minion      :1711][INFO    ][13721] Returning information for job: 20190621024925966579
2019-06-21 02:49:56,092 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621024956082159
2019-06-21 02:49:56,110 [salt.minion      :1432][INFO    ][13776] Starting a new job with PID 13776
2019-06-21 02:49:56,134 [salt.minion      :1711][INFO    ][13776] Returning information for job: 20190621024956082159
2019-06-21 02:49:58,819 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (74.0850279331s left)
2019-06-21 02:50:26,265 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025026251655
2019-06-21 02:50:26,298 [salt.minion      :1432][INFO    ][13795] Starting a new job with PID 13795
2019-06-21 02:50:26,323 [salt.minion      :1711][INFO    ][13795] Returning information for job: 20190621025026251655
2019-06-21 02:50:31,757 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (41.1472239494s left)
2019-06-21 02:50:56,407 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025056390744
2019-06-21 02:50:56,435 [salt.minion      :1432][INFO    ][13847] Starting a new job with PID 13847
2019-06-21 02:50:56,458 [salt.minion      :1711][INFO    ][13847] Returning information for job: 20190621025056390744
2019-06-21 02:51:04,603 [salt.loaded.ext.module.maas:1023][INFO    ][8282] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (8.30077409744s left)
2019-06-21 02:51:26,552 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025126541031
2019-06-21 02:51:26,580 [salt.minion      :1432][INFO    ][13866] Starting a new job with PID 13866
2019-06-21 02:51:26,608 [salt.minion      :1711][INFO    ][13866] Returning information for job: 20190621025126541031
2019-06-21 02:51:37,509 [salt.state       :302 ][ERROR   ][8282] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-06-21 02:51:37,510 [salt.state       :1951][INFO    ][8282] Completed state [maas.wait_for_machine_status] at time 02:51:37.510097 duration_in_ms=2274629.864
2019-06-21 02:51:37,518 [salt.minion      :1711][INFO    ][8282] Returning information for job: 20190621021332312319
2019-06-21 02:51:48,305 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command pillar.get with jid 20190621025148288017
2019-06-21 02:51:48,328 [salt.minion      :1432][INFO    ][13892] Starting a new job with PID 13892
2019-06-21 02:51:48,337 [salt.minion      :1711][INFO    ][13892] Returning information for job: 20190621025148288017
2019-06-21 02:51:48,983 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command service.status with jid 20190621025148969180
2019-06-21 02:51:49,007 [salt.minion      :1432][INFO    ][13912] Starting a new job with PID 13912
2019-06-21 02:51:49,675 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13912] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-21 02:51:49,717 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13912] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-21 02:51:49,737 [salt.minion      :1711][INFO    ][13912] Returning information for job: 20190621025148969180
2019-06-21 02:51:50,312 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command state.apply with jid 20190621025150296665
2019-06-21 02:51:50,338 [salt.minion      :1432][INFO    ][13937] Starting a new job with PID 13937
2019-06-21 02:51:58,614 [salt.state       :915 ][INFO    ][13937] Loading fresh modules for state activity
2019-06-21 02:51:59,255 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13937] Executing command 'salt-minion --version' in directory '/root'
2019-06-21 02:51:59,552 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13937] Executing command 'salt-minion --version' in directory '/root'
2019-06-21 02:52:00,583 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13937] Executing command 'salt-minion --version' in directory '/root'
2019-06-21 02:52:00,885 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13937] Executing command 'salt-minion --version' in directory '/root'
2019-06-21 02:52:02,788 [salt.state       :1780][INFO    ][13937] Running state [salt-minion] at time 02:52:02.788723
2019-06-21 02:52:02,789 [salt.state       :1813][INFO    ][13937] Executing state pkg.installed for [salt-minion]
2019-06-21 02:52:02,790 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13937] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-21 02:52:02,903 [salt.state       :300 ][INFO    ][13937] All specified packages are already installed
2019-06-21 02:52:02,903 [salt.state       :1951][INFO    ][13937] Completed state [salt-minion] at time 02:52:02.903644 duration_in_ms=114.922
2019-06-21 02:52:02,904 [salt.state       :1780][INFO    ][13937] Running state [salt_minion_dependency_packages] at time 02:52:02.903968
2019-06-21 02:52:02,904 [salt.state       :1813][INFO    ][13937] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-21 02:52:02,913 [salt.state       :300 ][INFO    ][13937] All specified packages are already installed
2019-06-21 02:52:02,913 [salt.state       :1951][INFO    ][13937] Completed state [salt_minion_dependency_packages] at time 02:52:02.913643 duration_in_ms=9.675
2019-06-21 02:52:02,922 [salt.state       :1780][INFO    ][13937] Running state [/etc/salt/minion.d/minion.conf] at time 02:52:02.922426
2019-06-21 02:52:02,922 [salt.state       :1813][INFO    ][13937] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-21 02:52:03,203 [salt.state       :300 ][INFO    ][13937] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-21 02:52:03,203 [salt.state       :1951][INFO    ][13937] Completed state [/etc/salt/minion.d/minion.conf] at time 02:52:03.203370 duration_in_ms=280.944
2019-06-21 02:52:03,203 [salt.state       :1780][INFO    ][13937] Running state [python-netaddr] at time 02:52:03.203646
2019-06-21 02:52:03,203 [salt.state       :1813][INFO    ][13937] Executing state pkg.installed for [python-netaddr]
2019-06-21 02:52:03,213 [salt.state       :300 ][INFO    ][13937] All specified packages are already installed
2019-06-21 02:52:03,213 [salt.state       :1951][INFO    ][13937] Completed state [python-netaddr] at time 02:52:03.213428 duration_in_ms=9.782
2019-06-21 02:52:03,219 [salt.state       :1780][INFO    ][13937] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:52:03.219373
2019-06-21 02:52:03,219 [salt.state       :1813][INFO    ][13937] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-21 02:52:03,239 [salt.state       :300 ][INFO    ][13937] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-21 02:52:03,239 [salt.state       :1951][INFO    ][13937] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:52:03.239711 duration_in_ms=20.337
2019-06-21 02:52:03,241 [salt.state       :1780][INFO    ][13937] Running state [salt-minion] at time 02:52:03.241419
2019-06-21 02:52:03,243 [salt.state       :1813][INFO    ][13937] Executing state service.running for [salt-minion]
2019-06-21 02:52:03,244 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13937] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-21 02:52:03,289 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13937] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-21 02:52:03,311 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13937] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-21 02:52:03,335 [salt.state       :300 ][INFO    ][13937] The service salt-minion is already running
2019-06-21 02:52:03,335 [salt.state       :1951][INFO    ][13937] Completed state [salt-minion] at time 02:52:03.335668 duration_in_ms=94.248
2019-06-21 02:52:03,340 [salt.state       :1780][INFO    ][13937] Running state [/etc/salt/grains.d] at time 02:52:03.340603
2019-06-21 02:52:03,341 [salt.state       :1813][INFO    ][13937] Executing state file.directory for [/etc/salt/grains.d]
2019-06-21 02:52:03,343 [salt.state       :300 ][INFO    ][13937] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-21 02:52:03,343 [salt.state       :1951][INFO    ][13937] Completed state [/etc/salt/grains.d] at time 02:52:03.343493 duration_in_ms=2.89
2019-06-21 02:52:03,344 [salt.state       :1780][INFO    ][13937] Running state [/etc/salt/grains] at time 02:52:03.344362
2019-06-21 02:52:03,344 [salt.state       :1813][INFO    ][13937] Executing state file.managed for [/etc/salt/grains]
2019-06-21 02:52:03,345 [salt.state       :300 ][INFO    ][13937] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-21 02:52:03,345 [salt.state       :1951][INFO    ][13937] Completed state [/etc/salt/grains] at time 02:52:03.345689 duration_in_ms=1.327
2019-06-21 02:52:03,346 [salt.state       :1780][INFO    ][13937] Running state [/etc/salt/grains.d/placeholder] at time 02:52:03.346353
2019-06-21 02:52:03,346 [salt.state       :1813][INFO    ][13937] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-21 02:52:03,347 [salt.state       :300 ][INFO    ][13937] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-21 02:52:03,347 [salt.state       :1951][INFO    ][13937] Completed state [/etc/salt/grains.d/placeholder] at time 02:52:03.347611 duration_in_ms=1.258
2019-06-21 02:52:03,348 [salt.state       :1780][INFO    ][13937] Running state [/etc/salt/grains.d/sphinx] at time 02:52:03.348249
2019-06-21 02:52:03,348 [salt.state       :1813][INFO    ][13937] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-21 02:52:03,351 [salt.state       :300 ][INFO    ][13937] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-21 02:52:03,352 [salt.state       :1951][INFO    ][13937] Completed state [/etc/salt/grains.d/sphinx] at time 02:52:03.352146 duration_in_ms=3.897
2019-06-21 02:52:03,354 [salt.state       :1780][INFO    ][13937] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:52:03.354270
2019-06-21 02:52:03,354 [salt.state       :1813][INFO    ][13937] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-21 02:52:03,354 [salt.state       :300 ][INFO    ][13937] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-21 02:52:03,355 [salt.state       :1951][INFO    ][13937] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:52:03.355061 duration_in_ms=0.793
2019-06-21 02:52:03,355 [salt.state       :1780][INFO    ][13937] Running state [/etc/salt/grains.d/dns_records] at time 02:52:03.355551
2019-06-21 02:52:03,355 [salt.state       :1813][INFO    ][13937] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-21 02:52:03,357 [salt.state       :300 ][INFO    ][13937] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-21 02:52:03,357 [salt.state       :1951][INFO    ][13937] Completed state [/etc/salt/grains.d/dns_records] at time 02:52:03.357585 duration_in_ms=2.033
2019-06-21 02:52:03,359 [salt.state       :1780][INFO    ][13937] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:52:03.359241
2019-06-21 02:52:03,359 [salt.state       :1813][INFO    ][13937] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-21 02:52:03,360 [salt.state       :300 ][INFO    ][13937] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-21 02:52:03,360 [salt.state       :1951][INFO    ][13937] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:52:03.360465 duration_in_ms=1.224
2019-06-21 02:52:03,361 [salt.state       :1780][INFO    ][13937] Running state [/etc/salt/grains.d/salt] at time 02:52:03.361214
2019-06-21 02:52:03,363 [salt.state       :1813][INFO    ][13937] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-21 02:52:03,364 [salt.state       :300 ][INFO    ][13937] File /etc/salt/grains.d/salt is in the correct state
2019-06-21 02:52:03,364 [salt.state       :1951][INFO    ][13937] Completed state [/etc/salt/grains.d/salt] at time 02:52:03.364820 duration_in_ms=3.607
2019-06-21 02:52:03,366 [salt.state       :1780][INFO    ][13937] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:52:03.366059
2019-06-21 02:52:03,366 [salt.state       :1813][INFO    ][13937] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-21 02:52:03,366 [salt.state       :300 ][INFO    ][13937] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-21 02:52:03,366 [salt.state       :1951][INFO    ][13937] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:52:03.366860 duration_in_ms=0.8
2019-06-21 02:52:03,368 [salt.state       :1780][INFO    ][13937] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:52:03.368803
2019-06-21 02:52:03,369 [salt.state       :1813][INFO    ][13937] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-21 02:52:03,369 [salt.state       :300 ][INFO    ][13937] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-21 02:52:03,369 [salt.state       :1951][INFO    ][13937] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:52:03.369612 duration_in_ms=0.808
2019-06-21 02:52:03,370 [salt.state       :1780][INFO    ][13937] Running state [mine.update] at time 02:52:03.370313
2019-06-21 02:52:03,370 [salt.state       :1813][INFO    ][13937] Executing state module.wait for [mine.update]
2019-06-21 02:52:03,370 [salt.state       :300 ][INFO    ][13937] No changes made for mine.update
2019-06-21 02:52:03,371 [salt.state       :1951][INFO    ][13937] Completed state [mine.update] at time 02:52:03.371052 duration_in_ms=0.738
2019-06-21 02:52:03,371 [salt.state       :1780][INFO    ][13937] Running state [ca-certificates] at time 02:52:03.371312
2019-06-21 02:52:03,371 [salt.state       :1813][INFO    ][13937] Executing state pkg.installed for [ca-certificates]
2019-06-21 02:52:03,382 [salt.state       :300 ][INFO    ][13937] All specified packages are already installed
2019-06-21 02:52:03,382 [salt.state       :1951][INFO    ][13937] Completed state [ca-certificates] at time 02:52:03.382724 duration_in_ms=11.412
2019-06-21 02:52:03,383 [salt.state       :1780][INFO    ][13937] Running state [update-ca-certificates] at time 02:52:03.383453
2019-06-21 02:52:03,383 [salt.state       :1813][INFO    ][13937] Executing state cmd.wait for [update-ca-certificates]
2019-06-21 02:52:03,384 [salt.state       :300 ][INFO    ][13937] No changes made for update-ca-certificates
2019-06-21 02:52:03,384 [salt.state       :1951][INFO    ][13937] Completed state [update-ca-certificates] at time 02:52:03.384206 duration_in_ms=0.753
2019-06-21 02:52:03,384 [salt.state       :1780][INFO    ][13937] Running state [iptables] at time 02:52:03.384470
2019-06-21 02:52:03,384 [salt.state       :1813][INFO    ][13937] Executing state pkg.installed for [iptables]
2019-06-21 02:52:03,394 [salt.state       :300 ][INFO    ][13937] All specified packages are already installed
2019-06-21 02:52:03,394 [salt.state       :1951][INFO    ][13937] Completed state [iptables] at time 02:52:03.394806 duration_in_ms=10.336
2019-06-21 02:52:03,395 [salt.state       :1780][INFO    ][13937] Running state [iptables-persistent] at time 02:52:03.395077
2019-06-21 02:52:03,395 [salt.state       :1813][INFO    ][13937] Executing state pkg.installed for [iptables-persistent]
2019-06-21 02:52:03,404 [salt.state       :300 ][INFO    ][13937] All specified packages are already installed
2019-06-21 02:52:03,405 [salt.state       :1951][INFO    ][13937] Completed state [iptables-persistent] at time 02:52:03.405192 duration_in_ms=10.114
2019-06-21 02:52:03,406 [salt.state       :1780][INFO    ][13937] Running state [iptables_modules_v4_load] at time 02:52:03.406215
2019-06-21 02:52:03,406 [salt.state       :1813][INFO    ][13937] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-21 02:52:03,407 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13937] Executing command 'lsmod' in directory '/root'
2019-06-21 02:52:03,429 [salt.state       :300 ][INFO    ][13937] Kernel modules iptable_filter, ip_tables are already present
2019-06-21 02:52:03,429 [salt.state       :1951][INFO    ][13937] Completed state [iptables_modules_v4_load] at time 02:52:03.429868 duration_in_ms=23.653
2019-06-21 02:52:03,431 [salt.state       :1780][INFO    ][13937] Running state [/etc/iptables/rules.v4] at time 02:52:03.431215
2019-06-21 02:52:03,431 [salt.state       :1813][INFO    ][13937] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-21 02:52:03,537 [salt.state       :300 ][INFO    ][13937] File /etc/iptables/rules.v4 is in the correct state
2019-06-21 02:52:03,537 [salt.state       :1951][INFO    ][13937] Completed state [/etc/iptables/rules.v4] at time 02:52:03.537247 duration_in_ms=106.032
2019-06-21 02:52:03,538 [salt.state       :1780][INFO    ][13937] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:52:03.538757
2019-06-21 02:52:03,539 [salt.state       :1813][INFO    ][13937] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-21 02:52:03,539 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13937] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-21 02:52:03,562 [salt.state       :300 ][INFO    ][13937] onlyif execution failed
2019-06-21 02:52:03,563 [salt.state       :1951][INFO    ][13937] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:52:03.563278 duration_in_ms=24.52
2019-06-21 02:52:03,567 [salt.state       :1780][INFO    ][13937] Running state [netfilter-persistent] at time 02:52:03.565624
2019-06-21 02:52:03,568 [salt.state       :1813][INFO    ][13937] Executing state service.running for [netfilter-persistent]
2019-06-21 02:52:03,569 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13937] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-21 02:52:03,593 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13937] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-21 02:52:03,616 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13937] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-21 02:52:03,637 [salt.state       :300 ][INFO    ][13937] The service netfilter-persistent is already running
2019-06-21 02:52:03,639 [salt.state       :1951][INFO    ][13937] Completed state [netfilter-persistent] at time 02:52:03.639632 duration_in_ms=74.009
2019-06-21 02:52:03,641 [salt.state       :1780][INFO    ][13937] Running state [iptables_extra.remove_stale_tables] at time 02:52:03.641148
2019-06-21 02:52:03,641 [salt.state       :1813][INFO    ][13937] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-21 02:52:03,642 [salt.state       :300 ][INFO    ][13937] No changes made for iptables_extra.remove_stale_tables
2019-06-21 02:52:03,642 [salt.state       :1951][INFO    ][13937] Completed state [iptables_extra.remove_stale_tables] at time 02:52:03.642566 duration_in_ms=1.419
2019-06-21 02:52:03,643 [salt.state       :1780][INFO    ][13937] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:52:03.642965
2019-06-21 02:52:03,643 [salt.state       :1813][INFO    ][13937] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-21 02:52:03,644 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13937] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-21 02:52:03,661 [salt.state       :300 ][INFO    ][13937] onlyif execution failed
2019-06-21 02:52:03,664 [salt.state       :1951][INFO    ][13937] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:52:03.664397 duration_in_ms=21.431
2019-06-21 02:52:03,666 [salt.state       :1780][INFO    ][13937] Running state [/etc/iptables/rules.v6] at time 02:52:03.666084
2019-06-21 02:52:03,666 [salt.state       :1813][INFO    ][13937] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-21 02:52:03,667 [salt.state       :300 ][INFO    ][13937] File /etc/iptables/rules.v6 is not present
2019-06-21 02:52:03,667 [salt.state       :1951][INFO    ][13937] Completed state [/etc/iptables/rules.v6] at time 02:52:03.667696 duration_in_ms=1.613
2019-06-21 02:52:03,668 [salt.state       :1780][INFO    ][13937] Running state [iptables_extra.flush_all] at time 02:52:03.668902
2019-06-21 02:52:03,669 [salt.state       :1813][INFO    ][13937] Executing state module.wait for [iptables_extra.flush_all]
2019-06-21 02:52:03,669 [salt.state       :300 ][INFO    ][13937] No changes made for iptables_extra.flush_all
2019-06-21 02:52:03,670 [salt.state       :1951][INFO    ][13937] Completed state [iptables_extra.flush_all] at time 02:52:03.670199 duration_in_ms=1.297
2019-06-21 02:52:03,679 [salt.minion      :1711][INFO    ][13937] Returning information for job: 20190621025150296665
2019-06-21 02:52:04,244 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command state.apply with jid 20190621025204232787
2019-06-21 02:52:04,276 [salt.minion      :1432][INFO    ][14020] Starting a new job with PID 14020
2019-06-21 02:52:05,406 [salt.state       :915 ][INFO    ][14020] Loading fresh modules for state activity
2019-06-21 02:52:06,361 [salt.state       :1780][INFO    ][14020] Running state [maas-rack-controller] at time 02:52:06.361336
2019-06-21 02:52:06,362 [salt.state       :1813][INFO    ][14020] Executing state pkg.installed for [maas-rack-controller]
2019-06-21 02:52:06,362 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14020] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-21 02:52:06,473 [salt.state       :300 ][INFO    ][14020] All specified packages are already installed
2019-06-21 02:52:06,473 [salt.state       :1951][INFO    ][14020] Completed state [maas-rack-controller] at time 02:52:06.473642 duration_in_ms=112.307
2019-06-21 02:52:06,474 [salt.state       :1780][INFO    ][14020] Running state [ipmitool] at time 02:52:06.473982
2019-06-21 02:52:06,474 [salt.state       :1813][INFO    ][14020] Executing state pkg.installed for [ipmitool]
2019-06-21 02:52:06,489 [salt.state       :300 ][INFO    ][14020] All specified packages are already installed
2019-06-21 02:52:06,489 [salt.state       :1951][INFO    ][14020] Completed state [ipmitool] at time 02:52:06.489702 duration_in_ms=15.719
2019-06-21 02:52:06,493 [salt.state       :1780][INFO    ][14020] Running state [/etc/maas/rackd.conf] at time 02:52:06.493014
2019-06-21 02:52:06,493 [salt.state       :1813][INFO    ][14020] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-21 02:52:06,494 [salt.state       :300 ][INFO    ][14020] No changes needed to be made
2019-06-21 02:52:06,495 [salt.state       :1951][INFO    ][14020] Completed state [/etc/maas/rackd.conf] at time 02:52:06.494976 duration_in_ms=1.962
2019-06-21 02:52:06,495 [salt.state       :1780][INFO    ][14020] Running state [/etc/maas/rackd.conf] at time 02:52:06.495222
2019-06-21 02:52:06,495 [salt.state       :1813][INFO    ][14020] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-21 02:52:06,495 [salt.loaded.int.states.file:2298][WARNING ][14020] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-21 02:52:06,496 [salt.state       :300 ][INFO    ][14020] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-21 02:52:06,496 [salt.state       :1951][INFO    ][14020] Completed state [/etc/maas/rackd.conf] at time 02:52:06.496690 duration_in_ms=1.468
2019-06-21 02:52:06,498 [salt.state       :1780][INFO    ][14020] Running state [maas-rackd] at time 02:52:06.497700
2019-06-21 02:52:06,498 [salt.state       :1813][INFO    ][14020] Executing state service.running for [maas-rackd]
2019-06-21 02:52:06,499 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14020] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-21 02:52:06,539 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14020] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-21 02:52:06,564 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14020] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-21 02:52:06,584 [salt.state       :300 ][INFO    ][14020] The service maas-rackd is already running
2019-06-21 02:52:06,585 [salt.state       :1951][INFO    ][14020] Completed state [maas-rackd] at time 02:52:06.585303 duration_in_ms=87.603
2019-06-21 02:52:06,587 [salt.minion      :1711][INFO    ][14020] Returning information for job: 20190621025204232787
2019-06-21 02:52:07,140 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command state.apply with jid 20190621025207127781
2019-06-21 02:52:07,168 [salt.minion      :1432][INFO    ][14043] Starting a new job with PID 14043
2019-06-21 02:52:08,381 [salt.state       :915 ][INFO    ][14043] Loading fresh modules for state activity
2019-06-21 02:52:09,439 [salt.state       :1780][INFO    ][14043] Running state [maas-region-controller] at time 02:52:09.439057
2019-06-21 02:52:09,439 [salt.state       :1813][INFO    ][14043] Executing state pkg.installed for [maas-region-controller]
2019-06-21 02:52:09,440 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14043] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-21 02:52:09,559 [salt.state       :300 ][INFO    ][14043] All specified packages are already installed
2019-06-21 02:52:09,559 [salt.state       :1951][INFO    ][14043] Completed state [maas-region-controller] at time 02:52:09.559706 duration_in_ms=120.65
2019-06-21 02:52:09,560 [salt.state       :1780][INFO    ][14043] Running state [python-oauth] at time 02:52:09.560035
2019-06-21 02:52:09,560 [salt.state       :1813][INFO    ][14043] Executing state pkg.installed for [python-oauth]
2019-06-21 02:52:09,570 [salt.state       :300 ][INFO    ][14043] All specified packages are already installed
2019-06-21 02:52:09,570 [salt.state       :1951][INFO    ][14043] Completed state [python-oauth] at time 02:52:09.570714 duration_in_ms=10.679
2019-06-21 02:52:09,574 [salt.state       :1780][INFO    ][14043] Running state [/etc/maas/regiond.conf] at time 02:52:09.574160
2019-06-21 02:52:09,574 [salt.state       :1813][INFO    ][14043] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-21 02:52:09,579 [salt.state       :300 ][INFO    ][14043] No changes needed to be made
2019-06-21 02:52:09,579 [salt.state       :1951][INFO    ][14043] Completed state [/etc/maas/regiond.conf] at time 02:52:09.579723 duration_in_ms=5.563
2019-06-21 02:52:09,580 [salt.state       :1780][INFO    ][14043] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:52:09.580202
2019-06-21 02:52:09,580 [salt.state       :1813][INFO    ][14043] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-21 02:52:09,640 [salt.state       :300 ][INFO    ][14043] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-21 02:52:09,640 [salt.state       :1951][INFO    ][14043] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:52:09.640439 duration_in_ms=60.237
2019-06-21 02:52:09,640 [salt.state       :1780][INFO    ][14043] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:52:09.640922
2019-06-21 02:52:09,641 [salt.state       :1813][INFO    ][14043] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-21 02:52:09,644 [salt.state       :300 ][INFO    ][14043] No changes needed to be made
2019-06-21 02:52:09,645 [salt.state       :1951][INFO    ][14043] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:52:09.645135 duration_in_ms=4.212
2019-06-21 02:52:09,645 [salt.state       :1780][INFO    ][14043] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:52:09.645611
2019-06-21 02:52:09,645 [salt.state       :1813][INFO    ][14043] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-21 02:52:09,649 [salt.state       :300 ][INFO    ][14043] No changes needed to be made
2019-06-21 02:52:09,649 [salt.state       :1951][INFO    ][14043] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:52:09.649461 duration_in_ms=3.85
2019-06-21 02:52:09,651 [salt.state       :1780][INFO    ][14043] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:52:09.651180
2019-06-21 02:52:09,651 [salt.state       :1813][INFO    ][14043] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-21 02:52:09,683 [salt.state       :300 ][INFO    ][14043] No changes needed to be made
2019-06-21 02:52:09,683 [salt.state       :1951][INFO    ][14043] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:52:09.683856 duration_in_ms=32.675
2019-06-21 02:52:09,684 [salt.state       :1780][INFO    ][14043] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:52:09.684397
2019-06-21 02:52:09,684 [salt.state       :1813][INFO    ][14043] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-21 02:52:09,706 [salt.state       :300 ][INFO    ][14043] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-21 02:52:09,706 [salt.state       :1951][INFO    ][14043] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:52:09.706730 duration_in_ms=22.332
2019-06-21 02:52:09,709 [salt.state       :1780][INFO    ][14043] Running state [a2enmod headers] at time 02:52:09.708986
2019-06-21 02:52:09,709 [salt.state       :1813][INFO    ][14043] Executing state cmd.run for [a2enmod headers]
2019-06-21 02:52:09,712 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14043] Executing command 'a2enmod headers' in directory '/root'
2019-06-21 02:52:09,776 [salt.state       :300 ][INFO    ][14043] {'pid': 14062, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-21 02:52:09,777 [salt.state       :1951][INFO    ][14043] Completed state [a2enmod headers] at time 02:52:09.777196 duration_in_ms=68.21
2019-06-21 02:52:09,777 [salt.state       :1780][INFO    ][14043] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:52:09.777852
2019-06-21 02:52:09,778 [salt.state       :1813][INFO    ][14043] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-21 02:52:09,804 [salt.state       :300 ][INFO    ][14043] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-21 02:52:09,804 [salt.state       :1951][INFO    ][14043] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:52:09.804771 duration_in_ms=26.919
2019-06-21 02:52:09,805 [salt.state       :1780][INFO    ][14043] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:52:09.805467
2019-06-21 02:52:09,809 [salt.state       :1813][INFO    ][14043] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-21 02:52:09,875 [salt.state       :300 ][INFO    ][14043] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-21 02:52:09,875 [salt.state       :1951][INFO    ][14043] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:52:09.875679 duration_in_ms=70.212
2019-06-21 02:52:09,876 [salt.state       :1780][INFO    ][14043] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:52:09.876171
2019-06-21 02:52:09,876 [salt.state       :1813][INFO    ][14043] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-21 02:52:09,948 [salt.state       :300 ][INFO    ][14043] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-21 02:52:09,948 [salt.state       :1951][INFO    ][14043] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:52:09.948195 duration_in_ms=72.024
2019-06-21 02:52:09,948 [salt.state       :1780][INFO    ][14043] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:52:09.948685
2019-06-21 02:52:09,948 [salt.state       :1813][INFO    ][14043] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-21 02:52:10,031 [salt.state       :300 ][INFO    ][14043] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-21 02:52:10,031 [salt.state       :1951][INFO    ][14043] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:52:10.031475 duration_in_ms=82.789
2019-06-21 02:52:10,031 [salt.state       :1780][INFO    ][14043] Running state [/root/.pgpass] at time 02:52:10.031745
2019-06-21 02:52:10,032 [salt.state       :1813][INFO    ][14043] Executing state file.managed for [/root/.pgpass]
2019-06-21 02:52:10,085 [salt.state       :300 ][INFO    ][14043] File /root/.pgpass is in the correct state
2019-06-21 02:52:10,086 [salt.state       :1951][INFO    ][14043] Completed state [/root/.pgpass] at time 02:52:10.086101 duration_in_ms=54.355
2019-06-21 02:52:10,095 [salt.state       :1780][INFO    ][14043] Running state [maas-region syncdb --noinput] at time 02:52:10.095738
2019-06-21 02:52:10,096 [salt.state       :1813][INFO    ][14043] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-21 02:52:10,096 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14043] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-21 02:52:12,638 [salt.state       :300 ][INFO    ][14043] {'pid': 14075, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: auth, piston3, maasserver, sites, contenttypes, 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-06-21 02:52:12,639 [salt.state       :1951][INFO    ][14043] Completed state [maas-region syncdb --noinput] at time 02:52:12.639575 duration_in_ms=2543.835
2019-06-21 02:52:12,640 [salt.state       :2022][WARNING ][14043] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-21 02:52:12,644 [salt.state       :1780][INFO    ][14043] Running state [maas-regiond] at time 02:52:12.644595
2019-06-21 02:52:12,645 [salt.state       :1813][INFO    ][14043] Executing state service.running for [maas-regiond]
2019-06-21 02:52:12,647 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14043] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-21 02:52:12,695 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14043] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-21 02:52:12,715 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14043] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-21 02:52:12,740 [salt.state       :300 ][INFO    ][14043] The service maas-regiond is already running
2019-06-21 02:52:12,740 [salt.state       :1951][INFO    ][14043] Completed state [maas-regiond] at time 02:52:12.740727 duration_in_ms=96.132
2019-06-21 02:52:12,744 [salt.state       :1780][INFO    ][14043] Running state [bind9] at time 02:52:12.744260
2019-06-21 02:52:12,744 [salt.state       :1813][INFO    ][14043] Executing state service.running for [bind9]
2019-06-21 02:52:12,748 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14043] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-21 02:52:12,766 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14043] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-21 02:52:12,790 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14043] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-21 02:52:12,814 [salt.state       :300 ][INFO    ][14043] The service bind9 is already running
2019-06-21 02:52:12,814 [salt.state       :1951][INFO    ][14043] Completed state [bind9] at time 02:52:12.814586 duration_in_ms=70.326
2019-06-21 02:52:12,819 [salt.state       :1780][INFO    ][14043] Running state [apache2] at time 02:52:12.818938
2019-06-21 02:52:12,819 [salt.state       :1813][INFO    ][14043] Executing state service.running for [apache2]
2019-06-21 02:52:12,820 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14043] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-21 02:52:12,844 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14043] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-21 02:52:12,864 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14043] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-21 02:52:12,894 [salt.state       :300 ][INFO    ][14043] The service apache2 is already running
2019-06-21 02:52:12,895 [salt.state       :1951][INFO    ][14043] Completed state [apache2] at time 02:52:12.895274 duration_in_ms=76.335
2019-06-21 02:52:12,897 [salt.state       :1780][INFO    ][14043] Running state [maasng.wait_for_http_code] at time 02:52:12.897376
2019-06-21 02:52:12,898 [salt.state       :1813][INFO    ][14043] Executing state module.run for [maasng.wait_for_http_code]
2019-06-21 02:52:12,898 [salt.utils.decorators:613 ][WARNING ][14043] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:52:13,047 [salt.state       :300 ][INFO    ][14043] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-21 02:52:13,048 [salt.state       :1951][INFO    ][14043] Completed state [maasng.wait_for_http_code] at time 02:52:13.048052 duration_in_ms=150.676
2019-06-21 02:52:13,049 [salt.state       :1780][INFO    ][14043] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:52:13.049150
2019-06-21 02:52:13,049 [salt.state       :1813][INFO    ][14043] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-21 02:52:13,051 [salt.state       :300 ][INFO    ][14043] /var/lib/maas/.setup_admin exists
2019-06-21 02:52:13,051 [salt.state       :1951][INFO    ][14043] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:52:13.051198 duration_in_ms=2.048
2019-06-21 02:52:13,052 [salt.state       :1780][INFO    ][14043] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:13.052007
2019-06-21 02:52:13,052 [salt.state       :1813][INFO    ][14043] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-21 02:52:13,053 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14043] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-21 02:52:14,738 [salt.state       :300 ][INFO    ][14043] {'pid': 14096, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-21 02:52:14,740 [salt.state       :1951][INFO    ][14043] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:14.740003 duration_in_ms=1687.994
2019-06-21 02:52:14,751 [salt.state       :1780][INFO    ][14043] Running state [maas_region_boot_source_resources_mirror] at time 02:52:14.751480
2019-06-21 02:52:14,751 [salt.state       :1813][INFO    ][14043] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-21 02:52:14,836 [salt.state       :300 ][INFO    ][14043] {'changes': {}}
2019-06-21 02:52:14,836 [salt.state       :1951][INFO    ][14043] Completed state [maas_region_boot_source_resources_mirror] at time 02:52:14.836499 duration_in_ms=85.019
2019-06-21 02:52:14,837 [salt.state       :1780][INFO    ][14043] Running state [maasng.boot_resources_import] at time 02:52:14.837334
2019-06-21 02:52:14,838 [salt.state       :1813][INFO    ][14043] Executing state module.run for [maasng.boot_resources_import]
2019-06-21 02:52:14,839 [salt.utils.decorators:613 ][WARNING ][14043] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:52:14,944 [salt.loaded.ext.module.maasng:1600][INFO    ][14043] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-21 02:52:20,001 [salt.loaded.ext.module.maasng:1600][INFO    ][14043] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-21 02:52:22,232 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025222221325
2019-06-21 02:52:22,260 [salt.minion      :1432][INFO    ][14127] Starting a new job with PID 14127
2019-06-21 02:52:22,285 [salt.minion      :1711][INFO    ][14127] Returning information for job: 20190621025222221325
2019-06-21 02:52:25,107 [salt.state       :300 ][INFO    ][14043] {'ret': True}
2019-06-21 02:52:25,107 [salt.state       :1951][INFO    ][14043] Completed state [maasng.boot_resources_import] at time 02:52:25.107676 duration_in_ms=10270.341
2019-06-21 02:52:25,109 [salt.state       :1780][INFO    ][14043] Running state [maas_region_boot_sources_selection_xenial] at time 02:52:25.109123
2019-06-21 02:52:25,109 [salt.state       :1813][INFO    ][14043] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-21 02:52:25,295 [salt.state       :300 ][INFO    ][14043] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-21 02:52:25,295 [salt.state       :1951][INFO    ][14043] Completed state [maas_region_boot_sources_selection_xenial] at time 02:52:25.295555 duration_in_ms=186.431
2019-06-21 02:52:25,297 [salt.state       :1780][INFO    ][14043] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:52:25.297252
2019-06-21 02:52:25,299 [salt.state       :1813][INFO    ][14043] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-21 02:52:25,300 [salt.utils.decorators:613 ][WARNING ][14043] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:52:25,301 [salt.loaded.ext.module.maasng:1771][INFO    ][14043] boot-sources sync initiated for ALL Rack's
2019-06-21 02:52:26,375 [salt.state       :300 ][INFO    ][14043] {'ret': True}
2019-06-21 02:52:26,375 [salt.state       :1951][INFO    ][14043] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:52:26.375795 duration_in_ms=1078.542
2019-06-21 02:52:26,380 [salt.state       :1780][INFO    ][14043] Running state [maas.process_maas_config] at time 02:52:26.380259
2019-06-21 02:52:26,380 [salt.state       :1813][INFO    ][14043] Executing state module.run for [maas.process_maas_config]
2019-06-21 02:52:26,381 [salt.utils.decorators:613 ][WARNING ][14043] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:52:26,381 [salt.loaded.ext.module.maas:92  ][INFO    ][14043] maasconfig name=enable_http_proxy value=True
2019-06-21 02:52:26,431 [salt.loaded.ext.module.maas:92  ][INFO    ][14043] maasconfig name=upstream_dns value=8.8.8.8
2019-06-21 02:52:26,489 [salt.loaded.ext.module.maas:92  ][INFO    ][14043] maasconfig name=commissioning_distro_series value=xenial
2019-06-21 02:52:26,552 [salt.loaded.ext.module.maas:92  ][INFO    ][14043] maasconfig name=default_osystem value=ubuntu
2019-06-21 02:52:26,615 [salt.loaded.ext.module.maas:92  ][INFO    ][14043] maasconfig name=active_discovery_interval value=600
2019-06-21 02:52:26,672 [salt.loaded.ext.module.maas:92  ][INFO    ][14043] maasconfig name=dnssec_validation value=no
2019-06-21 02:52:27,995 [salt.loaded.ext.module.maas:92  ][INFO    ][14043] maasconfig name=maas_name value=mas01
2019-06-21 02:52:28,059 [salt.loaded.ext.module.maas:92  ][INFO    ][14043] maasconfig name=network_discovery value=enabled
2019-06-21 02:52:28,164 [salt.loaded.ext.module.maas:92  ][INFO    ][14043] maasconfig name=enable_third_party_drivers value=True
2019-06-21 02:52:28,219 [salt.loaded.ext.module.maas:92  ][INFO    ][14043] maasconfig name=default_storage_layout value=lvm
2019-06-21 02:52:28,290 [salt.loaded.ext.module.maas:92  ][INFO    ][14043] maasconfig name=ntp_external_only value=True
2019-06-21 02:52:28,339 [salt.loaded.ext.module.maas:92  ][INFO    ][14043] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-21 02:52:28,400 [salt.loaded.ext.module.maas:92  ][INFO    ][14043] maasconfig name=default_distro_series value=xenial
2019-06-21 02:52:28,453 [salt.loaded.ext.module.maas:92  ][INFO    ][14043] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-06-21 02:52:28,561 [salt.state       :300 ][INFO    ][14043] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-06-21 02:52:28,563 [salt.state       :1951][INFO    ][14043] Completed state [maas.process_maas_config] at time 02:52:28.563058 duration_in_ms=2182.798
2019-06-21 02:52:28,563 [salt.state       :1780][INFO    ][14043] Running state [pxe_admin] at time 02:52:28.563857
2019-06-21 02:52:28,564 [salt.state       :1813][INFO    ][14043] Executing state maasng.fabric_present for [pxe_admin]
2019-06-21 02:52:28,617 [salt.loaded.ext.module.maasng:945 ][INFO    ][14043] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n7kf4t', 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-06-21 02:52:28,686 [salt.loaded.ext.module.maasng:1008][WARNING ][14043] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-21 02:52:28,687 [salt.loaded.ext.module.maasng:1011][WARNING ][14043] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-21 02:52:28,758 [salt.state       :300 ][INFO    ][14043] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-21 02:52:28,758 [salt.state       :1951][INFO    ][14043] Completed state [pxe_admin] at time 02:52:28.758806 duration_in_ms=194.949
2019-06-21 02:52:28,759 [salt.state       :1780][INFO    ][14043] Running state [vlan 0] at time 02:52:28.759109
2019-06-21 02:52:28,759 [salt.state       :1813][INFO    ][14043] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-21 02:52:28,811 [salt.loaded.ext.module.maasng:945 ][INFO    ][14043] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n7kf4t', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-21 02:52:28,912 [salt.loaded.ext.module.maasng:945 ][INFO    ][14043] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n7kf4t', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-21 02:52:29,153 [salt.loaded.ext.module.maasng:945 ][INFO    ][14043] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n7kf4t', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-21 02:52:29,251 [salt.state       :300 ][INFO    ][14043] {'new': 'Vlan untagged was updated'}
2019-06-21 02:52:29,251 [salt.state       :1951][INFO    ][14043] Completed state [vlan 0] at time 02:52:29.251803 duration_in_ms=492.693
2019-06-21 02:52:29,253 [salt.state       :1780][INFO    ][14043] Running state [192.168.11.0/24] at time 02:52:29.253348
2019-06-21 02:52:29,253 [salt.state       :1813][INFO    ][14043] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-21 02:52:29,435 [salt.loaded.ext.module.maasng:945 ][INFO    ][14043] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'n7kf4t', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-21 02:52:29,436 [salt.loaded.ext.module.maasng:1235][WARNING ][14043] Ignoring parameter vlan:0
2019-06-21 02:52:29,510 [salt.state       :300 ][INFO    ][14043] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-21 02:52:29,511 [salt.state       :1951][INFO    ][14043] Completed state [192.168.11.0/24] at time 02:52:29.511183 duration_in_ms=257.834
2019-06-21 02:52:29,512 [salt.state       :1780][INFO    ][14043] Running state [maas_create_iprange_1] at time 02:52:29.511969
2019-06-21 02:52:29,512 [salt.state       :1813][INFO    ][14043] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-21 02:52:29,557 [salt.state       :300 ][INFO    ][14043] Iprange maas_create_iprange_1 already exist.
2019-06-21 02:52:29,557 [salt.state       :1951][INFO    ][14043] Completed state [maas_create_iprange_1] at time 02:52:29.557513 duration_in_ms=45.544
2019-06-21 02:52:29,559 [salt.state       :1780][INFO    ][14043] Running state [vlan 0] at time 02:52:29.559892
2019-06-21 02:52:29,560 [salt.state       :1813][INFO    ][14043] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-21 02:52:29,611 [salt.loaded.ext.module.maasng:945 ][INFO    ][14043] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'n7kf4t', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-21 02:52:29,713 [salt.loaded.ext.module.maasng:945 ][INFO    ][14043] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'n7kf4t', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-21 02:52:29,998 [salt.loaded.ext.module.maasng:945 ][INFO    ][14043] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'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'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n7kf4t', 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-06-21 02:52:30,128 [salt.state       :300 ][INFO    ][14043] {'new': 'Vlan untagged was updated'}
2019-06-21 02:52:30,128 [salt.state       :1951][INFO    ][14043] Completed state [vlan 0] at time 02:52:30.128787 duration_in_ms=568.894
2019-06-21 02:52:30,129 [salt.state       :1780][INFO    ][14043] Running state [opnfv] at time 02:52:30.129609
2019-06-21 02:52:30,130 [salt.state       :1813][INFO    ][14043] Executing state maasng.sshkey_present for [opnfv]
2019-06-21 02:52:30,172 [salt.loaded.ext.module.maasng:1903][INFO    ][14043] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-06-21 02:52:30,173 [salt.state       :300 ][INFO    ][14043] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-21 02:52:30,173 [salt.state       :1951][INFO    ][14043] Completed state [opnfv] at time 02:52:30.173554 duration_in_ms=43.944
2019-06-21 02:52:30,179 [salt.minion      :1711][INFO    ][14043] Returning information for job: 20190621025207127781
2019-06-21 02:52:30,825 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command state.apply with jid 20190621025230813364
2019-06-21 02:52:30,846 [salt.minion      :1432][INFO    ][14481] Starting a new job with PID 14481
2019-06-21 02:52:39,259 [salt.state       :915 ][INFO    ][14481] Loading fresh modules for state activity
2019-06-21 02:52:39,374 [salt.state       :1780][INFO    ][14481] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:39.373666
2019-06-21 02:52:39,374 [salt.state       :1813][INFO    ][14481] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-21 02:52:39,376 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14481] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-21 02:52:41,104 [salt.state       :300 ][INFO    ][14481] {'pid': 14520, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-21 02:52:41,105 [salt.state       :1951][INFO    ][14481] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:41.105006 duration_in_ms=1731.34
2019-06-21 02:52:41,108 [salt.state       :1780][INFO    ][14481] Running state [maas.process_machines] at time 02:52:41.108802
2019-06-21 02:52:41,109 [salt.state       :1813][INFO    ][14481] Executing state module.run for [maas.process_machines]
2019-06-21 02:52:41,110 [salt.utils.decorators:613 ][WARNING ][14481] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:52:41,669 [salt.loaded.ext.module.maas:412 ][WARNING ][14481] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-21 02:52:41,669 [salt.loaded.ext.module.maas:92  ][INFO    ][14481] 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=rdndx8 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-21 02:52:42,874 [salt.loaded.ext.module.maas:412 ][WARNING ][14481] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-21 02:52:42,875 [salt.loaded.ext.module.maas:92  ][INFO    ][14481] 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=r4rxfc architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-21 02:52:44,115 [salt.loaded.ext.module.maas:412 ][WARNING ][14481] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-21 02:52:44,116 [salt.loaded.ext.module.maas:92  ][INFO    ][14481] 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=7bfp8s architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-21 02:52:45,319 [salt.loaded.ext.module.maas:412 ][WARNING ][14481] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-21 02:52:45,320 [salt.loaded.ext.module.maas:92  ][INFO    ][14481] 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=c3a86t architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-21 02:52:45,920 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025245908628
2019-06-21 02:52:45,945 [salt.minion      :1432][INFO    ][14682] Starting a new job with PID 14682
2019-06-21 02:52:45,970 [salt.minion      :1711][INFO    ][14682] Returning information for job: 20190621025245908628
2019-06-21 02:52:46,528 [salt.loaded.ext.module.maas:412 ][WARNING ][14481] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-21 02:52:46,529 [salt.loaded.ext.module.maas:92  ][INFO    ][14481] 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=6rwp4t architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-21 02:52:47,711 [salt.state       :300 ][INFO    ][14481] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-21 02:52:47,712 [salt.state       :1951][INFO    ][14481] Completed state [maas.process_machines] at time 02:52:47.712246 duration_in_ms=6603.444
2019-06-21 02:52:47,719 [salt.minion      :1711][INFO    ][14481] Returning information for job: 20190621025230813364
2019-06-21 02:53:20,788 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command state.apply with jid 20190621025320777753
2019-06-21 02:53:20,815 [salt.minion      :1432][INFO    ][14787] Starting a new job with PID 14787
2019-06-21 02:53:29,077 [salt.state       :915 ][INFO    ][14787] Loading fresh modules for state activity
2019-06-21 02:53:29,184 [salt.state       :1780][INFO    ][14787] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:29.184265
2019-06-21 02:53:29,184 [salt.state       :1813][INFO    ][14787] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-21 02:53:29,187 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14787] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-21 02:53:30,913 [salt.state       :300 ][INFO    ][14787] {'pid': 14794, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-21 02:53:30,915 [salt.state       :1951][INFO    ][14787] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:30.914908 duration_in_ms=1730.643
2019-06-21 02:53:30,919 [salt.state       :1780][INFO    ][14787] Running state [maas.wait_for_machine_status] at time 02:53:30.919174
2019-06-21 02:53:30,919 [salt.state       :1813][INFO    ][14787] Executing state module.run for [maas.wait_for_machine_status]
2019-06-21 02:53:30,919 [salt.utils.decorators:613 ][WARNING ][14787] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:53:33,712 [salt.state       :300 ][INFO    ][14787] {'ret': True}
2019-06-21 02:53:33,712 [salt.state       :1951][INFO    ][14787] Completed state [maas.wait_for_machine_status] at time 02:53:33.712774 duration_in_ms=2793.599
2019-06-21 02:53:33,716 [salt.minion      :1711][INFO    ][14787] Returning information for job: 20190621025320777753
2019-06-21 02:53:34,272 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command state.apply with jid 20190621025334256088
2019-06-21 02:53:34,300 [salt.minion      :1432][INFO    ][14820] Starting a new job with PID 14820
2019-06-21 02:53:35,419 [salt.state       :915 ][INFO    ][14820] Loading fresh modules for state activity
2019-06-21 02:53:35,569 [salt.state       :1780][INFO    ][14820] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:35.569478
2019-06-21 02:53:35,572 [salt.state       :1813][INFO    ][14820] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-21 02:53:35,575 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14820] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-21 02:53:37,335 [salt.state       :300 ][INFO    ][14820] {'pid': 14827, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-21 02:53:37,336 [salt.state       :1951][INFO    ][14820] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:37.336170 duration_in_ms=1766.694
2019-06-21 02:53:37,339 [salt.state       :1780][INFO    ][14820] Running state [maas_machines_storage_cmp002_lvm] at time 02:53:37.338967
2019-06-21 02:53:37,339 [salt.state       :1813][INFO    ][14820] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-21 02:53:37,891 [salt.state       :300 ][INFO    ][14820] Machine cmp002 is not in Ready state.
2019-06-21 02:53:37,891 [salt.state       :1951][INFO    ][14820] Completed state [maas_machines_storage_cmp002_lvm] at time 02:53:37.891836 duration_in_ms=552.866
2019-06-21 02:53:37,892 [salt.state       :1780][INFO    ][14820] Running state [maas_machines_storage_cmp001_lvm] at time 02:53:37.892584
2019-06-21 02:53:37,893 [salt.state       :1813][INFO    ][14820] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-21 02:53:38,440 [salt.state       :300 ][INFO    ][14820] Machine cmp001 is not in Ready state.
2019-06-21 02:53:38,440 [salt.state       :1951][INFO    ][14820] Completed state [maas_machines_storage_cmp001_lvm] at time 02:53:38.440802 duration_in_ms=548.219
2019-06-21 02:53:38,443 [salt.minion      :1711][INFO    ][14820] Returning information for job: 20190621025334256088
2019-06-21 02:53:38,998 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command state.apply with jid 20190621025338982234
2019-06-21 02:53:39,025 [salt.minion      :1432][INFO    ][14838] Starting a new job with PID 14838
2019-06-21 02:53:40,157 [salt.state       :915 ][INFO    ][14838] Loading fresh modules for state activity
2019-06-21 02:53:40,264 [salt.state       :1780][INFO    ][14838] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:40.264632
2019-06-21 02:53:40,264 [salt.state       :1813][INFO    ][14838] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-21 02:53:40,267 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14838] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-21 02:53:42,015 [salt.state       :300 ][INFO    ][14838] {'pid': 14845, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-21 02:53:42,016 [salt.state       :1951][INFO    ][14838] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:42.016503 duration_in_ms=1751.87
2019-06-21 02:53:42,019 [salt.state       :1780][INFO    ][14838] Running state [maas.deploy_machines] at time 02:53:42.019508
2019-06-21 02:53:42,020 [salt.state       :1813][INFO    ][14838] Executing state module.run for [maas.deploy_machines]
2019-06-21 02:53:42,021 [salt.utils.decorators:613 ][WARNING ][14838] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:53:42,586 [salt.loaded.ext.module.maas:684 ][INFO    ][14838] deploymachines hwe_kernel=ga-16.04 system_id=6rwp4t distro_series=xenial
2019-06-21 02:53:44,985 [salt.state       :300 ][INFO    ][14838] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-06-21 02:53:44,986 [salt.state       :1951][INFO    ][14838] Completed state [maas.deploy_machines] at time 02:53:44.985905 duration_in_ms=2966.395
2019-06-21 02:53:44,989 [salt.minion      :1711][INFO    ][14838] Returning information for job: 20190621025338982234
2019-06-21 02:53:45,547 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command state.apply with jid 20190621025345535393
2019-06-21 02:53:45,574 [salt.minion      :1432][INFO    ][14907] Starting a new job with PID 14907
2019-06-21 02:53:54,196 [salt.state       :915 ][INFO    ][14907] Loading fresh modules for state activity
2019-06-21 02:53:54,298 [salt.state       :1780][INFO    ][14907] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:54.298794
2019-06-21 02:53:54,299 [salt.state       :1813][INFO    ][14907] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-21 02:53:54,301 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14907] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-21 02:53:56,086 [salt.state       :300 ][INFO    ][14907] {'pid': 14948, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-21 02:53:56,087 [salt.state       :1951][INFO    ][14907] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:56.087473 duration_in_ms=1788.678
2019-06-21 02:53:56,091 [salt.state       :1780][INFO    ][14907] Running state [maas.wait_for_machine_status] at time 02:53:56.091418
2019-06-21 02:53:56,092 [salt.state       :1813][INFO    ][14907] Executing state module.run for [maas.wait_for_machine_status]
2019-06-21 02:53:56,092 [salt.utils.decorators:613 ][WARNING ][14907] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-21 02:53:58,904 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.2030549s left)
2019-06-21 02:54:00,720 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025400663442
2019-06-21 02:54:00,752 [salt.minion      :1432][INFO    ][14962] Starting a new job with PID 14962
2019-06-21 02:54:00,783 [salt.minion      :1711][INFO    ][14962] Returning information for job: 20190621025400663442
2019-06-21 02:54:30,812 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025430799215
2019-06-21 02:54:30,836 [salt.minion      :1432][INFO    ][14981] Starting a new job with PID 14981
2019-06-21 02:54:30,867 [salt.minion      :1711][INFO    ][14981] Returning information for job: 20190621025430799215
2019-06-21 02:54:31,894 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.21323395s left)
2019-06-21 02:55:00,924 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025500916647
2019-06-21 02:55:00,942 [salt.minion      :1432][INFO    ][15054] Starting a new job with PID 15054
2019-06-21 02:55:00,968 [salt.minion      :1711][INFO    ][15054] Returning information for job: 20190621025500916647
2019-06-21 02:55:04,513 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.59458995s left)
2019-06-21 02:55:31,000 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025530982449
2019-06-21 02:55:31,032 [salt.minion      :1432][INFO    ][15077] Starting a new job with PID 15077
2019-06-21 02:55:31,063 [salt.minion      :1711][INFO    ][15077] Returning information for job: 20190621025530982449
2019-06-21 02:55:37,342 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.76548791s left)
2019-06-21 02:56:01,102 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025601090976
2019-06-21 02:56:01,133 [salt.minion      :1432][INFO    ][15136] Starting a new job with PID 15136
2019-06-21 02:56:01,156 [salt.minion      :1711][INFO    ][15136] Returning information for job: 20190621025601090976
2019-06-21 02:56:10,104 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.00340796s left)
2019-06-21 02:56:31,184 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025631167749
2019-06-21 02:56:31,217 [salt.minion      :1432][INFO    ][15156] Starting a new job with PID 15156
2019-06-21 02:56:31,242 [salt.minion      :1711][INFO    ][15156] Returning information for job: 20190621025631167749
2019-06-21 02:56:42,988 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.11904788s left)
2019-06-21 02:57:01,291 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025701275973
2019-06-21 02:57:01,319 [salt.minion      :1432][INFO    ][15217] Starting a new job with PID 15217
2019-06-21 02:57:01,339 [salt.minion      :1711][INFO    ][15217] Returning information for job: 20190621025701275973
2019-06-21 02:57:15,767 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.3406179s left)
2019-06-21 02:57:31,380 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025731367180
2019-06-21 02:57:31,404 [salt.minion      :1432][INFO    ][15241] Starting a new job with PID 15241
2019-06-21 02:57:31,427 [salt.minion      :1711][INFO    ][15241] Returning information for job: 20190621025731367180
2019-06-21 02:57:48,595 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.51207304s left)
2019-06-21 02:58:01,484 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025801469820
2019-06-21 02:58:01,516 [salt.minion      :1432][INFO    ][15296] Starting a new job with PID 15296
2019-06-21 02:58:01,541 [salt.minion      :1711][INFO    ][15296] Returning information for job: 20190621025801469820
2019-06-21 02:58:21,154 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.95328999s left)
2019-06-21 02:58:31,592 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025831576704
2019-06-21 02:58:31,626 [salt.minion      :1432][INFO    ][15356] Starting a new job with PID 15356
2019-06-21 02:58:31,664 [salt.minion      :1711][INFO    ][15356] Returning information for job: 20190621025831576704
2019-06-21 02:58:54,224 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.88305402s left)
2019-06-21 02:59:01,753 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025901743109
2019-06-21 02:59:01,777 [salt.minion      :1432][INFO    ][15498] Starting a new job with PID 15498
2019-06-21 02:59:01,805 [salt.minion      :1711][INFO    ][15498] Returning information for job: 20190621025901743109
2019-06-21 02:59:26,815 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1919.29208398s left)
2019-06-21 02:59:31,879 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621025931866556
2019-06-21 02:59:31,907 [salt.minion      :1432][INFO    ][15531] Starting a new job with PID 15531
2019-06-21 02:59:31,930 [salt.minion      :1711][INFO    ][15531] Returning information for job: 20190621025931866556
2019-06-21 03:00:00,004 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1886.10286093s left)
2019-06-21 03:00:02,015 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621030002002853
2019-06-21 03:00:02,046 [salt.minion      :1432][INFO    ][15681] Starting a new job with PID 15681
2019-06-21 03:00:02,067 [salt.minion      :1711][INFO    ][15681] Returning information for job: 20190621030002002853
2019-06-21 03:00:32,136 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621030032128408
2019-06-21 03:00:32,155 [salt.minion      :1432][INFO    ][15712] Starting a new job with PID 15712
2019-06-21 03:00:32,180 [salt.minion      :1711][INFO    ][15712] Returning information for job: 20190621030032128408
2019-06-21 03:00:32,924 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1853.18297696s left)
2019-06-21 03:01:02,276 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621030102260802
2019-06-21 03:01:02,309 [salt.minion      :1432][INFO    ][15804] Starting a new job with PID 15804
2019-06-21 03:01:02,332 [salt.minion      :1711][INFO    ][15804] Returning information for job: 20190621030102260802
2019-06-21 03:01:06,023 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1820.08382487s left)
2019-06-21 03:01:32,431 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621030132415388
2019-06-21 03:01:32,461 [salt.minion      :1432][INFO    ][15832] Starting a new job with PID 15832
2019-06-21 03:01:32,485 [salt.minion      :1711][INFO    ][15832] Returning information for job: 20190621030132415388
2019-06-21 03:01:38,733 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1787.37449002s left)
2019-06-21 03:02:02,581 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621030202570407
2019-06-21 03:02:02,611 [salt.minion      :1432][INFO    ][15960] Starting a new job with PID 15960
2019-06-21 03:02:02,634 [salt.minion      :1711][INFO    ][15960] Returning information for job: 20190621030202570407
2019-06-21 03:02:11,531 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1754.57615399s left)
2019-06-21 03:02:32,746 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621030232730791
2019-06-21 03:02:32,772 [salt.minion      :1432][INFO    ][15991] Starting a new job with PID 15991
2019-06-21 03:02:32,796 [salt.minion      :1711][INFO    ][15991] Returning information for job: 20190621030232730791
2019-06-21 03:02:44,384 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1721.72360086s left)
2019-06-21 03:03:02,902 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621030302888680
2019-06-21 03:03:02,931 [salt.minion      :1432][INFO    ][16101] Starting a new job with PID 16101
2019-06-21 03:03:02,957 [salt.minion      :1711][INFO    ][16101] Returning information for job: 20190621030302888680
2019-06-21 03:03:17,387 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1688.72036886s left)
2019-06-21 03:03:33,080 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621030333069596
2019-06-21 03:03:33,109 [salt.minion      :1432][INFO    ][16134] Starting a new job with PID 16134
2019-06-21 03:03:33,136 [salt.minion      :1711][INFO    ][16134] Returning information for job: 20190621030333069596
2019-06-21 03:03:50,224 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1655.88302588s left)
2019-06-21 03:04:03,263 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621030403247630
2019-06-21 03:04:03,294 [salt.minion      :1432][INFO    ][16180] Starting a new job with PID 16180
2019-06-21 03:04:03,323 [salt.minion      :1711][INFO    ][16180] Returning information for job: 20190621030403247630
2019-06-21 03:04:23,274 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1622.83384895s left)
2019-06-21 03:04:33,453 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621030433438885
2019-06-21 03:04:33,480 [salt.minion      :1432][INFO    ][16213] Starting a new job with PID 16213
2019-06-21 03:04:33,507 [salt.minion      :1711][INFO    ][16213] Returning information for job: 20190621030433438885
2019-06-21 03:04:56,387 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1589.72001886s left)
2019-06-21 03:05:03,629 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621030503617405
2019-06-21 03:05:03,658 [salt.minion      :1432][INFO    ][16429] Starting a new job with PID 16429
2019-06-21 03:05:03,682 [salt.minion      :1711][INFO    ][16429] Returning information for job: 20190621030503617405
2019-06-21 03:05:29,344 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1556.76375389s left)
2019-06-21 03:05:33,826 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621030533809156
2019-06-21 03:05:33,851 [salt.minion      :1432][INFO    ][16457] Starting a new job with PID 16457
2019-06-21 03:05:33,876 [salt.minion      :1711][INFO    ][16457] Returning information for job: 20190621030533809156
2019-06-21 03:06:02,356 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1523.75155091s left)
2019-06-21 03:06:04,023 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621030604013703
2019-06-21 03:06:04,043 [salt.minion      :1432][INFO    ][16504] Starting a new job with PID 16504
2019-06-21 03:06:04,065 [salt.minion      :1711][INFO    ][16504] Returning information for job: 20190621030604013703
2019-06-21 03:06:05,101 [salt.utils.schedule:1377][INFO    ][6171] Running scheduled job: __mine_interval
2019-06-21 03:06:34,220 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621030634211435
2019-06-21 03:06:34,237 [salt.minion      :1432][INFO    ][16566] Starting a new job with PID 16566
2019-06-21 03:06:34,264 [salt.minion      :1711][INFO    ][16566] Returning information for job: 20190621030634211435
2019-06-21 03:06:35,331 [salt.loaded.ext.module.maas:1023][INFO    ][14907] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1490.77589798s left)
2019-06-21 03:07:04,352 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command saltutil.find_job with jid 20190621030704340803
2019-06-21 03:07:04,388 [salt.minion      :1432][INFO    ][16718] Starting a new job with PID 16718
2019-06-21 03:07:04,413 [salt.minion      :1711][INFO    ][16718] Returning information for job: 20190621030704340803
2019-06-21 03:07:08,177 [salt.state       :300 ][INFO    ][14907] {'ret': True}
2019-06-21 03:07:08,177 [salt.state       :1951][INFO    ][14907] Completed state [maas.wait_for_machine_status] at time 03:07:08.177545 duration_in_ms=792086.124
2019-06-21 03:07:08,183 [salt.minion      :1711][INFO    ][14907] Returning information for job: 20190621025345535393
2019-06-21 04:06:05,102 [salt.utils.schedule:1377][INFO    ][6171] Running scheduled job: __mine_interval
2019-06-21 04:39:16,181 [salt.minion      :1308][INFO    ][6171] User sudo_ubuntu Executing command cp.push_dir with jid 20190621043916164126
2019-06-21 04:39:16,208 [salt.minion      :1432][INFO    ][23225] Starting a new job with PID 23225
