2019-06-15 05:04:34,532 [salt.utils.decorators:613 ][WARNING ][2102] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:04:35,721 [salt.utils.decorators:613 ][WARNING ][2102] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:04:39,657 [salt.loaded.int.states.file:2298][WARNING ][2479] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-15 05:04:55,280 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2829] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-15 05:04:55,315 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2829] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-15 05:04:55,380 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-06-15 05:04:56,551 [salt.cli.daemons :293 ][INFO    ][2944] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-06-15 05:04:56,728 [salt.cli.daemons :82  ][INFO    ][2944] Starting up the Salt Minion
2019-06-15 05:04:56,729 [salt.utils.event :1017][INFO    ][2944] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-06-15 05:04:57,964 [salt.minion      :976 ][INFO    ][2944] Creating minion process manager
2019-06-15 05:05:00,169 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][2944] Executing command ['date', '+%z'] in directory '/root'
2019-06-15 05:05:00,203 [salt.utils.schedule:568 ][INFO    ][2944] Updating job settings for scheduled job: __mine_interval
2019-06-15 05:05:00,205 [salt.minion      :1108][INFO    ][2944] Added mine.update to scheduler
2019-06-15 05:05:00,211 [salt.minion      :1975][INFO    ][2944] Minion is starting as user 'root'
2019-06-15 05:05:00,233 [salt.minion      :2336][INFO    ][2944] Minion is ready to receive requests!
2019-06-15 05:05:06,760 [salt.state       :2022][WARNING ][2832] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-15 05:05:07,304 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615050507281602
2019-06-15 05:05:07,345 [salt.minion      :1432][INFO    ][3109] Starting a new job with PID 3109
2019-06-15 05:05:07,417 [salt.minion      :1711][INFO    ][3109] Returning information for job: 20190615050507281602
2019-06-15 05:05:10,054 [salt.utils.decorators:613 ][WARNING ][2832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:05:18,443 [salt.utils.decorators:613 ][WARNING ][2832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:05:37,392 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615050537374692
2019-06-15 05:05:37,420 [salt.minion      :1432][INFO    ][3988] Starting a new job with PID 3988
2019-06-15 05:05:37,450 [salt.minion      :1711][INFO    ][3988] Returning information for job: 20190615050537374692
2019-06-15 05:05:46,339 [salt.utils.decorators:613 ][WARNING ][2832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:05:47,412 [salt.utils.decorators:613 ][WARNING ][2832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:05:49,860 [salt.loaded.ext.module.maasng:1008][WARNING ][2832] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-06-15 05:05:49,861 [salt.loaded.ext.module.maasng:1011][WARNING ][2832] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-06-15 05:05:50,627 [salt.loaded.ext.module.maasng:1235][WARNING ][2832] Ignoring parameter vlan:0
2019-06-15 05:05:51,535 [salt.utils.decorators:613 ][WARNING ][2832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:05:52,556 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command state.apply with jid 20190615050552540776
2019-06-15 05:05:52,578 [salt.minion      :1432][INFO    ][4366] Starting a new job with PID 4366
2019-06-15 05:06:01,187 [salt.state       :915 ][INFO    ][4366] Loading fresh modules for state activity
2019-06-15 05:06:01,272 [salt.fileclient  :1219][INFO    ][4366] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-06-15 05:06:01,324 [salt.state       :1780][INFO    ][4366] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:06:01.324128
2019-06-15 05:06:01,324 [salt.state       :1813][INFO    ][4366] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 05:06:01,327 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4366] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 05:06:03,288 [salt.state       :300 ][INFO    ][4366] {'pid': 4404, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 05:06:03,289 [salt.state       :1951][INFO    ][4366] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:06:03.289136 duration_in_ms=1965.009
2019-06-15 05:06:03,292 [salt.state       :1780][INFO    ][4366] Running state [maas.process_machines] at time 05:06:03.292238
2019-06-15 05:06:03,292 [salt.state       :1813][INFO    ][4366] Executing state module.run for [maas.process_machines]
2019-06-15 05:06:03,293 [salt.utils.decorators:613 ][WARNING ][4366] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:06:03,354 [salt.loaded.ext.module.maas:412 ][WARNING ][4366] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 05:06:03,355 [salt.loaded.ext.module.maas:92  ][INFO    ][4366] 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-06-15 05:06:04,902 [salt.loaded.ext.module.maas:412 ][WARNING ][4366] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 05:06:04,903 [salt.loaded.ext.module.maas:92  ][INFO    ][4366] 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-06-15 05:06:06,211 [salt.loaded.ext.module.maas:412 ][WARNING ][4366] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 05:06:06,212 [salt.loaded.ext.module.maas:92  ][INFO    ][4366] 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-06-15 05:06:07,481 [salt.loaded.ext.module.maas:412 ][WARNING ][4366] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 05:06:07,481 [salt.loaded.ext.module.maas:92  ][INFO    ][4366] 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-06-15 05:06:07,614 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615050607600959
2019-06-15 05:06:07,639 [salt.minion      :1432][INFO    ][4572] Starting a new job with PID 4572
2019-06-15 05:06:07,673 [salt.minion      :1711][INFO    ][4572] Returning information for job: 20190615050607600959
2019-06-15 05:06:08,862 [salt.loaded.ext.module.maas:412 ][WARNING ][4366] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 05:06:08,863 [salt.loaded.ext.module.maas:92  ][INFO    ][4366] 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-06-15 05:06:10,474 [salt.state       :300 ][INFO    ][4366] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-15 05:06:10,475 [salt.state       :1951][INFO    ][4366] Completed state [maas.process_machines] at time 05:06:10.475261 duration_in_ms=7183.022
2019-06-15 05:06:10,480 [salt.minion      :1711][INFO    ][4366] Returning information for job: 20190615050552540776
2019-06-15 05:06:41,867 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command state.apply with jid 20190615050641854549
2019-06-15 05:06:41,892 [salt.minion      :1432][INFO    ][4726] Starting a new job with PID 4726
2019-06-15 05:06:50,158 [salt.state       :915 ][INFO    ][4726] Loading fresh modules for state activity
2019-06-15 05:06:50,227 [salt.fileclient  :1219][INFO    ][4726] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-15 05:06:50,277 [salt.state       :1780][INFO    ][4726] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:06:50.277258
2019-06-15 05:06:50,277 [salt.state       :1813][INFO    ][4726] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 05:06:50,280 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4726] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 05:06:52,104 [salt.state       :300 ][INFO    ][4726] {'pid': 4733, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 05:06:52,105 [salt.state       :1951][INFO    ][4726] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:06:52.105488 duration_in_ms=1828.229
2019-06-15 05:06:52,110 [salt.state       :1780][INFO    ][4726] Running state [maas.wait_for_machine_status] at time 05:06:52.110356
2019-06-15 05:06:52,111 [salt.state       :1813][INFO    ][4726] Executing state module.run for [maas.wait_for_machine_status]
2019-06-15 05:06:52,111 [salt.utils.decorators:613 ][WARNING ][4726] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:06:52,960 [salt.loaded.ext.module.maas:1023][INFO    ][4726] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.16659307s left)
2019-06-15 05:06:56,922 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615050656907210
2019-06-15 05:06:56,950 [salt.minion      :1432][INFO    ][4754] Starting a new job with PID 4754
2019-06-15 05:06:56,981 [salt.minion      :1711][INFO    ][4754] Returning information for job: 20190615050656907210
2019-06-15 05:07:23,965 [salt.loaded.ext.module.maas:1023][INFO    ][4726] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.16180611s left)
2019-06-15 05:07:27,020 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615050727003207
2019-06-15 05:07:27,047 [salt.minion      :1432][INFO    ][4797] Starting a new job with PID 4797
2019-06-15 05:07:27,078 [salt.minion      :1711][INFO    ][4797] Returning information for job: 20190615050727003207
2019-06-15 05:07:54,823 [salt.loaded.ext.module.maas:1023][INFO    ][4726] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.30342889s left)
2019-06-15 05:07:57,164 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615050757154655
2019-06-15 05:07:57,192 [salt.minion      :1432][INFO    ][4826] Starting a new job with PID 4826
2019-06-15 05:07:57,226 [salt.minion      :1711][INFO    ][4826] Returning information for job: 20190615050757154655
2019-06-15 05:08:25,654 [salt.loaded.ext.module.maas:1023][INFO    ][4726] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.47333002s left)
2019-06-15 05:08:27,253 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615050827241204
2019-06-15 05:08:27,282 [salt.minion      :1432][INFO    ][4872] Starting a new job with PID 4872
2019-06-15 05:08:27,318 [salt.minion      :1711][INFO    ][4872] Returning information for job: 20190615050827241204
2019-06-15 05:08:56,731 [salt.loaded.ext.module.maas:1023][INFO    ][4726] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.39526606s left)
2019-06-15 05:08:57,341 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615050857327450
2019-06-15 05:08:57,371 [salt.minion      :1432][INFO    ][4931] Starting a new job with PID 4931
2019-06-15 05:08:57,401 [salt.minion      :1711][INFO    ][4931] Returning information for job: 20190615050857327450
2019-06-15 05:09:27,443 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615050927430678
2019-06-15 05:09:27,468 [salt.minion      :1432][INFO    ][5073] Starting a new job with PID 5073
2019-06-15 05:09:27,500 [salt.minion      :1711][INFO    ][5073] Returning information for job: 20190615050927430678
2019-06-15 05:09:27,847 [salt.loaded.ext.module.maas:1023][INFO    ][4726] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.2792511s left)
2019-06-15 05:09:57,553 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615050957539494
2019-06-15 05:09:57,582 [salt.minion      :1432][INFO    ][5148] Starting a new job with PID 5148
2019-06-15 05:09:57,623 [salt.minion      :1711][INFO    ][5148] Returning information for job: 20190615050957539494
2019-06-15 05:09:58,989 [salt.loaded.ext.module.maas:1023][INFO    ][4726] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.13751006s left)
2019-06-15 05:10:27,720 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051027712316
2019-06-15 05:10:27,732 [salt.minion      :1432][INFO    ][5467] Starting a new job with PID 5467
2019-06-15 05:10:27,761 [salt.minion      :1711][INFO    ][5467] Returning information for job: 20190615051027712316
2019-06-15 05:10:30,160 [salt.loaded.ext.module.maas:1023][INFO    ][4726] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.96706009s left)
2019-06-15 05:10:57,844 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051057820883
2019-06-15 05:10:57,872 [salt.minion      :1432][INFO    ][5603] Starting a new job with PID 5603
2019-06-15 05:10:57,917 [salt.minion      :1711][INFO    ][5603] Returning information for job: 20190615051057820883
2019-06-15 05:11:02,003 [salt.loaded.ext.module.maas:1023][INFO    ][4726] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.12350297s left)
2019-06-15 05:11:28,049 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051128028658
2019-06-15 05:11:28,077 [salt.minion      :1432][INFO    ][5896] Starting a new job with PID 5896
2019-06-15 05:11:28,109 [salt.minion      :1711][INFO    ][5896] Returning information for job: 20190615051128028658
2019-06-15 05:11:34,279 [salt.loaded.ext.module.maas:1023][INFO    ][4726] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.84790611s left)
2019-06-15 05:11:58,213 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051158199104
2019-06-15 05:11:58,241 [salt.minion      :1432][INFO    ][6093] Starting a new job with PID 6093
2019-06-15 05:11:58,272 [salt.minion      :1711][INFO    ][6093] Returning information for job: 20190615051158199104
2019-06-15 05:12:06,412 [salt.loaded.ext.module.maas:1023][INFO    ][4726] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.71515894s left)
2019-06-15 05:12:28,363 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051228353370
2019-06-15 05:12:28,391 [salt.minion      :1432][INFO    ][6273] Starting a new job with PID 6273
2019-06-15 05:12:28,419 [salt.minion      :1711][INFO    ][6273] Returning information for job: 20190615051228353370
2019-06-15 05:12:38,825 [salt.loaded.ext.module.maas:1023][INFO    ][4726] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1153.30169892s left)
2019-06-15 05:12:58,512 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051258495182
2019-06-15 05:12:58,544 [salt.minion      :1432][INFO    ][6397] Starting a new job with PID 6397
2019-06-15 05:12:58,573 [salt.minion      :1711][INFO    ][6397] Returning information for job: 20190615051258495182
2019-06-15 05:13:12,041 [salt.state       :300 ][INFO    ][4726] {'ret': True}
2019-06-15 05:13:12,042 [salt.state       :1951][INFO    ][4726] Completed state [maas.wait_for_machine_status] at time 05:13:12.042755 duration_in_ms=379932.398
2019-06-15 05:13:12,047 [salt.minion      :1711][INFO    ][4726] Returning information for job: 20190615050641854549
2019-06-15 05:13:12,775 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command state.apply with jid 20190615051312758828
2019-06-15 05:13:12,802 [salt.minion      :1432][INFO    ][6465] Starting a new job with PID 6465
2019-06-15 05:13:21,241 [salt.state       :915 ][INFO    ][6465] Loading fresh modules for state activity
2019-06-15 05:13:21,306 [salt.fileclient  :1219][INFO    ][6465] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-15 05:13:21,419 [salt.state       :1780][INFO    ][6465] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:21.419691
2019-06-15 05:13:21,420 [salt.state       :1813][INFO    ][6465] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 05:13:21,424 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6465] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 05:13:23,441 [salt.state       :300 ][INFO    ][6465] {'pid': 6473, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 05:13:23,443 [salt.state       :1951][INFO    ][6465] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:23.443130 duration_in_ms=2023.44
2019-06-15 05:13:23,446 [salt.state       :1780][INFO    ][6465] Running state [maas_machines_storage_cmp002_lvm] at time 05:13:23.446802
2019-06-15 05:13:23,447 [salt.state       :1813][INFO    ][6465] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-15 05:13:24,593 [salt.loaded.ext.module.maasng:610 ][INFO    ][6465] wecscs
2019-06-15 05:13:24,594 [salt.loaded.ext.module.maasng:626 ][INFO    ][6465] sda
2019-06-15 05:13:25,146 [salt.loaded.ext.module.maasng:361 ][INFO    ][6465] wecscs
2019-06-15 05:13:25,265 [salt.loaded.ext.module.maasng:367 ][INFO    ][6465] [{u'size': 800109715456, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'ac1a13a4-920a-49d7-aefa-7887b30a612b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wecscs', u'filesystem': {u'uuid': u'af425a27-cdbd-4667-8f78-6075211de3ac', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}], u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wecscs', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/blockdevices/1/', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}, {u'size': 800097042432, u'available_size': 0, u'uuid': u'bcea8217-4d34-4b83-af18-1b968c3cb479', u'tags': [], u'type': u'virtual', u'partitions': [], u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wecscs', u'partition_table_type': None, u'filesystem': {u'uuid': u'36bc05d1-e605-49f9-85ab-7f14013ead76', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/blockdevices/3/', u'serial': None, u'name': u'vgroot-lvroot'}]
2019-06-15 05:13:25,266 [salt.loaded.ext.module.maasng:632 ][INFO    ][6465] vgroot
2019-06-15 05:13:25,266 [salt.loaded.ext.module.maasng:635 ][INFO    ][6465] lvroot
2019-06-15 05:13:25,267 [salt.loaded.ext.module.maasng:639 ][INFO    ][6465] 107374182400
2019-06-15 05:13:25,879 [salt.loaded.ext.module.maasng:645 ][INFO    ][6465] {u'hwe_kernel': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'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'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 19}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'id': 4, u'system_id': u'wecscs', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/interfaces/4/'}, u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'051d1f3c-13b0-4d80-9eb6-74dc68479140', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wecscs', u'filesystem': {u'uuid': u'9fcea1b0-bbae-4265-a0e4-06ace135be37', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wecscs', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/blockdevices/1/', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}, u'blockdevice_set': [{u'size': 800109715456, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'051d1f3c-13b0-4d80-9eb6-74dc68479140', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wecscs', u'filesystem': {u'uuid': u'9fcea1b0-bbae-4265-a0e4-06ace135be37', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wecscs', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/blockdevices/1/', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}, {u'size': 107374182400, u'available_size': 0, u'uuid': u'88129d5d-521c-44b3-884c-ef9bc3d1ff30', u'tags': [], u'type': u'virtual', u'partitions': [], u'id': 11, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wecscs', u'partition_table_type': None, u'filesystem': {u'uuid': u'4d869051-b3d6-453e-bd2d-04b5573c10e1', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/blockdevices/11/', u'serial': None, u'name': u'vgroot-lvroot'}], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/wecscs/', u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'wecscs', 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'physicalblockdevice_set': [{u'size': 800109715456, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'051d1f3c-13b0-4d80-9eb6-74dc68479140', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wecscs', u'filesystem': {u'uuid': u'9fcea1b0-bbae-4265-a0e4-06ace135be37', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wecscs', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/blockdevices/1/', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'wecscs', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 19}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'id': 4, u'system_id': u'wecscs', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'id': 15, u'system_id': u'wecscs', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/interfaces/15/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'id': 12, u'system_id': u'wecscs', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/interfaces/12/'}, {u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'id': 11, u'system_id': u'wecscs', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/interfaces/11/'}, {u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'id': 13, u'system_id': u'wecscs', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/interfaces/13/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'id': 14, u'system_id': u'wecscs', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/interfaces/14/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'available_size': 0, u'uuid': u'88129d5d-521c-44b3-884c-ef9bc3d1ff30', u'tags': [], u'type': u'virtual', u'partitions': [], u'id': 11, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wecscs', u'partition_table_type': None, u'filesystem': {u'uuid': u'4d869051-b3d6-453e-bd2d-04b5573c10e1', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/wecscs/blockdevices/11/', u'serial': None, u'name': u'vgroot-lvroot'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-06-15 05:13:25,882 [salt.state       :300 ][INFO    ][6465] {'new': {'storage_layout': 'lvm'}}
2019-06-15 05:13:25,882 [salt.state       :1951][INFO    ][6465] Completed state [maas_machines_storage_cmp002_lvm] at time 05:13:25.882637 duration_in_ms=2435.833
2019-06-15 05:13:25,883 [salt.state       :1780][INFO    ][6465] Running state [maas_machines_storage_cmp001_lvm] at time 05:13:25.883442
2019-06-15 05:13:25,884 [salt.state       :1813][INFO    ][6465] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-15 05:13:27,065 [salt.loaded.ext.module.maasng:610 ][INFO    ][6465] qgfqq7
2019-06-15 05:13:27,066 [salt.loaded.ext.module.maasng:626 ][INFO    ][6465] sda
2019-06-15 05:13:27,648 [salt.loaded.ext.module.maasng:361 ][INFO    ][6465] qgfqq7
2019-06-15 05:13:27,744 [salt.loaded.ext.module.maasng:367 ][INFO    ][6465] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'uuid': None, u'name': u'sda', u'system_id': u'qgfqq7', 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'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'7a7a767e-becd-4770-b155-4d1ec1cea956', u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qgfqq7', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'9e07fb95-c6f0-43f8-9fd8-5ab3c264b91d'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}]}, {u'size': 800097042432, u'model': None, u'block_size': 4096, u'available_size': 0, u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'uuid': u'446836da-a7d0-4326-afdf-8afd18bcd8c0', u'name': u'vgroot-lvroot', u'system_id': u'qgfqq7', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'dea6921b-693c-46c6-8d07-05b7f35bc5a9'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/blockdevices/4/', u'type': u'virtual', u'id': 4, u'partitions': []}]
2019-06-15 05:13:27,744 [salt.loaded.ext.module.maasng:632 ][INFO    ][6465] vgroot
2019-06-15 05:13:27,745 [salt.loaded.ext.module.maasng:635 ][INFO    ][6465] lvroot
2019-06-15 05:13:27,745 [salt.loaded.ext.module.maasng:639 ][INFO    ][6465] 107374182400
2019-06-15 05:13:27,871 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051327858746
2019-06-15 05:13:27,895 [salt.minion      :1432][INFO    ][6492] Starting a new job with PID 6492
2019-06-15 05:13:27,924 [salt.minion      :1711][INFO    ][6492] Returning information for job: 20190615051327858746
2019-06-15 05:13:28,376 [salt.loaded.ext.module.maasng:645 ][INFO    ][6465] {u'hwe_kernel': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'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'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 25}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, u'system_id': u'qgfqq7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'59644088-288f-44cc-97b9-9b449e15e178', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qgfqq7', u'filesystem': {u'uuid': u'6780e82f-fe94-43d5-b92b-596a65172b7e', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}], u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qgfqq7', 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'used_size': 800106479616, u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/blockdevices/2/', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}, u'blockdevice_set': [{u'size': 800109715456, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'59644088-288f-44cc-97b9-9b449e15e178', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qgfqq7', u'filesystem': {u'uuid': u'6780e82f-fe94-43d5-b92b-596a65172b7e', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}], u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qgfqq7', 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'used_size': 800106479616, u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/blockdevices/2/', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}, {u'size': 107374182400, u'available_size': 0, u'uuid': u'71c0733a-ae6e-449d-879b-271d98186411', u'tags': [], u'type': u'virtual', u'partitions': [], u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qgfqq7', u'partition_table_type': None, u'filesystem': {u'uuid': u'be9032a8-5d7a-472d-89a5-1fae33267d8f', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/blockdevices/12/', u'serial': None, u'name': u'vgroot-lvroot'}], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/qgfqq7/', u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'qgfqq7', 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'physicalblockdevice_set': [{u'size': 800109715456, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'59644088-288f-44cc-97b9-9b449e15e178', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qgfqq7', u'filesystem': {u'uuid': u'6780e82f-fe94-43d5-b92b-596a65172b7e', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}], u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qgfqq7', 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'used_size': 800106479616, u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/blockdevices/2/', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qgfqq7', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 25}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, u'system_id': u'qgfqq7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'id': 19, u'system_id': u'qgfqq7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/interfaces/19/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'id': 20, u'system_id': u'qgfqq7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/interfaces/20/'}, {u'name': u'ens2f1', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'id': 16, u'system_id': u'qgfqq7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/interfaces/16/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'id': 17, u'system_id': u'qgfqq7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/interfaces/17/'}, {u'name': u'ens2f0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'id': 18, u'system_id': u'qgfqq7', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/interfaces/18/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'available_size': 0, u'uuid': u'71c0733a-ae6e-449d-879b-271d98186411', u'tags': [], u'type': u'virtual', u'partitions': [], u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qgfqq7', u'partition_table_type': None, u'filesystem': {u'uuid': u'be9032a8-5d7a-472d-89a5-1fae33267d8f', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/qgfqq7/blockdevices/12/', u'serial': None, u'name': u'vgroot-lvroot'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-06-15 05:13:28,379 [salt.state       :300 ][INFO    ][6465] {'new': {'storage_layout': 'lvm'}}
2019-06-15 05:13:28,379 [salt.state       :1951][INFO    ][6465] Completed state [maas_machines_storage_cmp001_lvm] at time 05:13:28.379750 duration_in_ms=2496.307
2019-06-15 05:13:28,385 [salt.minion      :1711][INFO    ][6465] Returning information for job: 20190615051312758828
2019-06-15 05:13:29,141 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command state.apply with jid 20190615051329122993
2019-06-15 05:13:29,166 [salt.minion      :1432][INFO    ][6499] Starting a new job with PID 6499
2019-06-15 05:13:30,459 [salt.state       :915 ][INFO    ][6499] Loading fresh modules for state activity
2019-06-15 05:13:30,524 [salt.fileclient  :1219][INFO    ][6499] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-15 05:13:30,576 [salt.state       :1780][INFO    ][6499] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:30.576856
2019-06-15 05:13:30,577 [salt.state       :1813][INFO    ][6499] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 05:13:30,579 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6499] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 05:13:32,410 [salt.state       :300 ][INFO    ][6499] {'pid': 6507, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 05:13:32,411 [salt.state       :1951][INFO    ][6499] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:32.411387 duration_in_ms=1834.53
2019-06-15 05:13:32,416 [salt.state       :1780][INFO    ][6499] Running state [maas.deploy_machines] at time 05:13:32.416795
2019-06-15 05:13:32,417 [salt.state       :1813][INFO    ][6499] Executing state module.run for [maas.deploy_machines]
2019-06-15 05:13:32,419 [salt.utils.decorators:613 ][WARNING ][6499] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:13:33,040 [salt.loaded.ext.module.maas:684 ][INFO    ][6499] deploymachines hwe_kernel=hwe-16.04 system_id=wecscs distro_series=xenial
2019-06-15 05:13:35,660 [salt.loaded.ext.module.maas:684 ][INFO    ][6499] deploymachines hwe_kernel=hwe-16.04 system_id=qgfqq7 distro_series=xenial
2019-06-15 05:13:38,212 [salt.loaded.ext.module.maas:684 ][INFO    ][6499] deploymachines hwe_kernel=hwe-16.04 system_id=44wxqw distro_series=xenial
2019-06-15 05:13:40,751 [salt.loaded.ext.module.maas:684 ][INFO    ][6499] deploymachines hwe_kernel=hwe-16.04 system_id=f6xex7 distro_series=xenial
2019-06-15 05:13:43,284 [salt.loaded.ext.module.maas:684 ][INFO    ][6499] deploymachines hwe_kernel=hwe-16.04 system_id=8tfqqc distro_series=xenial
2019-06-15 05:13:44,191 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051344176427
2019-06-15 05:13:44,221 [salt.minion      :1432][INFO    ][6746] Starting a new job with PID 6746
2019-06-15 05:13:44,255 [salt.minion      :1711][INFO    ][6746] Returning information for job: 20190615051344176427
2019-06-15 05:13:45,876 [salt.state       :300 ][INFO    ][6499] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-15 05:13:45,877 [salt.state       :1951][INFO    ][6499] Completed state [maas.deploy_machines] at time 05:13:45.877150 duration_in_ms=13460.355
2019-06-15 05:13:45,882 [salt.minion      :1711][INFO    ][6499] Returning information for job: 20190615051329122993
2019-06-15 05:13:46,588 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command state.apply with jid 20190615051346569501
2019-06-15 05:13:46,607 [salt.minion      :1432][INFO    ][6784] Starting a new job with PID 6784
2019-06-15 05:13:55,128 [salt.state       :915 ][INFO    ][6784] Loading fresh modules for state activity
2019-06-15 05:13:55,196 [salt.fileclient  :1219][INFO    ][6784] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-15 05:13:55,254 [salt.state       :1780][INFO    ][6784] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:55.254774
2019-06-15 05:13:55,255 [salt.state       :1813][INFO    ][6784] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 05:13:55,257 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6784] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 05:13:57,238 [salt.state       :300 ][INFO    ][6784] {'pid': 6813, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 05:13:57,239 [salt.state       :1951][INFO    ][6784] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:57.239584 duration_in_ms=1984.807
2019-06-15 05:13:57,243 [salt.state       :1780][INFO    ][6784] Running state [maas.wait_for_machine_status] at time 05:13:57.243685
2019-06-15 05:13:57,244 [salt.state       :1813][INFO    ][6784] Executing state module.run for [maas.wait_for_machine_status]
2019-06-15 05:13:57,244 [salt.utils.decorators:613 ][WARNING ][6784] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:14:00,180 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.07620311s left)
2019-06-15 05:14:01,659 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051401647021
2019-06-15 05:14:01,686 [salt.minion      :1432][INFO    ][6826] Starting a new job with PID 6826
2019-06-15 05:14:01,715 [salt.minion      :1711][INFO    ][6826] Returning information for job: 20190615051401647021
2019-06-15 05:14:31,763 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051431751334
2019-06-15 05:14:31,791 [salt.minion      :1432][INFO    ][6878] Starting a new job with PID 6878
2019-06-15 05:14:31,820 [salt.minion      :1711][INFO    ][6878] Returning information for job: 20190615051431751334
2019-06-15 05:14:33,195 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.06268215s left)
2019-06-15 05:15:01,889 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051501871094
2019-06-15 05:15:01,918 [salt.minion      :1432][INFO    ][6911] Starting a new job with PID 6911
2019-06-15 05:15:01,948 [salt.minion      :1711][INFO    ][6911] Returning information for job: 20190615051501871094
2019-06-15 05:15:06,028 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.22874808s left)
2019-06-15 05:15:31,984 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051531972469
2019-06-15 05:15:32,012 [salt.minion      :1432][INFO    ][6970] Starting a new job with PID 6970
2019-06-15 05:15:32,043 [salt.minion      :1711][INFO    ][6970] Returning information for job: 20190615051531972469
2019-06-15 05:15:39,047 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.21016407s left)
2019-06-15 05:16:02,061 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051602047663
2019-06-15 05:16:02,083 [salt.minion      :1432][INFO    ][7000] Starting a new job with PID 7000
2019-06-15 05:16:02,112 [salt.minion      :1711][INFO    ][7000] Returning information for job: 20190615051602047663
2019-06-15 05:16:12,148 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.10879898s left)
2019-06-15 05:16:32,155 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051632145453
2019-06-15 05:16:32,181 [salt.minion      :1432][INFO    ][7084] Starting a new job with PID 7084
2019-06-15 05:16:32,218 [salt.minion      :1711][INFO    ][7084] Returning information for job: 20190615051632145453
2019-06-15 05:16:45,061 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.19572616s left)
2019-06-15 05:17:02,288 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051702266866
2019-06-15 05:17:02,320 [salt.minion      :1432][INFO    ][7151] Starting a new job with PID 7151
2019-06-15 05:17:02,350 [salt.minion      :1711][INFO    ][7151] Returning information for job: 20190615051702266866
2019-06-15 05:17:18,039 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.21721005s left)
2019-06-15 05:17:32,428 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051732409501
2019-06-15 05:17:32,461 [salt.minion      :1432][INFO    ][7391] Starting a new job with PID 7391
2019-06-15 05:17:32,492 [salt.minion      :1711][INFO    ][7391] Returning information for job: 20190615051732409501
2019-06-15 05:17:51,027 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.23139596s left)
2019-06-15 05:18:02,553 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051802542378
2019-06-15 05:18:02,574 [salt.minion      :1432][INFO    ][7461] Starting a new job with PID 7461
2019-06-15 05:18:02,607 [salt.minion      :1711][INFO    ][7461] Returning information for job: 20190615051802542378
2019-06-15 05:18:24,131 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.12594795s left)
2019-06-15 05:18:32,680 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051832663693
2019-06-15 05:18:32,700 [salt.minion      :1432][INFO    ][7745] Starting a new job with PID 7745
2019-06-15 05:18:32,731 [salt.minion      :1711][INFO    ][7745] Returning information for job: 20190615051832663693
2019-06-15 05:18:57,221 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.03560805s left)
2019-06-15 05:19:02,816 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051902800375
2019-06-15 05:19:02,845 [salt.minion      :1432][INFO    ][7804] Starting a new job with PID 7804
2019-06-15 05:19:02,881 [salt.minion      :1711][INFO    ][7804] Returning information for job: 20190615051902800375
2019-06-15 05:19:30,244 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.01289105s left)
2019-06-15 05:19:33,024 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615051933006825
2019-06-15 05:19:33,055 [salt.minion      :1432][INFO    ][8207] Starting a new job with PID 8207
2019-06-15 05:19:33,093 [salt.minion      :1711][INFO    ][8207] Returning information for job: 20190615051933006825
2019-06-15 05:20:03,200 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052003183261
2019-06-15 05:20:03,224 [salt.minion      :1432][INFO    ][8245] Starting a new job with PID 8245
2019-06-15 05:20:03,257 [salt.minion      :1711][INFO    ][8245] Returning information for job: 20190615052003183261
2019-06-15 05:20:03,522 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.73448396s left)
2019-06-15 05:20:33,396 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052033379842
2019-06-15 05:20:33,421 [salt.minion      :1432][INFO    ][8612] Starting a new job with PID 8612
2019-06-15 05:20:33,464 [salt.minion      :1711][INFO    ][8612] Returning information for job: 20190615052033379842
2019-06-15 05:20:36,594 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.66248393s left)
2019-06-15 05:21:03,576 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052103559068
2019-06-15 05:21:03,607 [salt.minion      :1432][INFO    ][8658] Starting a new job with PID 8658
2019-06-15 05:21:03,653 [salt.minion      :1711][INFO    ][8658] Returning information for job: 20190615052103559068
2019-06-15 05:21:09,520 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.73643208s left)
2019-06-15 05:21:33,793 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052133779491
2019-06-15 05:21:33,823 [salt.minion      :1432][INFO    ][8894] Starting a new job with PID 8894
2019-06-15 05:21:33,855 [salt.minion      :1711][INFO    ][8894] Returning information for job: 20190615052133779491
2019-06-15 05:21:42,582 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.67461896s left)
2019-06-15 05:22:03,974 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052203952471
2019-06-15 05:22:04,003 [salt.minion      :1432][INFO    ][8941] Starting a new job with PID 8941
2019-06-15 05:22:04,045 [salt.minion      :1711][INFO    ][8941] Returning information for job: 20190615052203952471
2019-06-15 05:22:15,749 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1751.50787592s left)
2019-06-15 05:22:34,003 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052233993474
2019-06-15 05:22:34,027 [salt.minion      :1432][INFO    ][9247] Starting a new job with PID 9247
2019-06-15 05:22:34,056 [salt.minion      :1711][INFO    ][9247] Returning information for job: 20190615052233993474
2019-06-15 05:22:48,760 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1718.49708605s left)
2019-06-15 05:23:04,194 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052304178919
2019-06-15 05:23:04,225 [salt.minion      :1432][INFO    ][9300] Starting a new job with PID 9300
2019-06-15 05:23:04,255 [salt.minion      :1711][INFO    ][9300] Returning information for job: 20190615052304178919
2019-06-15 05:23:21,795 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1685.46119499s left)
2019-06-15 05:23:34,332 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052334318389
2019-06-15 05:23:34,356 [salt.minion      :1432][INFO    ][9390] Starting a new job with PID 9390
2019-06-15 05:23:34,387 [salt.minion      :1711][INFO    ][9390] Returning information for job: 20190615052334318389
2019-06-15 05:23:55,020 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.23638201s left)
2019-06-15 05:24:04,465 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052404446610
2019-06-15 05:24:04,495 [salt.minion      :1432][INFO    ][9444] Starting a new job with PID 9444
2019-06-15 05:24:04,534 [salt.minion      :1711][INFO    ][9444] Returning information for job: 20190615052404446610
2019-06-15 05:24:27,893 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1619.36401415s left)
2019-06-15 05:24:34,696 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052434679261
2019-06-15 05:24:34,728 [salt.minion      :1432][INFO    ][9699] Starting a new job with PID 9699
2019-06-15 05:24:34,762 [salt.minion      :1711][INFO    ][9699] Returning information for job: 20190615052434679261
2019-06-15 05:25:00,907 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1586.35137606s left)
2019-06-15 05:25:04,899 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052504883606
2019-06-15 05:25:04,929 [salt.minion      :1432][INFO    ][9864] Starting a new job with PID 9864
2019-06-15 05:25:04,960 [salt.minion      :1711][INFO    ][9864] Returning information for job: 20190615052504883606
2019-06-15 05:25:33,749 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1553.50794101s left)
2019-06-15 05:25:35,113 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052535096316
2019-06-15 05:25:35,141 [salt.minion      :1432][INFO    ][9921] Starting a new job with PID 9921
2019-06-15 05:25:35,170 [salt.minion      :1711][INFO    ][9921] Returning information for job: 20190615052535096316
2019-06-15 05:26:05,324 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052605311324
2019-06-15 05:26:05,347 [salt.minion      :1432][INFO    ][9948] Starting a new job with PID 9948
2019-06-15 05:26:05,375 [salt.minion      :1711][INFO    ][9948] Returning information for job: 20190615052605311324
2019-06-15 05:26:06,640 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1520.61650395s left)
2019-06-15 05:26:35,541 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052635528491
2019-06-15 05:26:35,564 [salt.minion      :1432][INFO    ][10060] Starting a new job with PID 10060
2019-06-15 05:26:35,594 [salt.minion      :1711][INFO    ][10060] Returning information for job: 20190615052635528491
2019-06-15 05:26:39,688 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1487.56854105s left)
2019-06-15 05:27:05,664 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052705646719
2019-06-15 05:27:05,694 [salt.minion      :1432][INFO    ][10124] Starting a new job with PID 10124
2019-06-15 05:27:05,722 [salt.minion      :1711][INFO    ][10124] Returning information for job: 20190615052705646719
2019-06-15 05:27:14,181 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1453.07575297s left)
2019-06-15 05:27:35,749 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052735732033
2019-06-15 05:27:35,780 [salt.minion      :1432][INFO    ][10383] Starting a new job with PID 10383
2019-06-15 05:27:35,812 [salt.minion      :1711][INFO    ][10383] Returning information for job: 20190615052735732033
2019-06-15 05:27:46,978 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1420.278754s left)
2019-06-15 05:28:05,780 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052805768383
2019-06-15 05:28:05,801 [salt.minion      :1432][INFO    ][10415] Starting a new job with PID 10415
2019-06-15 05:28:05,829 [salt.minion      :1711][INFO    ][10415] Returning information for job: 20190615052805768383
2019-06-15 05:28:19,829 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1387.42807102s left)
2019-06-15 05:28:35,837 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052835825478
2019-06-15 05:28:35,867 [salt.minion      :1432][INFO    ][10462] Starting a new job with PID 10462
2019-06-15 05:28:35,894 [salt.minion      :1711][INFO    ][10462] Returning information for job: 20190615052835825478
2019-06-15 05:28:52,688 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1354.56833601s left)
2019-06-15 05:29:05,909 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052905897315
2019-06-15 05:29:05,940 [salt.minion      :1432][INFO    ][10500] Starting a new job with PID 10500
2019-06-15 05:29:05,965 [salt.minion      :1711][INFO    ][10500] Returning information for job: 20190615052905897315
2019-06-15 05:29:25,611 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1321.64605308s left)
2019-06-15 05:29:35,972 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615052935954272
2019-06-15 05:29:36,006 [salt.minion      :1432][INFO    ][10548] Starting a new job with PID 10548
2019-06-15 05:29:36,039 [salt.minion      :1711][INFO    ][10548] Returning information for job: 20190615052935954272
2019-06-15 05:29:58,520 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1288.73647404s left)
2019-06-15 05:30:06,081 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053006064011
2019-06-15 05:30:06,108 [salt.minion      :1432][INFO    ][10577] Starting a new job with PID 10577
2019-06-15 05:30:06,143 [salt.minion      :1711][INFO    ][10577] Returning information for job: 20190615053006064011
2019-06-15 05:30:31,268 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1255.98819613s left)
2019-06-15 05:30:36,180 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053036166572
2019-06-15 05:30:36,210 [salt.minion      :1432][INFO    ][10632] Starting a new job with PID 10632
2019-06-15 05:30:36,243 [salt.minion      :1711][INFO    ][10632] Returning information for job: 20190615053036166572
2019-06-15 05:31:04,148 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1223.1087141s left)
2019-06-15 05:31:06,301 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053106285201
2019-06-15 05:31:06,331 [salt.minion      :1432][INFO    ][10663] Starting a new job with PID 10663
2019-06-15 05:31:06,364 [salt.minion      :1711][INFO    ][10663] Returning information for job: 20190615053106285201
2019-06-15 05:31:36,425 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053136412009
2019-06-15 05:31:36,450 [salt.minion      :1432][INFO    ][10711] Starting a new job with PID 10711
2019-06-15 05:31:36,489 [salt.minion      :1711][INFO    ][10711] Returning information for job: 20190615053136412009
2019-06-15 05:31:36,997 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1190.25953507s left)
2019-06-15 05:32:06,597 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053206581467
2019-06-15 05:32:06,624 [salt.minion      :1432][INFO    ][10740] Starting a new job with PID 10740
2019-06-15 05:32:06,657 [salt.minion      :1711][INFO    ][10740] Returning information for job: 20190615053206581467
2019-06-15 05:32:09,892 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1157.36440206s left)
2019-06-15 05:32:36,744 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053236727593
2019-06-15 05:32:36,773 [salt.minion      :1432][INFO    ][10791] Starting a new job with PID 10791
2019-06-15 05:32:36,802 [salt.minion      :1711][INFO    ][10791] Returning information for job: 20190615053236727593
2019-06-15 05:32:42,610 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1124.64677715s left)
2019-06-15 05:33:06,916 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053306901493
2019-06-15 05:33:06,944 [salt.minion      :1432][INFO    ][10822] Starting a new job with PID 10822
2019-06-15 05:33:06,973 [salt.minion      :1711][INFO    ][10822] Returning information for job: 20190615053306901493
2019-06-15 05:33:15,463 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1091.79403496s left)
2019-06-15 05:33:37,076 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053337063351
2019-06-15 05:33:37,107 [salt.minion      :1432][INFO    ][10870] Starting a new job with PID 10870
2019-06-15 05:33:37,140 [salt.minion      :1711][INFO    ][10870] Returning information for job: 20190615053337063351
2019-06-15 05:33:48,370 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1058.88620996s left)
2019-06-15 05:34:07,285 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053407273591
2019-06-15 05:34:07,318 [salt.minion      :1432][INFO    ][10899] Starting a new job with PID 10899
2019-06-15 05:34:07,351 [salt.minion      :1711][INFO    ][10899] Returning information for job: 20190615053407273591
2019-06-15 05:34:21,129 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1026.12774611s left)
2019-06-15 05:34:37,304 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053437296423
2019-06-15 05:34:37,328 [salt.minion      :1432][INFO    ][10949] Starting a new job with PID 10949
2019-06-15 05:34:37,360 [salt.minion      :1711][INFO    ][10949] Returning information for job: 20190615053437296423
2019-06-15 05:34:53,914 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (993.343070984s left)
2019-06-15 05:35:07,521 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053507510641
2019-06-15 05:35:07,549 [salt.minion      :1432][INFO    ][10990] Starting a new job with PID 10990
2019-06-15 05:35:07,584 [salt.minion      :1711][INFO    ][10990] Returning information for job: 20190615053507510641
2019-06-15 05:35:26,902 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (960.354309082s left)
2019-06-15 05:35:37,555 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053537539985
2019-06-15 05:35:37,582 [salt.minion      :1432][INFO    ][11051] Starting a new job with PID 11051
2019-06-15 05:35:37,610 [salt.minion      :1711][INFO    ][11051] Returning information for job: 20190615053537539985
2019-06-15 05:35:59,764 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (927.49279213s left)
2019-06-15 05:36:07,594 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053607581346
2019-06-15 05:36:07,626 [salt.minion      :1432][INFO    ][11082] Starting a new job with PID 11082
2019-06-15 05:36:07,660 [salt.minion      :1711][INFO    ][11082] Returning information for job: 20190615053607581346
2019-06-15 05:36:32,484 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (894.772279978s left)
2019-06-15 05:36:37,655 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053637640457
2019-06-15 05:36:37,685 [salt.minion      :1432][INFO    ][11130] Starting a new job with PID 11130
2019-06-15 05:36:37,729 [salt.minion      :1711][INFO    ][11130] Returning information for job: 20190615053637640457
2019-06-15 05:37:05,146 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (862.110507011s left)
2019-06-15 05:37:07,756 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053707740766
2019-06-15 05:37:07,786 [salt.minion      :1432][INFO    ][11164] Starting a new job with PID 11164
2019-06-15 05:37:07,816 [salt.minion      :1711][INFO    ][11164] Returning information for job: 20190615053707740766
2019-06-15 05:37:37,846 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (829.410264015s left)
2019-06-15 05:37:37,883 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053737870242
2019-06-15 05:37:37,909 [salt.minion      :1432][INFO    ][11211] Starting a new job with PID 11211
2019-06-15 05:37:37,938 [salt.minion      :1711][INFO    ][11211] Returning information for job: 20190615053737870242
2019-06-15 05:38:07,997 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053807983975
2019-06-15 05:38:08,016 [salt.minion      :1432][INFO    ][11237] Starting a new job with PID 11237
2019-06-15 05:38:08,053 [salt.minion      :1711][INFO    ][11237] Returning information for job: 20190615053807983975
2019-06-15 05:38:11,108 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (796.148363113s left)
2019-06-15 05:38:38,168 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053838159175
2019-06-15 05:38:38,196 [salt.minion      :1432][INFO    ][11283] Starting a new job with PID 11283
2019-06-15 05:38:38,232 [salt.minion      :1711][INFO    ][11283] Returning information for job: 20190615053838159175
2019-06-15 05:38:43,872 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (763.384689093s left)
2019-06-15 05:39:08,297 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053908282243
2019-06-15 05:39:08,328 [salt.minion      :1432][INFO    ][11313] Starting a new job with PID 11313
2019-06-15 05:39:08,361 [salt.minion      :1711][INFO    ][11313] Returning information for job: 20190615053908282243
2019-06-15 05:39:16,599 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (730.659384012s left)
2019-06-15 05:39:38,505 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615053938496048
2019-06-15 05:39:38,531 [salt.minion      :1432][INFO    ][11358] Starting a new job with PID 11358
2019-06-15 05:39:38,567 [salt.minion      :1711][INFO    ][11358] Returning information for job: 20190615053938496048
2019-06-15 05:39:49,402 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (697.855363131s left)
2019-06-15 05:40:08,695 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054008685043
2019-06-15 05:40:08,722 [salt.minion      :1432][INFO    ][11389] Starting a new job with PID 11389
2019-06-15 05:40:08,754 [salt.minion      :1711][INFO    ][11389] Returning information for job: 20190615054008685043
2019-06-15 05:40:22,271 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (664.98566103s left)
2019-06-15 05:40:38,896 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054038880676
2019-06-15 05:40:38,921 [salt.minion      :1432][INFO    ][11439] Starting a new job with PID 11439
2019-06-15 05:40:38,955 [salt.minion      :1711][INFO    ][11439] Returning information for job: 20190615054038880676
2019-06-15 05:40:55,019 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (632.238153934s left)
2019-06-15 05:41:08,940 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054108924532
2019-06-15 05:41:08,969 [salt.minion      :1432][INFO    ][11468] Starting a new job with PID 11468
2019-06-15 05:41:09,007 [salt.minion      :1711][INFO    ][11468] Returning information for job: 20190615054108924532
2019-06-15 05:41:27,863 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (599.394140005s left)
2019-06-15 05:41:39,164 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054139147315
2019-06-15 05:41:39,191 [salt.minion      :1432][INFO    ][11520] Starting a new job with PID 11520
2019-06-15 05:41:39,222 [salt.minion      :1711][INFO    ][11520] Returning information for job: 20190615054139147315
2019-06-15 05:42:00,592 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (566.664220095s left)
2019-06-15 05:42:09,257 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054209241122
2019-06-15 05:42:09,287 [salt.minion      :1432][INFO    ][11553] Starting a new job with PID 11553
2019-06-15 05:42:09,320 [salt.minion      :1711][INFO    ][11553] Returning information for job: 20190615054209241122
2019-06-15 05:42:33,343 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (533.913654089s left)
2019-06-15 05:42:39,341 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054239324362
2019-06-15 05:42:39,368 [salt.minion      :1432][INFO    ][11602] Starting a new job with PID 11602
2019-06-15 05:42:39,406 [salt.minion      :1711][INFO    ][11602] Returning information for job: 20190615054239324362
2019-06-15 05:43:06,221 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (501.035529137s left)
2019-06-15 05:43:09,476 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054309456451
2019-06-15 05:43:09,505 [salt.minion      :1432][INFO    ][11631] Starting a new job with PID 11631
2019-06-15 05:43:09,538 [salt.minion      :1711][INFO    ][11631] Returning information for job: 20190615054309456451
2019-06-15 05:43:39,249 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (468.007317066s left)
2019-06-15 05:43:39,548 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054339532193
2019-06-15 05:43:39,573 [salt.minion      :1432][INFO    ][11677] Starting a new job with PID 11677
2019-06-15 05:43:39,604 [salt.minion      :1711][INFO    ][11677] Returning information for job: 20190615054339532193
2019-06-15 05:44:09,713 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054409703070
2019-06-15 05:44:09,733 [salt.minion      :1432][INFO    ][11703] Starting a new job with PID 11703
2019-06-15 05:44:09,767 [salt.minion      :1711][INFO    ][11703] Returning information for job: 20190615054409703070
2019-06-15 05:44:12,381 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (434.875751019s left)
2019-06-15 05:44:39,828 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054439813360
2019-06-15 05:44:39,858 [salt.minion      :1432][INFO    ][11750] Starting a new job with PID 11750
2019-06-15 05:44:39,888 [salt.minion      :1711][INFO    ][11750] Returning information for job: 20190615054439813360
2019-06-15 05:44:45,132 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (402.125097036s left)
2019-06-15 05:45:10,051 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054510035610
2019-06-15 05:45:10,079 [salt.minion      :1432][INFO    ][11920] Starting a new job with PID 11920
2019-06-15 05:45:10,113 [salt.minion      :1711][INFO    ][11920] Returning information for job: 20190615054510035610
2019-06-15 05:45:17,937 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (369.319916964s left)
2019-06-15 05:45:40,236 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054540227715
2019-06-15 05:45:40,260 [salt.minion      :1432][INFO    ][11977] Starting a new job with PID 11977
2019-06-15 05:45:40,296 [salt.minion      :1711][INFO    ][11977] Returning information for job: 20190615054540227715
2019-06-15 05:45:50,656 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (336.60026598s left)
2019-06-15 05:46:10,293 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054610272297
2019-06-15 05:46:10,320 [salt.minion      :1432][INFO    ][12019] Starting a new job with PID 12019
2019-06-15 05:46:10,362 [salt.minion      :1711][INFO    ][12019] Returning information for job: 20190615054610272297
2019-06-15 05:46:23,487 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (303.770031929s left)
2019-06-15 05:46:40,351 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054640337688
2019-06-15 05:46:40,377 [salt.minion      :1432][INFO    ][12054] Starting a new job with PID 12054
2019-06-15 05:46:40,409 [salt.minion      :1711][INFO    ][12054] Returning information for job: 20190615054640337688
2019-06-15 05:46:56,432 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (270.824538946s left)
2019-06-15 05:47:10,400 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054710387967
2019-06-15 05:47:10,426 [salt.minion      :1432][INFO    ][12104] Starting a new job with PID 12104
2019-06-15 05:47:10,459 [salt.minion      :1711][INFO    ][12104] Returning information for job: 20190615054710387967
2019-06-15 05:47:29,336 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (237.920855999s left)
2019-06-15 05:47:40,520 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054740504533
2019-06-15 05:47:40,548 [salt.minion      :1432][INFO    ][12136] Starting a new job with PID 12136
2019-06-15 05:47:40,582 [salt.minion      :1711][INFO    ][12136] Returning information for job: 20190615054740504533
2019-06-15 05:48:02,178 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (205.079092979s left)
2019-06-15 05:48:10,627 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054810615407
2019-06-15 05:48:10,653 [salt.minion      :1432][INFO    ][12177] Starting a new job with PID 12177
2019-06-15 05:48:10,687 [salt.minion      :1711][INFO    ][12177] Returning information for job: 20190615054810615407
2019-06-15 05:48:35,043 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (172.214061975s left)
2019-06-15 05:48:40,734 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054840721069
2019-06-15 05:48:40,759 [salt.minion      :1432][INFO    ][12209] Starting a new job with PID 12209
2019-06-15 05:48:40,791 [salt.minion      :1711][INFO    ][12209] Returning information for job: 20190615054840721069
2019-06-15 05:49:07,791 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (139.465469122s left)
2019-06-15 05:49:10,829 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054910816827
2019-06-15 05:49:10,855 [salt.minion      :1432][INFO    ][12262] Starting a new job with PID 12262
2019-06-15 05:49:10,890 [salt.minion      :1711][INFO    ][12262] Returning information for job: 20190615054910816827
2019-06-15 05:49:40,568 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (106.688830137s left)
2019-06-15 05:49:40,988 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615054940976347
2019-06-15 05:49:41,015 [salt.minion      :1432][INFO    ][12290] Starting a new job with PID 12290
2019-06-15 05:49:41,047 [salt.minion      :1711][INFO    ][12290] Returning information for job: 20190615054940976347
2019-06-15 05:50:11,140 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055011130826
2019-06-15 05:50:11,159 [salt.minion      :1432][INFO    ][12342] Starting a new job with PID 12342
2019-06-15 05:50:11,192 [salt.minion      :1711][INFO    ][12342] Returning information for job: 20190615055011130826
2019-06-15 05:50:13,415 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (73.8415670395s left)
2019-06-15 05:50:41,324 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055041312552
2019-06-15 05:50:41,352 [salt.minion      :1432][INFO    ][12367] Starting a new job with PID 12367
2019-06-15 05:50:41,387 [salt.minion      :1711][INFO    ][12367] Returning information for job: 20190615055041312552
2019-06-15 05:50:46,165 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (41.0922160149s left)
2019-06-15 05:51:11,516 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055111506394
2019-06-15 05:51:11,543 [salt.minion      :1432][INFO    ][12420] Starting a new job with PID 12420
2019-06-15 05:51:11,576 [salt.minion      :1711][INFO    ][12420] Returning information for job: 20190615055111506394
2019-06-15 05:51:18,975 [salt.loaded.ext.module.maas:1023][INFO    ][6784] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (8.28131914139s left)
2019-06-15 05:51:41,548 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055141528868
2019-06-15 05:51:41,576 [salt.minion      :1432][INFO    ][12447] Starting a new job with PID 12447
2019-06-15 05:51:41,607 [salt.minion      :1711][INFO    ][12447] Returning information for job: 20190615055141528868
2019-06-15 05:51:51,798 [salt.state       :302 ][ERROR   ][6784] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-06-15 05:51:51,799 [salt.state       :1951][INFO    ][6784] Completed state [maas.wait_for_machine_status] at time 05:51:51.798984 duration_in_ms=2274555.285
2019-06-15 05:51:51,807 [salt.minion      :1711][INFO    ][6784] Returning information for job: 20190615051346569501
2019-06-15 05:52:02,849 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command pillar.get with jid 20190615055202837114
2019-06-15 05:52:02,876 [salt.minion      :1432][INFO    ][12474] Starting a new job with PID 12474
2019-06-15 05:52:02,886 [salt.minion      :1711][INFO    ][12474] Returning information for job: 20190615055202837114
2019-06-15 05:52:03,604 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command service.status with jid 20190615055203593208
2019-06-15 05:52:03,630 [salt.minion      :1432][INFO    ][12479] Starting a new job with PID 12479
2019-06-15 05:52:04,472 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12479] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-15 05:52:04,516 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12479] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-15 05:52:04,536 [salt.minion      :1711][INFO    ][12479] Returning information for job: 20190615055203593208
2019-06-15 05:52:05,252 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command state.apply with jid 20190615055205242462
2019-06-15 05:52:05,277 [salt.minion      :1432][INFO    ][12490] Starting a new job with PID 12490
2019-06-15 05:52:13,902 [salt.state       :915 ][INFO    ][12490] Loading fresh modules for state activity
2019-06-15 05:52:14,482 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12490] Executing command 'salt-minion --version' in directory '/root'
2019-06-15 05:52:14,829 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12490] Executing command 'salt-minion --version' in directory '/root'
2019-06-15 05:52:15,965 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12490] Executing command 'salt-minion --version' in directory '/root'
2019-06-15 05:52:16,308 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12490] Executing command 'salt-minion --version' in directory '/root'
2019-06-15 05:52:18,695 [salt.state       :1780][INFO    ][12490] Running state [salt-minion] at time 05:52:18.695840
2019-06-15 05:52:18,696 [salt.state       :1813][INFO    ][12490] Executing state pkg.installed for [salt-minion]
2019-06-15 05:52:18,697 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12490] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-15 05:52:18,815 [salt.state       :300 ][INFO    ][12490] All specified packages are already installed
2019-06-15 05:52:18,815 [salt.state       :1951][INFO    ][12490] Completed state [salt-minion] at time 05:52:18.815793 duration_in_ms=119.954
2019-06-15 05:52:18,816 [salt.state       :1780][INFO    ][12490] Running state [salt_minion_dependency_packages] at time 05:52:18.816174
2019-06-15 05:52:18,816 [salt.state       :1813][INFO    ][12490] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-15 05:52:18,826 [salt.state       :300 ][INFO    ][12490] All specified packages are already installed
2019-06-15 05:52:18,826 [salt.state       :1951][INFO    ][12490] Completed state [salt_minion_dependency_packages] at time 05:52:18.826844 duration_in_ms=10.669
2019-06-15 05:52:18,832 [salt.state       :1780][INFO    ][12490] Running state [/etc/salt/minion.d/minion.conf] at time 05:52:18.832434
2019-06-15 05:52:18,832 [salt.state       :1813][INFO    ][12490] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-15 05:52:19,120 [salt.state       :300 ][INFO    ][12490] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-15 05:52:19,120 [salt.state       :1951][INFO    ][12490] Completed state [/etc/salt/minion.d/minion.conf] at time 05:52:19.120722 duration_in_ms=288.287
2019-06-15 05:52:19,121 [salt.state       :1780][INFO    ][12490] Running state [python-netaddr] at time 05:52:19.121038
2019-06-15 05:52:19,121 [salt.state       :1813][INFO    ][12490] Executing state pkg.installed for [python-netaddr]
2019-06-15 05:52:19,131 [salt.state       :300 ][INFO    ][12490] All specified packages are already installed
2019-06-15 05:52:19,131 [salt.state       :1951][INFO    ][12490] Completed state [python-netaddr] at time 05:52:19.131883 duration_in_ms=10.845
2019-06-15 05:52:19,136 [salt.state       :1780][INFO    ][12490] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:52:19.136163
2019-06-15 05:52:19,136 [salt.state       :1813][INFO    ][12490] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-15 05:52:19,152 [salt.state       :300 ][INFO    ][12490] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-15 05:52:19,153 [salt.state       :1951][INFO    ][12490] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:52:19.153248 duration_in_ms=17.084
2019-06-15 05:52:19,155 [salt.state       :1780][INFO    ][12490] Running state [salt-minion] at time 05:52:19.155819
2019-06-15 05:52:19,156 [salt.state       :1813][INFO    ][12490] Executing state service.running for [salt-minion]
2019-06-15 05:52:19,157 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12490] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-15 05:52:19,201 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12490] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-15 05:52:19,221 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12490] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-15 05:52:19,243 [salt.state       :300 ][INFO    ][12490] The service salt-minion is already running
2019-06-15 05:52:19,243 [salt.state       :1951][INFO    ][12490] Completed state [salt-minion] at time 05:52:19.243755 duration_in_ms=87.936
2019-06-15 05:52:19,246 [salt.state       :1780][INFO    ][12490] Running state [/etc/salt/grains.d] at time 05:52:19.246628
2019-06-15 05:52:19,247 [salt.state       :1813][INFO    ][12490] Executing state file.directory for [/etc/salt/grains.d]
2019-06-15 05:52:19,249 [salt.state       :300 ][INFO    ][12490] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-15 05:52:19,251 [salt.state       :1951][INFO    ][12490] Completed state [/etc/salt/grains.d] at time 05:52:19.249696 duration_in_ms=3.067
2019-06-15 05:52:19,252 [salt.state       :1780][INFO    ][12490] Running state [/etc/salt/grains] at time 05:52:19.252632
2019-06-15 05:52:19,253 [salt.state       :1813][INFO    ][12490] Executing state file.managed for [/etc/salt/grains]
2019-06-15 05:52:19,253 [salt.state       :300 ][INFO    ][12490] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-15 05:52:19,254 [salt.state       :1951][INFO    ][12490] Completed state [/etc/salt/grains] at time 05:52:19.253939 duration_in_ms=1.307
2019-06-15 05:52:19,254 [salt.state       :1780][INFO    ][12490] Running state [/etc/salt/grains.d/placeholder] at time 05:52:19.254544
2019-06-15 05:52:19,254 [salt.state       :1813][INFO    ][12490] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-15 05:52:19,255 [salt.state       :300 ][INFO    ][12490] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-15 05:52:19,255 [salt.state       :1951][INFO    ][12490] Completed state [/etc/salt/grains.d/placeholder] at time 05:52:19.255824 duration_in_ms=1.28
2019-06-15 05:52:19,256 [salt.state       :1780][INFO    ][12490] Running state [/etc/salt/grains.d/sphinx] at time 05:52:19.256431
2019-06-15 05:52:19,256 [salt.state       :1813][INFO    ][12490] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-15 05:52:19,258 [salt.state       :300 ][INFO    ][12490] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-15 05:52:19,258 [salt.state       :1951][INFO    ][12490] Completed state [/etc/salt/grains.d/sphinx] at time 05:52:19.258575 duration_in_ms=2.144
2019-06-15 05:52:19,261 [salt.state       :1780][INFO    ][12490] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:52:19.261045
2019-06-15 05:52:19,261 [salt.state       :1813][INFO    ][12490] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-15 05:52:19,263 [salt.state       :300 ][INFO    ][12490] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-15 05:52:19,264 [salt.state       :1951][INFO    ][12490] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:52:19.264333 duration_in_ms=3.288
2019-06-15 05:52:19,265 [salt.state       :1780][INFO    ][12490] Running state [/etc/salt/grains.d/dns_records] at time 05:52:19.265330
2019-06-15 05:52:19,266 [salt.state       :1813][INFO    ][12490] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-15 05:52:19,268 [salt.state       :300 ][INFO    ][12490] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-15 05:52:19,269 [salt.state       :1951][INFO    ][12490] Completed state [/etc/salt/grains.d/dns_records] at time 05:52:19.268956 duration_in_ms=3.625
2019-06-15 05:52:19,270 [salt.state       :1780][INFO    ][12490] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:52:19.270852
2019-06-15 05:52:19,271 [salt.state       :1813][INFO    ][12490] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-15 05:52:19,271 [salt.state       :300 ][INFO    ][12490] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-15 05:52:19,272 [salt.state       :1951][INFO    ][12490] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:52:19.272332 duration_in_ms=1.48
2019-06-15 05:52:19,273 [salt.state       :1780][INFO    ][12490] Running state [/etc/salt/grains.d/salt] at time 05:52:19.273239
2019-06-15 05:52:19,276 [salt.state       :1813][INFO    ][12490] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-15 05:52:19,277 [salt.state       :300 ][INFO    ][12490] File /etc/salt/grains.d/salt is in the correct state
2019-06-15 05:52:19,277 [salt.state       :1951][INFO    ][12490] Completed state [/etc/salt/grains.d/salt] at time 05:52:19.277293 duration_in_ms=4.054
2019-06-15 05:52:19,278 [salt.state       :1780][INFO    ][12490] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:52:19.278534
2019-06-15 05:52:19,278 [salt.state       :1813][INFO    ][12490] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-15 05:52:19,279 [salt.state       :300 ][INFO    ][12490] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-15 05:52:19,279 [salt.state       :1951][INFO    ][12490] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:52:19.279379 duration_in_ms=0.845
2019-06-15 05:52:19,281 [salt.state       :1780][INFO    ][12490] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:52:19.281337
2019-06-15 05:52:19,281 [salt.state       :1813][INFO    ][12490] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-15 05:52:19,282 [salt.state       :300 ][INFO    ][12490] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-15 05:52:19,282 [salt.state       :1951][INFO    ][12490] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:52:19.282211 duration_in_ms=0.873
2019-06-15 05:52:19,282 [salt.state       :1780][INFO    ][12490] Running state [mine.update] at time 05:52:19.282923
2019-06-15 05:52:19,283 [salt.state       :1813][INFO    ][12490] Executing state module.wait for [mine.update]
2019-06-15 05:52:19,283 [salt.state       :300 ][INFO    ][12490] No changes made for mine.update
2019-06-15 05:52:19,283 [salt.state       :1951][INFO    ][12490] Completed state [mine.update] at time 05:52:19.283720 duration_in_ms=0.796
2019-06-15 05:52:19,284 [salt.state       :1780][INFO    ][12490] Running state [ca-certificates] at time 05:52:19.283989
2019-06-15 05:52:19,284 [salt.state       :1813][INFO    ][12490] Executing state pkg.installed for [ca-certificates]
2019-06-15 05:52:19,294 [salt.state       :300 ][INFO    ][12490] All specified packages are already installed
2019-06-15 05:52:19,294 [salt.state       :1951][INFO    ][12490] Completed state [ca-certificates] at time 05:52:19.294622 duration_in_ms=10.633
2019-06-15 05:52:19,295 [salt.state       :1780][INFO    ][12490] Running state [update-ca-certificates] at time 05:52:19.295351
2019-06-15 05:52:19,295 [salt.state       :1813][INFO    ][12490] Executing state cmd.wait for [update-ca-certificates]
2019-06-15 05:52:19,295 [salt.state       :300 ][INFO    ][12490] No changes made for update-ca-certificates
2019-06-15 05:52:19,296 [salt.state       :1951][INFO    ][12490] Completed state [update-ca-certificates] at time 05:52:19.296154 duration_in_ms=0.803
2019-06-15 05:52:19,296 [salt.state       :1780][INFO    ][12490] Running state [iptables] at time 05:52:19.296425
2019-06-15 05:52:19,296 [salt.state       :1813][INFO    ][12490] Executing state pkg.installed for [iptables]
2019-06-15 05:52:19,306 [salt.state       :300 ][INFO    ][12490] All specified packages are already installed
2019-06-15 05:52:19,306 [salt.state       :1951][INFO    ][12490] Completed state [iptables] at time 05:52:19.306665 duration_in_ms=10.239
2019-06-15 05:52:19,306 [salt.state       :1780][INFO    ][12490] Running state [iptables-persistent] at time 05:52:19.306938
2019-06-15 05:52:19,307 [salt.state       :1813][INFO    ][12490] Executing state pkg.installed for [iptables-persistent]
2019-06-15 05:52:19,316 [salt.state       :300 ][INFO    ][12490] All specified packages are already installed
2019-06-15 05:52:19,316 [salt.state       :1951][INFO    ][12490] Completed state [iptables-persistent] at time 05:52:19.316847 duration_in_ms=9.909
2019-06-15 05:52:19,318 [salt.state       :1780][INFO    ][12490] Running state [iptables_modules_v4_load] at time 05:52:19.318244
2019-06-15 05:52:19,318 [salt.state       :1813][INFO    ][12490] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-15 05:52:19,319 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12490] Executing command 'lsmod' in directory '/root'
2019-06-15 05:52:19,340 [salt.state       :300 ][INFO    ][12490] Kernel modules iptable_filter, ip_tables are already present
2019-06-15 05:52:19,340 [salt.state       :1951][INFO    ][12490] Completed state [iptables_modules_v4_load] at time 05:52:19.340781 duration_in_ms=22.536
2019-06-15 05:52:19,342 [salt.state       :1780][INFO    ][12490] Running state [/etc/iptables/rules.v4] at time 05:52:19.342576
2019-06-15 05:52:19,343 [salt.state       :1813][INFO    ][12490] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-15 05:52:19,450 [salt.state       :300 ][INFO    ][12490] File /etc/iptables/rules.v4 is in the correct state
2019-06-15 05:52:19,450 [salt.state       :1951][INFO    ][12490] Completed state [/etc/iptables/rules.v4] at time 05:52:19.450660 duration_in_ms=108.084
2019-06-15 05:52:19,451 [salt.state       :1780][INFO    ][12490] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:52:19.451802
2019-06-15 05:52:19,452 [salt.state       :1813][INFO    ][12490] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-15 05:52:19,452 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12490] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-15 05:52:19,475 [salt.state       :300 ][INFO    ][12490] onlyif execution failed
2019-06-15 05:52:19,476 [salt.state       :1951][INFO    ][12490] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:52:19.476057 duration_in_ms=24.254
2019-06-15 05:52:19,480 [salt.state       :1780][INFO    ][12490] Running state [netfilter-persistent] at time 05:52:19.480547
2019-06-15 05:52:19,481 [salt.state       :1813][INFO    ][12490] Executing state service.running for [netfilter-persistent]
2019-06-15 05:52:19,482 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12490] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-15 05:52:19,504 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12490] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-15 05:52:19,525 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12490] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-15 05:52:19,548 [salt.state       :300 ][INFO    ][12490] The service netfilter-persistent is already running
2019-06-15 05:52:19,549 [salt.state       :1951][INFO    ][12490] Completed state [netfilter-persistent] at time 05:52:19.549414 duration_in_ms=68.866
2019-06-15 05:52:19,554 [salt.state       :1780][INFO    ][12490] Running state [iptables_extra.remove_stale_tables] at time 05:52:19.554245
2019-06-15 05:52:19,554 [salt.state       :1813][INFO    ][12490] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-15 05:52:19,555 [salt.state       :300 ][INFO    ][12490] No changes made for iptables_extra.remove_stale_tables
2019-06-15 05:52:19,555 [salt.state       :1951][INFO    ][12490] Completed state [iptables_extra.remove_stale_tables] at time 05:52:19.555784 duration_in_ms=1.539
2019-06-15 05:52:19,556 [salt.state       :1780][INFO    ][12490] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:52:19.556255
2019-06-15 05:52:19,556 [salt.state       :1813][INFO    ][12490] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-15 05:52:19,558 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12490] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-15 05:52:19,581 [salt.state       :300 ][INFO    ][12490] onlyif execution failed
2019-06-15 05:52:19,582 [salt.state       :1951][INFO    ][12490] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:52:19.581950 duration_in_ms=25.695
2019-06-15 05:52:19,583 [salt.state       :1780][INFO    ][12490] Running state [/etc/iptables/rules.v6] at time 05:52:19.583674
2019-06-15 05:52:19,584 [salt.state       :1813][INFO    ][12490] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-15 05:52:19,585 [salt.state       :300 ][INFO    ][12490] File /etc/iptables/rules.v6 is not present
2019-06-15 05:52:19,585 [salt.state       :1951][INFO    ][12490] Completed state [/etc/iptables/rules.v6] at time 05:52:19.585517 duration_in_ms=1.843
2019-06-15 05:52:19,589 [salt.state       :1780][INFO    ][12490] Running state [iptables_extra.flush_all] at time 05:52:19.589634
2019-06-15 05:52:19,590 [salt.state       :1813][INFO    ][12490] Executing state module.wait for [iptables_extra.flush_all]
2019-06-15 05:52:19,590 [salt.state       :300 ][INFO    ][12490] No changes made for iptables_extra.flush_all
2019-06-15 05:52:19,591 [salt.state       :1951][INFO    ][12490] Completed state [iptables_extra.flush_all] at time 05:52:19.591057 duration_in_ms=1.423
2019-06-15 05:52:19,595 [salt.minion      :1711][INFO    ][12490] Returning information for job: 20190615055205242462
2019-06-15 05:52:20,323 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command state.apply with jid 20190615055220307765
2019-06-15 05:52:20,350 [salt.minion      :1432][INFO    ][12596] Starting a new job with PID 12596
2019-06-15 05:52:21,508 [salt.state       :915 ][INFO    ][12596] Loading fresh modules for state activity
2019-06-15 05:52:22,857 [salt.state       :1780][INFO    ][12596] Running state [maas-rack-controller] at time 05:52:22.857612
2019-06-15 05:52:22,858 [salt.state       :1813][INFO    ][12596] Executing state pkg.installed for [maas-rack-controller]
2019-06-15 05:52:22,858 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12596] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-15 05:52:22,986 [salt.state       :300 ][INFO    ][12596] All specified packages are already installed
2019-06-15 05:52:22,986 [salt.state       :1951][INFO    ][12596] Completed state [maas-rack-controller] at time 05:52:22.986443 duration_in_ms=128.831
2019-06-15 05:52:22,986 [salt.state       :1780][INFO    ][12596] Running state [ipmitool] at time 05:52:22.986828
2019-06-15 05:52:22,987 [salt.state       :1813][INFO    ][12596] Executing state pkg.installed for [ipmitool]
2019-06-15 05:52:22,999 [salt.state       :300 ][INFO    ][12596] All specified packages are already installed
2019-06-15 05:52:23,000 [salt.state       :1951][INFO    ][12596] Completed state [ipmitool] at time 05:52:22.999967 duration_in_ms=13.139
2019-06-15 05:52:23,002 [salt.state       :1780][INFO    ][12596] Running state [/etc/maas/rackd.conf] at time 05:52:23.002923
2019-06-15 05:52:23,003 [salt.state       :1813][INFO    ][12596] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-15 05:52:23,004 [salt.state       :300 ][INFO    ][12596] No changes needed to be made
2019-06-15 05:52:23,004 [salt.state       :1951][INFO    ][12596] Completed state [/etc/maas/rackd.conf] at time 05:52:23.004500 duration_in_ms=1.577
2019-06-15 05:52:23,004 [salt.state       :1780][INFO    ][12596] Running state [/etc/maas/rackd.conf] at time 05:52:23.004798
2019-06-15 05:52:23,005 [salt.state       :1813][INFO    ][12596] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-15 05:52:23,005 [salt.loaded.int.states.file:2298][WARNING ][12596] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-15 05:52:23,010 [salt.state       :300 ][INFO    ][12596] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-15 05:52:23,010 [salt.state       :1951][INFO    ][12596] Completed state [/etc/maas/rackd.conf] at time 05:52:23.010318 duration_in_ms=5.519
2019-06-15 05:52:23,011 [salt.state       :1780][INFO    ][12596] Running state [maas-rackd] at time 05:52:23.011383
2019-06-15 05:52:23,011 [salt.state       :1813][INFO    ][12596] Executing state service.running for [maas-rackd]
2019-06-15 05:52:23,012 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12596] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-15 05:52:23,051 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12596] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-15 05:52:23,082 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12596] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-15 05:52:23,105 [salt.state       :300 ][INFO    ][12596] The service maas-rackd is already running
2019-06-15 05:52:23,108 [salt.state       :1951][INFO    ][12596] Completed state [maas-rackd] at time 05:52:23.107520 duration_in_ms=95.855
2019-06-15 05:52:23,111 [salt.minion      :1711][INFO    ][12596] Returning information for job: 20190615055220307765
2019-06-15 05:52:23,833 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command state.apply with jid 20190615055223816258
2019-06-15 05:52:23,860 [salt.minion      :1432][INFO    ][12623] Starting a new job with PID 12623
2019-06-15 05:52:25,169 [salt.state       :915 ][INFO    ][12623] Loading fresh modules for state activity
2019-06-15 05:52:26,623 [salt.state       :1780][INFO    ][12623] Running state [maas-region-controller] at time 05:52:26.623281
2019-06-15 05:52:26,623 [salt.state       :1813][INFO    ][12623] Executing state pkg.installed for [maas-region-controller]
2019-06-15 05:52:26,624 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12623] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-15 05:52:26,735 [salt.state       :300 ][INFO    ][12623] All specified packages are already installed
2019-06-15 05:52:26,736 [salt.state       :1951][INFO    ][12623] Completed state [maas-region-controller] at time 05:52:26.736326 duration_in_ms=113.045
2019-06-15 05:52:26,736 [salt.state       :1780][INFO    ][12623] Running state [python-oauth] at time 05:52:26.736709
2019-06-15 05:52:26,737 [salt.state       :1813][INFO    ][12623] Executing state pkg.installed for [python-oauth]
2019-06-15 05:52:26,746 [salt.state       :300 ][INFO    ][12623] All specified packages are already installed
2019-06-15 05:52:26,747 [salt.state       :1951][INFO    ][12623] Completed state [python-oauth] at time 05:52:26.746944 duration_in_ms=10.235
2019-06-15 05:52:26,750 [salt.state       :1780][INFO    ][12623] Running state [/etc/maas/regiond.conf] at time 05:52:26.750890
2019-06-15 05:52:26,751 [salt.state       :1813][INFO    ][12623] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-15 05:52:26,756 [salt.state       :300 ][INFO    ][12623] No changes needed to be made
2019-06-15 05:52:26,756 [salt.state       :1951][INFO    ][12623] Completed state [/etc/maas/regiond.conf] at time 05:52:26.756381 duration_in_ms=5.491
2019-06-15 05:52:26,756 [salt.state       :1780][INFO    ][12623] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:52:26.756912
2019-06-15 05:52:26,757 [salt.state       :1813][INFO    ][12623] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-15 05:52:26,834 [salt.state       :300 ][INFO    ][12623] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-15 05:52:26,834 [salt.state       :1951][INFO    ][12623] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:52:26.834467 duration_in_ms=77.555
2019-06-15 05:52:26,835 [salt.state       :1780][INFO    ][12623] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:52:26.834994
2019-06-15 05:52:26,835 [salt.state       :1813][INFO    ][12623] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-15 05:52:26,839 [salt.state       :300 ][INFO    ][12623] No changes needed to be made
2019-06-15 05:52:26,840 [salt.state       :1951][INFO    ][12623] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:52:26.840188 duration_in_ms=5.194
2019-06-15 05:52:26,840 [salt.state       :1780][INFO    ][12623] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:52:26.840705
2019-06-15 05:52:26,841 [salt.state       :1813][INFO    ][12623] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-15 05:52:26,845 [salt.state       :300 ][INFO    ][12623] No changes needed to be made
2019-06-15 05:52:26,845 [salt.state       :1951][INFO    ][12623] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:52:26.845523 duration_in_ms=4.818
2019-06-15 05:52:26,846 [salt.state       :1780][INFO    ][12623] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:52:26.846696
2019-06-15 05:52:26,847 [salt.state       :1813][INFO    ][12623] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-15 05:52:26,873 [salt.state       :300 ][INFO    ][12623] No changes needed to be made
2019-06-15 05:52:26,873 [salt.state       :1951][INFO    ][12623] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:52:26.873627 duration_in_ms=26.931
2019-06-15 05:52:26,874 [salt.state       :1780][INFO    ][12623] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:52:26.874612
2019-06-15 05:52:26,874 [salt.state       :1813][INFO    ][12623] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-15 05:52:26,890 [salt.state       :300 ][INFO    ][12623] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-15 05:52:26,890 [salt.state       :1951][INFO    ][12623] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:52:26.890525 duration_in_ms=15.912
2019-06-15 05:52:26,892 [salt.state       :1780][INFO    ][12623] Running state [a2enmod headers] at time 05:52:26.891971
2019-06-15 05:52:26,892 [salt.state       :1813][INFO    ][12623] Executing state cmd.run for [a2enmod headers]
2019-06-15 05:52:26,893 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12623] Executing command 'a2enmod headers' in directory '/root'
2019-06-15 05:52:26,986 [salt.state       :300 ][INFO    ][12623] {'pid': 12642, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-15 05:52:26,987 [salt.state       :1951][INFO    ][12623] Completed state [a2enmod headers] at time 05:52:26.987452 duration_in_ms=95.48
2019-06-15 05:52:26,988 [salt.state       :1780][INFO    ][12623] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:52:26.988128
2019-06-15 05:52:26,988 [salt.state       :1813][INFO    ][12623] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-15 05:52:27,017 [salt.state       :300 ][INFO    ][12623] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-15 05:52:27,018 [salt.state       :1951][INFO    ][12623] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:52:27.018052 duration_in_ms=29.923
2019-06-15 05:52:27,018 [salt.state       :1780][INFO    ][12623] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:52:27.018752
2019-06-15 05:52:27,019 [salt.state       :1813][INFO    ][12623] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-15 05:52:27,078 [salt.state       :300 ][INFO    ][12623] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-15 05:52:27,078 [salt.state       :1951][INFO    ][12623] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:52:27.078909 duration_in_ms=60.157
2019-06-15 05:52:27,079 [salt.state       :1780][INFO    ][12623] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:52:27.079460
2019-06-15 05:52:27,079 [salt.state       :1813][INFO    ][12623] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-15 05:52:27,156 [salt.state       :300 ][INFO    ][12623] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-15 05:52:27,156 [salt.state       :1951][INFO    ][12623] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:52:27.156293 duration_in_ms=76.833
2019-06-15 05:52:27,156 [salt.state       :1780][INFO    ][12623] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:52:27.156832
2019-06-15 05:52:27,157 [salt.state       :1813][INFO    ][12623] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-15 05:52:27,238 [salt.state       :300 ][INFO    ][12623] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-15 05:52:27,239 [salt.state       :1951][INFO    ][12623] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:52:27.239247 duration_in_ms=82.413
2019-06-15 05:52:27,240 [salt.state       :1780][INFO    ][12623] Running state [/root/.pgpass] at time 05:52:27.239930
2019-06-15 05:52:27,240 [salt.state       :1813][INFO    ][12623] Executing state file.managed for [/root/.pgpass]
2019-06-15 05:52:27,294 [salt.state       :300 ][INFO    ][12623] File /root/.pgpass is in the correct state
2019-06-15 05:52:27,294 [salt.state       :1951][INFO    ][12623] Completed state [/root/.pgpass] at time 05:52:27.294456 duration_in_ms=54.527
2019-06-15 05:52:27,299 [salt.state       :1780][INFO    ][12623] Running state [maas-region syncdb --noinput] at time 05:52:27.299398
2019-06-15 05:52:27,299 [salt.state       :1813][INFO    ][12623] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-15 05:52:27,300 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12623] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-15 05:52:30,084 [salt.state       :300 ][INFO    ][12623] {'pid': 12655, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: maasserver, piston3, metadataserver, contenttypes, sessions, sites, auth\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-15 05:52:30,086 [salt.state       :1951][INFO    ][12623] Completed state [maas-region syncdb --noinput] at time 05:52:30.085548 duration_in_ms=2786.149
2019-06-15 05:52:30,087 [salt.state       :2022][WARNING ][12623] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-15 05:52:30,090 [salt.state       :1780][INFO    ][12623] Running state [maas-regiond] at time 05:52:30.090504
2019-06-15 05:52:30,091 [salt.state       :1813][INFO    ][12623] Executing state service.running for [maas-regiond]
2019-06-15 05:52:30,093 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12623] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-15 05:52:30,143 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12623] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-15 05:52:30,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12623] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-15 05:52:30,192 [salt.state       :300 ][INFO    ][12623] The service maas-regiond is already running
2019-06-15 05:52:30,192 [salt.state       :1951][INFO    ][12623] Completed state [maas-regiond] at time 05:52:30.192771 duration_in_ms=102.266
2019-06-15 05:52:30,199 [salt.state       :1780][INFO    ][12623] Running state [bind9] at time 05:52:30.199221
2019-06-15 05:52:30,199 [salt.state       :1813][INFO    ][12623] Executing state service.running for [bind9]
2019-06-15 05:52:30,201 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12623] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-15 05:52:30,226 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12623] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-15 05:52:30,258 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12623] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-15 05:52:30,282 [salt.state       :300 ][INFO    ][12623] The service bind9 is already running
2019-06-15 05:52:30,283 [salt.state       :1951][INFO    ][12623] Completed state [bind9] at time 05:52:30.282979 duration_in_ms=83.756
2019-06-15 05:52:30,287 [salt.state       :1780][INFO    ][12623] Running state [apache2] at time 05:52:30.287008
2019-06-15 05:52:30,287 [salt.state       :1813][INFO    ][12623] Executing state service.running for [apache2]
2019-06-15 05:52:30,289 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12623] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-15 05:52:30,317 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12623] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-15 05:52:30,337 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12623] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-15 05:52:30,372 [salt.state       :300 ][INFO    ][12623] The service apache2 is already running
2019-06-15 05:52:30,372 [salt.state       :1951][INFO    ][12623] Completed state [apache2] at time 05:52:30.372647 duration_in_ms=85.638
2019-06-15 05:52:30,378 [salt.state       :1780][INFO    ][12623] Running state [maasng.wait_for_http_code] at time 05:52:30.378807
2019-06-15 05:52:30,379 [salt.state       :1813][INFO    ][12623] Executing state module.run for [maasng.wait_for_http_code]
2019-06-15 05:52:30,380 [salt.utils.decorators:613 ][WARNING ][12623] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:52:30,499 [salt.state       :300 ][INFO    ][12623] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-15 05:52:30,499 [salt.state       :1951][INFO    ][12623] Completed state [maasng.wait_for_http_code] at time 05:52:30.499744 duration_in_ms=120.937
2019-06-15 05:52:30,501 [salt.state       :1780][INFO    ][12623] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:52:30.500991
2019-06-15 05:52:30,501 [salt.state       :1813][INFO    ][12623] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-15 05:52:30,502 [salt.state       :300 ][INFO    ][12623] /var/lib/maas/.setup_admin exists
2019-06-15 05:52:30,503 [salt.state       :1951][INFO    ][12623] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:52:30.503085 duration_in_ms=2.094
2019-06-15 05:52:30,504 [salt.state       :1780][INFO    ][12623] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:52:30.504412
2019-06-15 05:52:30,505 [salt.state       :1813][INFO    ][12623] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 05:52:30,508 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12623] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 05:52:32,452 [salt.state       :300 ][INFO    ][12623] {'pid': 12676, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 05:52:32,453 [salt.state       :1951][INFO    ][12623] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:52:32.453068 duration_in_ms=1948.654
2019-06-15 05:52:32,465 [salt.state       :1780][INFO    ][12623] Running state [maas_region_boot_source_resources_mirror] at time 05:52:32.464941
2019-06-15 05:52:32,465 [salt.state       :1813][INFO    ][12623] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-15 05:52:32,574 [salt.state       :300 ][INFO    ][12623] {'changes': {}}
2019-06-15 05:52:32,574 [salt.state       :1951][INFO    ][12623] Completed state [maas_region_boot_source_resources_mirror] at time 05:52:32.574515 duration_in_ms=109.574
2019-06-15 05:52:32,575 [salt.state       :1780][INFO    ][12623] Running state [maasng.boot_resources_import] at time 05:52:32.575718
2019-06-15 05:52:32,576 [salt.state       :1813][INFO    ][12623] Executing state module.run for [maasng.boot_resources_import]
2019-06-15 05:52:32,576 [salt.utils.decorators:613 ][WARNING ][12623] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:52:32,670 [salt.loaded.ext.module.maasng:1600][INFO    ][12623] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-15 05:52:37,720 [salt.loaded.ext.module.maasng:1600][INFO    ][12623] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-15 05:52:38,888 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055238877613
2019-06-15 05:52:38,917 [salt.minion      :1432][INFO    ][12705] Starting a new job with PID 12705
2019-06-15 05:52:38,950 [salt.minion      :1711][INFO    ][12705] Returning information for job: 20190615055238877613
2019-06-15 05:52:42,823 [salt.state       :300 ][INFO    ][12623] {'ret': True}
2019-06-15 05:52:42,824 [salt.state       :1951][INFO    ][12623] Completed state [maasng.boot_resources_import] at time 05:52:42.823937 duration_in_ms=10248.219
2019-06-15 05:52:42,825 [salt.state       :1780][INFO    ][12623] Running state [maas_region_boot_sources_selection_xenial] at time 05:52:42.825293
2019-06-15 05:52:42,825 [salt.state       :1813][INFO    ][12623] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-15 05:52:43,008 [salt.state       :300 ][INFO    ][12623] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-15 05:52:43,009 [salt.state       :1951][INFO    ][12623] Completed state [maas_region_boot_sources_selection_xenial] at time 05:52:43.008972 duration_in_ms=183.678
2019-06-15 05:52:43,012 [salt.state       :1780][INFO    ][12623] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:52:43.012366
2019-06-15 05:52:43,012 [salt.state       :1813][INFO    ][12623] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-15 05:52:43,013 [salt.utils.decorators:613 ][WARNING ][12623] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:52:43,014 [salt.loaded.ext.module.maasng:1771][INFO    ][12623] boot-sources sync initiated for ALL Rack's
2019-06-15 05:52:44,051 [salt.state       :300 ][INFO    ][12623] {'ret': True}
2019-06-15 05:52:44,051 [salt.state       :1951][INFO    ][12623] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:52:44.051650 duration_in_ms=1039.283
2019-06-15 05:52:44,055 [salt.state       :1780][INFO    ][12623] Running state [maas.process_maas_config] at time 05:52:44.055048
2019-06-15 05:52:44,055 [salt.state       :1813][INFO    ][12623] Executing state module.run for [maas.process_maas_config]
2019-06-15 05:52:44,056 [salt.utils.decorators:613 ][WARNING ][12623] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:52:44,057 [salt.loaded.ext.module.maas:92  ][INFO    ][12623] maasconfig name=enable_http_proxy value=True
2019-06-15 05:52:44,110 [salt.loaded.ext.module.maas:92  ][INFO    ][12623] maasconfig name=upstream_dns value=8.8.8.8
2019-06-15 05:52:44,168 [salt.loaded.ext.module.maas:92  ][INFO    ][12623] maasconfig name=commissioning_distro_series value=xenial
2019-06-15 05:52:44,229 [salt.loaded.ext.module.maas:92  ][INFO    ][12623] maasconfig name=default_osystem value=ubuntu
2019-06-15 05:52:45,658 [salt.loaded.ext.module.maas:92  ][INFO    ][12623] maasconfig name=active_discovery_interval value=600
2019-06-15 05:52:45,726 [salt.loaded.ext.module.maas:92  ][INFO    ][12623] maasconfig name=dnssec_validation value=no
2019-06-15 05:52:45,790 [salt.loaded.ext.module.maas:92  ][INFO    ][12623] maasconfig name=maas_name value=mas01
2019-06-15 05:52:45,839 [salt.loaded.ext.module.maas:92  ][INFO    ][12623] maasconfig name=network_discovery value=enabled
2019-06-15 05:52:45,944 [salt.loaded.ext.module.maas:92  ][INFO    ][12623] maasconfig name=enable_third_party_drivers value=True
2019-06-15 05:52:46,012 [salt.loaded.ext.module.maas:92  ][INFO    ][12623] maasconfig name=default_storage_layout value=lvm
2019-06-15 05:52:46,081 [salt.loaded.ext.module.maas:92  ][INFO    ][12623] maasconfig name=ntp_external_only value=True
2019-06-15 05:52:46,135 [salt.loaded.ext.module.maas:92  ][INFO    ][12623] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-15 05:52:46,200 [salt.loaded.ext.module.maas:92  ][INFO    ][12623] maasconfig name=default_distro_series value=xenial
2019-06-15 05:52:46,256 [salt.loaded.ext.module.maas:92  ][INFO    ][12623] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-15 05:52:46,396 [salt.state       :300 ][INFO    ][12623] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-06-15 05:52:46,397 [salt.state       :1951][INFO    ][12623] Completed state [maas.process_maas_config] at time 05:52:46.397167 duration_in_ms=2342.118
2019-06-15 05:52:46,399 [salt.state       :1780][INFO    ][12623] Running state [pxe_admin] at time 05:52:46.398926
2019-06-15 05:52:46,399 [salt.state       :1813][INFO    ][12623] Executing state maasng.fabric_present for [pxe_admin]
2019-06-15 05:52:46,454 [salt.loaded.ext.module.maasng:945 ][INFO    ][12623] [{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'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'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {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'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'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2', u'class_type': None}, {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'primary_rack': u'qf4wqt', 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'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin', u'class_type': u''}]
2019-06-15 05:52:46,541 [salt.loaded.ext.module.maasng:1008][WARNING ][12623] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-15 05:52:46,541 [salt.loaded.ext.module.maasng:1011][WARNING ][12623] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-15 05:52:46,606 [salt.state       :300 ][INFO    ][12623] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-15 05:52:46,607 [salt.state       :1951][INFO    ][12623] Completed state [pxe_admin] at time 05:52:46.607042 duration_in_ms=208.116
2019-06-15 05:52:46,607 [salt.state       :1780][INFO    ][12623] Running state [vlan 0] at time 05:52:46.607454
2019-06-15 05:52:46,607 [salt.state       :1813][INFO    ][12623] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-15 05:52:46,660 [salt.loaded.ext.module.maasng:945 ][INFO    ][12623] [{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'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'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {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'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'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2', u'class_type': None}, {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'primary_rack': u'qf4wqt', 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'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin', u'class_type': u''}]
2019-06-15 05:52:46,904 [salt.loaded.ext.module.maasng:945 ][INFO    ][12623] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qf4wqt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-06-15 05:52:47,168 [salt.loaded.ext.module.maasng:945 ][INFO    ][12623] [{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'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'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {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'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'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2', u'class_type': None}, {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'primary_rack': u'qf4wqt', 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'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin', u'class_type': u''}]
2019-06-15 05:52:47,251 [salt.state       :300 ][INFO    ][12623] {'new': 'Vlan untagged was updated'}
2019-06-15 05:52:47,252 [salt.state       :1951][INFO    ][12623] Completed state [vlan 0] at time 05:52:47.252115 duration_in_ms=644.659
2019-06-15 05:52:47,253 [salt.state       :1780][INFO    ][12623] Running state [192.168.11.0/24] at time 05:52:47.253771
2019-06-15 05:52:47,254 [salt.state       :1813][INFO    ][12623] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-15 05:52:47,476 [salt.loaded.ext.module.maasng:945 ][INFO    ][12623] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric': u'fabric-2'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'qf4wqt', 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'fabric': u'pxe_admin'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-15 05:52:47,477 [salt.loaded.ext.module.maasng:1235][WARNING ][12623] Ignoring parameter vlan:0
2019-06-15 05:52:47,540 [salt.state       :300 ][INFO    ][12623] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-15 05:52:47,541 [salt.state       :1951][INFO    ][12623] Completed state [192.168.11.0/24] at time 05:52:47.540964 duration_in_ms=287.193
2019-06-15 05:52:47,542 [salt.state       :1780][INFO    ][12623] Running state [maas_create_iprange_1] at time 05:52:47.542410
2019-06-15 05:52:47,542 [salt.state       :1813][INFO    ][12623] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-15 05:52:47,591 [salt.state       :300 ][INFO    ][12623] Iprange maas_create_iprange_1 already exist.
2019-06-15 05:52:47,592 [salt.state       :1951][INFO    ][12623] Completed state [maas_create_iprange_1] at time 05:52:47.592058 duration_in_ms=49.648
2019-06-15 05:52:47,592 [salt.state       :1780][INFO    ][12623] Running state [vlan 0] at time 05:52:47.592344
2019-06-15 05:52:47,592 [salt.state       :1813][INFO    ][12623] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-15 05:52:47,639 [salt.loaded.ext.module.maasng:945 ][INFO    ][12623] [{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'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'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {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'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'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2', u'class_type': None}, {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'primary_rack': u'qf4wqt', 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'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin', u'class_type': u''}]
2019-06-15 05:52:47,732 [salt.loaded.ext.module.maasng:945 ][INFO    ][12623] [{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'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'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {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'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'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2', u'class_type': None}, {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'primary_rack': u'qf4wqt', 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'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin', u'class_type': u''}]
2019-06-15 05:52:47,952 [salt.loaded.ext.module.maasng:945 ][INFO    ][12623] [{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'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'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {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'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'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2', u'class_type': None}, {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'primary_rack': u'qf4wqt', 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'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin', u'class_type': u''}]
2019-06-15 05:52:48,071 [salt.state       :300 ][INFO    ][12623] {'new': 'Vlan untagged was updated'}
2019-06-15 05:52:48,071 [salt.state       :1951][INFO    ][12623] Completed state [vlan 0] at time 05:52:48.071454 duration_in_ms=479.11
2019-06-15 05:52:48,072 [salt.state       :1780][INFO    ][12623] Running state [opnfv] at time 05:52:48.072461
2019-06-15 05:52:48,072 [salt.state       :1813][INFO    ][12623] Executing state maasng.sshkey_present for [opnfv]
2019-06-15 05:52:48,154 [salt.loaded.ext.module.maasng:1903][INFO    ][12623] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-06-15 05:52:48,155 [salt.state       :300 ][INFO    ][12623] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-15 05:52:48,155 [salt.state       :1951][INFO    ][12623] Completed state [opnfv] at time 05:52:48.155432 duration_in_ms=82.971
2019-06-15 05:52:48,156 [salt.state       :1780][INFO    ][12623] Running state [maas.process_tags] at time 05:52:48.156317
2019-06-15 05:52:48,156 [salt.state       :1813][INFO    ][12623] Executing state module.run for [maas.process_tags]
2019-06-15 05:52:48,157 [salt.utils.decorators:613 ][WARNING ][12623] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:52:48,229 [salt.loaded.ext.module.maas:92  ][INFO    ][12623] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-06-15 05:52:48,285 [salt.state       :300 ][INFO    ][12623] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-06-15 05:52:48,286 [salt.state       :1951][INFO    ][12623] Completed state [maas.process_tags] at time 05:52:48.286290 duration_in_ms=129.973
2019-06-15 05:52:48,289 [salt.minion      :1711][INFO    ][12623] Returning information for job: 20190615055223816258
2019-06-15 05:52:49,060 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command state.apply with jid 20190615055249048550
2019-06-15 05:52:49,076 [salt.minion      :1432][INFO    ][13061] Starting a new job with PID 13061
2019-06-15 05:52:57,486 [salt.state       :915 ][INFO    ][13061] Loading fresh modules for state activity
2019-06-15 05:52:57,632 [salt.state       :1780][INFO    ][13061] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:52:57.632873
2019-06-15 05:52:57,633 [salt.state       :1813][INFO    ][13061] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 05:52:57,636 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13061] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 05:52:59,678 [salt.state       :300 ][INFO    ][13061] {'pid': 13098, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 05:52:59,679 [salt.state       :1951][INFO    ][13061] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:52:59.679172 duration_in_ms=2046.3
2019-06-15 05:52:59,682 [salt.state       :1780][INFO    ][13061] Running state [maas.process_machines] at time 05:52:59.682607
2019-06-15 05:52:59,683 [salt.state       :1813][INFO    ][13061] Executing state module.run for [maas.process_machines]
2019-06-15 05:52:59,683 [salt.utils.decorators:613 ][WARNING ][13061] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:53:00,257 [salt.loaded.ext.module.maas:412 ][WARNING ][13061] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 05:53:00,258 [salt.loaded.ext.module.maas:92  ][INFO    ][13061] 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=wecscs architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-15 05:53:01,500 [salt.loaded.ext.module.maas:412 ][WARNING ][13061] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 05:53:01,501 [salt.loaded.ext.module.maas:92  ][INFO    ][13061] 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=qgfqq7 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-15 05:53:02,722 [salt.loaded.ext.module.maas:412 ][WARNING ][13061] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 05:53:02,723 [salt.loaded.ext.module.maas:92  ][INFO    ][13061] 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=44wxqw architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-15 05:53:03,967 [salt.loaded.ext.module.maas:412 ][WARNING ][13061] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 05:53:03,968 [salt.loaded.ext.module.maas:92  ][INFO    ][13061] 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=f6xex7 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-15 05:53:04,146 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055304129250
2019-06-15 05:53:04,176 [salt.minion      :1432][INFO    ][13239] Starting a new job with PID 13239
2019-06-15 05:53:04,211 [salt.minion      :1711][INFO    ][13239] Returning information for job: 20190615055304129250
2019-06-15 05:53:05,209 [salt.loaded.ext.module.maas:412 ][WARNING ][13061] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 05:53:05,212 [salt.loaded.ext.module.maas:92  ][INFO    ][13061] 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=8tfqqc architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-15 05:53:06,420 [salt.state       :300 ][INFO    ][13061] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-15 05:53:06,421 [salt.state       :1951][INFO    ][13061] Completed state [maas.process_machines] at time 05:53:06.420918 duration_in_ms=6738.31
2019-06-15 05:53:06,425 [salt.minion      :1711][INFO    ][13061] Returning information for job: 20190615055249048550
2019-06-15 05:53:40,472 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command state.apply with jid 20190615055340462858
2019-06-15 05:53:40,499 [salt.minion      :1432][INFO    ][13367] Starting a new job with PID 13367
2019-06-15 05:53:48,964 [salt.state       :915 ][INFO    ][13367] Loading fresh modules for state activity
2019-06-15 05:53:49,075 [salt.state       :1780][INFO    ][13367] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:49.075114
2019-06-15 05:53:49,075 [salt.state       :1813][INFO    ][13367] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 05:53:49,077 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13367] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 05:53:50,956 [salt.state       :300 ][INFO    ][13367] {'pid': 13374, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 05:53:50,957 [salt.state       :1951][INFO    ][13367] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:50.957428 duration_in_ms=1882.314
2019-06-15 05:53:50,961 [salt.state       :1780][INFO    ][13367] Running state [maas.wait_for_machine_status] at time 05:53:50.960968
2019-06-15 05:53:50,961 [salt.state       :1813][INFO    ][13367] Executing state module.run for [maas.wait_for_machine_status]
2019-06-15 05:53:50,962 [salt.utils.decorators:613 ][WARNING ][13367] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:53:53,876 [salt.state       :300 ][INFO    ][13367] {'ret': True}
2019-06-15 05:53:53,876 [salt.state       :1951][INFO    ][13367] Completed state [maas.wait_for_machine_status] at time 05:53:53.876390 duration_in_ms=2915.421
2019-06-15 05:53:53,880 [salt.minion      :1711][INFO    ][13367] Returning information for job: 20190615055340462858
2019-06-15 05:53:54,604 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command state.apply with jid 20190615055354589705
2019-06-15 05:53:54,627 [salt.minion      :1432][INFO    ][13400] Starting a new job with PID 13400
2019-06-15 05:53:55,891 [salt.state       :915 ][INFO    ][13400] Loading fresh modules for state activity
2019-06-15 05:53:56,050 [salt.state       :1780][INFO    ][13400] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:56.050259
2019-06-15 05:53:56,050 [salt.state       :1813][INFO    ][13400] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 05:53:56,052 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13400] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 05:53:57,927 [salt.state       :300 ][INFO    ][13400] {'pid': 13407, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 05:53:57,928 [salt.state       :1951][INFO    ][13400] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:53:57.928402 duration_in_ms=1878.142
2019-06-15 05:53:57,932 [salt.state       :1780][INFO    ][13400] Running state [maas_machines_storage_cmp002_lvm] at time 05:53:57.932723
2019-06-15 05:53:57,933 [salt.state       :1813][INFO    ][13400] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-15 05:53:58,498 [salt.state       :300 ][INFO    ][13400] Machine cmp002 is not in Ready state.
2019-06-15 05:53:58,499 [salt.state       :1951][INFO    ][13400] Completed state [maas_machines_storage_cmp002_lvm] at time 05:53:58.499427 duration_in_ms=566.704
2019-06-15 05:53:58,500 [salt.state       :1780][INFO    ][13400] Running state [maas_machines_storage_cmp001_lvm] at time 05:53:58.500262
2019-06-15 05:53:58,501 [salt.state       :1813][INFO    ][13400] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-15 05:53:59,050 [salt.state       :300 ][INFO    ][13400] Machine cmp001 is not in Ready state.
2019-06-15 05:53:59,051 [salt.state       :1951][INFO    ][13400] Completed state [maas_machines_storage_cmp001_lvm] at time 05:53:59.051289 duration_in_ms=551.028
2019-06-15 05:53:59,058 [salt.minion      :1711][INFO    ][13400] Returning information for job: 20190615055354589705
2019-06-15 05:53:59,791 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command state.apply with jid 20190615055359777503
2019-06-15 05:53:59,815 [salt.minion      :1432][INFO    ][13420] Starting a new job with PID 13420
2019-06-15 05:54:01,016 [salt.state       :915 ][INFO    ][13420] Loading fresh modules for state activity
2019-06-15 05:54:01,128 [salt.state       :1780][INFO    ][13420] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:54:01.128780
2019-06-15 05:54:01,129 [salt.state       :1813][INFO    ][13420] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 05:54:01,134 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13420] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 05:54:02,996 [salt.state       :300 ][INFO    ][13420] {'pid': 13427, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 05:54:02,997 [salt.state       :1951][INFO    ][13420] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:54:02.997863 duration_in_ms=1869.085
2019-06-15 05:54:03,000 [salt.state       :1780][INFO    ][13420] Running state [maas.deploy_machines] at time 05:54:03.000764
2019-06-15 05:54:03,001 [salt.state       :1813][INFO    ][13420] Executing state module.run for [maas.deploy_machines]
2019-06-15 05:54:03,003 [salt.utils.decorators:613 ][WARNING ][13420] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:54:03,575 [salt.loaded.ext.module.maas:684 ][INFO    ][13420] deploymachines hwe_kernel=hwe-16.04 system_id=8tfqqc distro_series=xenial
2019-06-15 05:54:06,082 [salt.state       :300 ][INFO    ][13420] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-06-15 05:54:06,083 [salt.state       :1951][INFO    ][13420] Completed state [maas.deploy_machines] at time 05:54:06.083312 duration_in_ms=3082.547
2019-06-15 05:54:06,089 [salt.minion      :1711][INFO    ][13420] Returning information for job: 20190615055359777503
2019-06-15 05:54:06,813 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command state.apply with jid 20190615055406802046
2019-06-15 05:54:06,839 [salt.minion      :1432][INFO    ][13486] Starting a new job with PID 13486
2019-06-15 05:54:15,570 [salt.state       :915 ][INFO    ][13486] Loading fresh modules for state activity
2019-06-15 05:54:15,680 [salt.state       :1780][INFO    ][13486] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:54:15.680896
2019-06-15 05:54:15,681 [salt.state       :1813][INFO    ][13486] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 05:54:15,683 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13486] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 05:54:17,580 [salt.state       :300 ][INFO    ][13486] {'pid': 13531, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 05:54:17,583 [salt.state       :1951][INFO    ][13486] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:54:17.583342 duration_in_ms=1902.445
2019-06-15 05:54:17,586 [salt.state       :1780][INFO    ][13486] Running state [maas.wait_for_machine_status] at time 05:54:17.586863
2019-06-15 05:54:17,587 [salt.state       :1813][INFO    ][13486] Executing state module.run for [maas.wait_for_machine_status]
2019-06-15 05:54:17,588 [salt.utils.decorators:613 ][WARNING ][13486] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 05:54:20,327 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.27749896s left)
2019-06-15 05:54:21,871 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055421855629
2019-06-15 05:54:21,894 [salt.minion      :1432][INFO    ][13543] Starting a new job with PID 13543
2019-06-15 05:54:21,924 [salt.minion      :1711][INFO    ][13543] Returning information for job: 20190615055421855629
2019-06-15 05:54:52,145 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055452083605
2019-06-15 05:54:52,171 [salt.minion      :1432][INFO    ][13566] Starting a new job with PID 13566
2019-06-15 05:54:52,209 [salt.minion      :1711][INFO    ][13566] Returning information for job: 20190615055452083605
2019-06-15 05:54:53,242 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.36313009s left)
2019-06-15 05:55:22,278 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055522263058
2019-06-15 05:55:22,312 [salt.minion      :1432][INFO    ][13639] Starting a new job with PID 13639
2019-06-15 05:55:22,347 [salt.minion      :1711][INFO    ][13639] Returning information for job: 20190615055522263058
2019-06-15 05:55:26,200 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.40481901s left)
2019-06-15 05:55:52,380 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055552366286
2019-06-15 05:55:52,409 [salt.minion      :1432][INFO    ][13660] Starting a new job with PID 13660
2019-06-15 05:55:52,443 [salt.minion      :1711][INFO    ][13660] Returning information for job: 20190615055552366286
2019-06-15 05:55:58,930 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.67531896s left)
2019-06-15 05:56:22,470 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055622456024
2019-06-15 05:56:22,497 [salt.minion      :1432][INFO    ][13719] Starting a new job with PID 13719
2019-06-15 05:56:22,531 [salt.minion      :1711][INFO    ][13719] Returning information for job: 20190615055622456024
2019-06-15 05:56:31,662 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.94275713s left)
2019-06-15 05:56:52,566 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055652547671
2019-06-15 05:56:52,597 [salt.minion      :1432][INFO    ][13739] Starting a new job with PID 13739
2019-06-15 05:56:52,628 [salt.minion      :1711][INFO    ][13739] Returning information for job: 20190615055652547671
2019-06-15 05:57:04,467 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.13838601s left)
2019-06-15 05:57:22,680 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055722663600
2019-06-15 05:57:22,701 [salt.minion      :1432][INFO    ][13802] Starting a new job with PID 13802
2019-06-15 05:57:22,736 [salt.minion      :1711][INFO    ][13802] Returning information for job: 20190615055722663600
2019-06-15 05:57:37,383 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.22163606s left)
2019-06-15 05:57:52,764 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055752749674
2019-06-15 05:57:52,792 [salt.minion      :1432][INFO    ][13824] Starting a new job with PID 13824
2019-06-15 05:57:52,833 [salt.minion      :1711][INFO    ][13824] Returning information for job: 20190615055752749674
2019-06-15 05:58:10,088 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.516886s left)
2019-06-15 05:58:22,893 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055822885609
2019-06-15 05:58:22,912 [salt.minion      :1432][INFO    ][13903] Starting a new job with PID 13903
2019-06-15 05:58:22,947 [salt.minion      :1711][INFO    ][13903] Returning information for job: 20190615055822885609
2019-06-15 05:58:43,140 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.46505404s left)
2019-06-15 05:58:52,997 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055852978745
2019-06-15 05:58:53,028 [salt.minion      :1432][INFO    ][13935] Starting a new job with PID 13935
2019-06-15 05:58:53,083 [salt.minion      :1711][INFO    ][13935] Returning information for job: 20190615055852978745
2019-06-15 05:59:16,017 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.58820701s left)
2019-06-15 05:59:23,177 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055923163052
2019-06-15 05:59:23,205 [salt.minion      :1432][INFO    ][14091] Starting a new job with PID 14091
2019-06-15 05:59:23,243 [salt.minion      :1711][INFO    ][14091] Returning information for job: 20190615055923163052
2019-06-15 05:59:48,820 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.78520393s left)
2019-06-15 05:59:53,308 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615055953297350
2019-06-15 05:59:53,327 [salt.minion      :1432][INFO    ][14127] Starting a new job with PID 14127
2019-06-15 05:59:53,361 [salt.minion      :1711][INFO    ][14127] Returning information for job: 20190615055953297350
2019-06-15 06:00:21,725 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.88033199s left)
2019-06-15 06:00:23,432 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615060023421251
2019-06-15 06:00:23,467 [salt.minion      :1432][INFO    ][14247] Starting a new job with PID 14247
2019-06-15 06:00:23,503 [salt.minion      :1711][INFO    ][14247] Returning information for job: 20190615060023421251
2019-06-15 06:00:53,586 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615060053575238
2019-06-15 06:00:53,610 [salt.minion      :1432][INFO    ][14273] Starting a new job with PID 14273
2019-06-15 06:00:53,641 [salt.minion      :1711][INFO    ][14273] Returning information for job: 20190615060053575238
2019-06-15 06:00:55,073 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.53208113s left)
2019-06-15 06:01:23,741 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615060123726906
2019-06-15 06:01:23,766 [salt.minion      :1432][INFO    ][14359] Starting a new job with PID 14359
2019-06-15 06:01:23,799 [salt.minion      :1711][INFO    ][14359] Returning information for job: 20190615060123726906
2019-06-15 06:01:27,927 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.67763901s left)
2019-06-15 06:01:53,913 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615060153899342
2019-06-15 06:01:53,940 [salt.minion      :1432][INFO    ][14392] Starting a new job with PID 14392
2019-06-15 06:01:53,982 [salt.minion      :1711][INFO    ][14392] Returning information for job: 20190615060153899342
2019-06-15 06:02:00,730 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.87494612s left)
2019-06-15 06:02:24,111 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615060224098431
2019-06-15 06:02:24,137 [salt.minion      :1432][INFO    ][14586] Starting a new job with PID 14586
2019-06-15 06:02:24,173 [salt.minion      :1711][INFO    ][14586] Returning information for job: 20190615060224098431
2019-06-15 06:02:33,692 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.91257191s left)
2019-06-15 06:02:54,284 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615060254269042
2019-06-15 06:02:54,315 [salt.minion      :1432][INFO    ][14616] Starting a new job with PID 14616
2019-06-15 06:02:54,343 [salt.minion      :1711][INFO    ][14616] Returning information for job: 20190615060254269042
2019-06-15 06:03:06,584 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1721.02051997s left)
2019-06-15 06:03:24,464 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615060324447589
2019-06-15 06:03:24,492 [salt.minion      :1432][INFO    ][14664] Starting a new job with PID 14664
2019-06-15 06:03:24,524 [salt.minion      :1711][INFO    ][14664] Returning information for job: 20190615060324447589
2019-06-15 06:03:39,676 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.9289391s left)
2019-06-15 06:03:54,655 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615060354639346
2019-06-15 06:03:54,681 [salt.minion      :1432][INFO    ][14692] Starting a new job with PID 14692
2019-06-15 06:03:54,723 [salt.minion      :1711][INFO    ][14692] Returning information for job: 20190615060354639346
2019-06-15 06:04:12,880 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.72533512s left)
2019-06-15 06:04:24,852 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615060424835670
2019-06-15 06:04:24,880 [salt.minion      :1432][INFO    ][14740] Starting a new job with PID 14740
2019-06-15 06:04:24,911 [salt.minion      :1711][INFO    ][14740] Returning information for job: 20190615060424835670
2019-06-15 06:04:45,741 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.86386704s left)
2019-06-15 06:04:55,036 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615060455023775
2019-06-15 06:04:55,065 [salt.minion      :1432][INFO    ][14776] Starting a new job with PID 14776
2019-06-15 06:04:55,097 [salt.minion      :1711][INFO    ][14776] Returning information for job: 20190615060455023775
2019-06-15 06:05:01,237 [salt.utils.schedule:1377][INFO    ][2944] Running scheduled job: __mine_interval
2019-06-15 06:05:19,288 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.31724s left)
2019-06-15 06:05:25,223 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615060525207332
2019-06-15 06:05:25,252 [salt.minion      :1432][INFO    ][14994] Starting a new job with PID 14994
2019-06-15 06:05:25,284 [salt.minion      :1711][INFO    ][14994] Returning information for job: 20190615060525207332
2019-06-15 06:05:52,192 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.41255903s left)
2019-06-15 06:05:55,431 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615060555410950
2019-06-15 06:05:55,458 [salt.minion      :1432][INFO    ][15021] Starting a new job with PID 15021
2019-06-15 06:05:55,496 [salt.minion      :1711][INFO    ][15021] Returning information for job: 20190615060555410950
2019-06-15 06:06:25,203 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1522.40214705s left)
2019-06-15 06:06:25,643 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615060625635744
2019-06-15 06:06:25,664 [salt.minion      :1432][INFO    ][15069] Starting a new job with PID 15069
2019-06-15 06:06:25,701 [salt.minion      :1711][INFO    ][15069] Returning information for job: 20190615060625635744
2019-06-15 06:06:55,871 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615060655857336
2019-06-15 06:06:55,895 [salt.minion      :1432][INFO    ][15097] Starting a new job with PID 15097
2019-06-15 06:06:55,929 [salt.minion      :1711][INFO    ][15097] Returning information for job: 20190615060655857336
2019-06-15 06:06:58,163 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1489.44236207s left)
2019-06-15 06:07:25,905 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615060725890322
2019-06-15 06:07:25,932 [salt.minion      :1432][INFO    ][15164] Starting a new job with PID 15164
2019-06-15 06:07:25,968 [salt.minion      :1711][INFO    ][15164] Returning information for job: 20190615060725890322
2019-06-15 06:07:31,056 [salt.loaded.ext.module.maas:1023][INFO    ][13486] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1456.54922104s left)
2019-06-15 06:07:56,088 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615060756072195
2019-06-15 06:07:56,118 [salt.minion      :1432][INFO    ][15217] Starting a new job with PID 15217
2019-06-15 06:07:56,147 [salt.minion      :1711][INFO    ][15217] Returning information for job: 20190615060756072195
2019-06-15 06:08:04,036 [salt.state       :300 ][INFO    ][13486] {'ret': True}
2019-06-15 06:08:04,037 [salt.state       :1951][INFO    ][13486] Completed state [maas.wait_for_machine_status] at time 06:08:04.037092 duration_in_ms=826450.224
2019-06-15 06:08:04,049 [salt.minion      :1711][INFO    ][13486] Returning information for job: 20190615055406802046
2019-06-15 07:05:01,237 [salt.utils.schedule:1377][INFO    ][2944] Running scheduled job: __mine_interval
2019-06-15 07:43:18,952 [salt.minion      :1308][INFO    ][2944] User sudo_ubuntu Executing command cp.push_dir with jid 20190615074318932824
2019-06-15 07:43:18,978 [salt.minion      :1432][INFO    ][22493] Starting a new job with PID 22493
