2019-05-12 05:07:26,758 [salt.utils.decorators:613 ][WARNING ][2291] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:07:27,671 [salt.utils.decorators:613 ][WARNING ][2291] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:07:30,616 [salt.loaded.int.states.file:2298][WARNING ][2579] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-12 05:07:46,125 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2973] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-12 05:07:46,160 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2973] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-12 05:07:46,192 [salt.utils.parsers:1051][WARNING ][388] Minion received a SIGTERM. Exiting.
2019-05-12 05:07:47,200 [salt.cli.daemons :293 ][INFO    ][3087] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-05-12 05:07:47,331 [salt.cli.daemons :82  ][INFO    ][3087] Starting up the Salt Minion
2019-05-12 05:07:47,331 [salt.utils.event :1017][INFO    ][3087] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-05-12 05:07:48,481 [salt.minion      :976 ][INFO    ][3087] Creating minion process manager
2019-05-12 05:07:50,510 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3087] Executing command ['date', '+%z'] in directory '/root'
2019-05-12 05:07:50,531 [salt.utils.schedule:568 ][INFO    ][3087] Updating job settings for scheduled job: __mine_interval
2019-05-12 05:07:50,532 [salt.minion      :1108][INFO    ][3087] Added mine.update to scheduler
2019-05-12 05:07:50,540 [salt.minion      :1975][INFO    ][3087] Minion is starting as user 'root'
2019-05-12 05:07:50,560 [salt.minion      :2336][INFO    ][3087] Minion is ready to receive requests!
2019-05-12 05:07:55,181 [salt.state       :2022][WARNING ][2979] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-12 05:07:58,254 [salt.utils.decorators:613 ][WARNING ][2979] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:07:59,219 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512050759200958
2019-05-12 05:07:59,244 [salt.minion      :1432][INFO    ][3624] Starting a new job with PID 3624
2019-05-12 05:07:59,276 [salt.minion      :1711][INFO    ][3624] Returning information for job: 20190512050759200958
2019-05-12 05:08:10,427 [salt.utils.decorators:613 ][WARNING ][2979] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:08:29,361 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512050829349703
2019-05-12 05:08:29,384 [salt.minion      :1432][INFO    ][4257] Starting a new job with PID 4257
2019-05-12 05:08:29,404 [salt.minion      :1711][INFO    ][4257] Returning information for job: 20190512050829349703
2019-05-12 05:08:36,202 [salt.utils.decorators:613 ][WARNING ][2979] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:08:37,340 [salt.utils.decorators:613 ][WARNING ][2979] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:08:39,857 [salt.loaded.ext.module.maasng:1008][WARNING ][2979] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-05-12 05:08:39,858 [salt.loaded.ext.module.maasng:1011][WARNING ][2979] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-05-12 05:08:40,612 [salt.loaded.ext.module.maasng:1235][WARNING ][2979] Ignoring parameter vlan:0
2019-05-12 05:08:41,566 [salt.utils.decorators:613 ][WARNING ][2979] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:08:42,383 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command state.apply with jid 20190512050842369466
2019-05-12 05:08:42,407 [salt.minion      :1432][INFO    ][4617] Starting a new job with PID 4617
2019-05-12 05:08:48,546 [salt.state       :915 ][INFO    ][4617] Loading fresh modules for state activity
2019-05-12 05:08:48,618 [salt.fileclient  :1219][INFO    ][4617] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-05-12 05:08:48,670 [salt.state       :1780][INFO    ][4617] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:08:48.670194
2019-05-12 05:08:48,670 [salt.state       :1813][INFO    ][4617] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-12 05:08:48,672 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4617] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-12 05:08:50,402 [salt.state       :300 ][INFO    ][4617] {'pid': 4643, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-12 05:08:50,403 [salt.state       :1951][INFO    ][4617] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:08:50.403240 duration_in_ms=1733.045
2019-05-12 05:08:50,407 [salt.state       :1780][INFO    ][4617] Running state [maas.process_machines] at time 05:08:50.407103
2019-05-12 05:08:50,407 [salt.state       :1813][INFO    ][4617] Executing state module.run for [maas.process_machines]
2019-05-12 05:08:50,409 [salt.utils.decorators:613 ][WARNING ][4617] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:08:50,493 [salt.loaded.ext.module.maas:412 ][WARNING ][4617] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-12 05:08:50,493 [salt.loaded.ext.module.maas:92  ][INFO    ][4617] 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 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-12 05:08:52,098 [salt.loaded.ext.module.maas:412 ][WARNING ][4617] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-12 05:08:52,098 [salt.loaded.ext.module.maas:92  ][INFO    ][4617] 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 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-12 05:08:53,521 [salt.loaded.ext.module.maas:412 ][WARNING ][4617] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-12 05:08:53,525 [salt.loaded.ext.module.maas:92  ][INFO    ][4617] 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 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-12 05:08:54,948 [salt.loaded.ext.module.maas:412 ][WARNING ][4617] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-12 05:08:54,948 [salt.loaded.ext.module.maas:92  ][INFO    ][4617] 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 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-12 05:08:56,375 [salt.loaded.ext.module.maas:412 ][WARNING ][4617] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-12 05:08:56,375 [salt.loaded.ext.module.maas:92  ][INFO    ][4617] 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 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-12 05:08:57,413 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512050857400911
2019-05-12 05:08:57,430 [salt.minion      :1432][INFO    ][4906] Starting a new job with PID 4906
2019-05-12 05:08:57,451 [salt.minion      :1711][INFO    ][4906] Returning information for job: 20190512050857400911
2019-05-12 05:08:57,839 [salt.state       :300 ][INFO    ][4617] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-12 05:08:57,839 [salt.state       :1951][INFO    ][4617] Completed state [maas.process_machines] at time 05:08:57.839874 duration_in_ms=7432.771
2019-05-12 05:08:57,845 [salt.minion      :1711][INFO    ][4617] Returning information for job: 20190512050842369466
2019-05-12 05:09:28,931 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command state.apply with jid 20190512050928920182
2019-05-12 05:09:28,957 [salt.minion      :1432][INFO    ][4980] Starting a new job with PID 4980
2019-05-12 05:09:34,954 [salt.state       :915 ][INFO    ][4980] Loading fresh modules for state activity
2019-05-12 05:09:35,010 [salt.fileclient  :1219][INFO    ][4980] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-12 05:09:35,059 [salt.state       :1780][INFO    ][4980] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:09:35.059708
2019-05-12 05:09:35,060 [salt.state       :1813][INFO    ][4980] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-12 05:09:35,061 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4980] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-12 05:09:36,927 [salt.state       :300 ][INFO    ][4980] {'pid': 4990, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-12 05:09:36,928 [salt.state       :1951][INFO    ][4980] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:09:36.928144 duration_in_ms=1868.434
2019-05-12 05:09:36,932 [salt.state       :1780][INFO    ][4980] Running state [maas.wait_for_machine_status] at time 05:09:36.932041
2019-05-12 05:09:36,932 [salt.state       :1813][INFO    ][4980] Executing state module.run for [maas.wait_for_machine_status]
2019-05-12 05:09:36,933 [salt.utils.decorators:613 ][WARNING ][4980] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:09:38,222 [salt.loaded.ext.module.maas:1023][INFO    ][4980] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.72205901s left)
2019-05-12 05:09:44,037 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512050944021111
2019-05-12 05:09:44,070 [salt.minion      :1432][INFO    ][5001] Starting a new job with PID 5001
2019-05-12 05:09:44,094 [salt.minion      :1711][INFO    ][5001] Returning information for job: 20190512050944021111
2019-05-12 05:10:09,034 [salt.loaded.ext.module.maas:1023][INFO    ][4980] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.910043s left)
2019-05-12 05:10:14,134 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051014116960
2019-05-12 05:10:14,164 [salt.minion      :1432][INFO    ][5083] Starting a new job with PID 5083
2019-05-12 05:10:14,191 [salt.minion      :1711][INFO    ][5083] Returning information for job: 20190512051014116960
2019-05-12 05:10:39,898 [salt.loaded.ext.module.maas:1023][INFO    ][4980] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.04603195s left)
2019-05-12 05:10:44,225 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051044213795
2019-05-12 05:10:44,254 [salt.minion      :1432][INFO    ][5105] Starting a new job with PID 5105
2019-05-12 05:10:44,276 [salt.minion      :1711][INFO    ][5105] Returning information for job: 20190512051044213795
2019-05-12 05:11:10,697 [salt.loaded.ext.module.maas:1023][INFO    ][4980] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.24678898s left)
2019-05-12 05:11:14,297 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051114285491
2019-05-12 05:11:14,317 [salt.minion      :1432][INFO    ][5157] Starting a new job with PID 5157
2019-05-12 05:11:14,343 [salt.minion      :1711][INFO    ][5157] Returning information for job: 20190512051114285491
2019-05-12 05:11:41,791 [salt.loaded.ext.module.maas:1023][INFO    ][4980] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.15292501s left)
2019-05-12 05:11:44,380 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051144365645
2019-05-12 05:11:44,406 [salt.minion      :1432][INFO    ][5208] Starting a new job with PID 5208
2019-05-12 05:11:44,425 [salt.minion      :1711][INFO    ][5208] Returning information for job: 20190512051144365645
2019-05-12 05:12:12,999 [salt.loaded.ext.module.maas:1023][INFO    ][4980] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.94493318s left)
2019-05-12 05:12:14,462 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051214442618
2019-05-12 05:12:14,493 [salt.minion      :1432][INFO    ][5364] Starting a new job with PID 5364
2019-05-12 05:12:14,515 [salt.minion      :1711][INFO    ][5364] Returning information for job: 20190512051214442618
2019-05-12 05:12:44,186 [salt.loaded.ext.module.maas:1023][INFO    ][4980] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.75746918s left)
2019-05-12 05:12:44,581 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051244563957
2019-05-12 05:12:44,609 [salt.minion      :1432][INFO    ][5480] Starting a new job with PID 5480
2019-05-12 05:12:44,631 [salt.minion      :1711][INFO    ][5480] Returning information for job: 20190512051244563957
2019-05-12 05:13:14,716 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051314705674
2019-05-12 05:13:14,734 [salt.minion      :1432][INFO    ][5773] Starting a new job with PID 5773
2019-05-12 05:13:14,756 [salt.minion      :1711][INFO    ][5773] Returning information for job: 20190512051314705674
2019-05-12 05:13:15,556 [salt.loaded.ext.module.maas:1023][INFO    ][4980] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.38818717s left)
2019-05-12 05:13:44,787 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051344772593
2019-05-12 05:13:44,811 [salt.minion      :1432][INFO    ][5898] Starting a new job with PID 5898
2019-05-12 05:13:44,835 [salt.minion      :1711][INFO    ][5898] Returning information for job: 20190512051344772593
2019-05-12 05:13:47,331 [salt.loaded.ext.module.maas:1023][INFO    ][4980] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.61269212s left)
2019-05-12 05:14:14,934 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051414918531
2019-05-12 05:14:14,962 [salt.minion      :1432][INFO    ][6175] Starting a new job with PID 6175
2019-05-12 05:14:14,986 [salt.minion      :1711][INFO    ][6175] Returning information for job: 20190512051414918531
2019-05-12 05:14:19,525 [salt.loaded.ext.module.maas:1023][INFO    ][4980] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.41908908s left)
2019-05-12 05:14:45,046 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051445026016
2019-05-12 05:14:45,079 [salt.minion      :1432][INFO    ][6359] Starting a new job with PID 6359
2019-05-12 05:14:45,101 [salt.minion      :1711][INFO    ][6359] Returning information for job: 20190512051445026016
2019-05-12 05:14:51,891 [salt.loaded.ext.module.maas:1023][INFO    ][4980] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.05309916s left)
2019-05-12 05:15:15,187 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051515173670
2019-05-12 05:15:15,216 [salt.minion      :1432][INFO    ][6501] Starting a new job with PID 6501
2019-05-12 05:15:15,240 [salt.minion      :1711][INFO    ][6501] Returning information for job: 20190512051515173670
2019-05-12 05:15:24,046 [salt.loaded.ext.module.maas:1023][INFO    ][4980] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1152.89798903s left)
2019-05-12 05:15:45,317 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051545301573
2019-05-12 05:15:45,345 [salt.minion      :1432][INFO    ][6642] Starting a new job with PID 6642
2019-05-12 05:15:45,366 [salt.minion      :1711][INFO    ][6642] Returning information for job: 20190512051545301573
2019-05-12 05:15:57,037 [salt.state       :300 ][INFO    ][4980] {'ret': True}
2019-05-12 05:15:57,039 [salt.state       :1951][INFO    ][4980] Completed state [maas.wait_for_machine_status] at time 05:15:57.039233 duration_in_ms=380107.189
2019-05-12 05:15:57,043 [salt.minion      :1711][INFO    ][4980] Returning information for job: 20190512050928920182
2019-05-12 05:15:57,606 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command state.apply with jid 20190512051557592332
2019-05-12 05:15:57,632 [salt.minion      :1432][INFO    ][6678] Starting a new job with PID 6678
2019-05-12 05:16:03,643 [salt.state       :915 ][INFO    ][6678] Loading fresh modules for state activity
2019-05-12 05:16:03,708 [salt.fileclient  :1219][INFO    ][6678] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-12 05:16:03,807 [salt.state       :1780][INFO    ][6678] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:03.807839
2019-05-12 05:16:03,808 [salt.state       :1813][INFO    ][6678] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-12 05:16:03,809 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6678] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-12 05:16:05,531 [salt.state       :300 ][INFO    ][6678] {'pid': 6713, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-12 05:16:05,532 [salt.state       :1951][INFO    ][6678] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:05.532343 duration_in_ms=1724.504
2019-05-12 05:16:05,534 [salt.state       :1780][INFO    ][6678] Running state [maas_machines_storage_cmp002_lvm] at time 05:16:05.534899
2019-05-12 05:16:05,535 [salt.state       :1813][INFO    ][6678] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-12 05:16:06,715 [salt.loaded.ext.module.maasng:610 ][INFO    ][6678] 7tdsen
2019-05-12 05:16:06,716 [salt.loaded.ext.module.maasng:626 ][INFO    ][6678] sda
2019-05-12 05:16:07,387 [salt.loaded.ext.module.maasng:361 ][INFO    ][6678] 7tdsen
2019-05-12 05:16:07,488 [salt.loaded.ext.module.maasng:367 ][INFO    ][6678] [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'7tdsen', 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'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'9f654e39-3f4a-4aa3-8d7b-6f3f8cb876ca', u'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7tdsen', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2709f23d-cc17-43a3-8944-ff18020ff6f2', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'device_id': 1}]}, {u'size': 800097042432, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'uuid': u'eac2decb-de3a-4112-b7ad-4e88bc832f46', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'7tdsen', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'269e9a6c-9d5b-427f-8ad5-074c64055e58', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/blockdevices/3/', u'type': u'virtual', u'id': 3, u'serial': None, u'partitions': []}]
2019-05-12 05:16:07,489 [salt.loaded.ext.module.maasng:632 ][INFO    ][6678] vgroot
2019-05-12 05:16:07,490 [salt.loaded.ext.module.maasng:635 ][INFO    ][6678] lvroot
2019-05-12 05:16:07,490 [salt.loaded.ext.module.maasng:639 ][INFO    ][6678] 107374182400
2019-05-12 05:16:08,100 [salt.loaded.ext.module.maasng:645 ][INFO    ][6678] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qxfa6r', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qxfa6r', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qxfa6r', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'system_id': u'7tdsen', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/interfaces/4/'}, u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'7tdsen', 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'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/blockdevices/11/', u'id_path': None, u'uuid': u'a588aa3a-322d-4977-8680-9cf3a2f898d5', u'tags': [], u'type': u'virtual', u'partitions': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'7tdsen', u'partition_table_type': None, u'filesystem': {u'uuid': u'c7c77e7a-7c06-4fba-b88a-238a2fa0d5aa', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'block_size': 4096, u'available_size': 0, u'model': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/blockdevices/1/', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'a2f7aa59-0c7f-4a31-ac41-5e698c9020fa', u'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7tdsen', u'filesystem': {u'uuid': u'12d9623d-b5b0-4db4-a10e-f302a460ad73', 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': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'7tdsen', u'partition_table_type': u'MBR', u'filesystem': None, u'block_size': 4096, u'available_size': 0, u'model': u'LOGICAL VOLUME', u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/blockdevices/11/', u'id_path': None, u'uuid': u'a588aa3a-322d-4977-8680-9cf3a2f898d5', u'tags': [], u'type': u'virtual', u'partitions': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'7tdsen', u'partition_table_type': None, u'filesystem': {u'uuid': u'c7c77e7a-7c06-4fba-b88a-238a2fa0d5aa', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'block_size': 4096, u'available_size': 0, u'model': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/blockdevices/1/', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'a2f7aa59-0c7f-4a31-ac41-5e698c9020fa', u'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7tdsen', u'filesystem': {u'uuid': u'12d9623d-b5b0-4db4-a10e-f302a460ad73', 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': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'7tdsen', u'partition_table_type': u'MBR', u'filesystem': None, u'block_size': 4096, u'available_size': 0, u'model': u'LOGICAL VOLUME', u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'7tdsen', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/blockdevices/1/', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'a2f7aa59-0c7f-4a31-ac41-5e698c9020fa', u'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7tdsen', u'filesystem': {u'uuid': u'12d9623d-b5b0-4db4-a10e-f302a460ad73', 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': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'7tdsen', u'partition_table_type': u'MBR', u'filesystem': None, u'block_size': 4096, u'available_size': 0, u'model': u'LOGICAL VOLUME', u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qxfa6r', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qxfa6r', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qxfa6r', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'system_id': u'7tdsen', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'system_id': u'7tdsen', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/interfaces/14/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'system_id': u'7tdsen', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/interfaces/11/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qxfa6r', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qxfa6r', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qxfa6r', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'system_id': u'7tdsen', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/interfaces/15/'}, {u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'system_id': u'7tdsen', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/interfaces/12/'}, {u'name': u'ens2f0', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'system_id': u'7tdsen', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/7tdsen/interfaces/13/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/7tdsen/'}
2019-05-12 05:16:08,105 [salt.state       :300 ][INFO    ][6678] {'new': {'storage_layout': 'lvm'}}
2019-05-12 05:16:08,106 [salt.state       :1951][INFO    ][6678] Completed state [maas_machines_storage_cmp002_lvm] at time 05:16:08.105683 duration_in_ms=2570.782
2019-05-12 05:16:08,106 [salt.state       :1780][INFO    ][6678] Running state [maas_machines_storage_cmp001_lvm] at time 05:16:08.106683
2019-05-12 05:16:08,107 [salt.state       :1813][INFO    ][6678] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-12 05:16:09,299 [salt.loaded.ext.module.maasng:610 ][INFO    ][6678] eyxrnd
2019-05-12 05:16:09,299 [salt.loaded.ext.module.maasng:626 ][INFO    ][6678] sda
2019-05-12 05:16:09,868 [salt.loaded.ext.module.maasng:361 ][INFO    ][6678] eyxrnd
2019-05-12 05:16:09,961 [salt.loaded.ext.module.maasng:367 ][INFO    ][6678] [{u'size': 800109715456, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'partitions': [{u'uuid': u'12434632-ea0a-4493-b06f-c3d19d62a669', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'eyxrnd', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'19944ed1-fdb3-4904-ace5-d65053f67b71', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'eyxrnd', 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'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/blockdevices/2/'}, {u'size': 800097042432, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'id': 4, u'partitions': [], u'uuid': u'54a0cff2-f1fa-432b-bed1-9d8cc173fbbf', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'eyxrnd', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'fd0ae684-6614-4120-aeec-dd3ec153351a', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/blockdevices/4/'}]
2019-05-12 05:16:09,962 [salt.loaded.ext.module.maasng:632 ][INFO    ][6678] vgroot
2019-05-12 05:16:09,963 [salt.loaded.ext.module.maasng:635 ][INFO    ][6678] lvroot
2019-05-12 05:16:09,963 [salt.loaded.ext.module.maasng:639 ][INFO    ][6678] 107374182400
2019-05-12 05:16:10,558 [salt.loaded.ext.module.maasng:645 ][INFO    ][6678] {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'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qxfa6r', u'name': u'untagged', 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': 25}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qxfa6r', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qxfa6r', u'name': u'untagged', 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'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'system_id': u'eyxrnd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'f091c068-fd32-4ffb-a8b4-84f5242436ee', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'eyxrnd', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'8f63a8a0-52a1-427a-a6f1-dea73c499414', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'eyxrnd', 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'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/blockdevices/2/'}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'eyxrnd', 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'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'4c918f4b-d19f-4ec7-a2ff-682343500022', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'eyxrnd', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'52c9cfa7-2dff-4e7c-8884-73236f3c8b2c', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/blockdevices/12/'}], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'f091c068-fd32-4ffb-a8b4-84f5242436ee', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'eyxrnd', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'8f63a8a0-52a1-427a-a6f1-dea73c499414', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'eyxrnd', 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'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/blockdevices/2/'}, {u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'4c918f4b-d19f-4ec7-a2ff-682343500022', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'eyxrnd', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'52c9cfa7-2dff-4e7c-8884-73236f3c8b2c', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/blockdevices/12/'}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'f091c068-fd32-4ffb-a8b4-84f5242436ee', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'eyxrnd', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'8f63a8a0-52a1-427a-a6f1-dea73c499414', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'eyxrnd', 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'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/blockdevices/2/'}], u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'eyxrnd', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qxfa6r', u'name': u'untagged', 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': 25}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qxfa6r', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qxfa6r', u'name': u'untagged', 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'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'system_id': u'eyxrnd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'system_id': u'eyxrnd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/interfaces/16/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'system_id': u'eyxrnd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/interfaces/17/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 18, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'system_id': u'eyxrnd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/interfaces/18/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qxfa6r', u'name': u'untagged', 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': 27}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qxfa6r', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 19, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qxfa6r', u'name': u'untagged', 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.43'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'system_id': u'eyxrnd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/interfaces/19/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 28}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'system_id': u'eyxrnd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/eyxrnd/interfaces/20/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/eyxrnd/'}
2019-05-12 05:16:10,561 [salt.state       :300 ][INFO    ][6678] {'new': {'storage_layout': 'lvm'}}
2019-05-12 05:16:10,563 [salt.state       :1951][INFO    ][6678] Completed state [maas_machines_storage_cmp001_lvm] at time 05:16:10.563626 duration_in_ms=2456.943
2019-05-12 05:16:10,567 [salt.minion      :1711][INFO    ][6678] Returning information for job: 20190512051557592332
2019-05-12 05:16:11,135 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command state.apply with jid 20190512051611122466
2019-05-12 05:16:11,159 [salt.minion      :1432][INFO    ][6731] Starting a new job with PID 6731
2019-05-12 05:16:12,102 [salt.state       :915 ][INFO    ][6731] Loading fresh modules for state activity
2019-05-12 05:16:12,152 [salt.fileclient  :1219][INFO    ][6731] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-12 05:16:12,196 [salt.state       :1780][INFO    ][6731] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:12.196912
2019-05-12 05:16:12,197 [salt.state       :1813][INFO    ][6731] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-12 05:16:12,198 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6731] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-12 05:16:13,916 [salt.state       :300 ][INFO    ][6731] {'pid': 6738, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-12 05:16:13,917 [salt.state       :1951][INFO    ][6731] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:13.917442 duration_in_ms=1720.53
2019-05-12 05:16:13,920 [salt.state       :1780][INFO    ][6731] Running state [maas.deploy_machines] at time 05:16:13.920521
2019-05-12 05:16:13,921 [salt.state       :1813][INFO    ][6731] Executing state module.run for [maas.deploy_machines]
2019-05-12 05:16:13,924 [salt.utils.decorators:613 ][WARNING ][6731] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:16:14,478 [salt.loaded.ext.module.maas:684 ][INFO    ][6731] deploymachines hwe_kernel=hwe-16.04 system_id=7tdsen distro_series=xenial
2019-05-12 05:16:17,036 [salt.loaded.ext.module.maas:684 ][INFO    ][6731] deploymachines hwe_kernel=hwe-16.04 system_id=eyxrnd distro_series=xenial
2019-05-12 05:16:19,520 [salt.loaded.ext.module.maas:684 ][INFO    ][6731] deploymachines hwe_kernel=hwe-16.04 system_id=qgxg8d distro_series=xenial
2019-05-12 05:16:22,051 [salt.loaded.ext.module.maas:684 ][INFO    ][6731] deploymachines hwe_kernel=hwe-16.04 system_id=mesrsg distro_series=xenial
2019-05-12 05:16:24,572 [salt.loaded.ext.module.maas:684 ][INFO    ][6731] deploymachines hwe_kernel=hwe-16.04 system_id=67w8xp distro_series=xenial
2019-05-12 05:16:26,168 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051626157488
2019-05-12 05:16:26,191 [salt.minion      :1432][INFO    ][6998] Starting a new job with PID 6998
2019-05-12 05:16:26,212 [salt.minion      :1711][INFO    ][6998] Returning information for job: 20190512051626157488
2019-05-12 05:16:27,160 [salt.state       :300 ][INFO    ][6731] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-12 05:16:27,161 [salt.state       :1951][INFO    ][6731] Completed state [maas.deploy_machines] at time 05:16:27.161301 duration_in_ms=13240.779
2019-05-12 05:16:27,164 [salt.minion      :1711][INFO    ][6731] Returning information for job: 20190512051611122466
2019-05-12 05:16:27,728 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command state.apply with jid 20190512051627711828
2019-05-12 05:16:27,753 [salt.minion      :1432][INFO    ][7020] Starting a new job with PID 7020
2019-05-12 05:16:33,834 [salt.state       :915 ][INFO    ][7020] Loading fresh modules for state activity
2019-05-12 05:16:33,896 [salt.fileclient  :1219][INFO    ][7020] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-12 05:16:33,951 [salt.state       :1780][INFO    ][7020] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:33.951256
2019-05-12 05:16:33,951 [salt.state       :1813][INFO    ][7020] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-12 05:16:33,953 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7020] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-12 05:16:35,808 [salt.state       :300 ][INFO    ][7020] {'pid': 7040, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-12 05:16:35,809 [salt.state       :1951][INFO    ][7020] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:35.808918 duration_in_ms=1857.661
2019-05-12 05:16:35,814 [salt.state       :1780][INFO    ][7020] Running state [maas.wait_for_machine_status] at time 05:16:35.814349
2019-05-12 05:16:35,815 [salt.state       :1813][INFO    ][7020] Executing state module.run for [maas.wait_for_machine_status]
2019-05-12 05:16:35,816 [salt.utils.decorators:613 ][WARNING ][7020] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:16:38,852 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.978477s left)
2019-05-12 05:16:42,784 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051642773209
2019-05-12 05:16:42,800 [salt.minion      :1432][INFO    ][7057] Starting a new job with PID 7057
2019-05-12 05:16:42,824 [salt.minion      :1711][INFO    ][7057] Returning information for job: 20190512051642773209
2019-05-12 05:17:11,711 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.11908293s left)
2019-05-12 05:17:12,856 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051712838206
2019-05-12 05:17:12,887 [salt.minion      :1432][INFO    ][7110] Starting a new job with PID 7110
2019-05-12 05:17:12,912 [salt.minion      :1711][INFO    ][7110] Returning information for job: 20190512051712838206
2019-05-12 05:17:42,952 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051742939210
2019-05-12 05:17:42,969 [salt.minion      :1432][INFO    ][7133] Starting a new job with PID 7133
2019-05-12 05:17:42,991 [salt.minion      :1711][INFO    ][7133] Returning information for job: 20190512051742939210
2019-05-12 05:17:44,902 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.92811298s left)
2019-05-12 05:18:13,024 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051813008530
2019-05-12 05:18:13,049 [salt.minion      :1432][INFO    ][7208] Starting a new job with PID 7208
2019-05-12 05:18:13,071 [salt.minion      :1711][INFO    ][7208] Returning information for job: 20190512051813008530
2019-05-12 05:18:17,762 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.06810594s left)
2019-05-12 05:18:43,112 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051843101708
2019-05-12 05:18:43,144 [salt.minion      :1432][INFO    ][7235] Starting a new job with PID 7235
2019-05-12 05:18:43,165 [salt.minion      :1711][INFO    ][7235] Returning information for job: 20190512051843101708
2019-05-12 05:18:50,704 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.12644792s left)
2019-05-12 05:19:13,199 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051913180745
2019-05-12 05:19:13,228 [salt.minion      :1432][INFO    ][7305] Starting a new job with PID 7305
2019-05-12 05:19:13,250 [salt.minion      :1711][INFO    ][7305] Returning information for job: 20190512051913180745
2019-05-12 05:19:23,912 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.91872597s left)
2019-05-12 05:19:43,275 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512051943260435
2019-05-12 05:19:43,300 [salt.minion      :1432][INFO    ][7394] Starting a new job with PID 7394
2019-05-12 05:19:43,318 [salt.minion      :1711][INFO    ][7394] Returning information for job: 20190512051943260435
2019-05-12 05:19:57,003 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.82930088s left)
2019-05-12 05:20:13,381 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052013373730
2019-05-12 05:20:13,403 [salt.minion      :1432][INFO    ][7603] Starting a new job with PID 7603
2019-05-12 05:20:13,425 [salt.minion      :1711][INFO    ][7603] Returning information for job: 20190512052013373730
2019-05-12 05:20:30,053 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.77789283s left)
2019-05-12 05:20:43,492 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052043478756
2019-05-12 05:20:43,510 [salt.minion      :1432][INFO    ][7715] Starting a new job with PID 7715
2019-05-12 05:20:43,532 [salt.minion      :1711][INFO    ][7715] Returning information for job: 20190512052043478756
2019-05-12 05:21:03,068 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.76223087s left)
2019-05-12 05:21:13,589 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052113576072
2019-05-12 05:21:13,615 [salt.minion      :1432][INFO    ][7888] Starting a new job with PID 7888
2019-05-12 05:21:13,636 [salt.minion      :1711][INFO    ][7888] Returning information for job: 20190512052113576072
2019-05-12 05:21:36,270 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.56069398s left)
2019-05-12 05:21:43,712 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052143696834
2019-05-12 05:21:43,737 [salt.minion      :1432][INFO    ][8029] Starting a new job with PID 8029
2019-05-12 05:21:43,759 [salt.minion      :1711][INFO    ][8029] Returning information for job: 20190512052143696834
2019-05-12 05:22:09,300 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.53077292s left)
2019-05-12 05:22:13,859 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052213847596
2019-05-12 05:22:13,880 [salt.minion      :1432][INFO    ][8352] Starting a new job with PID 8352
2019-05-12 05:22:13,902 [salt.minion      :1711][INFO    ][8352] Returning information for job: 20190512052213847596
2019-05-12 05:22:42,241 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.58997989s left)
2019-05-12 05:22:44,000 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052243983675
2019-05-12 05:22:44,024 [salt.minion      :1432][INFO    ][8480] Starting a new job with PID 8480
2019-05-12 05:22:44,045 [salt.minion      :1711][INFO    ][8480] Returning information for job: 20190512052243983675
2019-05-12 05:23:14,169 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052314160967
2019-05-12 05:23:14,185 [salt.minion      :1432][INFO    ][8843] Starting a new job with PID 8843
2019-05-12 05:23:14,213 [salt.minion      :1711][INFO    ][8843] Returning information for job: 20190512052314160967
2019-05-12 05:23:15,165 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.66535687s left)
2019-05-12 05:23:44,313 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052344296910
2019-05-12 05:23:44,342 [salt.minion      :1432][INFO    ][8961] Starting a new job with PID 8961
2019-05-12 05:23:44,365 [salt.minion      :1711][INFO    ][8961] Returning information for job: 20190512052344296910
2019-05-12 05:23:48,057 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.77380395s left)
2019-05-12 05:24:14,505 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052414492683
2019-05-12 05:24:14,532 [salt.minion      :1432][INFO    ][9142] Starting a new job with PID 9142
2019-05-12 05:24:14,556 [salt.minion      :1711][INFO    ][9142] Returning information for job: 20190512052414492683
2019-05-12 05:24:21,076 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.75431585s left)
2019-05-12 05:24:44,648 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052444632433
2019-05-12 05:24:44,672 [salt.minion      :1432][INFO    ][9242] Starting a new job with PID 9242
2019-05-12 05:24:44,693 [salt.minion      :1711][INFO    ][9242] Returning information for job: 20190512052444632433
2019-05-12 05:24:54,087 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1751.74379301s left)
2019-05-12 05:25:14,840 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052514830860
2019-05-12 05:25:14,863 [salt.minion      :1432][INFO    ][9459] Starting a new job with PID 9459
2019-05-12 05:25:14,895 [salt.minion      :1711][INFO    ][9459] Returning information for job: 20190512052514830860
2019-05-12 05:25:27,004 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1718.8265779s left)
2019-05-12 05:25:44,964 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052544951948
2019-05-12 05:25:44,997 [salt.minion      :1432][INFO    ][9565] Starting a new job with PID 9565
2019-05-12 05:25:45,034 [salt.minion      :1711][INFO    ][9565] Returning information for job: 20190512052544951948
2019-05-12 05:26:00,387 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1685.44397688s left)
2019-05-12 05:26:15,137 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052615122982
2019-05-12 05:26:15,163 [salt.minion      :1432][INFO    ][9711] Starting a new job with PID 9711
2019-05-12 05:26:15,189 [salt.minion      :1711][INFO    ][9711] Returning information for job: 20190512052615122982
2019-05-12 05:26:33,244 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.58666992s left)
2019-05-12 05:26:45,257 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052645238765
2019-05-12 05:26:45,283 [salt.minion      :1432][INFO    ][9819] Starting a new job with PID 9819
2019-05-12 05:26:45,305 [salt.minion      :1711][INFO    ][9819] Returning information for job: 20190512052645238765
2019-05-12 05:27:06,219 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1619.61128187s left)
2019-05-12 05:27:15,441 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052715426392
2019-05-12 05:27:15,471 [salt.minion      :1432][INFO    ][9954] Starting a new job with PID 9954
2019-05-12 05:27:15,492 [salt.minion      :1711][INFO    ][9954] Returning information for job: 20190512052715426392
2019-05-12 05:27:39,153 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1586.67766094s left)
2019-05-12 05:27:45,609 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052745595881
2019-05-12 05:27:45,631 [salt.minion      :1432][INFO    ][10132] Starting a new job with PID 10132
2019-05-12 05:27:45,655 [salt.minion      :1711][INFO    ][10132] Returning information for job: 20190512052745595881
2019-05-12 05:28:12,040 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1553.79074097s left)
2019-05-12 05:28:15,797 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052815782851
2019-05-12 05:28:15,826 [salt.minion      :1432][INFO    ][10192] Starting a new job with PID 10192
2019-05-12 05:28:15,849 [salt.minion      :1711][INFO    ][10192] Returning information for job: 20190512052815782851
2019-05-12 05:28:44,920 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1520.91060901s left)
2019-05-12 05:28:46,003 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052845994701
2019-05-12 05:28:46,026 [salt.minion      :1432][INFO    ][10225] Starting a new job with PID 10225
2019-05-12 05:28:46,048 [salt.minion      :1711][INFO    ][10225] Returning information for job: 20190512052845994701
2019-05-12 05:29:16,207 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052916198347
2019-05-12 05:29:16,232 [salt.minion      :1432][INFO    ][10337] Starting a new job with PID 10337
2019-05-12 05:29:16,255 [salt.minion      :1711][INFO    ][10337] Returning information for job: 20190512052916198347
2019-05-12 05:29:17,883 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1487.9475379s left)
2019-05-12 05:29:46,293 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512052946276041
2019-05-12 05:29:46,319 [salt.minion      :1432][INFO    ][10440] Starting a new job with PID 10440
2019-05-12 05:29:46,341 [salt.minion      :1711][INFO    ][10440] Returning information for job: 20190512052946276041
2019-05-12 05:29:50,670 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1455.16051698s left)
2019-05-12 05:30:16,316 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053016303865
2019-05-12 05:30:16,340 [salt.minion      :1432][INFO    ][10629] Starting a new job with PID 10629
2019-05-12 05:30:16,366 [salt.minion      :1711][INFO    ][10629] Returning information for job: 20190512053016303865
2019-05-12 05:30:23,667 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1422.16356993s left)
2019-05-12 05:30:46,351 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053046338972
2019-05-12 05:30:46,372 [salt.minion      :1432][INFO    ][10687] Starting a new job with PID 10687
2019-05-12 05:30:46,391 [salt.minion      :1711][INFO    ][10687] Returning information for job: 20190512053046338972
2019-05-12 05:30:56,568 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1389.2625289s left)
2019-05-12 05:31:16,373 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053116358531
2019-05-12 05:31:16,401 [salt.minion      :1432][INFO    ][10727] Starting a new job with PID 10727
2019-05-12 05:31:16,423 [salt.minion      :1711][INFO    ][10727] Returning information for job: 20190512053116358531
2019-05-12 05:31:29,506 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1356.32467604s left)
2019-05-12 05:31:46,439 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053146423804
2019-05-12 05:31:46,463 [salt.minion      :1432][INFO    ][10760] Starting a new job with PID 10760
2019-05-12 05:31:46,486 [salt.minion      :1711][INFO    ][10760] Returning information for job: 20190512053146423804
2019-05-12 05:32:02,527 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1323.30337787s left)
2019-05-12 05:32:16,496 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053216482217
2019-05-12 05:32:16,525 [salt.minion      :1432][INFO    ][10806] Starting a new job with PID 10806
2019-05-12 05:32:16,546 [salt.minion      :1711][INFO    ][10806] Returning information for job: 20190512053216482217
2019-05-12 05:32:35,523 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1290.30756283s left)
2019-05-12 05:32:46,576 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053246560954
2019-05-12 05:32:46,604 [salt.minion      :1432][INFO    ][10850] Starting a new job with PID 10850
2019-05-12 05:32:46,624 [salt.minion      :1711][INFO    ][10850] Returning information for job: 20190512053246560954
2019-05-12 05:33:08,243 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1257.58712888s left)
2019-05-12 05:33:16,655 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053316639138
2019-05-12 05:33:16,679 [salt.minion      :1432][INFO    ][10895] Starting a new job with PID 10895
2019-05-12 05:33:16,700 [salt.minion      :1711][INFO    ][10895] Returning information for job: 20190512053316639138
2019-05-12 05:33:41,328 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1224.50236487s left)
2019-05-12 05:33:46,761 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053346750603
2019-05-12 05:33:46,786 [salt.minion      :1432][INFO    ][10940] Starting a new job with PID 10940
2019-05-12 05:33:46,813 [salt.minion      :1711][INFO    ][10940] Returning information for job: 20190512053346750603
2019-05-12 05:34:14,211 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1191.61971498s left)
2019-05-12 05:34:16,868 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053416860070
2019-05-12 05:34:16,891 [salt.minion      :1432][INFO    ][10981] Starting a new job with PID 10981
2019-05-12 05:34:16,914 [salt.minion      :1711][INFO    ][10981] Returning information for job: 20190512053416860070
2019-05-12 05:34:46,959 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1158.87135696s left)
2019-05-12 05:34:46,999 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053446983430
2019-05-12 05:34:47,024 [salt.minion      :1432][INFO    ][11014] Starting a new job with PID 11014
2019-05-12 05:34:47,049 [salt.minion      :1711][INFO    ][11014] Returning information for job: 20190512053446983430
2019-05-12 05:35:17,152 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053517140584
2019-05-12 05:35:17,169 [salt.minion      :1432][INFO    ][11054] Starting a new job with PID 11054
2019-05-12 05:35:17,191 [salt.minion      :1711][INFO    ][11054] Returning information for job: 20190512053517140584
2019-05-12 05:35:19,792 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1126.03871489s left)
2019-05-12 05:35:47,284 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053547270458
2019-05-12 05:35:47,311 [salt.minion      :1432][INFO    ][11088] Starting a new job with PID 11088
2019-05-12 05:35:47,332 [salt.minion      :1711][INFO    ][11088] Returning information for job: 20190512053547270458
2019-05-12 05:35:52,507 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1093.32328796s left)
2019-05-12 05:36:17,464 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053617446457
2019-05-12 05:36:17,492 [salt.minion      :1432][INFO    ][11131] Starting a new job with PID 11131
2019-05-12 05:36:17,520 [salt.minion      :1711][INFO    ][11131] Returning information for job: 20190512053617446457
2019-05-12 05:36:25,504 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1060.32674694s left)
2019-05-12 05:36:47,644 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053647627505
2019-05-12 05:36:47,669 [salt.minion      :1432][INFO    ][11164] Starting a new job with PID 11164
2019-05-12 05:36:47,689 [salt.minion      :1711][INFO    ][11164] Returning information for job: 20190512053647627505
2019-05-12 05:36:58,230 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1027.60128284s left)
2019-05-12 05:37:17,861 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053717846428
2019-05-12 05:37:17,888 [salt.minion      :1432][INFO    ][11211] Starting a new job with PID 11211
2019-05-12 05:37:17,910 [salt.minion      :1711][INFO    ][11211] Returning information for job: 20190512053717846428
2019-05-12 05:37:31,014 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (994.816311836s left)
2019-05-12 05:37:48,052 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053748040291
2019-05-12 05:37:48,072 [salt.minion      :1432][INFO    ][11252] Starting a new job with PID 11252
2019-05-12 05:37:48,105 [salt.minion      :1711][INFO    ][11252] Returning information for job: 20190512053748040291
2019-05-12 05:38:03,891 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (961.939620972s left)
2019-05-12 05:38:18,076 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053818061564
2019-05-12 05:38:18,106 [salt.minion      :1432][INFO    ][11315] Starting a new job with PID 11315
2019-05-12 05:38:18,129 [salt.minion      :1711][INFO    ][11315] Returning information for job: 20190512053818061564
2019-05-12 05:38:36,791 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (929.039929867s left)
2019-05-12 05:38:48,106 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053848091414
2019-05-12 05:38:48,131 [salt.minion      :1432][INFO    ][11348] Starting a new job with PID 11348
2019-05-12 05:38:48,156 [salt.minion      :1711][INFO    ][11348] Returning information for job: 20190512053848091414
2019-05-12 05:39:09,658 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (896.172744036s left)
2019-05-12 05:39:18,149 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053918135012
2019-05-12 05:39:18,180 [salt.minion      :1432][INFO    ][11393] Starting a new job with PID 11393
2019-05-12 05:39:18,201 [salt.minion      :1711][INFO    ][11393] Returning information for job: 20190512053918135012
2019-05-12 05:39:42,455 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (863.375888824s left)
2019-05-12 05:39:48,198 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512053948184606
2019-05-12 05:39:48,224 [salt.minion      :1432][INFO    ][11428] Starting a new job with PID 11428
2019-05-12 05:39:48,244 [salt.minion      :1711][INFO    ][11428] Returning information for job: 20190512053948184606
2019-05-12 05:40:15,204 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (830.626288891s left)
2019-05-12 05:40:18,300 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054018284679
2019-05-12 05:40:18,332 [salt.minion      :1432][INFO    ][11475] Starting a new job with PID 11475
2019-05-12 05:40:18,357 [salt.minion      :1711][INFO    ][11475] Returning information for job: 20190512054018284679
2019-05-12 05:40:47,945 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (797.885823965s left)
2019-05-12 05:40:48,415 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054048404213
2019-05-12 05:40:48,437 [salt.minion      :1432][INFO    ][11512] Starting a new job with PID 11512
2019-05-12 05:40:48,461 [salt.minion      :1711][INFO    ][11512] Returning information for job: 20190512054048404213
2019-05-12 05:41:18,539 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054118528532
2019-05-12 05:41:18,565 [salt.minion      :1432][INFO    ][11554] Starting a new job with PID 11554
2019-05-12 05:41:18,587 [salt.minion      :1711][INFO    ][11554] Returning information for job: 20190512054118528532
2019-05-12 05:41:20,878 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (764.95259285s left)
2019-05-12 05:41:48,700 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054148685055
2019-05-12 05:41:48,726 [salt.minion      :1432][INFO    ][11588] Starting a new job with PID 11588
2019-05-12 05:41:48,748 [salt.minion      :1711][INFO    ][11588] Returning information for job: 20190512054148685055
2019-05-12 05:41:53,719 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (732.111253023s left)
2019-05-12 05:42:18,837 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054218829161
2019-05-12 05:42:18,862 [salt.minion      :1432][INFO    ][11633] Starting a new job with PID 11633
2019-05-12 05:42:18,886 [salt.minion      :1711][INFO    ][11633] Returning information for job: 20190512054218829161
2019-05-12 05:42:26,646 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (699.184147835s left)
2019-05-12 05:42:49,004 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054248980127
2019-05-12 05:42:49,030 [salt.minion      :1432][INFO    ][11668] Starting a new job with PID 11668
2019-05-12 05:42:49,062 [salt.minion      :1711][INFO    ][11668] Returning information for job: 20190512054248980127
2019-05-12 05:42:59,628 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (666.202939034s left)
2019-05-12 05:43:19,200 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054319185067
2019-05-12 05:43:19,232 [salt.minion      :1432][INFO    ][11715] Starting a new job with PID 11715
2019-05-12 05:43:19,257 [salt.minion      :1711][INFO    ][11715] Returning information for job: 20190512054319185067
2019-05-12 05:43:32,602 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (633.229275942s left)
2019-05-12 05:43:49,234 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054349219597
2019-05-12 05:43:49,262 [salt.minion      :1432][INFO    ][11752] Starting a new job with PID 11752
2019-05-12 05:43:49,292 [salt.minion      :1711][INFO    ][11752] Returning information for job: 20190512054349219597
2019-05-12 05:44:05,361 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (600.469710827s left)
2019-05-12 05:44:19,255 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054419244927
2019-05-12 05:44:19,281 [salt.minion      :1432][INFO    ][11796] Starting a new job with PID 11796
2019-05-12 05:44:19,308 [salt.minion      :1711][INFO    ][11796] Returning information for job: 20190512054419244927
2019-05-12 05:44:38,272 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (567.558107853s left)
2019-05-12 05:44:49,333 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054449319278
2019-05-12 05:44:49,360 [salt.minion      :1432][INFO    ][11830] Starting a new job with PID 11830
2019-05-12 05:44:49,393 [salt.minion      :1711][INFO    ][11830] Returning information for job: 20190512054449319278
2019-05-12 05:45:11,177 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (534.653316021s left)
2019-05-12 05:45:19,564 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054519549658
2019-05-12 05:45:19,592 [salt.minion      :1432][INFO    ][11873] Starting a new job with PID 11873
2019-05-12 05:45:19,620 [salt.minion      :1711][INFO    ][11873] Returning information for job: 20190512054519549658
2019-05-12 05:45:44,034 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (501.796593904s left)
2019-05-12 05:45:49,678 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054549668772
2019-05-12 05:45:49,704 [salt.minion      :1432][INFO    ][11907] Starting a new job with PID 11907
2019-05-12 05:45:49,733 [salt.minion      :1711][INFO    ][11907] Returning information for job: 20190512054549668772
2019-05-12 05:46:16,906 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (468.92484498s left)
2019-05-12 05:46:19,803 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054619790287
2019-05-12 05:46:19,821 [salt.minion      :1432][INFO    ][11951] Starting a new job with PID 11951
2019-05-12 05:46:19,846 [salt.minion      :1711][INFO    ][11951] Returning information for job: 20190512054619790287
2019-05-12 05:46:49,657 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (436.173671961s left)
2019-05-12 05:46:49,907 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054649895169
2019-05-12 05:46:49,936 [salt.minion      :1432][INFO    ][11985] Starting a new job with PID 11985
2019-05-12 05:46:49,959 [salt.minion      :1711][INFO    ][11985] Returning information for job: 20190512054649895169
2019-05-12 05:47:20,085 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054720071208
2019-05-12 05:47:20,108 [salt.minion      :1432][INFO    ][12027] Starting a new job with PID 12027
2019-05-12 05:47:20,132 [salt.minion      :1711][INFO    ][12027] Returning information for job: 20190512054720071208
2019-05-12 05:47:22,401 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (403.429571867s left)
2019-05-12 05:47:50,259 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054750241129
2019-05-12 05:47:50,289 [salt.minion      :1432][INFO    ][12192] Starting a new job with PID 12192
2019-05-12 05:47:50,312 [salt.minion      :1711][INFO    ][12192] Returning information for job: 20190512054750241129
2019-05-12 05:47:55,294 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (370.537338972s left)
2019-05-12 05:48:20,479 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054820462924
2019-05-12 05:48:20,508 [salt.minion      :1432][INFO    ][12250] Starting a new job with PID 12250
2019-05-12 05:48:20,535 [salt.minion      :1711][INFO    ][12250] Returning information for job: 20190512054820462924
2019-05-12 05:48:28,338 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (337.492225885s left)
2019-05-12 05:48:50,667 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054850652403
2019-05-12 05:48:50,693 [salt.minion      :1432][INFO    ][12282] Starting a new job with PID 12282
2019-05-12 05:48:50,717 [salt.minion      :1711][INFO    ][12282] Returning information for job: 20190512054850652403
2019-05-12 05:49:01,219 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (304.611959934s left)
2019-05-12 05:49:20,719 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054920702480
2019-05-12 05:49:20,744 [salt.minion      :1432][INFO    ][12327] Starting a new job with PID 12327
2019-05-12 05:49:20,767 [salt.minion      :1711][INFO    ][12327] Returning information for job: 20190512054920702480
2019-05-12 05:49:33,939 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (271.891758919s left)
2019-05-12 05:49:50,734 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512054950725010
2019-05-12 05:49:50,758 [salt.minion      :1432][INFO    ][12362] Starting a new job with PID 12362
2019-05-12 05:49:50,783 [salt.minion      :1711][INFO    ][12362] Returning information for job: 20190512054950725010
2019-05-12 05:50:06,696 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (239.134917021s left)
2019-05-12 05:50:20,832 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055020817954
2019-05-12 05:50:20,857 [salt.minion      :1432][INFO    ][12408] Starting a new job with PID 12408
2019-05-12 05:50:20,884 [salt.minion      :1711][INFO    ][12408] Returning information for job: 20190512055020817954
2019-05-12 05:50:39,667 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (206.163882971s left)
2019-05-12 05:50:50,922 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055050909433
2019-05-12 05:50:50,947 [salt.minion      :1432][INFO    ][12447] Starting a new job with PID 12447
2019-05-12 05:50:50,969 [salt.minion      :1711][INFO    ][12447] Returning information for job: 20190512055050909433
2019-05-12 05:51:12,441 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (173.389384985s left)
2019-05-12 05:51:21,039 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055121024249
2019-05-12 05:51:21,068 [salt.minion      :1432][INFO    ][12491] Starting a new job with PID 12491
2019-05-12 05:51:21,093 [salt.minion      :1711][INFO    ][12491] Returning information for job: 20190512055121024249
2019-05-12 05:51:45,357 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (140.473458052s left)
2019-05-12 05:51:51,125 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055151115180
2019-05-12 05:51:51,155 [salt.minion      :1432][INFO    ][12524] Starting a new job with PID 12524
2019-05-12 05:51:51,180 [salt.minion      :1711][INFO    ][12524] Returning information for job: 20190512055151115180
2019-05-12 05:52:18,223 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (107.607702971s left)
2019-05-12 05:52:21,285 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055221268187
2019-05-12 05:52:21,315 [salt.minion      :1432][INFO    ][12566] Starting a new job with PID 12566
2019-05-12 05:52:21,341 [salt.minion      :1711][INFO    ][12566] Returning information for job: 20190512055221268187
2019-05-12 05:52:51,156 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (74.6746490002s left)
2019-05-12 05:52:51,478 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055251466187
2019-05-12 05:52:51,500 [salt.minion      :1432][INFO    ][12600] Starting a new job with PID 12600
2019-05-12 05:52:51,524 [salt.minion      :1711][INFO    ][12600] Returning information for job: 20190512055251466187
2019-05-12 05:53:21,583 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055321572418
2019-05-12 05:53:21,606 [salt.minion      :1432][INFO    ][12644] Starting a new job with PID 12644
2019-05-12 05:53:21,630 [salt.minion      :1711][INFO    ][12644] Returning information for job: 20190512055321572418
2019-05-12 05:53:23,901 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (41.9299209118s left)
2019-05-12 05:53:51,817 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055351806440
2019-05-12 05:53:51,847 [salt.minion      :1432][INFO    ][12675] Starting a new job with PID 12675
2019-05-12 05:53:51,870 [salt.minion      :1711][INFO    ][12675] Returning information for job: 20190512055351806440
2019-05-12 05:53:56,708 [salt.loaded.ext.module.maas:1023][INFO    ][7020] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (9.12229204178s left)
2019-05-12 05:54:22,029 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055422012961
2019-05-12 05:54:22,059 [salt.minion      :1432][INFO    ][12716] Starting a new job with PID 12716
2019-05-12 05:54:22,084 [salt.minion      :1711][INFO    ][12716] Returning information for job: 20190512055422012961
2019-05-12 05:54:29,468 [salt.state       :302 ][ERROR   ][7020] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-12 05:54:29,468 [salt.state       :1951][INFO    ][7020] Completed state [maas.wait_for_machine_status] at time 05:54:29.468704 duration_in_ms=2273654.352
2019-05-12 05:54:29,477 [salt.minion      :1711][INFO    ][7020] Returning information for job: 20190512051627711828
2019-05-12 05:54:40,279 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command pillar.get with jid 20190512055440265521
2019-05-12 05:54:40,306 [salt.minion      :1432][INFO    ][12737] Starting a new job with PID 12737
2019-05-12 05:54:40,315 [salt.minion      :1711][INFO    ][12737] Returning information for job: 20190512055440265521
2019-05-12 05:54:40,872 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command service.status with jid 20190512055440858207
2019-05-12 05:54:40,902 [salt.minion      :1432][INFO    ][12742] Starting a new job with PID 12742
2019-05-12 05:54:41,430 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12742] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-12 05:54:41,473 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12742] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-12 05:54:41,494 [salt.minion      :1711][INFO    ][12742] Returning information for job: 20190512055440858207
2019-05-12 05:54:42,065 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command state.apply with jid 20190512055442051076
2019-05-12 05:54:42,093 [salt.minion      :1432][INFO    ][12753] Starting a new job with PID 12753
2019-05-12 05:54:48,052 [salt.state       :915 ][INFO    ][12753] Loading fresh modules for state activity
2019-05-12 05:54:48,655 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12753] Executing command 'salt-minion --version' in directory '/root'
2019-05-12 05:54:48,956 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12753] Executing command 'salt-minion --version' in directory '/root'
2019-05-12 05:54:49,958 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12753] Executing command 'salt-minion --version' in directory '/root'
2019-05-12 05:54:50,250 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12753] Executing command 'salt-minion --version' in directory '/root'
2019-05-12 05:54:52,104 [salt.state       :1780][INFO    ][12753] Running state [salt-minion] at time 05:54:52.104800
2019-05-12 05:54:52,105 [salt.state       :1813][INFO    ][12753] Executing state pkg.installed for [salt-minion]
2019-05-12 05:54:52,107 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12753] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-12 05:54:52,228 [salt.state       :300 ][INFO    ][12753] All specified packages are already installed
2019-05-12 05:54:52,229 [salt.state       :1951][INFO    ][12753] Completed state [salt-minion] at time 05:54:52.228921 duration_in_ms=124.123
2019-05-12 05:54:52,229 [salt.state       :1780][INFO    ][12753] Running state [salt_minion_dependency_packages] at time 05:54:52.229279
2019-05-12 05:54:52,229 [salt.state       :1813][INFO    ][12753] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-12 05:54:52,239 [salt.state       :300 ][INFO    ][12753] All specified packages are already installed
2019-05-12 05:54:52,239 [salt.state       :1951][INFO    ][12753] Completed state [salt_minion_dependency_packages] at time 05:54:52.239284 duration_in_ms=10.005
2019-05-12 05:54:52,242 [salt.state       :1780][INFO    ][12753] Running state [/etc/salt/minion.d/minion.conf] at time 05:54:52.242811
2019-05-12 05:54:52,243 [salt.state       :1813][INFO    ][12753] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-12 05:54:52,519 [salt.state       :300 ][INFO    ][12753] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-12 05:54:52,520 [salt.state       :1951][INFO    ][12753] Completed state [/etc/salt/minion.d/minion.conf] at time 05:54:52.520072 duration_in_ms=277.261
2019-05-12 05:54:52,520 [salt.state       :1780][INFO    ][12753] Running state [python-netaddr] at time 05:54:52.520333
2019-05-12 05:54:52,520 [salt.state       :1813][INFO    ][12753] Executing state pkg.installed for [python-netaddr]
2019-05-12 05:54:52,529 [salt.state       :300 ][INFO    ][12753] All specified packages are already installed
2019-05-12 05:54:52,529 [salt.state       :1951][INFO    ][12753] Completed state [python-netaddr] at time 05:54:52.529936 duration_in_ms=9.603
2019-05-12 05:54:52,533 [salt.state       :1780][INFO    ][12753] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:54:52.532973
2019-05-12 05:54:52,533 [salt.state       :1813][INFO    ][12753] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-12 05:54:52,547 [salt.state       :300 ][INFO    ][12753] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-12 05:54:52,547 [salt.state       :1951][INFO    ][12753] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:54:52.547383 duration_in_ms=14.41
2019-05-12 05:54:52,548 [salt.state       :1780][INFO    ][12753] Running state [salt-minion] at time 05:54:52.548655
2019-05-12 05:54:52,548 [salt.state       :1813][INFO    ][12753] Executing state service.running for [salt-minion]
2019-05-12 05:54:52,549 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12753] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-12 05:54:52,594 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12753] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-12 05:54:52,615 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12753] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-12 05:54:52,635 [salt.state       :300 ][INFO    ][12753] The service salt-minion is already running
2019-05-12 05:54:52,635 [salt.state       :1951][INFO    ][12753] Completed state [salt-minion] at time 05:54:52.635572 duration_in_ms=86.917
2019-05-12 05:54:52,637 [salt.state       :1780][INFO    ][12753] Running state [/etc/salt/grains.d] at time 05:54:52.637254
2019-05-12 05:54:52,637 [salt.state       :1813][INFO    ][12753] Executing state file.directory for [/etc/salt/grains.d]
2019-05-12 05:54:52,640 [salt.state       :300 ][INFO    ][12753] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-12 05:54:52,640 [salt.state       :1951][INFO    ][12753] Completed state [/etc/salt/grains.d] at time 05:54:52.640705 duration_in_ms=3.45
2019-05-12 05:54:52,641 [salt.state       :1780][INFO    ][12753] Running state [/etc/salt/grains] at time 05:54:52.641384
2019-05-12 05:54:52,641 [salt.state       :1813][INFO    ][12753] Executing state file.managed for [/etc/salt/grains]
2019-05-12 05:54:52,642 [salt.state       :300 ][INFO    ][12753] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-12 05:54:52,642 [salt.state       :1951][INFO    ][12753] Completed state [/etc/salt/grains] at time 05:54:52.642695 duration_in_ms=1.312
2019-05-12 05:54:52,643 [salt.state       :1780][INFO    ][12753] Running state [/etc/salt/grains.d/placeholder] at time 05:54:52.643165
2019-05-12 05:54:52,643 [salt.state       :1813][INFO    ][12753] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-12 05:54:52,643 [salt.state       :300 ][INFO    ][12753] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-12 05:54:52,644 [salt.state       :1951][INFO    ][12753] Completed state [/etc/salt/grains.d/placeholder] at time 05:54:52.644126 duration_in_ms=0.96
2019-05-12 05:54:52,644 [salt.state       :1780][INFO    ][12753] Running state [/etc/salt/grains.d/sphinx] at time 05:54:52.644593
2019-05-12 05:54:52,644 [salt.state       :1813][INFO    ][12753] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-12 05:54:52,647 [salt.state       :300 ][INFO    ][12753] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-12 05:54:52,647 [salt.state       :1951][INFO    ][12753] Completed state [/etc/salt/grains.d/sphinx] at time 05:54:52.647345 duration_in_ms=2.751
2019-05-12 05:54:52,649 [salt.state       :1780][INFO    ][12753] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:54:52.649472
2019-05-12 05:54:52,651 [salt.state       :1813][INFO    ][12753] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-12 05:54:52,651 [salt.state       :300 ][INFO    ][12753] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-12 05:54:52,651 [salt.state       :1951][INFO    ][12753] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:54:52.651630 duration_in_ms=2.157
2019-05-12 05:54:52,652 [salt.state       :1780][INFO    ][12753] Running state [/etc/salt/grains.d/dns_records] at time 05:54:52.652119
2019-05-12 05:54:52,652 [salt.state       :1813][INFO    ][12753] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-12 05:54:52,653 [salt.state       :300 ][INFO    ][12753] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-12 05:54:52,653 [salt.state       :1951][INFO    ][12753] Completed state [/etc/salt/grains.d/dns_records] at time 05:54:52.653454 duration_in_ms=1.335
2019-05-12 05:54:52,654 [salt.state       :1780][INFO    ][12753] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:54:52.654385
2019-05-12 05:54:52,654 [salt.state       :1813][INFO    ][12753] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-12 05:54:52,654 [salt.state       :300 ][INFO    ][12753] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-12 05:54:52,655 [salt.state       :1951][INFO    ][12753] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:54:52.655146 duration_in_ms=0.761
2019-05-12 05:54:52,655 [salt.state       :1780][INFO    ][12753] Running state [/etc/salt/grains.d/salt] at time 05:54:52.655634
2019-05-12 05:54:52,655 [salt.state       :1813][INFO    ][12753] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-12 05:54:52,656 [salt.state       :300 ][INFO    ][12753] File /etc/salt/grains.d/salt is in the correct state
2019-05-12 05:54:52,657 [salt.state       :1951][INFO    ][12753] Completed state [/etc/salt/grains.d/salt] at time 05:54:52.656978 duration_in_ms=1.344
2019-05-12 05:54:52,658 [salt.state       :1780][INFO    ][12753] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:54:52.658453
2019-05-12 05:54:52,658 [salt.state       :1813][INFO    ][12753] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-12 05:54:52,659 [salt.state       :300 ][INFO    ][12753] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-12 05:54:52,659 [salt.state       :1951][INFO    ][12753] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:54:52.659209 duration_in_ms=0.756
2019-05-12 05:54:52,661 [salt.state       :1780][INFO    ][12753] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:54:52.661134
2019-05-12 05:54:52,661 [salt.state       :1813][INFO    ][12753] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-12 05:54:52,662 [salt.state       :300 ][INFO    ][12753] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-12 05:54:52,662 [salt.state       :1951][INFO    ][12753] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:54:52.662757 duration_in_ms=1.624
2019-05-12 05:54:52,663 [salt.state       :1780][INFO    ][12753] Running state [mine.update] at time 05:54:52.663417
2019-05-12 05:54:52,663 [salt.state       :1813][INFO    ][12753] Executing state module.wait for [mine.update]
2019-05-12 05:54:52,663 [salt.state       :300 ][INFO    ][12753] No changes made for mine.update
2019-05-12 05:54:52,664 [salt.state       :1951][INFO    ][12753] Completed state [mine.update] at time 05:54:52.664103 duration_in_ms=0.687
2019-05-12 05:54:52,664 [salt.state       :1780][INFO    ][12753] Running state [ca-certificates] at time 05:54:52.664339
2019-05-12 05:54:52,664 [salt.state       :1813][INFO    ][12753] Executing state pkg.installed for [ca-certificates]
2019-05-12 05:54:52,675 [salt.state       :300 ][INFO    ][12753] All specified packages are already installed
2019-05-12 05:54:52,675 [salt.state       :1951][INFO    ][12753] Completed state [ca-certificates] at time 05:54:52.675243 duration_in_ms=10.903
2019-05-12 05:54:52,675 [salt.state       :1780][INFO    ][12753] Running state [update-ca-certificates] at time 05:54:52.675929
2019-05-12 05:54:52,676 [salt.state       :1813][INFO    ][12753] Executing state cmd.wait for [update-ca-certificates]
2019-05-12 05:54:52,676 [salt.state       :300 ][INFO    ][12753] No changes made for update-ca-certificates
2019-05-12 05:54:52,676 [salt.state       :1951][INFO    ][12753] Completed state [update-ca-certificates] at time 05:54:52.676663 duration_in_ms=0.734
2019-05-12 05:54:52,676 [salt.state       :1780][INFO    ][12753] Running state [iptables] at time 05:54:52.676901
2019-05-12 05:54:52,677 [salt.state       :1813][INFO    ][12753] Executing state pkg.installed for [iptables]
2019-05-12 05:54:52,686 [salt.state       :300 ][INFO    ][12753] All specified packages are already installed
2019-05-12 05:54:52,686 [salt.state       :1951][INFO    ][12753] Completed state [iptables] at time 05:54:52.686814 duration_in_ms=9.912
2019-05-12 05:54:52,687 [salt.state       :1780][INFO    ][12753] Running state [iptables-persistent] at time 05:54:52.687058
2019-05-12 05:54:52,687 [salt.state       :1813][INFO    ][12753] Executing state pkg.installed for [iptables-persistent]
2019-05-12 05:54:52,696 [salt.state       :300 ][INFO    ][12753] All specified packages are already installed
2019-05-12 05:54:52,696 [salt.state       :1951][INFO    ][12753] Completed state [iptables-persistent] at time 05:54:52.696529 duration_in_ms=9.471
2019-05-12 05:54:52,697 [salt.state       :1780][INFO    ][12753] Running state [iptables_modules_v4_load] at time 05:54:52.697525
2019-05-12 05:54:52,697 [salt.state       :1813][INFO    ][12753] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-12 05:54:52,698 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12753] Executing command 'lsmod' in directory '/root'
2019-05-12 05:54:52,717 [salt.state       :300 ][INFO    ][12753] Kernel modules iptable_filter, ip_tables are already present
2019-05-12 05:54:52,719 [salt.state       :1951][INFO    ][12753] Completed state [iptables_modules_v4_load] at time 05:54:52.719194 duration_in_ms=21.668
2019-05-12 05:54:52,720 [salt.state       :1780][INFO    ][12753] Running state [/etc/iptables/rules.v4] at time 05:54:52.720307
2019-05-12 05:54:52,720 [salt.state       :1813][INFO    ][12753] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-12 05:54:52,824 [salt.state       :300 ][INFO    ][12753] File /etc/iptables/rules.v4 is in the correct state
2019-05-12 05:54:52,824 [salt.state       :1951][INFO    ][12753] Completed state [/etc/iptables/rules.v4] at time 05:54:52.824735 duration_in_ms=104.428
2019-05-12 05:54:52,826 [salt.state       :1780][INFO    ][12753] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:54:52.826515
2019-05-12 05:54:52,826 [salt.state       :1813][INFO    ][12753] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-12 05:54:52,827 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12753] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-12 05:54:52,848 [salt.state       :300 ][INFO    ][12753] onlyif execution failed
2019-05-12 05:54:52,848 [salt.state       :1951][INFO    ][12753] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:54:52.848464 duration_in_ms=21.948
2019-05-12 05:54:52,851 [salt.state       :1780][INFO    ][12753] Running state [netfilter-persistent] at time 05:54:52.851845
2019-05-12 05:54:52,852 [salt.state       :1813][INFO    ][12753] Executing state service.running for [netfilter-persistent]
2019-05-12 05:54:52,853 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12753] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-12 05:54:52,879 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12753] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-12 05:54:52,905 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12753] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-12 05:54:52,929 [salt.state       :300 ][INFO    ][12753] The service netfilter-persistent is already running
2019-05-12 05:54:52,929 [salt.state       :1951][INFO    ][12753] Completed state [netfilter-persistent] at time 05:54:52.929828 duration_in_ms=77.983
2019-05-12 05:54:52,931 [salt.state       :1780][INFO    ][12753] Running state [iptables_extra.remove_stale_tables] at time 05:54:52.931397
2019-05-12 05:54:52,931 [salt.state       :1813][INFO    ][12753] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-12 05:54:52,932 [salt.state       :300 ][INFO    ][12753] No changes made for iptables_extra.remove_stale_tables
2019-05-12 05:54:52,932 [salt.state       :1951][INFO    ][12753] Completed state [iptables_extra.remove_stale_tables] at time 05:54:52.932842 duration_in_ms=1.445
2019-05-12 05:54:52,933 [salt.state       :1780][INFO    ][12753] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:54:52.933280
2019-05-12 05:54:52,935 [salt.state       :1813][INFO    ][12753] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-12 05:54:52,937 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12753] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-12 05:54:52,955 [salt.state       :300 ][INFO    ][12753] onlyif execution failed
2019-05-12 05:54:52,956 [salt.state       :1951][INFO    ][12753] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:54:52.956141 duration_in_ms=22.862
2019-05-12 05:54:52,957 [salt.state       :1780][INFO    ][12753] Running state [/etc/iptables/rules.v6] at time 05:54:52.957291
2019-05-12 05:54:52,957 [salt.state       :1813][INFO    ][12753] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-12 05:54:52,959 [salt.state       :300 ][INFO    ][12753] File /etc/iptables/rules.v6 is not present
2019-05-12 05:54:52,959 [salt.state       :1951][INFO    ][12753] Completed state [/etc/iptables/rules.v6] at time 05:54:52.959322 duration_in_ms=2.031
2019-05-12 05:54:52,960 [salt.state       :1780][INFO    ][12753] Running state [iptables_extra.flush_all] at time 05:54:52.960162
2019-05-12 05:54:52,960 [salt.state       :1813][INFO    ][12753] Executing state module.wait for [iptables_extra.flush_all]
2019-05-12 05:54:52,960 [salt.state       :300 ][INFO    ][12753] No changes made for iptables_extra.flush_all
2019-05-12 05:54:52,961 [salt.state       :1951][INFO    ][12753] Completed state [iptables_extra.flush_all] at time 05:54:52.961046 duration_in_ms=0.883
2019-05-12 05:54:52,966 [salt.minion      :1711][INFO    ][12753] Returning information for job: 20190512055442051076
2019-05-12 05:54:53,541 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command state.apply with jid 20190512055453526798
2019-05-12 05:54:53,569 [salt.minion      :1432][INFO    ][12845] Starting a new job with PID 12845
2019-05-12 05:54:54,564 [salt.state       :915 ][INFO    ][12845] Loading fresh modules for state activity
2019-05-12 05:54:55,538 [salt.state       :1780][INFO    ][12845] Running state [maas-rack-controller] at time 05:54:55.538776
2019-05-12 05:54:55,539 [salt.state       :1813][INFO    ][12845] Executing state pkg.installed for [maas-rack-controller]
2019-05-12 05:54:55,539 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12845] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-12 05:54:55,652 [salt.state       :300 ][INFO    ][12845] All specified packages are already installed
2019-05-12 05:54:55,652 [salt.state       :1951][INFO    ][12845] Completed state [maas-rack-controller] at time 05:54:55.652741 duration_in_ms=113.965
2019-05-12 05:54:55,653 [salt.state       :1780][INFO    ][12845] Running state [ipmitool] at time 05:54:55.653072
2019-05-12 05:54:55,653 [salt.state       :1813][INFO    ][12845] Executing state pkg.installed for [ipmitool]
2019-05-12 05:54:55,662 [salt.state       :300 ][INFO    ][12845] All specified packages are already installed
2019-05-12 05:54:55,663 [salt.state       :1951][INFO    ][12845] Completed state [ipmitool] at time 05:54:55.663162 duration_in_ms=10.09
2019-05-12 05:54:55,666 [salt.state       :1780][INFO    ][12845] Running state [/etc/maas/rackd.conf] at time 05:54:55.666256
2019-05-12 05:54:55,666 [salt.state       :1813][INFO    ][12845] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-12 05:54:55,667 [salt.state       :300 ][INFO    ][12845] No changes needed to be made
2019-05-12 05:54:55,667 [salt.state       :1951][INFO    ][12845] Completed state [/etc/maas/rackd.conf] at time 05:54:55.667599 duration_in_ms=1.343
2019-05-12 05:54:55,667 [salt.state       :1780][INFO    ][12845] Running state [/etc/maas/rackd.conf] at time 05:54:55.667839
2019-05-12 05:54:55,668 [salt.state       :1813][INFO    ][12845] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-12 05:54:55,668 [salt.loaded.int.states.file:2298][WARNING ][12845] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-12 05:54:55,669 [salt.state       :300 ][INFO    ][12845] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-12 05:54:55,669 [salt.state       :1951][INFO    ][12845] Completed state [/etc/maas/rackd.conf] at time 05:54:55.669272 duration_in_ms=1.433
2019-05-12 05:54:55,671 [salt.state       :1780][INFO    ][12845] Running state [maas-rackd] at time 05:54:55.671172
2019-05-12 05:54:55,671 [salt.state       :1813][INFO    ][12845] Executing state service.running for [maas-rackd]
2019-05-12 05:54:55,672 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12845] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-12 05:54:55,712 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12845] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-12 05:54:55,736 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12845] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-12 05:54:55,757 [salt.state       :300 ][INFO    ][12845] The service maas-rackd is already running
2019-05-12 05:54:55,759 [salt.state       :1951][INFO    ][12845] Completed state [maas-rackd] at time 05:54:55.759695 duration_in_ms=88.521
2019-05-12 05:54:55,762 [salt.minion      :1711][INFO    ][12845] Returning information for job: 20190512055453526798
2019-05-12 05:54:56,320 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command state.apply with jid 20190512055456303458
2019-05-12 05:54:56,341 [salt.minion      :1432][INFO    ][12869] Starting a new job with PID 12869
2019-05-12 05:54:57,287 [salt.state       :915 ][INFO    ][12869] Loading fresh modules for state activity
2019-05-12 05:54:58,378 [salt.state       :1780][INFO    ][12869] Running state [maas-region-controller] at time 05:54:58.378646
2019-05-12 05:54:58,379 [salt.state       :1813][INFO    ][12869] Executing state pkg.installed for [maas-region-controller]
2019-05-12 05:54:58,381 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12869] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-12 05:54:58,499 [salt.state       :300 ][INFO    ][12869] All specified packages are already installed
2019-05-12 05:54:58,500 [salt.state       :1951][INFO    ][12869] Completed state [maas-region-controller] at time 05:54:58.500103 duration_in_ms=121.459
2019-05-12 05:54:58,500 [salt.state       :1780][INFO    ][12869] Running state [python-oauth] at time 05:54:58.500446
2019-05-12 05:54:58,500 [salt.state       :1813][INFO    ][12869] Executing state pkg.installed for [python-oauth]
2019-05-12 05:54:58,517 [salt.state       :300 ][INFO    ][12869] All specified packages are already installed
2019-05-12 05:54:58,517 [salt.state       :1951][INFO    ][12869] Completed state [python-oauth] at time 05:54:58.517278 duration_in_ms=16.832
2019-05-12 05:54:58,522 [salt.state       :1780][INFO    ][12869] Running state [/etc/maas/regiond.conf] at time 05:54:58.522758
2019-05-12 05:54:58,523 [salt.state       :1813][INFO    ][12869] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-12 05:54:58,531 [salt.state       :300 ][INFO    ][12869] No changes needed to be made
2019-05-12 05:54:58,531 [salt.state       :1951][INFO    ][12869] Completed state [/etc/maas/regiond.conf] at time 05:54:58.531334 duration_in_ms=8.575
2019-05-12 05:54:58,531 [salt.state       :1780][INFO    ][12869] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:54:58.531820
2019-05-12 05:54:58,532 [salt.state       :1813][INFO    ][12869] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-12 05:54:58,595 [salt.state       :300 ][INFO    ][12869] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-12 05:54:58,595 [salt.state       :1951][INFO    ][12869] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:54:58.595315 duration_in_ms=63.495
2019-05-12 05:54:58,595 [salt.state       :1780][INFO    ][12869] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:54:58.595826
2019-05-12 05:54:58,596 [salt.state       :1813][INFO    ][12869] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-12 05:54:58,601 [salt.state       :300 ][INFO    ][12869] No changes needed to be made
2019-05-12 05:54:58,601 [salt.state       :1951][INFO    ][12869] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:54:58.601535 duration_in_ms=5.709
2019-05-12 05:54:58,602 [salt.state       :1780][INFO    ][12869] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:54:58.602024
2019-05-12 05:54:58,602 [salt.state       :1813][INFO    ][12869] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-12 05:54:58,605 [salt.state       :300 ][INFO    ][12869] No changes needed to be made
2019-05-12 05:54:58,605 [salt.state       :1951][INFO    ][12869] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:54:58.605845 duration_in_ms=3.821
2019-05-12 05:54:58,606 [salt.state       :1780][INFO    ][12869] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:54:58.606330
2019-05-12 05:54:58,606 [salt.state       :1813][INFO    ][12869] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-12 05:54:58,638 [salt.state       :300 ][INFO    ][12869] No changes needed to be made
2019-05-12 05:54:58,638 [salt.state       :1951][INFO    ][12869] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:54:58.638865 duration_in_ms=32.534
2019-05-12 05:54:58,639 [salt.state       :1780][INFO    ][12869] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:54:58.639417
2019-05-12 05:54:58,639 [salt.state       :1813][INFO    ][12869] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-12 05:54:58,653 [salt.state       :300 ][INFO    ][12869] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-12 05:54:58,653 [salt.state       :1951][INFO    ][12869] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:54:58.653315 duration_in_ms=13.897
2019-05-12 05:54:58,655 [salt.state       :1780][INFO    ][12869] Running state [a2enmod headers] at time 05:54:58.655061
2019-05-12 05:54:58,655 [salt.state       :1813][INFO    ][12869] Executing state cmd.run for [a2enmod headers]
2019-05-12 05:54:58,656 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12869] Executing command 'a2enmod headers' in directory '/root'
2019-05-12 05:54:58,723 [salt.state       :300 ][INFO    ][12869] {'pid': 12906, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-12 05:54:58,723 [salt.state       :1951][INFO    ][12869] Completed state [a2enmod headers] at time 05:54:58.723678 duration_in_ms=68.616
2019-05-12 05:54:58,724 [salt.state       :1780][INFO    ][12869] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:54:58.724347
2019-05-12 05:54:58,725 [salt.state       :1813][INFO    ][12869] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-12 05:54:58,747 [salt.state       :300 ][INFO    ][12869] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-12 05:54:58,748 [salt.state       :1951][INFO    ][12869] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:54:58.748263 duration_in_ms=23.915
2019-05-12 05:54:58,749 [salt.state       :1780][INFO    ][12869] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:54:58.749055
2019-05-12 05:54:58,749 [salt.state       :1813][INFO    ][12869] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-12 05:54:58,806 [salt.state       :300 ][INFO    ][12869] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-12 05:54:58,807 [salt.state       :1951][INFO    ][12869] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:54:58.806999 duration_in_ms=57.944
2019-05-12 05:54:58,807 [salt.state       :1780][INFO    ][12869] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:54:58.807511
2019-05-12 05:54:58,807 [salt.state       :1813][INFO    ][12869] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-12 05:54:58,868 [salt.state       :300 ][INFO    ][12869] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-12 05:54:58,868 [salt.state       :1951][INFO    ][12869] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:54:58.868244 duration_in_ms=60.733
2019-05-12 05:54:58,868 [salt.state       :1780][INFO    ][12869] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:54:58.868746
2019-05-12 05:54:58,869 [salt.state       :1813][INFO    ][12869] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-12 05:54:58,941 [salt.state       :300 ][INFO    ][12869] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-12 05:54:58,942 [salt.state       :1951][INFO    ][12869] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:54:58.942320 duration_in_ms=73.574
2019-05-12 05:54:58,942 [salt.state       :1780][INFO    ][12869] Running state [/root/.pgpass] at time 05:54:58.942625
2019-05-12 05:54:58,942 [salt.state       :1813][INFO    ][12869] Executing state file.managed for [/root/.pgpass]
2019-05-12 05:54:58,997 [salt.state       :300 ][INFO    ][12869] File /root/.pgpass is in the correct state
2019-05-12 05:54:58,997 [salt.state       :1951][INFO    ][12869] Completed state [/root/.pgpass] at time 05:54:58.997791 duration_in_ms=55.166
2019-05-12 05:54:59,002 [salt.state       :1780][INFO    ][12869] Running state [maas-region syncdb --noinput] at time 05:54:59.002794
2019-05-12 05:54:59,003 [salt.state       :1813][INFO    ][12869] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-12 05:54:59,003 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12869] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-12 05:55:01,512 [salt.state       :300 ][INFO    ][12869] {'pid': 12922, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, sites, piston3, auth, sessions, contenttypes, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-12 05:55:01,513 [salt.state       :1951][INFO    ][12869] Completed state [maas-region syncdb --noinput] at time 05:55:01.513325 duration_in_ms=2510.529
2019-05-12 05:55:01,514 [salt.state       :2022][WARNING ][12869] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-12 05:55:01,518 [salt.state       :1780][INFO    ][12869] Running state [maas-regiond] at time 05:55:01.518425
2019-05-12 05:55:01,519 [salt.state       :1813][INFO    ][12869] Executing state service.running for [maas-regiond]
2019-05-12 05:55:01,520 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12869] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-12 05:55:01,568 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12869] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-12 05:55:01,589 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12869] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-12 05:55:01,613 [salt.state       :300 ][INFO    ][12869] The service maas-regiond is already running
2019-05-12 05:55:01,614 [salt.state       :1951][INFO    ][12869] Completed state [maas-regiond] at time 05:55:01.613657 duration_in_ms=95.232
2019-05-12 05:55:01,617 [salt.state       :1780][INFO    ][12869] Running state [bind9] at time 05:55:01.617144
2019-05-12 05:55:01,617 [salt.state       :1813][INFO    ][12869] Executing state service.running for [bind9]
2019-05-12 05:55:01,618 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12869] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-12 05:55:01,641 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12869] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-12 05:55:01,661 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12869] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-12 05:55:01,684 [salt.state       :300 ][INFO    ][12869] The service bind9 is already running
2019-05-12 05:55:01,685 [salt.state       :1951][INFO    ][12869] Completed state [bind9] at time 05:55:01.685190 duration_in_ms=68.046
2019-05-12 05:55:01,688 [salt.state       :1780][INFO    ][12869] Running state [apache2] at time 05:55:01.688454
2019-05-12 05:55:01,688 [salt.state       :1813][INFO    ][12869] Executing state service.running for [apache2]
2019-05-12 05:55:01,689 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12869] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-12 05:55:01,714 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12869] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-12 05:55:01,737 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12869] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-12 05:55:01,764 [salt.state       :300 ][INFO    ][12869] The service apache2 is already running
2019-05-12 05:55:01,764 [salt.state       :1951][INFO    ][12869] Completed state [apache2] at time 05:55:01.764751 duration_in_ms=76.296
2019-05-12 05:55:01,768 [salt.state       :1780][INFO    ][12869] Running state [maasng.wait_for_http_code] at time 05:55:01.768527
2019-05-12 05:55:01,768 [salt.state       :1813][INFO    ][12869] Executing state module.run for [maasng.wait_for_http_code]
2019-05-12 05:55:01,769 [salt.utils.decorators:613 ][WARNING ][12869] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:55:01,780 [salt.state       :300 ][INFO    ][12869] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-12 05:55:01,781 [salt.state       :1951][INFO    ][12869] Completed state [maasng.wait_for_http_code] at time 05:55:01.781006 duration_in_ms=12.478
2019-05-12 05:55:01,783 [salt.state       :1780][INFO    ][12869] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:55:01.783054
2019-05-12 05:55:01,783 [salt.state       :1813][INFO    ][12869] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-12 05:55:01,784 [salt.state       :300 ][INFO    ][12869] /var/lib/maas/.setup_admin exists
2019-05-12 05:55:01,784 [salt.state       :1951][INFO    ][12869] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:55:01.784464 duration_in_ms=1.41
2019-05-12 05:55:01,785 [salt.state       :1780][INFO    ][12869] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:55:01.785565
2019-05-12 05:55:01,786 [salt.state       :1813][INFO    ][12869] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-12 05:55:01,786 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12869] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-12 05:55:03,579 [salt.state       :300 ][INFO    ][12869] {'pid': 12947, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-12 05:55:03,580 [salt.state       :1951][INFO    ][12869] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:55:03.579996 duration_in_ms=1794.431
2019-05-12 05:55:03,588 [salt.state       :1780][INFO    ][12869] Running state [maas_region_boot_source_resources_mirror] at time 05:55:03.588868
2019-05-12 05:55:03,589 [salt.state       :1813][INFO    ][12869] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-12 05:55:03,668 [salt.state       :300 ][INFO    ][12869] {'changes': {}}
2019-05-12 05:55:03,668 [salt.state       :1951][INFO    ][12869] Completed state [maas_region_boot_source_resources_mirror] at time 05:55:03.668588 duration_in_ms=79.721
2019-05-12 05:55:03,669 [salt.state       :1780][INFO    ][12869] Running state [maasng.boot_resources_import] at time 05:55:03.669274
2019-05-12 05:55:03,669 [salt.state       :1813][INFO    ][12869] Executing state module.run for [maasng.boot_resources_import]
2019-05-12 05:55:03,669 [salt.utils.decorators:613 ][WARNING ][12869] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:55:05,104 [salt.loaded.ext.module.maasng:1600][INFO    ][12869] Waiting boot-resources import done
sleep for:5s Left:899.0/900s
2019-05-12 05:55:10,168 [salt.loaded.ext.module.maasng:1600][INFO    ][12869] Waiting boot-resources import done
sleep for:5s Left:894.0/900s
2019-05-12 05:55:11,362 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055511338986
2019-05-12 05:55:11,405 [salt.minion      :1432][INFO    ][13078] Starting a new job with PID 13078
2019-05-12 05:55:11,449 [salt.minion      :1711][INFO    ][13078] Returning information for job: 20190512055511338986
2019-05-12 05:55:15,268 [salt.state       :300 ][INFO    ][12869] {'ret': True}
2019-05-12 05:55:15,268 [salt.state       :1951][INFO    ][12869] Completed state [maasng.boot_resources_import] at time 05:55:15.268562 duration_in_ms=11599.288
2019-05-12 05:55:15,269 [salt.state       :1780][INFO    ][12869] Running state [maas_region_boot_sources_selection_xenial] at time 05:55:15.269634
2019-05-12 05:55:15,271 [salt.state       :1813][INFO    ][12869] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-12 05:55:15,456 [salt.state       :300 ][INFO    ][12869] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-12 05:55:15,456 [salt.state       :1951][INFO    ][12869] Completed state [maas_region_boot_sources_selection_xenial] at time 05:55:15.456505 duration_in_ms=186.871
2019-05-12 05:55:15,459 [salt.state       :1780][INFO    ][12869] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:55:15.458910
2019-05-12 05:55:15,459 [salt.state       :1813][INFO    ][12869] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-12 05:55:15,460 [salt.utils.decorators:613 ][WARNING ][12869] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:55:15,460 [salt.loaded.ext.module.maasng:1771][INFO    ][12869] boot-sources sync initiated for ALL Rack's
2019-05-12 05:55:16,535 [salt.state       :300 ][INFO    ][12869] {'ret': True}
2019-05-12 05:55:16,536 [salt.state       :1951][INFO    ][12869] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:55:16.536096 duration_in_ms=1077.185
2019-05-12 05:55:16,539 [salt.state       :1780][INFO    ][12869] Running state [maas.process_maas_config] at time 05:55:16.539291
2019-05-12 05:55:16,540 [salt.state       :1813][INFO    ][12869] Executing state module.run for [maas.process_maas_config]
2019-05-12 05:55:16,540 [salt.utils.decorators:613 ][WARNING ][12869] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:55:16,541 [salt.loaded.ext.module.maas:92  ][INFO    ][12869] maasconfig name=enable_http_proxy value=True
2019-05-12 05:55:16,616 [salt.loaded.ext.module.maas:92  ][INFO    ][12869] maasconfig name=upstream_dns value=8.8.8.8
2019-05-12 05:55:16,674 [salt.loaded.ext.module.maas:92  ][INFO    ][12869] maasconfig name=commissioning_distro_series value=xenial
2019-05-12 05:55:18,153 [salt.loaded.ext.module.maas:92  ][INFO    ][12869] maasconfig name=default_osystem value=ubuntu
2019-05-12 05:55:18,232 [salt.loaded.ext.module.maas:92  ][INFO    ][12869] maasconfig name=active_discovery_interval value=600
2019-05-12 05:55:18,280 [salt.loaded.ext.module.maas:92  ][INFO    ][12869] maasconfig name=dnssec_validation value=no
2019-05-12 05:55:18,330 [salt.loaded.ext.module.maas:92  ][INFO    ][12869] maasconfig name=maas_name value=mas01
2019-05-12 05:55:18,379 [salt.loaded.ext.module.maas:92  ][INFO    ][12869] maasconfig name=network_discovery value=enabled
2019-05-12 05:55:18,475 [salt.loaded.ext.module.maas:92  ][INFO    ][12869] maasconfig name=enable_third_party_drivers value=True
2019-05-12 05:55:18,525 [salt.loaded.ext.module.maas:92  ][INFO    ][12869] maasconfig name=default_storage_layout value=lvm
2019-05-12 05:55:18,573 [salt.loaded.ext.module.maas:92  ][INFO    ][12869] maasconfig name=ntp_external_only value=True
2019-05-12 05:55:18,641 [salt.loaded.ext.module.maas:92  ][INFO    ][12869] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-12 05:55:18,704 [salt.loaded.ext.module.maas:92  ][INFO    ][12869] maasconfig name=default_distro_series value=xenial
2019-05-12 05:55:18,766 [salt.loaded.ext.module.maas:92  ][INFO    ][12869] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-12 05:55:18,883 [salt.state       :300 ][INFO    ][12869] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-12 05:55:18,883 [salt.state       :1951][INFO    ][12869] Completed state [maas.process_maas_config] at time 05:55:18.883839 duration_in_ms=2344.548
2019-05-12 05:55:18,884 [salt.state       :1780][INFO    ][12869] Running state [pxe_admin] at time 05:55:18.884460
2019-05-12 05:55:18,884 [salt.state       :1813][INFO    ][12869] Executing state maasng.fabric_present for [pxe_admin]
2019-05-12 05:55:18,946 [salt.loaded.ext.module.maasng:945 ][INFO    ][12869] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qxfa6r', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-12 05:55:19,019 [salt.loaded.ext.module.maasng:1008][WARNING ][12869] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-12 05:55:19,019 [salt.loaded.ext.module.maasng:1011][WARNING ][12869] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-12 05:55:19,092 [salt.state       :300 ][INFO    ][12869] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-12 05:55:19,093 [salt.state       :1951][INFO    ][12869] Completed state [pxe_admin] at time 05:55:19.093150 duration_in_ms=208.688
2019-05-12 05:55:19,093 [salt.state       :1780][INFO    ][12869] Running state [vlan 0] at time 05:55:19.093465
2019-05-12 05:55:19,096 [salt.state       :1813][INFO    ][12869] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-12 05:55:19,148 [salt.loaded.ext.module.maasng:945 ][INFO    ][12869] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qxfa6r', u'name': u'untagged', 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-05-12 05:55:19,252 [salt.loaded.ext.module.maasng:945 ][INFO    ][12869] [{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'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-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'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-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'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qxfa6r', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-05-12 05:55:19,496 [salt.loaded.ext.module.maasng:945 ][INFO    ][12869] [{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'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-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'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-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'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qxfa6r', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-05-12 05:55:19,584 [salt.state       :300 ][INFO    ][12869] {'new': 'Vlan untagged was updated'}
2019-05-12 05:55:19,585 [salt.state       :1951][INFO    ][12869] Completed state [vlan 0] at time 05:55:19.585213 duration_in_ms=491.748
2019-05-12 05:55:19,587 [salt.state       :1780][INFO    ][12869] Running state [192.168.11.0/24] at time 05:55:19.587825
2019-05-12 05:55:19,588 [salt.state       :1813][INFO    ][12869] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-12 05:55:19,804 [salt.loaded.ext.module.maasng:945 ][INFO    ][12869] [{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'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-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'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qxfa6r', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-05-12 05:55:19,805 [salt.loaded.ext.module.maasng:1235][WARNING ][12869] Ignoring parameter vlan:0
2019-05-12 05:55:19,865 [salt.state       :300 ][INFO    ][12869] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-12 05:55:19,865 [salt.state       :1951][INFO    ][12869] Completed state [192.168.11.0/24] at time 05:55:19.865594 duration_in_ms=277.77
2019-05-12 05:55:19,867 [salt.state       :1780][INFO    ][12869] Running state [maas_create_iprange_1] at time 05:55:19.867220
2019-05-12 05:55:19,867 [salt.state       :1813][INFO    ][12869] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-12 05:55:19,918 [salt.state       :300 ][INFO    ][12869] Iprange maas_create_iprange_1 already exist.
2019-05-12 05:55:19,919 [salt.state       :1951][INFO    ][12869] Completed state [maas_create_iprange_1] at time 05:55:19.919243 duration_in_ms=52.022
2019-05-12 05:55:19,919 [salt.state       :1780][INFO    ][12869] Running state [vlan 0] at time 05:55:19.919562
2019-05-12 05:55:19,919 [salt.state       :1813][INFO    ][12869] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-12 05:55:19,963 [salt.loaded.ext.module.maasng:945 ][INFO    ][12869] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qxfa6r', u'name': u'untagged', 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-05-12 05:55:20,053 [salt.loaded.ext.module.maasng:945 ][INFO    ][12869] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qxfa6r', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-12 05:55:20,292 [salt.loaded.ext.module.maasng:945 ][INFO    ][12869] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qxfa6r', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-12 05:55:20,407 [salt.state       :300 ][INFO    ][12869] {'new': 'Vlan untagged was updated'}
2019-05-12 05:55:20,408 [salt.state       :1951][INFO    ][12869] Completed state [vlan 0] at time 05:55:20.408298 duration_in_ms=488.735
2019-05-12 05:55:20,409 [salt.state       :1780][INFO    ][12869] Running state [opnfv] at time 05:55:20.409130
2019-05-12 05:55:20,409 [salt.state       :1813][INFO    ][12869] Executing state maasng.sshkey_present for [opnfv]
2019-05-12 05:55:20,477 [salt.loaded.ext.module.maasng:1903][INFO    ][12869] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-05-12 05:55:20,477 [salt.state       :300 ][INFO    ][12869] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-12 05:55:20,478 [salt.state       :1951][INFO    ][12869] Completed state [opnfv] at time 05:55:20.478039 duration_in_ms=68.908
2019-05-12 05:55:20,478 [salt.state       :1780][INFO    ][12869] Running state [maas.process_tags] at time 05:55:20.478735
2019-05-12 05:55:20,479 [salt.state       :1813][INFO    ][12869] Executing state module.run for [maas.process_tags]
2019-05-12 05:55:20,479 [salt.utils.decorators:613 ][WARNING ][12869] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:55:20,519 [salt.loaded.ext.module.maas:92  ][INFO    ][12869] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-12 05:55:20,599 [salt.state       :300 ][INFO    ][12869] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-12 05:55:20,600 [salt.state       :1951][INFO    ][12869] Completed state [maas.process_tags] at time 05:55:20.600256 duration_in_ms=121.521
2019-05-12 05:55:20,604 [salt.minion      :1711][INFO    ][12869] Returning information for job: 20190512055456303458
2019-05-12 05:55:21,235 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command state.apply with jid 20190512055521220295
2019-05-12 05:55:21,256 [salt.minion      :1432][INFO    ][13325] Starting a new job with PID 13325
2019-05-12 05:55:27,511 [salt.state       :915 ][INFO    ][13325] Loading fresh modules for state activity
2019-05-12 05:55:27,617 [salt.state       :1780][INFO    ][13325] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:55:27.617369
2019-05-12 05:55:27,618 [salt.state       :1813][INFO    ][13325] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-12 05:55:27,620 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13325] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-12 05:55:29,338 [salt.state       :300 ][INFO    ][13325] {'pid': 13352, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-12 05:55:29,339 [salt.state       :1951][INFO    ][13325] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:55:29.339337 duration_in_ms=1721.968
2019-05-12 05:55:29,343 [salt.state       :1780][INFO    ][13325] Running state [maas.process_machines] at time 05:55:29.343655
2019-05-12 05:55:29,344 [salt.state       :1813][INFO    ][13325] Executing state module.run for [maas.process_machines]
2019-05-12 05:55:29,344 [salt.utils.decorators:613 ][WARNING ][13325] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:55:29,923 [salt.loaded.ext.module.maas:412 ][WARNING ][13325] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-12 05:55:29,924 [salt.loaded.ext.module.maas:92  ][INFO    ][13325] 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=7tdsen architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-12 05:55:31,175 [salt.loaded.ext.module.maas:412 ][WARNING ][13325] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-12 05:55:31,176 [salt.loaded.ext.module.maas:92  ][INFO    ][13325] 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=eyxrnd architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-12 05:55:32,392 [salt.loaded.ext.module.maas:412 ][WARNING ][13325] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-12 05:55:32,395 [salt.loaded.ext.module.maas:92  ][INFO    ][13325] 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=qgxg8d architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-12 05:55:33,628 [salt.loaded.ext.module.maas:412 ][WARNING ][13325] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-12 05:55:33,629 [salt.loaded.ext.module.maas:92  ][INFO    ][13325] 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=mesrsg architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-12 05:55:34,847 [salt.loaded.ext.module.maas:412 ][WARNING ][13325] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-12 05:55:34,848 [salt.loaded.ext.module.maas:92  ][INFO    ][13325] 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=67w8xp architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-12 05:55:36,027 [salt.state       :300 ][INFO    ][13325] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-12 05:55:36,027 [salt.state       :1951][INFO    ][13325] Completed state [maas.process_machines] at time 05:55:36.027651 duration_in_ms=6683.994
2019-05-12 05:55:36,031 [salt.minion      :1711][INFO    ][13325] Returning information for job: 20190512055521220295
2019-05-12 05:56:09,066 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command state.apply with jid 20190512055609051190
2019-05-12 05:56:09,084 [salt.minion      :1432][INFO    ][13614] Starting a new job with PID 13614
2019-05-12 05:56:15,080 [salt.state       :915 ][INFO    ][13614] Loading fresh modules for state activity
2019-05-12 05:56:15,184 [salt.state       :1780][INFO    ][13614] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:56:15.184338
2019-05-12 05:56:15,184 [salt.state       :1813][INFO    ][13614] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-12 05:56:15,186 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13614] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-12 05:56:16,879 [salt.state       :300 ][INFO    ][13614] {'pid': 13626, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-12 05:56:16,880 [salt.state       :1951][INFO    ][13614] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:56:16.880111 duration_in_ms=1695.774
2019-05-12 05:56:16,883 [salt.state       :1780][INFO    ][13614] Running state [maas.wait_for_machine_status] at time 05:56:16.883142
2019-05-12 05:56:16,883 [salt.state       :1813][INFO    ][13614] Executing state module.run for [maas.wait_for_machine_status]
2019-05-12 05:56:16,883 [salt.utils.decorators:613 ][WARNING ][13614] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:56:19,824 [salt.state       :300 ][INFO    ][13614] {'ret': True}
2019-05-12 05:56:19,825 [salt.state       :1951][INFO    ][13614] Completed state [maas.wait_for_machine_status] at time 05:56:19.824964 duration_in_ms=2941.82
2019-05-12 05:56:19,830 [salt.minion      :1711][INFO    ][13614] Returning information for job: 20190512055609051190
2019-05-12 05:56:20,440 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command state.apply with jid 20190512055620376969
2019-05-12 05:56:20,470 [salt.minion      :1432][INFO    ][13641] Starting a new job with PID 13641
2019-05-12 05:56:21,451 [salt.state       :915 ][INFO    ][13641] Loading fresh modules for state activity
2019-05-12 05:56:21,598 [salt.state       :1780][INFO    ][13641] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:56:21.598388
2019-05-12 05:56:21,598 [salt.state       :1813][INFO    ][13641] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-12 05:56:21,600 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13641] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-12 05:56:23,282 [salt.state       :300 ][INFO    ][13641] {'pid': 13648, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-12 05:56:23,283 [salt.state       :1951][INFO    ][13641] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:56:23.283623 duration_in_ms=1685.235
2019-05-12 05:56:23,287 [salt.state       :1780][INFO    ][13641] Running state [maas_machines_storage_cmp002_lvm] at time 05:56:23.287209
2019-05-12 05:56:23,287 [salt.state       :1813][INFO    ][13641] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-12 05:56:23,793 [salt.state       :300 ][INFO    ][13641] Machine cmp002 is not in Ready state.
2019-05-12 05:56:23,793 [salt.state       :1951][INFO    ][13641] Completed state [maas_machines_storage_cmp002_lvm] at time 05:56:23.793584 duration_in_ms=506.373
2019-05-12 05:56:23,794 [salt.state       :1780][INFO    ][13641] Running state [maas_machines_storage_cmp001_lvm] at time 05:56:23.794235
2019-05-12 05:56:23,794 [salt.state       :1813][INFO    ][13641] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-12 05:56:24,331 [salt.state       :300 ][INFO    ][13641] Machine cmp001 is not in Ready state.
2019-05-12 05:56:24,331 [salt.state       :1951][INFO    ][13641] Completed state [maas_machines_storage_cmp001_lvm] at time 05:56:24.331862 duration_in_ms=537.627
2019-05-12 05:56:24,336 [salt.minion      :1711][INFO    ][13641] Returning information for job: 20190512055620376969
2019-05-12 05:56:24,899 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command state.apply with jid 20190512055624876958
2019-05-12 05:56:24,923 [salt.minion      :1432][INFO    ][13658] Starting a new job with PID 13658
2019-05-12 05:56:25,921 [salt.state       :915 ][INFO    ][13658] Loading fresh modules for state activity
2019-05-12 05:56:26,024 [salt.state       :1780][INFO    ][13658] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:56:26.024786
2019-05-12 05:56:26,025 [salt.state       :1813][INFO    ][13658] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-12 05:56:26,027 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13658] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-12 05:56:27,754 [salt.state       :300 ][INFO    ][13658] {'pid': 13665, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-12 05:56:27,755 [salt.state       :1951][INFO    ][13658] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:56:27.755791 duration_in_ms=1731.004
2019-05-12 05:56:27,759 [salt.state       :1780][INFO    ][13658] Running state [maas.deploy_machines] at time 05:56:27.759759
2019-05-12 05:56:27,760 [salt.state       :1813][INFO    ][13658] Executing state module.run for [maas.deploy_machines]
2019-05-12 05:56:27,760 [salt.utils.decorators:613 ][WARNING ][13658] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:56:28,329 [salt.loaded.ext.module.maas:684 ][INFO    ][13658] deploymachines hwe_kernel=hwe-16.04 system_id=67w8xp distro_series=xenial
2019-05-12 05:56:30,778 [salt.state       :300 ][INFO    ][13658] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-12 05:56:30,779 [salt.state       :1951][INFO    ][13658] Completed state [maas.deploy_machines] at time 05:56:30.779267 duration_in_ms=3019.505
2019-05-12 05:56:30,787 [salt.minion      :1711][INFO    ][13658] Returning information for job: 20190512055624876958
2019-05-12 05:56:31,352 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command state.apply with jid 20190512055631341170
2019-05-12 05:56:31,380 [salt.minion      :1432][INFO    ][13733] Starting a new job with PID 13733
2019-05-12 05:56:37,422 [salt.state       :915 ][INFO    ][13733] Loading fresh modules for state activity
2019-05-12 05:56:37,524 [salt.state       :1780][INFO    ][13733] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:56:37.524128
2019-05-12 05:56:37,524 [salt.state       :1813][INFO    ][13733] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-12 05:56:37,526 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13733] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-12 05:56:39,240 [salt.state       :300 ][INFO    ][13733] {'pid': 13744, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-12 05:56:39,241 [salt.state       :1951][INFO    ][13733] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:56:39.241113 duration_in_ms=1716.984
2019-05-12 05:56:39,245 [salt.state       :1780][INFO    ][13733] Running state [maas.wait_for_machine_status] at time 05:56:39.245371
2019-05-12 05:56:39,246 [salt.state       :1813][INFO    ][13733] Executing state module.run for [maas.wait_for_machine_status]
2019-05-12 05:56:39,247 [salt.utils.decorators:613 ][WARNING ][13733] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-12 05:56:42,027 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.24458885s left)
2019-05-12 05:56:46,388 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055646376566
2019-05-12 05:56:46,414 [salt.minion      :1432][INFO    ][13771] Starting a new job with PID 13771
2019-05-12 05:56:46,437 [salt.minion      :1711][INFO    ][13771] Returning information for job: 20190512055646376566
2019-05-12 05:57:14,739 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.53253293s left)
2019-05-12 05:57:16,465 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055716452092
2019-05-12 05:57:16,486 [salt.minion      :1432][INFO    ][13819] Starting a new job with PID 13819
2019-05-12 05:57:16,511 [salt.minion      :1711][INFO    ][13819] Returning information for job: 20190512055716452092
2019-05-12 05:57:46,540 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055746525048
2019-05-12 05:57:46,567 [salt.minion      :1432][INFO    ][13854] Starting a new job with PID 13854
2019-05-12 05:57:46,601 [salt.minion      :1711][INFO    ][13854] Returning information for job: 20190512055746525048
2019-05-12 05:57:47,673 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.59828591s left)
2019-05-12 05:58:16,632 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055816617596
2019-05-12 05:58:16,663 [salt.minion      :1432][INFO    ][13915] Starting a new job with PID 13915
2019-05-12 05:58:16,685 [salt.minion      :1711][INFO    ][13915] Returning information for job: 20190512055816617596
2019-05-12 05:58:20,369 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.90272284s left)
2019-05-12 05:58:46,712 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055846703072
2019-05-12 05:58:46,739 [salt.minion      :1432][INFO    ][13948] Starting a new job with PID 13948
2019-05-12 05:58:46,762 [salt.minion      :1711][INFO    ][13948] Returning information for job: 20190512055846703072
2019-05-12 05:58:53,241 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.03058386s left)
2019-05-12 05:59:16,821 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055916801200
2019-05-12 05:59:16,850 [salt.minion      :1432][INFO    ][13996] Starting a new job with PID 13996
2019-05-12 05:59:16,877 [salt.minion      :1711][INFO    ][13996] Returning information for job: 20190512055916801200
2019-05-12 05:59:26,089 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.18257594s left)
2019-05-12 05:59:46,906 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512055946894392
2019-05-12 05:59:46,937 [salt.minion      :1432][INFO    ][14029] Starting a new job with PID 14029
2019-05-12 05:59:46,968 [salt.minion      :1711][INFO    ][14029] Returning information for job: 20190512055946894392
2019-05-12 05:59:59,220 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.05186701s left)
2019-05-12 06:00:17,024 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060017008528
2019-05-12 06:00:17,052 [salt.minion      :1432][INFO    ][14073] Starting a new job with PID 14073
2019-05-12 06:00:17,086 [salt.minion      :1711][INFO    ][14073] Returning information for job: 20190512060017008528
2019-05-12 06:00:32,082 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.18949795s left)
2019-05-12 06:00:47,132 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060047114921
2019-05-12 06:00:47,160 [salt.minion      :1432][INFO    ][14110] Starting a new job with PID 14110
2019-05-12 06:00:47,184 [salt.minion      :1711][INFO    ][14110] Returning information for job: 20190512060047114921
2019-05-12 06:01:04,827 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.44491887s left)
2019-05-12 06:01:17,241 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060117229896
2019-05-12 06:01:17,273 [salt.minion      :1432][INFO    ][14178] Starting a new job with PID 14178
2019-05-12 06:01:17,309 [salt.minion      :1711][INFO    ][14178] Returning information for job: 20190512060117229896
2019-05-12 06:01:37,671 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.60084701s left)
2019-05-12 06:01:47,356 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060147343498
2019-05-12 06:01:47,381 [salt.minion      :1432][INFO    ][14255] Starting a new job with PID 14255
2019-05-12 06:01:47,412 [salt.minion      :1711][INFO    ][14255] Returning information for job: 20190512060147343498
2019-05-12 06:02:10,642 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.62951183s left)
2019-05-12 06:02:17,495 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060217476679
2019-05-12 06:02:17,525 [salt.minion      :1432][INFO    ][14407] Starting a new job with PID 14407
2019-05-12 06:02:17,558 [salt.minion      :1711][INFO    ][14407] Returning information for job: 20190512060217476679
2019-05-12 06:02:43,639 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.63314605s left)
2019-05-12 06:02:47,632 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060247619466
2019-05-12 06:02:47,658 [salt.minion      :1432][INFO    ][14462] Starting a new job with PID 14462
2019-05-12 06:02:47,687 [salt.minion      :1711][INFO    ][14462] Returning information for job: 20190512060247619466
2019-05-12 06:03:16,616 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.65555501s left)
2019-05-12 06:03:17,768 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060317758411
2019-05-12 06:03:17,796 [salt.minion      :1432][INFO    ][14532] Starting a new job with PID 14532
2019-05-12 06:03:17,826 [salt.minion      :1711][INFO    ][14532] Returning information for job: 20190512060317758411
2019-05-12 06:03:47,916 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060347908319
2019-05-12 06:03:47,940 [salt.minion      :1432][INFO    ][14566] Starting a new job with PID 14566
2019-05-12 06:03:47,975 [salt.minion      :1711][INFO    ][14566] Returning information for job: 20190512060347908319
2019-05-12 06:03:49,647 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.62422085s left)
2019-05-12 06:04:18,075 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060418060048
2019-05-12 06:04:18,108 [salt.minion      :1432][INFO    ][14658] Starting a new job with PID 14658
2019-05-12 06:04:18,140 [salt.minion      :1711][INFO    ][14658] Returning information for job: 20190512060418060048
2019-05-12 06:04:22,476 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.79558682s left)
2019-05-12 06:04:48,252 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060448233805
2019-05-12 06:04:48,280 [salt.minion      :1432][INFO    ][14735] Starting a new job with PID 14735
2019-05-12 06:04:48,312 [salt.minion      :1711][INFO    ][14735] Returning information for job: 20190512060448233805
2019-05-12 06:04:55,385 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.88648391s left)
2019-05-12 06:05:18,429 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060518418534
2019-05-12 06:05:18,459 [salt.minion      :1432][INFO    ][14815] Starting a new job with PID 14815
2019-05-12 06:05:18,493 [salt.minion      :1711][INFO    ][14815] Returning information for job: 20190512060518418534
2019-05-12 06:05:28,292 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.98003697s left)
2019-05-12 06:05:48,616 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060548603076
2019-05-12 06:05:48,644 [salt.minion      :1432][INFO    ][14856] Starting a new job with PID 14856
2019-05-12 06:05:48,677 [salt.minion      :1711][INFO    ][14856] Returning information for job: 20190512060548603076
2019-05-12 06:06:01,493 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.77880096s left)
2019-05-12 06:06:18,807 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060618788561
2019-05-12 06:06:18,832 [salt.minion      :1432][INFO    ][14895] Starting a new job with PID 14895
2019-05-12 06:06:18,863 [salt.minion      :1711][INFO    ][14895] Returning information for job: 20190512060618788561
2019-05-12 06:06:34,588 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.68385601s left)
2019-05-12 06:06:48,990 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060648979361
2019-05-12 06:06:49,014 [salt.minion      :1432][INFO    ][14928] Starting a new job with PID 14928
2019-05-12 06:06:49,053 [salt.minion      :1711][INFO    ][14928] Returning information for job: 20190512060648979361
2019-05-12 06:07:07,441 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.83069897s left)
2019-05-12 06:07:19,172 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060719155782
2019-05-12 06:07:19,204 [salt.minion      :1432][INFO    ][14971] Starting a new job with PID 14971
2019-05-12 06:07:19,233 [salt.minion      :1711][INFO    ][14971] Returning information for job: 20190512060719155782
2019-05-12 06:07:40,314 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.95721889s left)
2019-05-12 06:07:49,373 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060749355559
2019-05-12 06:07:49,401 [salt.minion      :1432][INFO    ][15009] Starting a new job with PID 15009
2019-05-12 06:07:49,431 [salt.minion      :1711][INFO    ][15009] Returning information for job: 20190512060749355559
2019-05-12 06:07:51,563 [salt.utils.schedule:1377][INFO    ][3087] Running scheduled job: __mine_interval
2019-05-12 06:08:13,144 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1556.12782288s left)
2019-05-12 06:08:19,556 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060819542766
2019-05-12 06:08:19,582 [salt.minion      :1432][INFO    ][15223] Starting a new job with PID 15223
2019-05-12 06:08:19,613 [salt.minion      :1711][INFO    ][15223] Returning information for job: 20190512060819542766
2019-05-12 06:08:46,271 [salt.loaded.ext.module.maas:1023][INFO    ][13733] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1523.00063491s left)
2019-05-12 06:08:49,772 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command saltutil.find_job with jid 20190512060849756822
2019-05-12 06:08:49,799 [salt.minion      :1432][INFO    ][15276] Starting a new job with PID 15276
2019-05-12 06:08:49,835 [salt.minion      :1711][INFO    ][15276] Returning information for job: 20190512060849756822
2019-05-12 06:09:19,231 [salt.state       :300 ][INFO    ][13733] {'ret': True}
2019-05-12 06:09:19,232 [salt.state       :1951][INFO    ][13733] Completed state [maas.wait_for_machine_status] at time 06:09:19.232253 duration_in_ms=759986.877
2019-05-12 06:09:19,240 [salt.minion      :1711][INFO    ][13733] Returning information for job: 20190512055631341170
2019-05-12 07:07:51,563 [salt.utils.schedule:1377][INFO    ][3087] Running scheduled job: __mine_interval
2019-05-12 07:31:52,750 [salt.minion      :1308][INFO    ][3087] User sudo_ubuntu Executing command cp.push_dir with jid 20190512073152737136
2019-05-12 07:31:52,783 [salt.minion      :1432][INFO    ][21417] Starting a new job with PID 21417
